builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-085 starttime: 1487823688.39 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.389956) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.390457) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.390763) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.434844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.435225) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 20:21:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-02-22 20:21:28 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112791 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.562500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.562823) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022367 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.611981) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:28.612271) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 20:21:28,726 truncating revision to first 12 chars 2017-02-22 20:21:28,726 Setting DEBUG logging. 2017-02-22 20:21:28,726 attempt 1/10 2017-02-22 20:21:28,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 20:21:29,894 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.503636 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:30.128473) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.128797) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.129304) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.129628) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.139015) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-22 20:21:30.139319) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:30 INFO - MultiFileLogger online at 20170222 20:21:30 in /builds/slave/test 20:21:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 20:21:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:30 INFO - {'append_to_log': False, 20:21:30 INFO - 'base_work_dir': '/builds/slave/test', 20:21:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:30 INFO - 'branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:30 INFO - 'config_files': ('talos/linux_config.py',), 20:21:30 INFO - 'default_actions': ('clobber', 20:21:30 INFO - 'read-buildbot-config', 20:21:30 INFO - 'download-and-extract', 20:21:30 INFO - 'populate-webroot', 20:21:30 INFO - 'create-virtualenv', 20:21:30 INFO - 'install', 20:21:30 INFO - 'run-tests'), 20:21:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:30 INFO - 'download_minidump_stackwalk': True, 20:21:30 INFO - 'download_symbols': 'ondemand', 20:21:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:30 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:30 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:30 INFO - 'gecko_profile': False, 20:21:30 INFO - 'gecko_profile_interval': 0, 20:21:30 INFO - 'installer_path': 'installer.exe', 20:21:30 INFO - 'log_level': 'info', 20:21:30 INFO - 'log_name': 'talos', 20:21:30 INFO - 'log_to_console': True, 20:21:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:30 INFO - 'opt_config_files': (), 20:21:30 INFO - 'pip_index': False, 20:21:30 INFO - 'suite': 'dromaeojs-e10s', 20:21:30 INFO - 'system_bits': '32', 20:21:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:30 INFO - 'title': 'talos-linux64-ix-085', 20:21:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:30 INFO - 'use_talos_json': True, 20:21:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:30 INFO - 'work_dir': 'build'} 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.352273Z] Running clobber step. 20:21:30 INFO - Running pre-action listener: _resource_record_pre_action 20:21:30 INFO - Running main action method: clobber 20:21:30 INFO - rmtree: /builds/slave/test/build 20:21:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:31 INFO - Running post-action listener: _resource_record_post_action 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.763800Z] Finished clobber step (success) 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.763898Z] Running read-buildbot-config step. 20:21:31 INFO - Running pre-action listener: _resource_record_pre_action 20:21:31 INFO - Running main action method: read_buildbot_config 20:21:31 INFO - Using buildbot properties: 20:21:31 INFO - { 20:21:31 INFO - "project": "", 20:21:31 INFO - "product": "firefox", 20:21:31 INFO - "who": "kwierso@gmail.com", 20:21:31 INFO - "installer_path": "public/build/target.tar.bz2", 20:21:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:21:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:21:31 INFO - "stage_platform": "linux64", 20:21:31 INFO - "basedir": "/builds/slave/test", 20:21:31 INFO - "buildnumber": 716, 20:21:31 INFO - "platform": "ubuntu64_hw", 20:21:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:21:31 INFO - "slavebuilddir": "test", 20:21:31 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 20:21:31 INFO - "branch": "mozilla-central", 20:21:31 INFO - "script_repo_revision": "production", 20:21:31 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 20:21:31 INFO - "slavename": "talos-linux64-ix-085", 20:21:31 INFO - "repo_path": "mozilla-central" 20:21:31 INFO - } 20:21:31 INFO - Finding installer, test and symbols from parent task. 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:32 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 20:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 20:21:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 20:21:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 20:21:33 INFO - Running post-action listener: _resource_record_post_action 20:21:33 INFO - [mozharness: 2017-02-23 04:21:33.392849Z] Finished read-buildbot-config step (success) 20:21:33 INFO - [mozharness: 2017-02-23 04:21:33.393067Z] Running download-and-extract step. 20:21:33 INFO - Running pre-action listener: _resource_record_pre_action 20:21:33 INFO - Running main action method: download_and_extract 20:21:33 INFO - mkdir: /builds/slave/test/build/tests 20:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:33 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 20:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:21:34 INFO - Downloaded 896 bytes. 20:21:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:21:34 INFO - Using the following test package requirements: 20:21:34 INFO - {u'common': [u'target.common.tests.zip'], 20:21:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:21:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:21:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:21:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:21:34 INFO - u'mozbase': [u'target.common.tests.zip'], 20:21:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:21:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:21:34 INFO - u'web-platform': [u'target.common.tests.zip', 20:21:34 INFO - u'target.web-platform.tests.zip'], 20:21:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:21:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:21:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:34 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:37 INFO - Expected file size: 57165889 20:21:37 INFO - Obtained file size: 57165889 20:21:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:49 INFO - Expected file size: 57165889 20:21:49 INFO - Obtained file size: 57165889 20:21:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 20:21:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:21:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 20:22:00 INFO - Expected file size: 14404319 20:22:00 INFO - Obtained file size: 14404319 20:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:00 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:00 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:22:03 INFO - Downloaded 61547669 bytes. 20:22:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:03 INFO - mkdir: /builds/slave/test/properties 20:22:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:03 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:03 INFO - Contents: 20:22:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:03 INFO - Running post-action listener: _resource_record_post_action 20:22:03 INFO - Running post-action listener: set_extra_try_arguments 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.237675Z] Finished download-and-extract step (success) 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.237809Z] Running populate-webroot step. 20:22:03 INFO - Running pre-action listener: _resource_record_pre_action 20:22:03 INFO - Running main action method: populate_webroot 20:22:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:03 INFO - '--tppagecycles', 20:22:03 INFO - '7'], 20:22:03 INFO - 'tests': ['tsvgm']}}, 20:22:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['damp', 'tps']}, 20:22:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'tests': ['damp', 'tps']}, 20:22:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['dromaeo_dom']}, 20:22:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['basic_compositor_video']}, 20:22:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:22:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tsvgx', 20:22:03 INFO - 'tsvgr_opacity', 20:22:03 INFO - 'tart', 20:22:03 INFO - 'tscrollx', 20:22:03 INFO - 'cart', 20:22:03 INFO - 'tsvg_static']}, 20:22:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:03 INFO - 'tsvgr_opacity', 20:22:03 INFO - 'tart', 20:22:03 INFO - 'tscrollx', 20:22:03 INFO - 'cart', 20:22:03 INFO - 'tsvg_static']}, 20:22:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tp5o']}, 20:22:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:22:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s', 20:22:03 INFO - '--xperf_path', 20:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:03 INFO - 'tests': ['tp5n']}, 20:22:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--xperf_path', 20:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:03 INFO - 'tests': ['tp5n']}}, 20:22:03 INFO - 'talos.zip': {'path': '', 20:22:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:03 INFO - Running post-action listener: _resource_record_post_action 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.251824Z] Finished populate-webroot step (success) 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.251914Z] Running create-virtualenv step. 20:22:03 INFO - Running pre-action listener: _resource_record_pre_action 20:22:03 INFO - Running main action method: create_virtualenv 20:22:03 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:22:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:03 INFO - Using real prefix '/usr' 20:22:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:03 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:05 INFO - Installing pip.................done. 20:22:05 INFO - Return code: 0 20:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:22:06 INFO - Reading from file tmpfile_stdout 20:22:06 INFO - Output received: 20:22:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:22:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:22:06 INFO - Using env: {'DISPLAY': ':0', 20:22:06 INFO - 'HOME': '/home/cltbld', 20:22:06 INFO - 'LANG': 'en_US.UTF-8', 20:22:06 INFO - 'LANGUAGE': 'en_US:en', 20:22:06 INFO - 'LOGNAME': 'cltbld', 20:22:06 INFO - 'MAIL': '/var/mail/cltbld', 20:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:06 INFO - 'MOZ_NO_REMOTE': '1', 20:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:06 INFO - 'NO_EM_RESTART': '1', 20:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:06 INFO - 'PWD': '/builds/slave/test', 20:22:06 INFO - 'SHELL': '/bin/bash', 20:22:06 INFO - 'SHLVL': '1', 20:22:06 INFO - 'TERM': 'linux', 20:22:06 INFO - 'TMOUT': '86400', 20:22:06 INFO - 'USER': 'cltbld', 20:22:06 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:06 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:22:06 INFO - Cleaning up... 20:22:06 INFO - Return code: 0 20:22:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:22:06 INFO - Using env: {'DISPLAY': ':0', 20:22:06 INFO - 'HOME': '/home/cltbld', 20:22:06 INFO - 'LANG': 'en_US.UTF-8', 20:22:06 INFO - 'LANGUAGE': 'en_US:en', 20:22:06 INFO - 'LOGNAME': 'cltbld', 20:22:06 INFO - 'MAIL': '/var/mail/cltbld', 20:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:06 INFO - 'MOZ_NO_REMOTE': '1', 20:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:06 INFO - 'NO_EM_RESTART': '1', 20:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:06 INFO - 'PWD': '/builds/slave/test', 20:22:06 INFO - 'SHELL': '/bin/bash', 20:22:06 INFO - 'SHLVL': '1', 20:22:06 INFO - 'TERM': 'linux', 20:22:06 INFO - 'TMOUT': '86400', 20:22:06 INFO - 'USER': 'cltbld', 20:22:06 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:06 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 INFO - Downloading/unpacking psutil>=3.1.1 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:08 INFO - Installing collected packages: psutil 20:22:08 INFO - Running setup.py install for psutil 20:22:08 INFO - building 'psutil._psutil_linux' extension 20:22:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:22:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:22:09 INFO - building 'psutil._psutil_posix' extension 20:22:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:22:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:22:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:09 INFO - Successfully installed psutil 20:22:09 INFO - Cleaning up... 20:22:09 INFO - Return code: 0 20:22:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:22:09 INFO - Using env: {'DISPLAY': ':0', 20:22:09 INFO - 'HOME': '/home/cltbld', 20:22:09 INFO - 'LANG': 'en_US.UTF-8', 20:22:09 INFO - 'LANGUAGE': 'en_US:en', 20:22:09 INFO - 'LOGNAME': 'cltbld', 20:22:09 INFO - 'MAIL': '/var/mail/cltbld', 20:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:09 INFO - 'MOZ_NO_REMOTE': '1', 20:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:09 INFO - 'NO_EM_RESTART': '1', 20:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:09 INFO - 'PWD': '/builds/slave/test', 20:22:09 INFO - 'SHELL': '/bin/bash', 20:22:09 INFO - 'SHLVL': '1', 20:22:09 INFO - 'TERM': 'linux', 20:22:09 INFO - 'TMOUT': '86400', 20:22:09 INFO - 'USER': 'cltbld', 20:22:09 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:09 INFO - '_': '/tools/buildbot/bin/python'} 20:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:11 INFO - Installing collected packages: mozsystemmonitor 20:22:11 INFO - Running setup.py install for mozsystemmonitor 20:22:11 INFO - Successfully installed mozsystemmonitor 20:22:11 INFO - Cleaning up... 20:22:11 INFO - Return code: 0 20:22:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:22:11 INFO - Using env: {'DISPLAY': ':0', 20:22:11 INFO - 'HOME': '/home/cltbld', 20:22:11 INFO - 'LANG': 'en_US.UTF-8', 20:22:11 INFO - 'LANGUAGE': 'en_US:en', 20:22:11 INFO - 'LOGNAME': 'cltbld', 20:22:11 INFO - 'MAIL': '/var/mail/cltbld', 20:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:11 INFO - 'MOZ_NO_REMOTE': '1', 20:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:11 INFO - 'NO_EM_RESTART': '1', 20:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:11 INFO - 'PWD': '/builds/slave/test', 20:22:11 INFO - 'SHELL': '/bin/bash', 20:22:11 INFO - 'SHLVL': '1', 20:22:11 INFO - 'TERM': 'linux', 20:22:11 INFO - 'TMOUT': '86400', 20:22:11 INFO - 'USER': 'cltbld', 20:22:11 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:11 INFO - '_': '/tools/buildbot/bin/python'} 20:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:12 INFO - Downloading/unpacking jsonschema==2.5.1 20:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:14 INFO - warning: no files found matching '*.txt' 20:22:14 INFO - no previously-included directories found matching 'build' 20:22:14 INFO - no previously-included directories found matching 'dist' 20:22:14 INFO - no previously-included directories found matching '.git*' 20:22:14 INFO - Installing collected packages: jsonschema, functools32 20:22:14 INFO - Running setup.py install for functools32 20:22:14 INFO - warning: no files found matching '*.txt' 20:22:14 INFO - no previously-included directories found matching 'build' 20:22:14 INFO - no previously-included directories found matching 'dist' 20:22:14 INFO - no previously-included directories found matching '.git*' 20:22:14 INFO - Successfully installed jsonschema functools32 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:14 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:16 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:17 INFO - Downloading docopt-0.6.1.tar.gz 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:17 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:17 INFO - Running setup.py install for blobuploader 20:22:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:17 INFO - Running setup.py install for requests 20:22:18 INFO - Running setup.py install for docopt 20:22:18 INFO - Successfully installed blobuploader requests docopt 20:22:18 INFO - Cleaning up... 20:22:18 INFO - Return code: 0 20:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:18 INFO - Using env: {'DISPLAY': ':0', 20:22:18 INFO - 'HOME': '/home/cltbld', 20:22:18 INFO - 'LANG': 'en_US.UTF-8', 20:22:18 INFO - 'LANGUAGE': 'en_US:en', 20:22:18 INFO - 'LOGNAME': 'cltbld', 20:22:18 INFO - 'MAIL': '/var/mail/cltbld', 20:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:18 INFO - 'MOZ_NO_REMOTE': '1', 20:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:18 INFO - 'NO_EM_RESTART': '1', 20:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:18 INFO - 'PWD': '/builds/slave/test', 20:22:18 INFO - 'SHELL': '/bin/bash', 20:22:18 INFO - 'SHLVL': '1', 20:22:18 INFO - 'TERM': 'linux', 20:22:18 INFO - 'TMOUT': '86400', 20:22:18 INFO - 'USER': 'cltbld', 20:22:18 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:18 INFO - '_': '/tools/buildbot/bin/python'} 20:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Running setup.py (path:/tmp/pip-2T_kny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Running setup.py (path:/tmp/pip-zfTFnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Running setup.py (path:/tmp/pip-JL_huP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Running setup.py (path:/tmp/pip-MFkzKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Running setup.py (path:/tmp/pip-UcmRIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 INFO - Running setup.py (path:/tmp/pip-yqwmc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:18 INFO - Running setup.py (path:/tmp/pip-zbAAns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:19 INFO - Running setup.py (path:/tmp/pip-TrwFyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:19 INFO - Running setup.py (path:/tmp/pip-WpgFRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Running setup.py (path:/tmp/pip-PYWbtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Running setup.py (path:/tmp/pip-ly6VsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Running setup.py (path:/tmp/pip-qqccQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Running setup.py (path:/tmp/pip-fXFsUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Running setup.py (path:/tmp/pip-RSXyIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Running setup.py (path:/tmp/pip-q73J7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Running setup.py (path:/tmp/pip-b66cyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Running setup.py (path:/tmp/pip-xC8u8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:19 INFO - Running setup.py install for manifestparser 20:22:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:19 INFO - Running setup.py install for mozcrash 20:22:19 INFO - Running setup.py install for mozdebug 20:22:20 INFO - Running setup.py install for mozdevice 20:22:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozfile 20:22:20 INFO - Running setup.py install for mozhttpd 20:22:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozinfo 20:22:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozInstall 20:22:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozleak 20:22:20 INFO - Running setup.py install for mozlog 20:22:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for moznetwork 20:22:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozprocess 20:22:21 INFO - Running setup.py install for mozprofile 20:22:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozrunner 20:22:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozscreenshot 20:22:21 INFO - Running setup.py install for moztest 20:22:21 INFO - Running setup.py install for mozversion 20:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:21 INFO - Cleaning up... 20:22:21 INFO - Return code: 0 20:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:21 INFO - Using env: {'DISPLAY': ':0', 20:22:21 INFO - 'HOME': '/home/cltbld', 20:22:21 INFO - 'LANG': 'en_US.UTF-8', 20:22:21 INFO - 'LANGUAGE': 'en_US:en', 20:22:21 INFO - 'LOGNAME': 'cltbld', 20:22:21 INFO - 'MAIL': '/var/mail/cltbld', 20:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:21 INFO - 'MOZ_NO_REMOTE': '1', 20:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:21 INFO - 'NO_EM_RESTART': '1', 20:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:21 INFO - 'PWD': '/builds/slave/test', 20:22:21 INFO - 'SHELL': '/bin/bash', 20:22:21 INFO - 'SHLVL': '1', 20:22:21 INFO - 'TERM': 'linux', 20:22:21 INFO - 'TMOUT': '86400', 20:22:21 INFO - 'USER': 'cltbld', 20:22:21 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:21 INFO - '_': '/tools/buildbot/bin/python'} 20:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:21 INFO - Running setup.py (path:/tmp/pip-yqH5Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:21 INFO - Running setup.py (path:/tmp/pip-RDrIvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:21 INFO - Running setup.py (path:/tmp/pip-uP_fQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:22 INFO - Running setup.py (path:/tmp/pip-egtMYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:22 INFO - Running setup.py (path:/tmp/pip-0A1sDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:22 INFO - Running setup.py (path:/tmp/pip-GgildB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:22 INFO - Running setup.py (path:/tmp/pip-HPMN9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:22 INFO - Running setup.py (path:/tmp/pip-ZJjnhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:22 INFO - Running setup.py (path:/tmp/pip-WeAd13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:22 INFO - Running setup.py (path:/tmp/pip-101kyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 INFO - Running setup.py (path:/tmp/pip-RvpnTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 INFO - Running setup.py (path:/tmp/pip-lVvdTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:22 INFO - Running setup.py (path:/tmp/pip-FM0sQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:22 INFO - Running setup.py (path:/tmp/pip-8ioN5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:22 INFO - Running setup.py (path:/tmp/pip-fpwPTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:22 INFO - Running setup.py (path:/tmp/pip-O3OpRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:23 INFO - Running setup.py (path:/tmp/pip-gdHT_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:22:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:25 INFO - Downloading blessings-1.6.tar.gz 20:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:25 INFO - Installing collected packages: blessings 20:22:25 INFO - Running setup.py install for blessings 20:22:25 INFO - Successfully installed blessings 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:25 INFO - Reading from file tmpfile_stdout 20:22:25 INFO - Current package versions: 20:22:25 INFO - argparse == 1.2.1 20:22:25 INFO - blessings == 1.6 20:22:25 INFO - blobuploader == 1.2.4 20:22:25 INFO - docopt == 0.6.1 20:22:25 INFO - functools32 == 3.2.3-2 20:22:25 INFO - jsonschema == 2.5.1 20:22:25 INFO - manifestparser == 1.1 20:22:25 INFO - mozInstall == 1.12 20:22:25 INFO - mozcrash == 1.0 20:22:25 INFO - mozdebug == 0.1 20:22:25 INFO - mozdevice == 0.49 20:22:25 INFO - mozfile == 1.2 20:22:25 INFO - mozhttpd == 0.7 20:22:25 INFO - mozinfo == 0.9 20:22:25 INFO - mozleak == 0.1 20:22:25 INFO - mozlog == 3.4 20:22:25 INFO - moznetwork == 0.27 20:22:25 INFO - mozprocess == 0.25 20:22:25 INFO - mozprofile == 0.28 20:22:25 INFO - mozrunner == 6.13 20:22:25 INFO - mozscreenshot == 0.1 20:22:25 INFO - mozsystemmonitor == 0.3 20:22:25 INFO - moztest == 0.8 20:22:25 INFO - mozversion == 1.4 20:22:25 INFO - psutil == 3.1.1 20:22:25 INFO - requests == 1.2.3 20:22:25 INFO - wsgiref == 0.1.2 20:22:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf0fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:26 INFO - Cleaning up... 20:22:26 INFO - Return code: 0 20:22:26 INFO - Running post-action listener: _resource_record_post_action 20:22:26 INFO - Running post-action listener: _start_resource_monitoring 20:22:26 INFO - Starting resource monitoring. 20:22:26 INFO - [mozharness: 2017-02-23 04:22:26.077649Z] Finished create-virtualenv step (success) 20:22:26 INFO - [mozharness: 2017-02-23 04:22:26.078122Z] Running install step. 20:22:26 INFO - Running pre-action listener: _resource_record_pre_action 20:22:26 INFO - Running main action method: install 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Output received: 20:22:26 INFO - Usage: mozinstall [options] installer 20:22:26 INFO - Options: 20:22:26 INFO - -h, --help show this help message and exit 20:22:26 INFO - -d DEST, --destination=DEST 20:22:26 INFO - Directory to install application into. [default: 20:22:26 INFO - "/builds/slave/test"] 20:22:26 INFO - --app=APP Application being installed. [default: firefox] 20:22:26 INFO - mkdir: /builds/slave/test/build/application 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:22:41 INFO - Reading from file tmpfile_stdout 20:22:41 INFO - Output received: 20:22:41 INFO - /builds/slave/test/build/application/firefox/firefox 20:22:41 INFO - Running post-action listener: _resource_record_post_action 20:22:41 INFO - [mozharness: 2017-02-23 04:22:41.792481Z] Finished install step (success) 20:22:41 INFO - [mozharness: 2017-02-23 04:22:41.792644Z] Running run-tests step. 20:22:41 INFO - Running pre-action listener: _resource_record_pre_action 20:22:41 INFO - Running main action method: run_tests 20:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:41 INFO - Python 2.7.3 20:22:41 INFO - Return code: 0 20:22:41 INFO - grabbing minidump binary from tooltool 20:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0d290>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0e040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:22:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:22:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:22:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:41 INFO - Return code: 0 20:22:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:22:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - ENV: RUST_BACKTRACE is now 1 20:22:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:22:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-085 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:22:41 INFO - Using env: {'DISPLAY': ':0', 20:22:41 INFO - 'HOME': '/home/cltbld', 20:22:41 INFO - 'LANG': 'en_US.UTF-8', 20:22:41 INFO - 'LANGUAGE': 'en_US:en', 20:22:41 INFO - 'LOGNAME': 'cltbld', 20:22:41 INFO - 'MAIL': '/var/mail/cltbld', 20:22:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:41 INFO - 'MOZ_NO_REMOTE': '1', 20:22:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:41 INFO - 'NO_EM_RESTART': '1', 20:22:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:41 INFO - 'PWD': '/builds/slave/test', 20:22:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:41 INFO - 'RUST_BACKTRACE': '1', 20:22:41 INFO - 'SHELL': '/bin/bash', 20:22:41 INFO - 'SHLVL': '1', 20:22:41 INFO - 'TERM': 'linux', 20:22:41 INFO - 'TMOUT': '86400', 20:22:41 INFO - 'USER': 'cltbld', 20:22:41 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783', 20:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:41 INFO - '_': '/tools/buildbot/bin/python'} 20:22:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:22:42 INFO - mozversion application_buildid: 20170221181700 20:22:42 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:42 INFO - mozversion application_display_name: Nightly 20:22:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:42 INFO - mozversion application_name: Firefox 20:22:42 INFO - mozversion application_remotingname: firefox 20:22:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:22:42 INFO - mozversion application_vendor: Mozilla 20:22:42 INFO - mozversion application_version: 54.0a1 20:22:42 INFO - mozversion platform_buildid: 20170221181700 20:22:42 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:22:42 INFO - mozversion platform_version: 54.0a1 20:22:42 INFO - using testdate: 1487823762 20:22:42 INFO - actual date: 1487823762 20:22:42 INFO - starting webserver on 'localhost:34861' 20:22:42 INFO - SUITE-START | Running 2 tests 20:22:42 INFO - TEST-START | dromaeo_css 20:22:42 INFO - operating with platform_type : linux_ 20:22:42 INFO - Initialising browser for dromaeo_css test... 20:22:42 INFO - TEST-INFO | started process 21925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9dKY6h/profile http://localhost:34861/getInfo.html) 20:22:45 INFO - PROCESS | 21925 | Iterating over profile /tmp/tmp9dKY6h/profile 20:22:46 INFO - PROCESS | 21925 | Stat failure 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/prefs.js = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/places.sqlite = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/.parentlock = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/user.js = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/places.sqlite-wal = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/extensions = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | Stat failure 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/crashes = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/places.sqlite-shm = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/secmod.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/minidumps = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/thumbnails = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/blocklist.xml = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/cert8.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/addons.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/startupCache = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/compatibility.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/extensions.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/extensions.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/webappsstore.sqlite = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/frequencyCap.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/safebrowsing = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/mimeTypes.rdf = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/localstore.rdf = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/content-prefs.sqlite = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/pluginreg.dat = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/bookmarkbackups = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/permissions.sqlite = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/key3.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/directoryLinks.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/cache2 = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 21925 | Using date: /tmp/tmp9dKY6h/profile/gmp = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 21925 | __metrics Screen width/height:1600/1200 20:22:46 INFO - PROCESS | 21925 | colorDepth:24 20:22:46 INFO - PROCESS | 21925 | Browser inner width/height: 1024/697 20:22:46 INFO - PROCESS | 21925 | __metrics 20:22:47 ERROR - PROCESS | 21925 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:22:48 INFO - TEST-INFO | 21925: exit 0 20:22:48 INFO - Browser initialized. 20:22:48 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:48 INFO - TEST-INFO | started process 22054 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9dKY6h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:00 INFO - PROCESS | 22054 | RSS: Main: 153964544 20:23:00 INFO - PROCESS | 22054 | 20:26:04 INFO - PROCESS | 22054 | Cycle 1(1): loaded http://localhost:34861/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34861/tests/dromaeo/cssquery-ext.html) 20:26:05 INFO - PROCESS | 22054 | RSS: Main: 164003840 20:26:05 INFO - PROCESS | 22054 | 20:27:49 INFO - PROCESS | 22054 | ************************* 20:27:49 INFO - PROCESS | 22054 | A coding exception was thrown and uncaught in a Task. 20:27:49 INFO - PROCESS | 22054 | 20:27:49 ERROR - PROCESS | 22054 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:27:49 INFO - PROCESS | 22054 | Full stack: 20:27:49 INFO - PROCESS | 22054 | ************************* 20:29:08 INFO - PROCESS | 22054 | Cycle 1(1): loaded http://localhost:34861/tests/dromaeo/cssquery-ext.html (next: http://localhost:34861/tests/dromaeo/cssquery-jquery.html) 20:29:09 INFO - PROCESS | 22054 | RSS: Main: 158089216 20:29:09 INFO - PROCESS | 22054 | 20:32:11 INFO - PROCESS | 22054 | Cycle 1(1): loaded http://localhost:34861/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34861/tests/dromaeo/cssquery-mootools.html) 20:32:11 INFO - PROCESS | 22054 | RSS: Main: 165396480 20:32:11 INFO - PROCESS | 22054 | 20:35:13 INFO - PROCESS | 22054 | Cycle 1(1): loaded http://localhost:34861/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34861/tests/dromaeo/cssquery-prototype.html) 20:35:14 INFO - PROCESS | 22054 | RSS: Main: 158633984 20:35:14 INFO - PROCESS | 22054 | 20:38:17 INFO - PROCESS | 22054 | Cycle 1(1): loaded http://localhost:34861/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34861/tests/dromaeo/cssquery-yui.html) 20:38:18 INFO - PROCESS | 22054 | RSS: Main: 165703680 20:38:18 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | Cycle 1(1): loaded http://localhost:34861/tests/dromaeo/cssquery-yui.html (next: http://localhost:34861/tests/dromaeo/cssquery-dojo.html) 20:41:21 INFO - PROCESS | 22054 | RSS: Main: 158916608 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | __start_tp_report 20:41:21 INFO - PROCESS | 22054 | _x_x_mozilla_page_load 20:41:21 INFO - PROCESS | 22054 | _x_x_mozilla_page_load_details 20:41:21 INFO - PROCESS | 22054 | |i|pagename|runs| 20:41:21 INFO - PROCESS | 22054 | |0;dojo.html;2543.885525151368;2544.1604270590706;2546.567083595789;2571.138668546037;2586.8965241390347;4500.617447516965;4594.609458196057;4627.629789616827;4630.467496237934;4642.558956899097;4542.523035081318;4565.9954809941755;4568.428946381702;4576.656750743691;4630.791614377353;4043.595640435962;4229.555896630855;4272.1028583997395;4326.502452217988;4350.499692535367;4249.936250956238;4289.806958686867;4290.292101803198;4311.3101903695415;4352.412424322723;4125;4172.958270417318;4177.060161463677;4194.30793919003;4226.725262857904;4169.124483858393;4172.08214192877;4175.081482073969;4179.1432756284885;4211.241976444238;4124.896877578055;4155.210510003089;4158.625723684852;4174.248635245654;4199.643030342425;4359.476862776487;4401.053773438694;4521.751303678297;4545.704529205625;4570.405847239871;4878.560929516325;4897.363342765418;4899.167141585904;4946.629002824802;4946.703197808143;4935.703857768545;4936.062148191832;4941.20940649498;4941.308216849644;4945.7774400152075;4494.101179763995;4497.460304763449;4507.954920450819;4549.431321084864;4669.11286855493;4931.284963680247;4936.432310284292;4936.679115857458;4968.080905032581;4980.651354405157;4698.154332220233;4727.669063165614;4769.976150119227;4803.231482962709;4812.735299558558;4886.804527818918;4887.902241955141;4889.046635906036;4908.141075311878;4933.925991110136;4729.574338309569;4739.739314337745;4827.61379089672;4827.8551643450755;4866.343043689057;4833.975830120827;4868.367112275452;4888.26675998596;4918.262260660929;4941.184704523714;4192.3082691355585;4193.7903104845245;4199.202151591249;4228.154369126187;4231.196072746168;3529.9294014119573;3544.680978711928;3634.491171236029;3654.958336873978;3689.94465083024;4217.430646862635;4236.131593023423;4240.194363071007;4250.978745106255;4299.742015479082;4890.486498917634;4899.58353539946;4902.509749025069;4908.680935739166;4954.47977962316;4119.938200926988;4245.830166793294;4259.74441533509;4261.786910654455;4279;4275.893102672458;4400.031992961543;4406.030673251879;4435.334699795057;4475.373447717322;4564.224081906151;4583.862484125549;4584.88537786558;4620.607248383925;4637.443506779073;4910.067087253553;4942.654014218971;4950.529699678525;4950.77721502526;4993.775280112476;4506.369108324771;4540.296254080623;4583.816647334203;4623.075384922961;4627.467841198305;4885.9022819544125;4893.339399181207;4896.290037944549;4907.705537667752;4932.1122198004705;4712.835050773207;4742.359781429463;4952.975235123801;4956.851294461172;4961.875953101201;4763.285507173951;4787.688800228113;4802.375691160267;4804.0872234275375;4852.199387101089;4778.211595086772;4779.880502987314;4800.687955282895;4809.759512024455;4834.178189707828;4580.564846339593;4714.434267887876;4748.905021899612;4752.025834703946;4804.8798780030775;4658.324542941323;4745.33565300865;4767.880802979953;4783.473817879961;4827.203511420576;4667.066586682745;4680.836170734008;4688.5545873142;4706.623470122314;4725.10223057618 20:41:21 INFO - PROCESS | 22054 | |1;ext.html;529.0952471274121;545.1414022913912;555.6055200807426;556.7605929450336;571.2060236271583;38095;39357.80321098394;39376.212475750464;39453.60546394539;42204.788976055155;39630.811075667705;40049.199016019666;40125.598744012554;40150.397744033864;40172.19655606886;7355.558666480001;7548.433867459935;7553.773386798405;7556.924430755677;9210;9027.684031058914;9333;9338.29962752793;9343.672971446002;9355.532223388804;9399.06009399058;9481.051894810498;9488.620455181785;9501.52492375382;9501.95249023754;9342.906570934272;9355.251579873593;9427.670031548863;9442.291828112919;9450.243980481546;9232.30757693169;9249.953750231272;9292.070792920722;9306.069393060707;9318.673846415411;32695.346093078006;32810.50784238238;32828.6717132828;32901.670983290336;32931;3349.8995030149135;3430.091025878119;3441.5181874537584;3483.8780642677384;3490.3019396120876;24392;24927.5014499709;24927.750722492725;25040.87479562609;25221.747782522125;3138.937221255585;3208.2300247940325;3233.3048394595276;3316.5688460499982;3326.550915626384;232930;234222;235361;235870;236554;38158.42762358567;39275.21449570992;45226.09547809091;45874.311885321746;45957.7702111494;44543;46058.76970615125;46181.22556714214;46347.768261158475;46404.5359546407;12111.091668124749;12243.938780306042;12272;12273.938630306791;12303.630891073282;9251.213646839959;9353.532323383804;9363.765905852271;9373;9385.859212111824;15550.222488875512;15717.921410393104;15747.606309842346;15747.763783543256;15770.92114539443;6529.449967527564;6532.771353002622;6649.468042556584;6699.665016749143;6801.319868013258;729.6023667101442;757.5076200469629;761.2235519769804;762.9923700763032;772.1969152081884;28854.567181492293;31012.055758505918;32041;32480.75192480733;32885.342293154004;927.2674587076269;929.869818225449;940.4686352211115;951.8667386565886;952.2239374909427;13487.460501580023;14348.856511434751;14397.280135993367;14707.411703531952;14730.410783568537;325044;332812;333192;334025;334850;48664.970279904126;49107.754461227465;49272.50727492679;49338.10590305606;49466;1188.7173247583544;1205.8673545909942;1212.7453234820787;1219.951201951912;1224.4734764051366;25181.37046573777;26553.468930621664;26973.325666858487;27196.592051119645;27255.318617034733;42580.36129458123;43279.13441731211;43385;43439;43565;309907;310995;311494;311674;313112;31998.52002219922;32795.34409311848;32967.34065318632;35368.646313536534;35553.28893422169;35649;35694.82152589324;36870;36991.445128323634;37300.2539949194;31697.524537132424;31800;31931.68068319287;31969.200769980936;33328.83335583306;31853.36293274168;31962.406578849073;34269.657303427644;34422.48366274458;35042.29915401729;31460.68539314669;31711.524327135572;31790.841045794623;31910.52134218035;32066.19834504156;28653.856730717045;28869.566956495248;29777.25556861096;29964.25089372696;29980.400391991603;22481;22581.43546411353;22797.544049119257;22817.657735133653;23057.538849223256 20:41:21 INFO - PROCESS | 22054 | |2;jquery.html;386.91487872668006;398.9960100398995;401.65658362100413;406.7864371205117;424.57330382965114;12573.24560526369;16101.275442605081;16387.098709570982;17918.462446126603;17928.103594820255;16348.10085445303;16771.413000544984;17453.3018679253;18387.26450941964;18622.25510979559;4997.150484417649;5036.697798132123;5155.974220128895;5185.948140518603;5216;5102.770375333101;5106.259592359106;5169.534741873231;5172.12073947428;5176.974115129419;5053.408176424433;5113.437521872591;5118.33461649985;5123.692578445287;5131.435542090367;5196.480351964811;5413.539849112811;5424.430434804348;5458.536024437888;5481.205225242317;4795.808167673289;5031.647784655075;5322.520973112439;5340.492653197979;5352.839414817562;12812.167209131376;12958;13000.674983125404;13001.414936327888;18013.27946882123;2200.559888022402;2205.757366689663;2233.4304752288226;2255.4023183856293;2374.3392169160516;9555.761105972337;9789.412635241908;10029.297949143564;12118.969887559568;13053.282069486175;1633.9428120015743;1669.3994620273525;1689.510042087793;1702.7105392083377;1726.4130195733385;64674;66106;71785;76140.61929690372;78126.60936695337;10666.413347265974;10963.56145754177;11045.116390688725;11210.439478026066;11468.197226194086;13028.934855325662;15247.161406122548;15391.769123463157;15417.147827651921;15473.071615703095;5996.760129594767;6931.584104953719;7257.854842903218;7262.346388825031;7339.669714862844;6530.346965303432;6561.967190164114;6582.769603063965;6716.529842911047;6804.829879253059;4379.890502737457;4426.269665505156;4742.241241401359;4782.689125206851;4872.342233798463;1921.5196200949763;1948.9220431182594;1977.6835706287213;1999.600079984009;2022.4236092713506;775.8021704465438;801.4950581133965;802.3140215116108;802.530519646002;818.3616778912458;17164;17536.12319384051;18019.459416217534;18186.36347727823;18525.81474185268;775.9068911730516;793.9841203175904;794.6424109150905;797.1868693932157;816.0411516468126;9575.664851730156;9684;9938.850917236246;9964.402135871871;10022.398656080659;449725;454323;458997;466793;467030;73495.63252183812;85521;90520.09479905249;92234;92658;934.2525979216599;967.066780556766;973.7273563402259;976.6728146071003;977.0229770229912;16055.277512512199;16428.83571164321;16916.238769255167;16954.83045169582;17203.741943870602;40436.59563404328;40584.391234132105;40688.38967415432;41107.383389249786;41488.1702365957;38232.60507896285;49069.509304906496;57694.7115264421;58771.53071173255;60326.69836650789;11369.204155709022;11407.543698251977;11552.826707599224;11663.88336116628;12613.558525451564;12748.298843563693;13255.270960097287;13395.799063013868;13595.252261125734;13821.239831809347;11119.666410007712;11247.156463265452;11646.941765291458;11792.469338879942;11802.94098529502;11560.132990025613;11603.941980290045;11626.302421854716;11628.476718547516;11896.107791915814;10424.947875260576;10771.838422423512;11088.390138542134;11742.471588778355;11820.940895295469;11565.942170289094;11788.115891308014;11863.466144023367;12276.447559860006;12622.43199056063;11285.21003529745;11336.546538138382;11385.601503947322;11699.181057325906;11929.761404771682 20:41:21 INFO - PROCESS | 22054 | |3;mootools.html;1294.864039275876;1297.4680381043775;1309.083641450984;1312.4422120598747;1316.624761942846;7492.363149132323;7598.848023039536;7776.883346749796;7828.608569571513;7835.333996610295;7851.725189618364;7961.044674639037;7975.960120199391;7981.401394895377;7982.600869956494;2107.1360742095703;2138.101997161188;2140.283005193254;2142.860714053589;2152.3758110148037;2131.8081372676456;2163.718716566852;2168.4687251623373;2169.2949791317806;2181.4982554012586;2073.968890466644;2111.6199084164846;2111.6304646686845;2137.9465513362134;2145.2598853395552;2060.423081537172;2082.9687554686693;2084.2080009596257;2085.0408811946522;2087.342487116561;1759.81521940197;1818.5635447492507;2052.2406709517427;2078.8752674839407;2108.4728817795553;7633.770986870402;7752.922470775333;7872.488288261245;7873.881891771628;7911.604419779045;4565.680402371802;4697.694649847749;4701.083288758693;4703.858884233478;4706.458757242926;2825.773938084969;2856.7714582833532;2868.139558132552;2886.090881372361;2907.3167805565677;1124.7356871135278;1138.6925530106826;1723.7759091318048;1798.4964210021215;1885.651154536415;9474.194693451065;9631.855522167172;9671.274654400948;10475.58097676092;10719.946400268027;4265.765382903908;4334.176506463762;4350.151720414488;4389.714668546534;4392.033752574429;9094.590743416562;9097.772555686028;9100.453972761656;9130.452172869649;9163.450192988308;4642.187617166982;4655.813767449332;4661.953380466152;4679.555442232982;4679.719216847;4228.323468245066;4295.29127693927;4397.912041759147;4438.178936896685;4445.799939002753;3517.82410879455;3581.462780582933;3634.1823089804584;3684.428913518409;3694.168812017275;1054.778496515725;1066.386827574148;1070.8019016481976;1071.9410432426291;1072.222638587018;793.1750978981794;804.927556519916;816.7468084893583;824.2416976381743;828.3911325175991;4971.403431588233;5025.874353141201;5057.595392368602;5111.616628752784;5138.3577052868395;501.3207104373594;521.0932976620728;523.4163907243396;525.3511912787704;526.0084740264629;3321.4353559894876;3371.494275858641;3456.9308613827584;3461.152017755666;3592.0846277557066;8122.878156827653;8461;8717.346199035097;8907.955460222787;9068.591913363915;9206.079392060741;9231.90768092324;9231.953840230755;9234.538273086318;9238.076192380842;6754.966225168842;6941.305869413019;6977.6511174441075;7013.123359580052;7092.11348581417;4972.378452693413;5050.696958182521;5067.341245638044;5127.948720512747;5199.816077247489;7969.242921922408;8245.464044837066;8307.709230176915;8355.582220889053;8581.785455363666;8554;8690.826183476422;8692.91307086921;8712.39013269065;8719.607617657348;6523.347665233438;6611.371919667817;6626.801195964129;6646.368594983469;6666.333366663489;7149.392301654418;7245.239249878687;7322;7330.303621155982;7380.520266182681;6574.4740420765265;6673.799786006428;6684.331566843277;6702.932970670231;6706.8993965089585;6409;6426.325235850355;6442.806715798533;6499.935000649933;6525.184351956005;5790.739416726173;5857.26784151981;5887.911681324869;5910.881782364415;5922.378150294157;4321.58944900234;4348.565143485626;4431.601155895984;4451.198784218742;4477.70894891831;4399.5820397062225;4407.228734971303;4430.778461076998;4553.3397657340165;4574.10804893056 20:41:21 INFO - PROCESS | 22054 | |4;prototype.html;361.22697427505125;364.456959130895;368.1440650487617;369.76150383002965;372.90304520824577;4961.702297862131;4999.825006124786;5007.148784706599;5031.748412579374;5121.437961421774;4940.580050695687;4961.238760240117;4975.9101177505445;5002.474740152286;5025.698458092508;3748.9062773430746;3859.20886218326;3869.342211823983;3873.767573945572;3887.611238876118;3878.4182372644045;3882.864099756509;3888.105735680795;3893.532776066876;3896.610338966095;3869.1487872667967;3891.727579069452;3912.7065470089715;3913.882583522499;3925.862594809182;3647.726420518445;3882.495275614166;3909.882703518899;3912.4326972588815;3921.686265098785;3309.25541753106;3322.534845121685;3325.5676762021026;3335.8832440864453;3342.866285348583;4871.855114048196;4874.65877388583;4876.146674332013;4876.609871210295;4888.437829649601;4410.412251589425;4418.867433976954;4426.513083560806;4436.491592858443;4444.555544445529;5041.092603331399;5239.318888544503;5305.681659100466;5313.256144139824;5323.148296272617;3987.843525377637;3993;4011.919761604781;4017.9196416071513;4020.316546187126;5289.259503669489;5373.677579345291;5408.675479471205;5584.050711379075;5643.984082865082;4069.6337329640473;4075;4086.141910198832;4097.016715988201;4099.97950010248;4110.630043296118;4123.443335149716;4129.483814523184;4144;4147.709660323749;4506.977465112654;4559.3844830948065;4568.428946381702;4591.678582499259;4662.743549104832;5259.552938000313;5276.39321478035;5276.445973172838;5281.260623512711;5296.814611488579;1380.4202235061298;1387.7579566288116;1394.9398457172622;1402.7334806386755;1405.7539930512323;951.2437612098398;997.4015590645699;1007.8236308645958;1015.263933648114;1017.7455636090978;4441.800118994588;4452.5992660660695;4457.353683715843;4457.977710111429;4487.394201782718;5247.7113758743635;5261.237120617489;5276.657017293864;5306.230596563477;5334.146536554133;4337.392765012901;4498.302763071729;4513.75174365413;4527.230370836965;4535.501094879561;2791.776657867366;2812.170409729135;2815.957760633592;2819.5911592818925;2820.633317668731;4934.432540257916;4935.889424879373;4938.08645400602;4943.431505376928;4976.353074100344;5112.795488180505;5365.3293338332705;5414.7021913795115;5435.456454354533;5457.69982650958;4877.341558889575;4879.438864530624;4882.5605695487575;4919.4834542373255;4926.531979461945;5082.491750824888;5114.181730923183;5217.156649625887;5233.39815921174;5243.973780131228;4971.577415919543;5030.773615187252;5077.06527441236;5130.56344223618;5196.7401629917595;4870.439899411613;4884.0476107158765;4995.5004499551205;5015.7492125392855;5057.69653820772;3780.6030366811087;3807.828647710915;3809.7333186676665;3826.215625796761;3830.8659196927974;5448.128299472065;5459.836204913859;5463.699496527729;5465.808696695596;5471.808486703106;5064.366954130734;5250.763715632729;5256.211568264791;5263.026340127089;5319.175527793198;5161.277421161116;5244.816431424882;5249.084084309124;5253.553447956813;5268.710220937732;5126.512981266774;5132.230165475208;5136.332276803992;5138.8972220556125;5160.967806438653;5038.412899936508;5270.8155214567305;5277.73334399749;5282.947170528246;5290.629655924048;5197.740112994259;5213.687178769286;5221.869453263699;5226.216067589892;5267.710275934707 20:41:21 INFO - PROCESS | 22054 | |5;yui.html;593.6705128653598;594.8899453601086;694.0006390797255;724.6268171891477;727.5852763924563;11834.349110798914;12577.666744478274;13308;13436.261005644687;13619.659508512292;12105.818412723816;12380.071494637916;13002.60992170234;13051.673708157301;13199.93400033001;799.9600019998991;811.0267678785451;813.1137060603941;814.9551774652392;815.963281652327;785.5051317669888;794.297047113306;795.1769918134731;808.5674164322089;816.4285000499674;834.9540775257359;847.4279861093737;848.6011574559947;851.4210336970857;855.0423525651279;833.9708110216144;838.9412741108064;843.3337663246059;843.8396704626132;844.7719115838751;619.5508256513991;620.2928661326092;621.4810633121349;621.9533534984894;622.9563930524865;13034.869651303461;13490.797638035438;13514.729705405836;13536.661583460394;13866.584002479842;12598;12959.352032398343;12988.350582470934;13037.21776693392;13095.214287142706;10030.699079027641;10384.480775961172;10465.529051192714;10507.369557826478;10508.737281567946;3162.762792790527;3196.6963138502038;3234.4663130583426;3239.0768630940306;3243.562119113913;60819.69590152065;60991.69504152495;61726.38273617251;62013.68993155051;62669.68665156645;9293.860592091123;9454.243660507143;9725.0761177688;9793.118619324152;9864.506774661239;18463.53841153982;18520.81479185218;18663.066846657613;18720.906395467937;18826.24695012184;6201.131841542187;6210.472109870711;6255.12428260044;6393;6402.679866006681;3410.931781364359;3503.8423270952867;3510.57873055235;3564.500969864221;3619.5475565554307;2998.34036511967;3051.542268659719;3057.6942305769244;3071.4932036213772;3072.3087305356116;528.5137673340536;532.6111938285073;535.7937194180269;550.3753240072547;551.555997422079;621.9346968568235;646.4472875691216;648.4391001783423;654.1724718231346;658.1181217168921;8223.21879421454;8637.56812159402;8648.870266946002;8654.524001179996;8968.417052891542;605.7546693589103;617.6510271696477;620.4447019917149;629.9212598425197;634.330781026022;4057.715959882839;4197.097624010852;4266.1254442839145;4302.7490894576285;4348.9130217396105;64284.67857660682;64437.35562644407;65446.672766635864;67979.66010169918;68249.31750682529;57724.13413798797;57841;57904.13143802846;58146;59873.40126598765;50677;50734.746326268134;50888.23667644988;52215.73892130515;53497.1975420369;7818.413618978486;8072.354211663171;8171;8240.876386854321;8358.58207089631;36464;36981.63018369782;37568.81215593904;38159.61840381672;38736;34648.30703385868;35542.111447214025;36108;36772.81613592022;41600.79199603983;9534.523273836141;9571.664991725256;9577.281703872099;9670.903290967282;9752.317337786288;10143.49282535885;10289.43408112561;10329.328593641389;10548;10602.946985265024;9738.707838764849;9943.453110079015;10033.949830250802;10082.941291164616;10308.072273495422;9471.526423678926;9756.804863902824;9937.205023598239;10049.095581397709;10277.897221027693;9957;10051.849222261819;10455.059044686015;10478;10866.836997445203;7895.7236496722335;7921.485103468256;8045.9597702011115;8515.276201522693;8526.190011948855;8386.916130838614;8711.869321960048;8749.518776467356;8887.466751994902;9029.548522573976 20:41:21 INFO - PROCESS | 22054 | __end_tp_report 20:41:21 INFO - PROCESS | 22054 | __start_cc_report 20:41:21 INFO - PROCESS | 22054 | _x_x_mozilla_cycle_collect,373 20:41:21 INFO - PROCESS | 22054 | __end_cc_report 20:41:21 INFO - PROCESS | 22054 | __startTimestamp1487824881287__endTimestamp 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | ------- Summary: start ------- 20:41:21 INFO - PROCESS | 22054 | Number of tests: 6 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | [#0] dojo.html Cycles:165 Average:4522.94 Median:4630.79 stddev:473.27 (10.2%) stddev-sans-first:448.54 20:41:21 INFO - PROCESS | 22054 | Values: 2543.9 2544.2 2546.6 2571.1 2586.9 4500.6 4594.6 4627.6 4630.5 4642.6 4542.5 4566.0 4568.4 4576.7 4630.8 4043.6 4229.6 4272.1 4326.5 4350.5 4249.9 4289.8 4290.3 4311.3 4352.4 4125.0 4173.0 4177.1 4194.3 4226.7 4169.1 4172.1 4175.1 4179.1 4211.2 4124.9 4155.2 4158.6 4174.2 4199.6 4359.5 4401.1 4521.8 4545.7 4570.4 4878.6 4897.4 4899.2 4946.6 4946.7 4935.7 4936.1 4941.2 4941.3 4945.8 4494.1 4497.5 4508.0 4549.4 4669.1 4931.3 4936.4 4936.7 4968.1 4980.7 4698.2 4727.7 4770.0 4803.2 4812.7 4886.8 4887.9 4889.0 4908.1 4933.9 4729.6 4739.7 4827.6 4827.9 4866.3 4834.0 4868.4 4888.3 4918.3 4941.2 4192.3 4193.8 4199.2 4228.2 4231.2 3529.9 3544.7 3634.5 3655.0 3689.9 4217.4 4236.1 4240.2 4251.0 4299.7 4890.5 4899.6 4902.5 4908.7 4954.5 4119.9 4245.8 4259.7 4261.8 4279.0 4275.9 4400.0 4406.0 4435.3 4475.4 4564.2 4583.9 4584.9 4620.6 4637.4 4910.1 4942.7 4950.5 4950.8 4993.8 4506.4 4540.3 4583.8 4623.1 4627.5 4885.9 4893.3 4896.3 4907.7 4932.1 4712.8 4742.4 4953.0 4956.9 4961.9 4763.3 4787.7 4802.4 4804.1 4852.2 4778.2 4779.9 4800.7 4809.8 4834.2 4580.6 4714.4 4748.9 4752.0 4804.9 4658.3 4745.3 4767.9 4783.5 4827.2 4667.1 4680.8 4688.6 4706.6 4725.1 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | [#1] ext.html Cycles:180 Average:44131.98 Median:3487.09 stddev:77559.24 (2224.2%) stddev-sans-first:77707.72 20:41:21 INFO - PROCESS | 22054 | Values: 529.1 545.1 555.6 556.8 571.2 38095.0 39357.8 39376.2 39453.6 42204.8 39630.8 40049.2 40125.6 40150.4 40172.2 7355.6 7548.4 7553.8 7556.9 9210.0 9027.7 9333.0 9338.3 9343.7 9355.5 9399.1 9481.1 9488.6 9501.5 9502.0 9342.9 9355.3 9427.7 9442.3 9450.2 9232.3 9250.0 9292.1 9306.1 9318.7 32695.3 32810.5 32828.7 32901.7 32931.0 3349.9 3430.1 3441.5 3483.9 3490.3 24392.0 24927.5 24927.8 25040.9 25221.7 3138.9 3208.2 3233.3 3316.6 3326.6 232930.0 234222.0 235361.0 235870.0 236554.0 38158.4 39275.2 45226.1 45874.3 45957.8 44543.0 46058.8 46181.2 46347.8 46404.5 12111.1 12243.9 12272.0 12273.9 12303.6 9251.2 9353.5 9363.8 9373.0 9385.9 15550.2 15717.9 15747.6 15747.8 15770.9 6529.4 6532.8 6649.5 6699.7 6801.3 729.6 757.5 761.2 763.0 772.2 28854.6 31012.1 32041.0 32480.8 32885.3 927.3 929.9 940.5 951.9 952.2 13487.5 14348.9 14397.3 14707.4 14730.4 325044.0 332812.0 333192.0 334025.0 334850.0 48665.0 49107.8 49272.5 49338.1 49466.0 1188.7 1205.9 1212.7 1220.0 1224.5 25181.4 26553.5 26973.3 27196.6 27255.3 42580.4 43279.1 43385.0 43439.0 43565.0 309907.0 310995.0 311494.0 311674.0 313112.0 31998.5 32795.3 32967.3 35368.6 35553.3 35649.0 35694.8 36870.0 36991.4 37300.3 31697.5 31800.0 31931.7 31969.2 33328.8 31853.4 31962.4 34269.7 34422.5 35042.3 31460.7 31711.5 31790.8 31910.5 32066.2 28653.9 28869.6 29777.3 29964.3 29980.4 22481.0 22581.4 22797.5 22817.7 23057.5 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | [#2] jquery.html Cycles:180 Average:27120.23 Median:392.96 stddev:75677.34 (19258.5%) stddev-sans-first:75863.02 20:41:21 INFO - PROCESS | 22054 | Values: 386.9 399.0 401.7 406.8 424.6 12573.2 16101.3 16387.1 17918.5 17928.1 16348.1 16771.4 17453.3 18387.3 18622.3 4997.2 5036.7 5156.0 5185.9 5216.0 5102.8 5106.3 5169.5 5172.1 5177.0 5053.4 5113.4 5118.3 5123.7 5131.4 5196.5 5413.5 5424.4 5458.5 5481.2 4795.8 5031.6 5322.5 5340.5 5352.8 12812.2 12958.0 13000.7 13001.4 18013.3 2200.6 2205.8 2233.4 2255.4 2374.3 9555.8 9789.4 10029.3 12119.0 13053.3 1633.9 1669.4 1689.5 1702.7 1726.4 64674.0 66106.0 71785.0 76140.6 78126.6 10666.4 10963.6 11045.1 11210.4 11468.2 13028.9 15247.2 15391.8 15417.1 15473.1 5996.8 6931.6 7257.9 7262.3 7339.7 6530.3 6562.0 6582.8 6716.5 6804.8 4379.9 4426.3 4742.2 4782.7 4872.3 1921.5 1948.9 1977.7 1999.6 2022.4 775.8 801.5 802.3 802.5 818.4 17164.0 17536.1 18019.5 18186.4 18525.8 775.9 794.0 794.6 797.2 816.0 9575.7 9684.0 9938.9 9964.4 10022.4 449725.0 454323.0 458997.0 466793.0 467030.0 73495.6 85521.0 90520.1 92234.0 92658.0 934.3 967.1 973.7 976.7 977.0 16055.3 16428.8 16916.2 16954.8 17203.7 40436.6 40584.4 40688.4 41107.4 41488.2 38232.6 49069.5 57694.7 58771.5 60326.7 11369.2 11407.5 11552.8 11663.9 12613.6 12748.3 13255.3 13395.8 13595.3 13821.2 11119.7 11247.2 11646.9 11792.5 11802.9 11560.1 11603.9 11626.3 11628.5 11896.1 10424.9 10771.8 11088.4 11742.5 11820.9 11565.9 11788.1 11863.5 12276.4 12622.4 11285.2 11336.5 11385.6 11699.2 11929.8 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | [#3] mootools.html Cycles:180 Average:4999.67 Median:5038.29 stddev:2751.26 (54.6%) stddev-sans-first:2744.89 20:41:21 INFO - PROCESS | 22054 | Values: 1294.9 1297.5 1309.1 1312.4 1316.6 7492.4 7598.8 7776.9 7828.6 7835.3 7851.7 7961.0 7976.0 7981.4 7982.6 2107.1 2138.1 2140.3 2142.9 2152.4 2131.8 2163.7 2168.5 2169.3 2181.5 2074.0 2111.6 2111.6 2137.9 2145.3 2060.4 2083.0 2084.2 2085.0 2087.3 1759.8 1818.6 2052.2 2078.9 2108.5 7633.8 7752.9 7872.5 7873.9 7911.6 4565.7 4697.7 4701.1 4703.9 4706.5 2825.8 2856.8 2868.1 2886.1 2907.3 1124.7 1138.7 1723.8 1798.5 1885.7 9474.2 9631.9 9671.3 10475.6 10719.9 4265.8 4334.2 4350.2 4389.7 4392.0 9094.6 9097.8 9100.5 9130.5 9163.5 4642.2 4655.8 4662.0 4679.6 4679.7 4228.3 4295.3 4397.9 4438.2 4445.8 3517.8 3581.5 3634.2 3684.4 3694.2 1054.8 1066.4 1070.8 1071.9 1072.2 793.2 804.9 816.7 824.2 828.4 4971.4 5025.9 5057.6 5111.6 5138.4 501.3 521.1 523.4 525.4 526.0 3321.4 3371.5 3456.9 3461.2 3592.1 8122.9 8461.0 8717.3 8908.0 9068.6 9206.1 9231.9 9232.0 9234.5 9238.1 6755.0 6941.3 6977.7 7013.1 7092.1 4972.4 5050.7 5067.3 5127.9 5199.8 7969.2 8245.5 8307.7 8355.6 8581.8 8554.0 8690.8 8692.9 8712.4 8719.6 6523.3 6611.4 6626.8 6646.4 6666.3 7149.4 7245.2 7322.0 7330.3 7380.5 6574.5 6673.8 6684.3 6702.9 6706.9 6409.0 6426.3 6442.8 6499.9 6525.2 5790.7 5857.3 5887.9 5910.9 5922.4 4321.6 4348.6 4431.6 4451.2 4477.7 4399.6 4407.2 4430.8 4553.3 4574.1 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | [#4] prototype.html Cycles:180 Average:4346.46 Median:4883.30 stddev:1228.27 (25.2%) stddev-sans-first:1194.74 20:41:21 INFO - PROCESS | 22054 | Values: 361.2 364.5 368.1 369.8 372.9 4961.7 4999.8 5007.1 5031.7 5121.4 4940.6 4961.2 4975.9 5002.5 5025.7 3748.9 3859.2 3869.3 3873.8 3887.6 3878.4 3882.9 3888.1 3893.5 3896.6 3869.1 3891.7 3912.7 3913.9 3925.9 3647.7 3882.5 3909.9 3912.4 3921.7 3309.3 3322.5 3325.6 3335.9 3342.9 4871.9 4874.7 4876.1 4876.6 4888.4 4410.4 4418.9 4426.5 4436.5 4444.6 5041.1 5239.3 5305.7 5313.3 5323.1 3987.8 3993.0 4011.9 4017.9 4020.3 5289.3 5373.7 5408.7 5584.1 5644.0 4069.6 4075.0 4086.1 4097.0 4100.0 4110.6 4123.4 4129.5 4144.0 4147.7 4507.0 4559.4 4568.4 4591.7 4662.7 5259.6 5276.4 5276.4 5281.3 5296.8 1380.4 1387.8 1394.9 1402.7 1405.8 951.2 997.4 1007.8 1015.3 1017.7 4441.8 4452.6 4457.4 4458.0 4487.4 5247.7 5261.2 5276.7 5306.2 5334.1 4337.4 4498.3 4513.8 4527.2 4535.5 2791.8 2812.2 2816.0 2819.6 2820.6 4934.4 4935.9 4938.1 4943.4 4976.4 5112.8 5365.3 5414.7 5435.5 5457.7 4877.3 4879.4 4882.6 4919.5 4926.5 5082.5 5114.2 5217.2 5233.4 5244.0 4971.6 5030.8 5077.1 5130.6 5196.7 4870.4 4884.0 4995.5 5015.7 5057.7 3780.6 3807.8 3809.7 3826.2 3830.9 5448.1 5459.8 5463.7 5465.8 5471.8 5064.4 5250.8 5256.2 5263.0 5319.2 5161.3 5244.8 5249.1 5253.6 5268.7 5126.5 5132.2 5136.3 5138.9 5161.0 5038.4 5270.8 5277.7 5282.9 5290.6 5197.7 5213.7 5221.9 5226.2 5267.7 20:41:21 INFO - PROCESS | 22054 | 20:41:21 INFO - PROCESS | 22054 | [#5] yui.html Cycles:180 Average:14266.67 Median:30239.58 stddev:18156.82 (60.0%) stddev-sans-first:18178.73 20:41:21 INFO - PROCESS | 22054 | Values: 593.7 594.9 694.0 724.6 727.6 11834.3 12577.7 13308.0 13436.3 13619.7 12105.8 12380.1 13002.6 13051.7 13199.9 800.0 811.0 813.1 815.0 816.0 785.5 794.3 795.2 808.6 816.4 835.0 847.4 848.6 851.4 855.0 834.0 838.9 843.3 843.8 844.8 619.6 620.3 621.5 622.0 623.0 13034.9 13490.8 13514.7 13536.7 13866.6 12598.0 12959.4 12988.4 13037.2 13095.2 10030.7 10384.5 10465.5 10507.4 10508.7 3162.8 3196.7 3234.5 3239.1 3243.6 60819.7 60991.7 61726.4 62013.7 62669.7 9293.9 9454.2 9725.1 9793.1 9864.5 18463.5 18520.8 18663.1 18720.9 18826.2 6201.1 6210.5 6255.1 6393.0 6402.7 3410.9 3503.8 3510.6 3564.5 3619.5 2998.3 3051.5 3057.7 3071.5 3072.3 528.5 532.6 535.8 550.4 551.6 621.9 646.4 648.4 654.2 658.1 8223.2 8637.6 8648.9 8654.5 8968.4 605.8 617.7 620.4 629.9 634.3 4057.7 4197.1 4266.1 4302.7 4348.9 64284.7 64437.4 65446.7 67979.7 68249.3 57724.1 57841.0 57904.1 58146.0 59873.4 50677.0 50734.7 50888.2 52215.7 53497.2 7818.4 8072.4 8171.0 8240.9 8358.6 36464.0 36981.6 37568.8 38159.6 38736.0 34648.3 35542.1 36108.0 36772.8 41600.8 9534.5 9571.7 9577.3 9670.9 9752.3 10143.5 10289.4 10329.3 10548.0 10602.9 9738.7 9943.5 10033.9 10082.9 10308.1 9471.5 9756.8 9937.2 10049.1 10277.9 9957.0 10051.8 10455.1 10478.0 10866.8 7895.7 7921.5 8046.0 8515.3 8526.2 8386.9 8711.9 8749.5 8887.5 9029.5 20:41:21 INFO - PROCESS | 22054 | -------- Summary: end -------- 20:41:21 INFO - PROCESS | 22054 | 20:41:22 INFO - TEST-INFO | 22054: exit 0 20:41:22 INFO - TEST-OK | dromaeo_css | took 1120123ms 20:41:22 INFO - TEST-START | kraken 20:41:22 INFO - operating with platform_type : linux_ 20:41:22 INFO - Initialising browser for kraken test... 20:41:22 INFO - TEST-INFO | started process 22431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpInBXO8/profile http://localhost:34861/getInfo.html) 20:41:25 INFO - PROCESS | 22431 | Iterating over profile /tmp/tmpInBXO8/profile 20:41:25 INFO - PROCESS | 22431 | Stat failure 20:41:25 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/prefs.js = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/places.sqlite = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/.parentlock = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/user.js = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/places.sqlite-wal = Wed Feb 22 2017 20:41:26 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/extensions = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/crashes = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/places.sqlite-shm = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/secmod.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/minidumps = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | __metrics Screen width/height:1600/1200 20:41:26 INFO - PROCESS | 22431 | colorDepth:24 20:41:26 INFO - PROCESS | 22431 | Browser inner width/height: 1024/697 20:41:26 INFO - PROCESS | 22431 | __metrics 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/thumbnails = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/blocklist.xml = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/cert8.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/addons.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/startupCache = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/compatibility.ini = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/extensions.ini = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/extensions.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/frequencyCap.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/safebrowsing = Wed Feb 22 2017 20:41:26 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/localstore.rdf = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/content-prefs.sqlite = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/pluginreg.dat = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/bookmarkbackups = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/permissions.sqlite = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/key3.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/directoryLinks.json = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/cache2 = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 22431 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 22431 | Using date: /tmp/tmpInBXO8/profile/gmp = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 ERROR - PROCESS | 22431 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:41:28 INFO - TEST-INFO | 22431: exit 0 20:41:28 INFO - Browser initialized. 20:41:28 INFO - Running cycle 1/1 for kraken test... 20:41:28 INFO - TEST-INFO | started process 22561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpInBXO8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:40 INFO - PROCESS | 22561 | RSS: Main: 154542080 20:41:40 INFO - PROCESS | 22561 | 20:41:43 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34861/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:44 INFO - PROCESS | 22561 | RSS: Main: 160780288 20:41:44 INFO - PROCESS | 22561 | 20:41:47 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34861/tests/kraken/driver.html?testName=audio-dft) 20:41:47 INFO - PROCESS | 22561 | RSS: Main: 159387648 20:41:47 INFO - PROCESS | 22561 | 20:41:50 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34861/tests/kraken/driver.html?testName=audio-fft) 20:41:51 INFO - PROCESS | 22561 | RSS: Main: 156495872 20:41:51 INFO - PROCESS | 22561 | 20:41:53 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34861/tests/kraken/driver.html?testName=audio-oscillator) 20:41:54 INFO - PROCESS | 22561 | RSS: Main: 157192192 20:41:54 INFO - PROCESS | 22561 | 20:41:56 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34861/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:56 INFO - PROCESS | 22561 | RSS: Main: 156954624 20:41:56 INFO - PROCESS | 22561 | 20:41:59 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34861/tests/kraken/driver.html?testName=imaging-darkroom) 20:42:00 INFO - PROCESS | 22561 | RSS: Main: 162189312 20:42:00 INFO - PROCESS | 22561 | 20:42:04 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34861/tests/kraken/driver.html?testName=imaging-desaturate) 20:42:04 INFO - PROCESS | 22561 | RSS: Main: 158965760 20:42:04 INFO - PROCESS | 22561 | 20:42:07 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34861/tests/kraken/driver.html?testName=json-parse-financial) 20:42:08 INFO - PROCESS | 22561 | RSS: Main: 159694848 20:42:08 INFO - PROCESS | 22561 | 20:42:10 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34861/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:42:10 INFO - PROCESS | 22561 | RSS: Main: 159776768 20:42:10 INFO - PROCESS | 22561 | 20:42:12 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:42:13 INFO - PROCESS | 22561 | RSS: Main: 159866880 20:42:13 INFO - PROCESS | 22561 | 20:42:15 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:42:16 INFO - PROCESS | 22561 | RSS: Main: 159453184 20:42:16 INFO - PROCESS | 22561 | 20:42:18 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:42:19 INFO - PROCESS | 22561 | RSS: Main: 160378880 20:42:19 INFO - PROCESS | 22561 | 20:42:22 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:42:23 INFO - PROCESS | 22561 | RSS: Main: 163225600 20:42:23 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | Cycle 1(1): loaded http://localhost:34861/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34861/tests/kraken/driver.html?testName=ai-astar) 20:42:25 INFO - PROCESS | 22561 | RSS: Main: 164696064 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | __start_tp_report 20:42:25 INFO - PROCESS | 22561 | _x_x_mozilla_page_load 20:42:25 INFO - PROCESS | 22561 | _x_x_mozilla_page_load_details 20:42:25 INFO - PROCESS | 22561 | |i|pagename|runs| 20:42:25 INFO - PROCESS | 22561 | |0;ai-astar;109;116;115;115;113;113;114;114;115;114 20:42:25 INFO - PROCESS | 22561 | |1;audio-beat-detection;158;131;129;163;129;126;129;131;165;129 20:42:25 INFO - PROCESS | 22561 | |2;audio-dft;164;169;166;173;163;163;164;165;161;164 20:42:25 INFO - PROCESS | 22561 | |3;audio-fft;76;76;76;114;77;77;77;78;105;78 20:42:25 INFO - PROCESS | 22561 | |4;audio-oscillator;85;84;112;86;85;85;97;84;83;110 20:42:25 INFO - PROCESS | 22561 | |5;imaging-gaussian-blur;114;115;114;115;120;114;118;114;114;115 20:42:25 INFO - PROCESS | 22561 | |6;imaging-darkroom;150;149;151;150;150;151;150;150;151;152 20:42:25 INFO - PROCESS | 22561 | |7;imaging-desaturate;96;97;96;98;96;97;96;97;98;96 20:42:25 INFO - PROCESS | 22561 | |8;json-parse-financial;67;65;66;68;69;68;67;69;67;66 20:42:25 INFO - PROCESS | 22561 | |9;json-stringify-tinderbox;66;67;66;66;66;66;66;66;66;67 20:42:25 INFO - PROCESS | 22561 | |10;stanford-crypto-aes;97;88;90;85;94;92;85;89;89;88 20:42:25 INFO - PROCESS | 22561 | |11;stanford-crypto-ccm;144;140;138;143;136;150;150;141;199;139 20:42:25 INFO - PROCESS | 22561 | |12;stanford-crypto-pbkdf2;190;186;194;181;185;180;183;188;182;183 20:42:25 INFO - PROCESS | 22561 | |13;stanford-crypto-sha256-iterative;70;71;70;71;70;72;69;70;70;69 20:42:25 INFO - PROCESS | 22561 | __end_tp_report 20:42:25 INFO - PROCESS | 22561 | __start_cc_report 20:42:25 INFO - PROCESS | 22561 | _x_x_mozilla_cycle_collect,902 20:42:25 INFO - PROCESS | 22561 | __end_cc_report 20:42:25 INFO - PROCESS | 22561 | __startTimestamp1487824945569__endTimestamp 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | ------- Summary: start ------- 20:42:25 INFO - PROCESS | 22561 | Number of tests: 14 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.50 stddev:1.93 (1.7%) stddev-sans-first:1.00 20:42:25 INFO - PROCESS | 22561 | Values: 109.0 116.0 115.0 115.0 113.0 113.0 114.0 114.0 115.0 114.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:131.00 stddev:16.02 (12.2%) stddev-sans-first:15.45 20:42:25 INFO - PROCESS | 22561 | Values: 158.0 131.0 129.0 163.0 129.0 126.0 129.0 131.0 165.0 129.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#2] audio-dft Cycles:10 Average:165.20 Median:164.50 stddev:3.46 (2.1%) stddev-sans-first:3.64 20:42:25 INFO - PROCESS | 22561 | Values: 164.0 169.0 166.0 173.0 163.0 163.0 164.0 165.0 161.0 164.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#3] audio-fft Cycles:10 Average:83.40 Median:77.00 stddev:13.94 (18.1%) stddev-sans-first:14.52 20:42:25 INFO - PROCESS | 22561 | Values: 76.0 76.0 76.0 114.0 77.0 77.0 77.0 78.0 105.0 78.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:85.00 stddev:11.22 (13.2%) stddev-sans-first:11.68 20:42:25 INFO - PROCESS | 22561 | Values: 85.0 84.0 112.0 86.0 85.0 85.0 97.0 84.0 83.0 110.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#5] imaging-gaussian-blur Cycles:10 Average:115.30 Median:115.00 stddev:2.06 (1.8%) stddev-sans-first:2.13 20:42:25 INFO - PROCESS | 22561 | Values: 114.0 115.0 114.0 115.0 120.0 114.0 118.0 114.0 114.0 115.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 20:42:25 INFO - PROCESS | 22561 | Values: 150.0 149.0 151.0 150.0 150.0 151.0 150.0 150.0 151.0 152.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:97.00 stddev:0.82 (0.8%) stddev-sans-first:0.83 20:42:25 INFO - PROCESS | 22561 | Values: 96.0 97.0 96.0 98.0 96.0 97.0 96.0 97.0 98.0 96.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.50 stddev:1.32 (2.0%) stddev-sans-first:1.39 20:42:25 INFO - PROCESS | 22561 | Values: 67.0 65.0 66.0 68.0 69.0 68.0 67.0 69.0 67.0 66.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 20:42:25 INFO - PROCESS | 22561 | Values: 66.0 67.0 66.0 66.0 66.0 66.0 66.0 66.0 66.0 67.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:89.50 stddev:3.77 (4.2%) stddev-sans-first:2.93 20:42:25 INFO - PROCESS | 22561 | Values: 97.0 88.0 90.0 85.0 94.0 92.0 85.0 89.0 89.0 88.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:143.50 stddev:18.52 (12.9%) stddev-sans-first:19.59 20:42:25 INFO - PROCESS | 22561 | Values: 144.0 140.0 138.0 143.0 136.0 150.0 150.0 141.0 199.0 139.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:185.50 stddev:4.39 (2.4%) stddev-sans-first:4.30 20:42:25 INFO - PROCESS | 22561 | Values: 190.0 186.0 194.0 181.0 185.0 180.0 183.0 188.0 182.0 183.0 20:42:25 INFO - PROCESS | 22561 | 20:42:25 INFO - PROCESS | 22561 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 20:42:25 INFO - PROCESS | 22561 | Values: 70.0 71.0 70.0 71.0 70.0 72.0 69.0 70.0 70.0 69.0 20:42:25 INFO - PROCESS | 22561 | -------- Summary: end -------- 20:42:25 INFO - PROCESS | 22561 | 20:42:26 INFO - TEST-INFO | 22561: exit 0 20:42:26 INFO - TEST-OK | kraken | took 64724ms 20:42:26 INFO - SUITE-END | took 1184s 20:42:27 INFO - Completed test suite (00:19:45) 20:42:27 INFO - javascript benchmark 20:42:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6332.489922042546, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2543.885525151368, 2544.1604270590706, 2546.567083595789, 2571.138668546037, 2586.8965241390347, 4500.617447516965, 4594.609458196057, 4627.629789616827, 4630.467496237934, 4642.558956899097, 4542.523035081318, 4565.9954809941755, 4568.428946381702, 4576.656750743691, 4630.791614377353, 4043.595640435962, 4229.555896630855, 4272.1028583997395, 4326.502452217988, 4350.499692535367, 4249.936250956238, 4289.806958686867, 4290.292101803198, 4311.3101903695415, 4352.412424322723, 4125.0, 4172.958270417318, 4177.060161463677, 4194.30793919003, 4226.725262857904, 4169.124483858393, 4172.08214192877, 4175.081482073969, 4179.1432756284885, 4211.241976444238, 4124.896877578055, 4155.210510003089, 4158.625723684852, 4174.248635245654, 4199.643030342425, 4359.476862776487, 4401.053773438694, 4521.751303678297, 4545.704529205625, 4570.405847239871, 4878.560929516325, 4897.363342765418, 4899.167141585904, 4946.629002824802, 4946.703197808143, 4935.703857768545, 4936.062148191832, 4941.20940649498, 4941.308216849644, 4945.7774400152075, 4494.101179763995, 4497.460304763449, 4507.954920450819, 4549.431321084864, 4669.11286855493, 4931.284963680247, 4936.432310284292, 4936.679115857458, 4968.080905032581, 4980.651354405157, 4698.154332220233, 4727.669063165614, 4769.976150119227, 4803.231482962709, 4812.735299558558, 4886.804527818918, 4887.902241955141, 4889.046635906036, 4908.141075311878, 4933.925991110136, 4729.574338309569, 4739.739314337745, 4827.61379089672, 4827.8551643450755, 4866.343043689057, 4833.975830120827, 4868.367112275452, 4888.26675998596, 4918.262260660929, 4941.184704523714, 4192.3082691355585, 4193.7903104845245, 4199.202151591249, 4228.154369126187, 4231.196072746168, 3529.9294014119573, 3544.680978711928, 3634.491171236029, 3654.958336873978, 3689.94465083024, 4217.430646862635, 4236.131593023423, 4240.194363071007, 4250.978745106255, 4299.742015479082, 4890.486498917634, 4899.58353539946, 4902.509749025069, 4908.680935739166, 4954.47977962316, 4119.938200926988, 4245.830166793294, 4259.74441533509, 4261.786910654455, 4279.0, 4275.893102672458, 4400.031992961543, 4406.030673251879, 4435.334699795057, 4475.373447717322, 4564.224081906151, 4583.862484125549, 4584.88537786558, 4620.607248383925, 4637.443506779073, 4910.067087253553, 4942.654014218971, 4950.529699678525, 4950.77721502526, 4993.775280112476, 4506.369108324771, 4540.296254080623, 4583.816647334203, 4623.075384922961, 4627.467841198305, 4885.9022819544125, 4893.339399181207, 4896.290037944549, 4907.705537667752, 4932.1122198004705, 4712.835050773207, 4742.359781429463, 4952.975235123801, 4956.851294461172, 4961.875953101201, 4763.285507173951, 4787.688800228113, 4802.375691160267, 4804.0872234275375, 4852.199387101089, 4778.211595086772, 4779.880502987314, 4800.687955282895, 4809.759512024455, 4834.178189707828, 4580.564846339593, 4714.434267887876, 4748.905021899612, 4752.025834703946, 4804.8798780030775, 4658.324542941323, 4745.33565300865, 4767.880802979953, 4783.473817879961, 4827.203511420576, 4667.066586682745, 4680.836170734008, 4688.5545873142, 4706.623470122314, 4725.10223057618], "value": 4492.801670960926, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.0952471274121, 545.1414022913912, 555.6055200807426, 556.7605929450336, 571.2060236271583, 38095.0, 39357.80321098394, 39376.212475750464, 39453.60546394539, 42204.788976055155, 39630.811075667705, 40049.199016019666, 40125.598744012554, 40150.397744033864, 40172.19655606886, 7355.558666480001, 7548.433867459935, 7553.773386798405, 7556.924430755677, 9210.0, 9027.684031058914, 9333.0, 9338.29962752793, 9343.672971446002, 9355.532223388804, 9399.06009399058, 9481.051894810498, 9488.620455181785, 9501.52492375382, 9501.95249023754, 9342.906570934272, 9355.251579873593, 9427.670031548863, 9442.291828112919, 9450.243980481546, 9232.30757693169, 9249.953750231272, 9292.070792920722, 9306.069393060707, 9318.673846415411, 32695.346093078006, 32810.50784238238, 32828.6717132828, 32901.670983290336, 32931.0, 3349.8995030149135, 3430.091025878119, 3441.5181874537584, 3483.8780642677384, 3490.3019396120876, 24392.0, 24927.5014499709, 24927.750722492725, 25040.87479562609, 25221.747782522125, 3138.937221255585, 3208.2300247940325, 3233.3048394595276, 3316.5688460499982, 3326.550915626384, 232930.0, 234222.0, 235361.0, 235870.0, 236554.0, 38158.42762358567, 39275.21449570992, 45226.09547809091, 45874.311885321746, 45957.7702111494, 44543.0, 46058.76970615125, 46181.22556714214, 46347.768261158475, 46404.5359546407, 12111.091668124749, 12243.938780306042, 12272.0, 12273.938630306791, 12303.630891073282, 9251.213646839959, 9353.532323383804, 9363.765905852271, 9373.0, 9385.859212111824, 15550.222488875512, 15717.921410393104, 15747.606309842346, 15747.763783543256, 15770.92114539443, 6529.449967527564, 6532.771353002622, 6649.468042556584, 6699.665016749143, 6801.319868013258, 729.6023667101442, 757.5076200469629, 761.2235519769804, 762.9923700763032, 772.1969152081884, 28854.567181492293, 31012.055758505918, 32041.0, 32480.75192480733, 32885.342293154004, 927.2674587076269, 929.869818225449, 940.4686352211115, 951.8667386565886, 952.2239374909427, 13487.460501580023, 14348.856511434751, 14397.280135993367, 14707.411703531952, 14730.410783568537, 325044.0, 332812.0, 333192.0, 334025.0, 334850.0, 48664.970279904126, 49107.754461227465, 49272.50727492679, 49338.10590305606, 49466.0, 1188.7173247583544, 1205.8673545909942, 1212.7453234820787, 1219.951201951912, 1224.4734764051366, 25181.37046573777, 26553.468930621664, 26973.325666858487, 27196.592051119645, 27255.318617034733, 42580.36129458123, 43279.13441731211, 43385.0, 43439.0, 43565.0, 309907.0, 310995.0, 311494.0, 311674.0, 313112.0, 31998.52002219922, 32795.34409311848, 32967.34065318632, 35368.646313536534, 35553.28893422169, 35649.0, 35694.82152589324, 36870.0, 36991.445128323634, 37300.2539949194, 31697.524537132424, 31800.0, 31931.68068319287, 31969.200769980936, 33328.83335583306, 31853.36293274168, 31962.406578849073, 34269.657303427644, 34422.48366274458, 35042.29915401729, 31460.68539314669, 31711.524327135572, 31790.841045794623, 31910.52134218035, 32066.19834504156, 28653.856730717045, 28869.566956495248, 29777.25556861096, 29964.25089372696, 29980.400391991603, 22481.0, 22581.43546411353, 22797.544049119257, 22817.657735133653, 23057.538849223256], "value": 16933.079465279232, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.91487872668006, 398.9960100398995, 401.65658362100413, 406.7864371205117, 424.57330382965114, 12573.24560526369, 16101.275442605081, 16387.098709570982, 17918.462446126603, 17928.103594820255, 16348.10085445303, 16771.413000544984, 17453.3018679253, 18387.26450941964, 18622.25510979559, 4997.150484417649, 5036.697798132123, 5155.974220128895, 5185.948140518603, 5216.0, 5102.770375333101, 5106.259592359106, 5169.534741873231, 5172.12073947428, 5176.974115129419, 5053.408176424433, 5113.437521872591, 5118.33461649985, 5123.692578445287, 5131.435542090367, 5196.480351964811, 5413.539849112811, 5424.430434804348, 5458.536024437888, 5481.205225242317, 4795.808167673289, 5031.647784655075, 5322.520973112439, 5340.492653197979, 5352.839414817562, 12812.167209131376, 12958.0, 13000.674983125404, 13001.414936327888, 18013.27946882123, 2200.559888022402, 2205.757366689663, 2233.4304752288226, 2255.4023183856293, 2374.3392169160516, 9555.761105972337, 9789.412635241908, 10029.297949143564, 12118.969887559568, 13053.282069486175, 1633.9428120015743, 1669.3994620273525, 1689.510042087793, 1702.7105392083377, 1726.4130195733385, 64674.0, 66106.0, 71785.0, 76140.61929690372, 78126.60936695337, 10666.413347265974, 10963.56145754177, 11045.116390688725, 11210.439478026066, 11468.197226194086, 13028.934855325662, 15247.161406122548, 15391.769123463157, 15417.147827651921, 15473.071615703095, 5996.760129594767, 6931.584104953719, 7257.854842903218, 7262.346388825031, 7339.669714862844, 6530.346965303432, 6561.967190164114, 6582.769603063965, 6716.529842911047, 6804.829879253059, 4379.890502737457, 4426.269665505156, 4742.241241401359, 4782.689125206851, 4872.342233798463, 1921.5196200949763, 1948.9220431182594, 1977.6835706287213, 1999.600079984009, 2022.4236092713506, 775.8021704465438, 801.4950581133965, 802.3140215116108, 802.530519646002, 818.3616778912458, 17164.0, 17536.12319384051, 18019.459416217534, 18186.36347727823, 18525.81474185268, 775.9068911730516, 793.9841203175904, 794.6424109150905, 797.1868693932157, 816.0411516468126, 9575.664851730156, 9684.0, 9938.850917236246, 9964.402135871871, 10022.398656080659, 449725.0, 454323.0, 458997.0, 466793.0, 467030.0, 73495.63252183812, 85521.0, 90520.09479905249, 92234.0, 92658.0, 934.2525979216599, 967.066780556766, 973.7273563402259, 976.6728146071003, 977.0229770229912, 16055.277512512199, 16428.83571164321, 16916.238769255167, 16954.83045169582, 17203.741943870602, 40436.59563404328, 40584.391234132105, 40688.38967415432, 41107.383389249786, 41488.1702365957, 38232.60507896285, 49069.509304906496, 57694.7115264421, 58771.53071173255, 60326.69836650789, 11369.204155709022, 11407.543698251977, 11552.826707599224, 11663.88336116628, 12613.558525451564, 12748.298843563693, 13255.270960097287, 13395.799063013868, 13595.252261125734, 13821.239831809347, 11119.666410007712, 11247.156463265452, 11646.941765291458, 11792.469338879942, 11802.94098529502, 11560.132990025613, 11603.941980290045, 11626.302421854716, 11628.476718547516, 11896.107791915814, 10424.947875260576, 10771.838422423512, 11088.390138542134, 11742.471588778355, 11820.940895295469, 11565.942170289094, 11788.115891308014, 11863.466144023367, 12276.447559860006, 12622.43199056063, 11285.21003529745, 11336.546538138382, 11385.601503947322, 11699.181057325906, 11929.761404771682], "value": 8687.653591652015, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.864039275876, 1297.4680381043775, 1309.083641450984, 1312.4422120598747, 1316.624761942846, 7492.363149132323, 7598.848023039536, 7776.883346749796, 7828.608569571513, 7835.333996610295, 7851.725189618364, 7961.044674639037, 7975.960120199391, 7981.401394895377, 7982.600869956494, 2107.1360742095703, 2138.101997161188, 2140.283005193254, 2142.860714053589, 2152.3758110148037, 2131.8081372676456, 2163.718716566852, 2168.4687251623373, 2169.2949791317806, 2181.4982554012586, 2073.968890466644, 2111.6199084164846, 2111.6304646686845, 2137.9465513362134, 2145.2598853395552, 2060.423081537172, 2082.9687554686693, 2084.2080009596257, 2085.0408811946522, 2087.342487116561, 1759.81521940197, 1818.5635447492507, 2052.2406709517427, 2078.8752674839407, 2108.4728817795553, 7633.770986870402, 7752.922470775333, 7872.488288261245, 7873.881891771628, 7911.604419779045, 4565.680402371802, 4697.694649847749, 4701.083288758693, 4703.858884233478, 4706.458757242926, 2825.773938084969, 2856.7714582833532, 2868.139558132552, 2886.090881372361, 2907.3167805565677, 1124.7356871135278, 1138.6925530106826, 1723.7759091318048, 1798.4964210021215, 1885.651154536415, 9474.194693451065, 9631.855522167172, 9671.274654400948, 10475.58097676092, 10719.946400268027, 4265.765382903908, 4334.176506463762, 4350.151720414488, 4389.714668546534, 4392.033752574429, 9094.590743416562, 9097.772555686028, 9100.453972761656, 9130.452172869649, 9163.450192988308, 4642.187617166982, 4655.813767449332, 4661.953380466152, 4679.555442232982, 4679.719216847, 4228.323468245066, 4295.29127693927, 4397.912041759147, 4438.178936896685, 4445.799939002753, 3517.82410879455, 3581.462780582933, 3634.1823089804584, 3684.428913518409, 3694.168812017275, 1054.778496515725, 1066.386827574148, 1070.8019016481976, 1071.9410432426291, 1072.222638587018, 793.1750978981794, 804.927556519916, 816.7468084893583, 824.2416976381743, 828.3911325175991, 4971.403431588233, 5025.874353141201, 5057.595392368602, 5111.616628752784, 5138.3577052868395, 501.3207104373594, 521.0932976620728, 523.4163907243396, 525.3511912787704, 526.0084740264629, 3321.4353559894876, 3371.494275858641, 3456.9308613827584, 3461.152017755666, 3592.0846277557066, 8122.878156827653, 8461.0, 8717.346199035097, 8907.955460222787, 9068.591913363915, 9206.079392060741, 9231.90768092324, 9231.953840230755, 9234.538273086318, 9238.076192380842, 6754.966225168842, 6941.305869413019, 6977.6511174441075, 7013.123359580052, 7092.11348581417, 4972.378452693413, 5050.696958182521, 5067.341245638044, 5127.948720512747, 5199.816077247489, 7969.242921922408, 8245.464044837066, 8307.709230176915, 8355.582220889053, 8581.785455363666, 8554.0, 8690.826183476422, 8692.91307086921, 8712.39013269065, 8719.607617657348, 6523.347665233438, 6611.371919667817, 6626.801195964129, 6646.368594983469, 6666.333366663489, 7149.392301654418, 7245.239249878687, 7322.0, 7330.303621155982, 7380.520266182681, 6574.4740420765265, 6673.799786006428, 6684.331566843277, 6702.932970670231, 6706.8993965089585, 6409.0, 6426.325235850355, 6442.806715798533, 6499.935000649933, 6525.184351956005, 5790.739416726173, 5857.26784151981, 5887.911681324869, 5910.881782364415, 5922.378150294157, 4321.58944900234, 4348.565143485626, 4431.601155895984, 4451.198784218742, 4477.70894891831, 4399.5820397062225, 4407.228734971303, 4430.778461076998, 4553.3397657340165, 4574.10804893056], "value": 4022.2455413854527, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.22697427505125, 364.456959130895, 368.1440650487617, 369.76150383002965, 372.90304520824577, 4961.702297862131, 4999.825006124786, 5007.148784706599, 5031.748412579374, 5121.437961421774, 4940.580050695687, 4961.238760240117, 4975.9101177505445, 5002.474740152286, 5025.698458092508, 3748.9062773430746, 3859.20886218326, 3869.342211823983, 3873.767573945572, 3887.611238876118, 3878.4182372644045, 3882.864099756509, 3888.105735680795, 3893.532776066876, 3896.610338966095, 3869.1487872667967, 3891.727579069452, 3912.7065470089715, 3913.882583522499, 3925.862594809182, 3647.726420518445, 3882.495275614166, 3909.882703518899, 3912.4326972588815, 3921.686265098785, 3309.25541753106, 3322.534845121685, 3325.5676762021026, 3335.8832440864453, 3342.866285348583, 4871.855114048196, 4874.65877388583, 4876.146674332013, 4876.609871210295, 4888.437829649601, 4410.412251589425, 4418.867433976954, 4426.513083560806, 4436.491592858443, 4444.555544445529, 5041.092603331399, 5239.318888544503, 5305.681659100466, 5313.256144139824, 5323.148296272617, 3987.843525377637, 3993.0, 4011.919761604781, 4017.9196416071513, 4020.316546187126, 5289.259503669489, 5373.677579345291, 5408.675479471205, 5584.050711379075, 5643.984082865082, 4069.6337329640473, 4075.0, 4086.141910198832, 4097.016715988201, 4099.97950010248, 4110.630043296118, 4123.443335149716, 4129.483814523184, 4144.0, 4147.709660323749, 4506.977465112654, 4559.3844830948065, 4568.428946381702, 4591.678582499259, 4662.743549104832, 5259.552938000313, 5276.39321478035, 5276.445973172838, 5281.260623512711, 5296.814611488579, 1380.4202235061298, 1387.7579566288116, 1394.9398457172622, 1402.7334806386755, 1405.7539930512323, 951.2437612098398, 997.4015590645699, 1007.8236308645958, 1015.263933648114, 1017.7455636090978, 4441.800118994588, 4452.5992660660695, 4457.353683715843, 4457.977710111429, 4487.394201782718, 5247.7113758743635, 5261.237120617489, 5276.657017293864, 5306.230596563477, 5334.146536554133, 4337.392765012901, 4498.302763071729, 4513.75174365413, 4527.230370836965, 4535.501094879561, 2791.776657867366, 2812.170409729135, 2815.957760633592, 2819.5911592818925, 2820.633317668731, 4934.432540257916, 4935.889424879373, 4938.08645400602, 4943.431505376928, 4976.353074100344, 5112.795488180505, 5365.3293338332705, 5414.7021913795115, 5435.456454354533, 5457.69982650958, 4877.341558889575, 4879.438864530624, 4882.5605695487575, 4919.4834542373255, 4926.531979461945, 5082.491750824888, 5114.181730923183, 5217.156649625887, 5233.39815921174, 5243.973780131228, 4971.577415919543, 5030.773615187252, 5077.06527441236, 5130.56344223618, 5196.7401629917595, 4870.439899411613, 4884.0476107158765, 4995.5004499551205, 5015.7492125392855, 5057.69653820772, 3780.6030366811087, 3807.828647710915, 3809.7333186676665, 3826.215625796761, 3830.8659196927974, 5448.128299472065, 5459.836204913859, 5463.699496527729, 5465.808696695596, 5471.808486703106, 5064.366954130734, 5250.763715632729, 5256.211568264791, 5263.026340127089, 5319.175527793198, 5161.277421161116, 5244.816431424882, 5249.084084309124, 5253.553447956813, 5268.710220937732, 5126.512981266774, 5132.230165475208, 5136.332276803992, 5138.8972220556125, 5160.967806438653, 5038.412899936508, 5270.8155214567305, 5277.73334399749, 5282.947170528246, 5290.629655924048, 5197.740112994259, 5213.687178769286, 5221.869453263699, 5226.216067589892, 5267.710275934707], "value": 3979.1448486565464, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.6705128653598, 594.8899453601086, 694.0006390797255, 724.6268171891477, 727.5852763924563, 11834.349110798914, 12577.666744478274, 13308.0, 13436.261005644687, 13619.659508512292, 12105.818412723816, 12380.071494637916, 13002.60992170234, 13051.673708157301, 13199.93400033001, 799.9600019998991, 811.0267678785451, 813.1137060603941, 814.9551774652392, 815.963281652327, 785.5051317669888, 794.297047113306, 795.1769918134731, 808.5674164322089, 816.4285000499674, 834.9540775257359, 847.4279861093737, 848.6011574559947, 851.4210336970857, 855.0423525651279, 833.9708110216144, 838.9412741108064, 843.3337663246059, 843.8396704626132, 844.7719115838751, 619.5508256513991, 620.2928661326092, 621.4810633121349, 621.9533534984894, 622.9563930524865, 13034.869651303461, 13490.797638035438, 13514.729705405836, 13536.661583460394, 13866.584002479842, 12598.0, 12959.352032398343, 12988.350582470934, 13037.21776693392, 13095.214287142706, 10030.699079027641, 10384.480775961172, 10465.529051192714, 10507.369557826478, 10508.737281567946, 3162.762792790527, 3196.6963138502038, 3234.4663130583426, 3239.0768630940306, 3243.562119113913, 60819.69590152065, 60991.69504152495, 61726.38273617251, 62013.68993155051, 62669.68665156645, 9293.860592091123, 9454.243660507143, 9725.0761177688, 9793.118619324152, 9864.506774661239, 18463.53841153982, 18520.81479185218, 18663.066846657613, 18720.906395467937, 18826.24695012184, 6201.131841542187, 6210.472109870711, 6255.12428260044, 6393.0, 6402.679866006681, 3410.931781364359, 3503.8423270952867, 3510.57873055235, 3564.500969864221, 3619.5475565554307, 2998.34036511967, 3051.542268659719, 3057.6942305769244, 3071.4932036213772, 3072.3087305356116, 528.5137673340536, 532.6111938285073, 535.7937194180269, 550.3753240072547, 551.555997422079, 621.9346968568235, 646.4472875691216, 648.4391001783423, 654.1724718231346, 658.1181217168921, 8223.21879421454, 8637.56812159402, 8648.870266946002, 8654.524001179996, 8968.417052891542, 605.7546693589103, 617.6510271696477, 620.4447019917149, 629.9212598425197, 634.330781026022, 4057.715959882839, 4197.097624010852, 4266.1254442839145, 4302.7490894576285, 4348.9130217396105, 64284.67857660682, 64437.35562644407, 65446.672766635864, 67979.66010169918, 68249.31750682529, 57724.13413798797, 57841.0, 57904.13143802846, 58146.0, 59873.40126598765, 50677.0, 50734.746326268134, 50888.23667644988, 52215.73892130515, 53497.1975420369, 7818.413618978486, 8072.354211663171, 8171.0, 8240.876386854321, 8358.58207089631, 36464.0, 36981.63018369782, 37568.81215593904, 38159.61840381672, 38736.0, 34648.30703385868, 35542.111447214025, 36108.0, 36772.81613592022, 41600.79199603983, 9534.523273836141, 9571.664991725256, 9577.281703872099, 9670.903290967282, 9752.317337786288, 10143.49282535885, 10289.43408112561, 10329.328593641389, 10548.0, 10602.946985265024, 9738.707838764849, 9943.453110079015, 10033.949830250802, 10082.941291164616, 10308.072273495422, 9471.526423678926, 9756.804863902824, 9937.205023598239, 10049.095581397709, 10277.897221027693, 9957.0, 10051.849222261819, 10455.059044686015, 10478.0, 10866.836997445203, 7895.7236496722335, 7921.485103468256, 8045.9597702011115, 8515.276201522693, 8526.190011948855, 8386.916130838614, 8711.869321960048, 8749.518776467356, 8887.466751994902, 9029.548522573976], "value": 6095.142485978501, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 115.0, 115.0, 113.0, 113.0, 114.0, 114.0, 115.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 131.0, 129.0, 163.0, 129.0, 126.0, 129.0, 131.0, 165.0, 129.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 166.0, 173.0, 163.0, 163.0, 164.0, 165.0, 161.0, 164.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 114.0, 77.0, 77.0, 77.0, 78.0, 105.0, 78.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 112.0, 86.0, 85.0, 85.0, 97.0, 84.0, 83.0, 110.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 115.0, 120.0, 114.0, 118.0, 114.0, 114.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 151.0, 150.0, 150.0, 151.0, 150.0, 150.0, 151.0, 152.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 98.0, 96.0, 97.0, 96.0, 97.0, 98.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 68.0, 69.0, 68.0, 67.0, 69.0, 67.0, 66.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 88.0, 90.0, 85.0, 94.0, 92.0, 85.0, 89.0, 89.0, 88.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 140.0, 138.0, 143.0, 136.0, 150.0, 150.0, 141.0, 199.0, 139.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 186.0, 194.0, 181.0, 185.0, 180.0, 183.0, 188.0, 182.0, 183.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 70.0, 71.0, 70.0, 72.0, 69.0, 70.0, 70.0, 69.0], "value": 70.2, "unit": "score"}]}]} 20:42:27 INFO - Return code: 0 20:42:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:27 ERROR - # TBPL SUCCESS # 20:42:27 INFO - Running post-action listener: _resource_record_post_action 20:42:27 INFO - [mozharness: 2017-02-23 04:42:27.396979Z] Finished run-tests step (success) 20:42:27 INFO - Running post-run listener: _resource_record_post_run 20:42:27 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12288; Write bytes: 362463232; Read time: 28; Write time: 338284 20:42:27 INFO - TinderboxPrint: CPU usage
13.5% 20:42:27 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 28 20:42:27 INFO - TinderboxPrint: I/O write bytes / time
362,463,232 / 338,284 20:42:27 INFO - TinderboxPrint: CPU idle
8,289.3 (86.5%) 20:42:27 INFO - TinderboxPrint: CPU user
1,236.7 (12.9%) 20:42:27 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105582592; Read time: 0; Write time: 153240 20:42:27 INFO - run-tests - Wall time: 1186s; CPU: 14.0%; Read bytes: 12288; Write bytes: 256880640; Read time: 28; Write time: 185044 20:42:28 INFO - Running post-run listener: _upload_blobber_files 20:42:28 INFO - Blob upload gear active. 20:42:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:42:28 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:42:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:29 INFO - (blobuploader) - INFO - Done attempting. 20:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:32 INFO - (blobuploader) - INFO - Done attempting. 20:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:33 INFO - (blobuploader) - INFO - Done attempting. 20:42:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:42:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:35 INFO - (blobuploader) - INFO - Done attempting. 20:42:35 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:35 INFO - Return code: 0 20:42:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:35 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94fd67839f17c2bcc5973e69124f0702156a50fe4e05808becf56d6f5d1cc520993ffc409e1d98374d5313849eaff88e27803269f5d04bfea9a861c0623d796", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63d27b0a98779a26a9cb4baacb7e9894fbf57b28c9e52efa23be1d5ac78a6b57181fc28cec97eaa424881be3c235f7fb2502e9e1a7e1d0aea59cebbaa11ff2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e912443de3828bccdafd54d0609edd7bde1efaf6bf93ed6808bd6363362f31dce5f4e682c01a81317615fd777624f7dc44e5f7ef7ac2c414e19031ffe89a395f"} 20:42:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:35 INFO - Contents: 20:42:35 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94fd67839f17c2bcc5973e69124f0702156a50fe4e05808becf56d6f5d1cc520993ffc409e1d98374d5313849eaff88e27803269f5d04bfea9a861c0623d796", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63d27b0a98779a26a9cb4baacb7e9894fbf57b28c9e52efa23be1d5ac78a6b57181fc28cec97eaa424881be3c235f7fb2502e9e1a7e1d0aea59cebbaa11ff2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e912443de3828bccdafd54d0609edd7bde1efaf6bf93ed6808bd6363362f31dce5f4e682c01a81317615fd777624f7dc44e5f7ef7ac2c414e19031ffe89a395f"} 20:42:35 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:35 INFO - Copying logs to upload dir... 20:42:35 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.158140 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-22 20:42:35.355585) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-22 20:42:35.358929) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94fd67839f17c2bcc5973e69124f0702156a50fe4e05808becf56d6f5d1cc520993ffc409e1d98374d5313849eaff88e27803269f5d04bfea9a861c0623d796", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63d27b0a98779a26a9cb4baacb7e9894fbf57b28c9e52efa23be1d5ac78a6b57181fc28cec97eaa424881be3c235f7fb2502e9e1a7e1d0aea59cebbaa11ff2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e912443de3828bccdafd54d0609edd7bde1efaf6bf93ed6808bd6363362f31dce5f4e682c01a81317615fd777624f7dc44e5f7ef7ac2c414e19031ffe89a395f"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94fd67839f17c2bcc5973e69124f0702156a50fe4e05808becf56d6f5d1cc520993ffc409e1d98374d5313849eaff88e27803269f5d04bfea9a861c0623d796", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63d27b0a98779a26a9cb4baacb7e9894fbf57b28c9e52efa23be1d5ac78a6b57181fc28cec97eaa424881be3c235f7fb2502e9e1a7e1d0aea59cebbaa11ff2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e912443de3828bccdafd54d0609edd7bde1efaf6bf93ed6808bd6363362f31dce5f4e682c01a81317615fd777624f7dc44e5f7ef7ac2c414e19031ffe89a395f"}' ========= master_lag: 1.34 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-22 20:42:36.710212) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:36.710590) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5c37b704b588aaa989324530000001d0-1487821866.218154-1405534783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.40 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:37.117585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:37.117902) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:37.148434) ========= ========= Total master_lag: 1.85 =========