builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1471518328.72 results: success (0) buildid: 20160817151514 builduid: 5ca560887ea949039c2417d51dd6fc7c revision: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.722026) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.722384) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.722663) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.842404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.842664) ========= 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=0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 04:05:29-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.4M=0s 2016-08-18 04:05:29 (42.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127002 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.005305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.005572) ========= 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=0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017726 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.069268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.069558) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8ececbd9880dc48a879d7a0d44dbf5e15248b09c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8ececbd9880dc48a879d7a0d44dbf5e15248b09c --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=0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 04:05:29,690 truncating revision to first 12 chars 2016-08-18 04:05:29,690 Setting DEBUG logging. 2016-08-18 04:05:29,690 attempt 1/10 2016-08-18 04:05:29,690 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8ececbd9880d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 04:05:30,047 unpacking tar archive at: mozilla-aurora-8ececbd9880d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756897 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.840115) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.840393) ========= script_repo_revision: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.840760) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.841019) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.849838) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-08-18 04:05:29.850107) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:05:30 INFO - MultiFileLogger online at 20160818 04:05:30 in /builds/slave/test 04:05:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:05:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:05:30 INFO - {'append_to_log': False, 04:05:30 INFO - 'base_work_dir': '/builds/slave/test', 04:05:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:05:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:05:30 INFO - 'branch': 'Mozilla-Aurora', 04:05:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:05:30 INFO - 'config_files': ('talos/linux_config.py',), 04:05:30 INFO - 'default_actions': ('clobber', 04:05:30 INFO - 'read-buildbot-config', 04:05:30 INFO - 'download-and-extract', 04:05:30 INFO - 'populate-webroot', 04:05:30 INFO - 'create-virtualenv', 04:05:30 INFO - 'install', 04:05:30 INFO - 'run-tests'), 04:05:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:05:30 INFO - 'download_minidump_stackwalk': True, 04:05:30 INFO - 'download_symbols': 'ondemand', 04:05:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:05:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:05:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:05:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:05:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:05:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:05:30 INFO - 'installer_path': 'installer.exe', 04:05:30 INFO - 'log_level': 'info', 04:05:30 INFO - 'log_name': 'talos', 04:05:30 INFO - 'log_to_console': True, 04:05:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:05:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:05:30 INFO - 'opt_config_files': (), 04:05:30 INFO - 'pip_index': False, 04:05:30 INFO - 'sps_profile': False, 04:05:30 INFO - 'sps_profile_interval': 0, 04:05:30 INFO - 'suite': 'dromaeojs', 04:05:30 INFO - 'system_bits': '32', 04:05:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:05:30 INFO - 'title': 'talos-linux64-ix-017', 04:05:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:05:30 INFO - 'use_talos_json': True, 04:05:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:05:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:05:30 INFO - 'work_dir': 'build'} 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.565925Z] Running clobber step. 04:05:30 INFO - Running pre-action listener: _resource_record_pre_action 04:05:30 INFO - Running main action method: clobber 04:05:30 INFO - rmtree: /builds/slave/test/build 04:05:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:05:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.028927Z] Finished clobber step (success) 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.029028Z] Running read-buildbot-config step. 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: read_buildbot_config 04:05:31 INFO - Using buildbot properties: 04:05:31 INFO - { 04:05:31 INFO - "project": "", 04:05:31 INFO - "product": "firefox", 04:05:31 INFO - "script_repo_revision": "production", 04:05:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:05:31 INFO - "repository": "", 04:05:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:05:31 INFO - "buildid": "20160817151514", 04:05:31 INFO - "pgo_build": "True", 04:05:31 INFO - "basedir": "/builds/slave/test", 04:05:31 INFO - "buildnumber": 692, 04:05:31 INFO - "slavename": "talos-linux64-ix-017", 04:05:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:05:31 INFO - "platform": "ubuntu64_hw", 04:05:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:05:31 INFO - "branch": "mozilla-aurora", 04:05:31 INFO - "repo_path": "releases/mozilla-aurora", 04:05:31 INFO - "revision": "8ececbd9880dc48a879d7a0d44dbf5e15248b09c", 04:05:31 INFO - "stage_platform": "linux64-pgo", 04:05:31 INFO - "builduid": "5ca560887ea949039c2417d51dd6fc7c", 04:05:31 INFO - "slavebuilddir": "test" 04:05:31 INFO - } 04:05:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:05:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.031455Z] Finished read-buildbot-config step (success) 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.031544Z] Running download-and-extract step. 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: download_and_extract 04:05:31 INFO - mkdir: /builds/slave/test/build/tests 04:05:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:31 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:05:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:05:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:05:31 INFO - retry: Failed, sleeping 30 seconds before retrying 04:06:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 04:06:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:06:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:06:01 INFO - retry: Failed, sleeping 60 seconds before retrying 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 04:07:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:07:01 INFO - Downloaded 1361 bytes. 04:07:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - Using the following test package requirements: 04:07:01 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:01 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:07:01 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:07:01 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'jsshell-linux-x86_64.zip'], 04:07:01 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:07:01 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:01 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:07:01 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:07:01 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:07:01 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:07:01 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:07:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:01 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:07:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:07:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:07:01 INFO - retry: Failed, sleeping 30 seconds before retrying 04:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:07:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:07:31 INFO - retry: Failed, sleeping 60 seconds before retrying 04:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:08:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:08:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:08:31 INFO - Caught exception: HTTP Error 404: Not Found 04:08:31 INFO - Caught exception: HTTP Error 404: Not Found 04:08:31 INFO - Caught exception: HTTP Error 404: Not Found 04:08:31 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:32 INFO - Downloaded 21766774 bytes. 04:08:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:08:34 INFO - Return code: 0 04:08:34 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:08:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:34 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:08:34 INFO - retry: Failed, sleeping 30 seconds before retrying 04:09:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:09:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:09:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:09:04 INFO - retry: Failed, sleeping 60 seconds before retrying 04:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:10:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:10:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:10:04 INFO - Caught exception: HTTP Error 404: Not Found 04:10:04 INFO - Caught exception: HTTP Error 404: Not Found 04:10:04 INFO - Caught exception: HTTP Error 404: Not Found 04:10:04 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:04 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:10:05 INFO - Downloaded 21766774 bytes. 04:10:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:10:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:10:06 INFO - Return code: 0 04:10: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:06 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:10:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:10:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:10:06 INFO - retry: Failed, sleeping 30 seconds before retrying 04:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 04:10:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:10:36 INFO - retry: Failed, sleeping 60 seconds before retrying 04:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 04:11:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:11:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 04:11:36 INFO - Caught exception: HTTP Error 404: Not Found 04:11:36 INFO - Caught exception: HTTP Error 404: Not Found 04:11:36 INFO - Caught exception: HTTP Error 404: Not Found 04:11:36 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:36 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:11:37 INFO - Downloaded 14413723 bytes. 04:11:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:11:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:11:37 INFO - Return code: 0 04:11:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:37 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:11:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:11:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:11:37 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 04:12:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:12:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:12:07 INFO - retry: Failed, sleeping 60 seconds before retrying 04:13:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 04:13:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:13:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 04:13:07 INFO - Caught exception: HTTP Error 404: Not Found 04:13:07 INFO - Caught exception: HTTP Error 404: Not Found 04:13:07 INFO - Caught exception: HTTP Error 404: Not Found 04:13:07 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:07 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:13:09 INFO - Downloaded 63785079 bytes. 04:13:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:09 INFO - mkdir: /builds/slave/test/properties 04:13:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:09 INFO - Contents: 04:13:09 INFO - build_url:https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:10 INFO - Running post-action listener: _resource_record_post_action 04:13:10 INFO - Running post-action listener: set_extra_try_arguments 04:13:10 INFO - [mozharness: 2016-08-18 11:13:10.788120Z] Finished download-and-extract step (success) 04:13:10 INFO - [mozharness: 2016-08-18 11:13:10.788355Z] Running populate-webroot step. 04:13:10 INFO - Running pre-action listener: _resource_record_pre_action 04:13:10 INFO - Running main action method: populate_webroot 04:13:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:10 INFO - '--tppagecycles', 04:13:10 INFO - '7'], 04:13:10 INFO - 'tests': ['tsvgm']}}, 04:13:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['damp', 'tps']}, 04:13:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'tests': ['damp', 'tps']}, 04:13:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['dromaeo_dom']}, 04:13:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['basic_compositor_video']}, 04:13:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['a11yr', 04:13:10 INFO - 'ts_paint', 04:13:10 INFO - 'tpaint', 04:13:10 INFO - 'sessionrestore', 04:13:10 INFO - 'sessionrestore_no_auto_restore', 04:13:10 INFO - 'tabpaint']}, 04:13:10 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:10 INFO - 'ts_paint', 04:13:10 INFO - 'tpaint', 04:13:10 INFO - 'sessionrestore', 04:13:10 INFO - 'sessionrestore_no_auto_restore', 04:13:10 INFO - 'tabpaint']}, 04:13:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:10 INFO - 'ts_paint', 04:13:10 INFO - 'tpaint', 04:13:10 INFO - 'sessionrestore', 04:13:10 INFO - 'sessionrestore_no_auto_restore', 04:13:10 INFO - 'tabpaint']}, 04:13:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:10 INFO - 'ts_paint', 04:13:10 INFO - 'tpaint', 04:13:10 INFO - 'sessionrestore', 04:13:10 INFO - 'sessionrestore_no_auto_restore', 04:13:10 INFO - 'tabpaint']}, 04:13:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['a11yr', 04:13:10 INFO - 'ts_paint', 04:13:10 INFO - 'tpaint', 04:13:10 INFO - 'sessionrestore', 04:13:10 INFO - 'sessionrestore_no_auto_restore', 04:13:10 INFO - 'tabpaint']}, 04:13:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['a11yr', 04:13:10 INFO - 'ts_paint', 04:13:10 INFO - 'tpaint', 04:13:10 INFO - 'sessionrestore', 04:13:10 INFO - 'sessionrestore_no_auto_restore', 04:13:10 INFO - 'tabpaint']}, 04:13:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['tsvgx', 04:13:10 INFO - 'tsvgr_opacity', 04:13:10 INFO - 'tart', 04:13:10 INFO - 'tscrollx', 04:13:10 INFO - 'cart']}, 04:13:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:10 INFO - 'tsvgr_opacity', 04:13:10 INFO - 'tart', 04:13:10 INFO - 'tscrollx', 04:13:10 INFO - 'cart']}, 04:13:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'talos_options': ['--disable-e10s'], 04:13:10 INFO - 'tests': ['tp5o']}, 04:13:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'tests': ['tp5o']}, 04:13:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'talos_options': ['--disable-e10s', 04:13:10 INFO - '--xperf_path', 04:13:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:10 INFO - 'tests': ['tp5n']}, 04:13:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:10 INFO - 'talos_options': ['--xperf_path', 04:13:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:10 INFO - 'tests': ['tp5n']}}, 04:13:10 INFO - 'talos.zip': {'path': '', 04:13:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:10 INFO - Running post-action listener: _resource_record_post_action 04:13:10 INFO - [mozharness: 2016-08-18 11:13:10.809689Z] Finished populate-webroot step (success) 04:13:10 INFO - [mozharness: 2016-08-18 11:13:10.809778Z] Running create-virtualenv step. 04:13:10 INFO - Running pre-action listener: _resource_record_pre_action 04:13:10 INFO - Running main action method: create_virtualenv 04:13:10 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:10 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 04:13:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:10 INFO - Using real prefix '/usr' 04:13:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:11 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:13 INFO - Installing pip.................done. 04:13:13 INFO - Return code: 0 04:13:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:13 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:13 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 04:13:13 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 04:13:13 INFO - Using env: {'DISPLAY': ':0', 04:13:13 INFO - 'HOME': '/home/cltbld', 04:13:13 INFO - 'LANG': 'en_US.UTF-8', 04:13:13 INFO - 'LANGUAGE': 'en_US:en', 04:13:13 INFO - 'LOGNAME': 'cltbld', 04:13:13 INFO - 'MAIL': '/var/mail/cltbld', 04:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:13 INFO - 'MOZ_NO_REMOTE': '1', 04:13:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:13 INFO - 'NO_EM_RESTART': '1', 04:13:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:13 INFO - 'PWD': '/builds/slave/test', 04:13:13 INFO - 'SHELL': '/bin/bash', 04:13:13 INFO - 'SHLVL': '1', 04:13:13 INFO - 'TERM': 'linux', 04:13:13 INFO - 'TMOUT': '86400', 04:13:13 INFO - 'USER': 'cltbld', 04:13:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:13 INFO - '_': '/tools/buildbot/bin/python'} 04:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:13 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 04:13:13 INFO - Cleaning up... 04:13:13 INFO - Return code: 0 04:13:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:13 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:13 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 04:13:13 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 04:13:13 INFO - Using env: {'DISPLAY': ':0', 04:13:13 INFO - 'HOME': '/home/cltbld', 04:13:13 INFO - 'LANG': 'en_US.UTF-8', 04:13:13 INFO - 'LANGUAGE': 'en_US:en', 04:13:13 INFO - 'LOGNAME': 'cltbld', 04:13:13 INFO - 'MAIL': '/var/mail/cltbld', 04:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:13 INFO - 'MOZ_NO_REMOTE': '1', 04:13:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:13 INFO - 'NO_EM_RESTART': '1', 04:13:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:13 INFO - 'PWD': '/builds/slave/test', 04:13:13 INFO - 'SHELL': '/bin/bash', 04:13:13 INFO - 'SHLVL': '1', 04:13:13 INFO - 'TERM': 'linux', 04:13:13 INFO - 'TMOUT': '86400', 04:13:13 INFO - 'USER': 'cltbld', 04:13:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:13 INFO - '_': '/tools/buildbot/bin/python'} 04:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:13 INFO - Downloading/unpacking psutil>=3.1.1 04:13:13 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 04:13:13 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 04:13:13 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 04:13:13 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 04:13:13 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 04:13:13 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 04:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:17 INFO - Installing collected packages: psutil 04:13:17 INFO - Running setup.py install for psutil 04:13:17 INFO - building 'psutil._psutil_linux' extension 04:13:17 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 04:13:17 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 04:13:17 INFO - building 'psutil._psutil_posix' extension 04:13:17 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 04:13:17 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 04:13:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:17 INFO - Successfully installed psutil 04:13:17 INFO - Cleaning up... 04:13:17 INFO - Return code: 0 04:13:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:17 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:17 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 04:13:17 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 04:13:17 INFO - Using env: {'DISPLAY': ':0', 04:13:17 INFO - 'HOME': '/home/cltbld', 04:13:17 INFO - 'LANG': 'en_US.UTF-8', 04:13:17 INFO - 'LANGUAGE': 'en_US:en', 04:13:17 INFO - 'LOGNAME': 'cltbld', 04:13:17 INFO - 'MAIL': '/var/mail/cltbld', 04:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:17 INFO - 'MOZ_NO_REMOTE': '1', 04:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:17 INFO - 'NO_EM_RESTART': '1', 04:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:17 INFO - 'PWD': '/builds/slave/test', 04:13:17 INFO - 'SHELL': '/bin/bash', 04:13:17 INFO - 'SHLVL': '1', 04:13:17 INFO - 'TERM': 'linux', 04:13:17 INFO - 'TMOUT': '86400', 04:13:17 INFO - 'USER': 'cltbld', 04:13:17 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:17 INFO - '_': '/tools/buildbot/bin/python'} 04:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13:18 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 04:13:18 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 04:13:18 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 04:13:18 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 04:13:18 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 04:13:18 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 04:13:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:20 INFO - Installing collected packages: mozsystemmonitor 04:13:20 INFO - Running setup.py install for mozsystemmonitor 04:13:20 INFO - Successfully installed mozsystemmonitor 04:13:20 INFO - Cleaning up... 04:13:20 INFO - Return code: 0 04:13:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:20 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:20 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 04:13:20 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 04:13:20 INFO - Using env: {'DISPLAY': ':0', 04:13:20 INFO - 'HOME': '/home/cltbld', 04:13:20 INFO - 'LANG': 'en_US.UTF-8', 04:13:20 INFO - 'LANGUAGE': 'en_US:en', 04:13:20 INFO - 'LOGNAME': 'cltbld', 04:13:20 INFO - 'MAIL': '/var/mail/cltbld', 04:13:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:20 INFO - 'MOZ_NO_REMOTE': '1', 04:13:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:20 INFO - 'NO_EM_RESTART': '1', 04:13:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:20 INFO - 'PWD': '/builds/slave/test', 04:13:20 INFO - 'SHELL': '/bin/bash', 04:13:20 INFO - 'SHLVL': '1', 04:13:20 INFO - 'TERM': 'linux', 04:13:20 INFO - 'TMOUT': '86400', 04:13:20 INFO - 'USER': 'cltbld', 04:13:20 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:20 INFO - '_': '/tools/buildbot/bin/python'} 04:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:20 INFO - Downloading/unpacking jsonschema==2.5.1 04:13:20 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 04:13:20 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 04:13:20 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 04:13:20 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 04:13:20 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 04:13:20 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 04:13:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:23 INFO - warning: no files found matching '*.txt' 04:13:23 INFO - no previously-included directories found matching 'build' 04:13:23 INFO - no previously-included directories found matching 'dist' 04:13:23 INFO - no previously-included directories found matching '.git*' 04:13:23 INFO - Installing collected packages: jsonschema, functools32 04:13:23 INFO - Running setup.py install for functools32 04:13:23 INFO - warning: no files found matching '*.txt' 04:13:23 INFO - no previously-included directories found matching 'build' 04:13:23 INFO - no previously-included directories found matching 'dist' 04:13:23 INFO - no previously-included directories found matching '.git*' 04:13:23 INFO - Successfully installed jsonschema functools32 04:13:23 INFO - Cleaning up... 04:13:23 INFO - Return code: 0 04:13:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:13:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:23 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:23 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 04:13:23 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 04:13:23 INFO - Using env: {'DISPLAY': ':0', 04:13:23 INFO - 'HOME': '/home/cltbld', 04:13:23 INFO - 'LANG': 'en_US.UTF-8', 04:13:23 INFO - 'LANGUAGE': 'en_US:en', 04:13:23 INFO - 'LOGNAME': 'cltbld', 04:13:23 INFO - 'MAIL': '/var/mail/cltbld', 04:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:23 INFO - 'MOZ_NO_REMOTE': '1', 04:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:23 INFO - 'NO_EM_RESTART': '1', 04:13:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:23 INFO - 'PWD': '/builds/slave/test', 04:13:23 INFO - 'SHELL': '/bin/bash', 04:13:23 INFO - 'SHLVL': '1', 04:13:23 INFO - 'TERM': 'linux', 04:13:23 INFO - 'TMOUT': '86400', 04:13:23 INFO - 'USER': 'cltbld', 04:13:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:23 INFO - '_': '/tools/buildbot/bin/python'} 04:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:24 INFO - Cleaning up... 04:13:24 INFO - Return code: 0 04:13:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:24 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 04:13:24 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 04:13:24 INFO - Using env: {'DISPLAY': ':0', 04:13:24 INFO - 'HOME': '/home/cltbld', 04:13:24 INFO - 'LANG': 'en_US.UTF-8', 04:13:24 INFO - 'LANGUAGE': 'en_US:en', 04:13:24 INFO - 'LOGNAME': 'cltbld', 04:13:24 INFO - 'MAIL': '/var/mail/cltbld', 04:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:24 INFO - 'MOZ_NO_REMOTE': '1', 04:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:24 INFO - 'NO_EM_RESTART': '1', 04:13:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:24 INFO - 'PWD': '/builds/slave/test', 04:13:24 INFO - 'SHELL': '/bin/bash', 04:13:24 INFO - 'SHLVL': '1', 04:13:24 INFO - 'TERM': 'linux', 04:13:24 INFO - 'TMOUT': '86400', 04:13:24 INFO - 'USER': 'cltbld', 04:13:24 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:24 INFO - '_': '/tools/buildbot/bin/python'} 04:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 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 04:13:24 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 04:13:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 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 04:13:26 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 04:13:27 INFO - Downloading docopt-0.6.1.tar.gz 04:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:27 INFO - Running setup.py install for blobuploader 04:13:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:27 INFO - Running setup.py install for requests 04:13:27 INFO - Running setup.py install for docopt 04:13:27 INFO - Successfully installed blobuploader requests docopt 04:13:27 INFO - Cleaning up... 04:13:27 INFO - Return code: 0 04:13:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:27 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:27 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 04:13:27 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 04:13:27 INFO - Using env: {'DISPLAY': ':0', 04:13:27 INFO - 'HOME': '/home/cltbld', 04:13:27 INFO - 'LANG': 'en_US.UTF-8', 04:13:27 INFO - 'LANGUAGE': 'en_US:en', 04:13:27 INFO - 'LOGNAME': 'cltbld', 04:13:27 INFO - 'MAIL': '/var/mail/cltbld', 04:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:27 INFO - 'MOZ_NO_REMOTE': '1', 04:13:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:27 INFO - 'NO_EM_RESTART': '1', 04:13:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:27 INFO - 'PWD': '/builds/slave/test', 04:13:27 INFO - 'SHELL': '/bin/bash', 04:13:27 INFO - 'SHLVL': '1', 04:13:27 INFO - 'TERM': 'linux', 04:13:27 INFO - 'TMOUT': '86400', 04:13:27 INFO - 'USER': 'cltbld', 04:13:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:27 INFO - '_': '/tools/buildbot/bin/python'} 04:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:28 INFO - Running setup.py (path:/tmp/pip-vIKcrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:28 INFO - Running setup.py (path:/tmp/pip-ACyQ_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:28 INFO - Running setup.py (path:/tmp/pip-9HuAqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:28 INFO - Running setup.py (path:/tmp/pip-Gub5xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:28 INFO - Running setup.py (path:/tmp/pip-IoZ1th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:28 INFO - Running setup.py (path:/tmp/pip-wYXmaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:28 INFO - Running setup.py (path:/tmp/pip-mZGyuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:28 INFO - Running setup.py (path:/tmp/pip-nxI5Z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:28 INFO - Running setup.py (path:/tmp/pip-LAbLyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:28 INFO - Running setup.py (path:/tmp/pip-TBaloG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:28 INFO - Running setup.py (path:/tmp/pip-jzcD6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:28 INFO - Running setup.py (path:/tmp/pip-1H8lQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:28 INFO - Running setup.py (path:/tmp/pip-MZFoIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:28 INFO - Running setup.py (path:/tmp/pip-Vtea1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:29 INFO - Running setup.py (path:/tmp/pip-AYrFof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:29 INFO - Running setup.py (path:/tmp/pip-TiMovU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:29 INFO - Running setup.py (path:/tmp/pip-S1BO0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:29 INFO - Running setup.py install for manifestparser 04:13:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozcrash 04:13:29 INFO - Running setup.py install for mozdebug 04:13:29 INFO - Running setup.py install for mozdevice 04:13:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozfile 04:13:29 INFO - Running setup.py install for mozhttpd 04:13:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozinfo 04:13:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozInstall 04:13:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Running setup.py install for mozleak 04:13:30 INFO - Running setup.py install for mozlog 04:13:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Running setup.py install for moznetwork 04:13:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Running setup.py install for mozprocess 04:13:30 INFO - Running setup.py install for mozprofile 04:13:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Running setup.py install for mozrunner 04:13:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Running setup.py install for mozscreenshot 04:13:30 INFO - Running setup.py install for moztest 04:13:30 INFO - Running setup.py install for mozversion 04:13:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:31 INFO - Cleaning up... 04:13:31 INFO - Return code: 0 04:13:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:31 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:31 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 04:13:31 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 04:13:31 INFO - Using env: {'DISPLAY': ':0', 04:13:31 INFO - 'HOME': '/home/cltbld', 04:13:31 INFO - 'LANG': 'en_US.UTF-8', 04:13:31 INFO - 'LANGUAGE': 'en_US:en', 04:13:31 INFO - 'LOGNAME': 'cltbld', 04:13:31 INFO - 'MAIL': '/var/mail/cltbld', 04:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:31 INFO - 'MOZ_NO_REMOTE': '1', 04:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:31 INFO - 'NO_EM_RESTART': '1', 04:13:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:31 INFO - 'PWD': '/builds/slave/test', 04:13:31 INFO - 'SHELL': '/bin/bash', 04:13:31 INFO - 'SHLVL': '1', 04:13:31 INFO - 'TERM': 'linux', 04:13:31 INFO - 'TMOUT': '86400', 04:13:31 INFO - 'USER': 'cltbld', 04:13:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:31 INFO - '_': '/tools/buildbot/bin/python'} 04:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:31 INFO - Running setup.py (path:/tmp/pip-lM4QKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:31 INFO - Running setup.py (path:/tmp/pip-mmjVdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:31 INFO - Running setup.py (path:/tmp/pip-Cl3RVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:31 INFO - Running setup.py (path:/tmp/pip-C33DzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:31 INFO - Running setup.py (path:/tmp/pip-x701bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:31 INFO - Running setup.py (path:/tmp/pip-i_zG6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:31 INFO - Running setup.py (path:/tmp/pip-2XiUsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:31 INFO - Running setup.py (path:/tmp/pip-9sQrNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:31 INFO - Running setup.py (path:/tmp/pip-RX24VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:31 INFO - Running setup.py (path:/tmp/pip-zuS1ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:32 INFO - Running setup.py (path:/tmp/pip-gWNPqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:32 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:32 INFO - Running setup.py (path:/tmp/pip-kNXVCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:32 INFO - Running setup.py (path:/tmp/pip-deAksS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:32 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:32 INFO - Running setup.py (path:/tmp/pip-zwbPf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:32 INFO - Running setup.py (path:/tmp/pip-JONqVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:32 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:32 INFO - Running setup.py (path:/tmp/pip-oj_Wup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:32 INFO - Running setup.py (path:/tmp/pip-uogf8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:32 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)) 04:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:13:32 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)) 04:13:32 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:13:32 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:13:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:32 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 04:13:32 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 04:13:32 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 04:13:32 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 04:13:32 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 04:13:32 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 04:13:34 INFO - Downloading blessings-1.6.tar.gz 04:13:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:34 INFO - Installing collected packages: blessings 04:13:34 INFO - Running setup.py install for blessings 04:13:34 INFO - Successfully installed blessings 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:34 INFO - Reading from file tmpfile_stdout 04:13:34 INFO - Current package versions: 04:13:34 INFO - argparse == 1.2.1 04:13:34 INFO - blessings == 1.6 04:13:34 INFO - blobuploader == 1.2.4 04:13:34 INFO - docopt == 0.6.1 04:13:34 INFO - functools32 == 3.2.3-2 04:13:34 INFO - jsonschema == 2.5.1 04:13:34 INFO - manifestparser == 1.1 04:13:34 INFO - mozInstall == 1.12 04:13:34 INFO - mozcrash == 0.17 04:13:34 INFO - mozdebug == 0.1 04:13:34 INFO - mozdevice == 0.48 04:13:34 INFO - mozfile == 1.2 04:13:34 INFO - mozhttpd == 0.7 04:13:34 INFO - mozinfo == 0.9 04:13:34 INFO - mozleak == 0.1 04:13:34 INFO - mozlog == 3.3 04:13:34 INFO - moznetwork == 0.27 04:13:34 INFO - mozprocess == 0.23 04:13:34 INFO - mozprofile == 0.28 04:13:34 INFO - mozrunner == 6.12 04:13:34 INFO - mozscreenshot == 0.1 04:13:34 INFO - mozsystemmonitor == 0.3 04:13:34 INFO - moztest == 0.7 04:13:34 INFO - mozversion == 1.4 04:13:34 INFO - psutil == 3.1.1 04:13:34 INFO - requests == 1.2.3 04:13:34 INFO - wsgiref == 0.1.2 04:13:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:34 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 04:13:34 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 04:13:34 INFO - Using env: {'DISPLAY': ':0', 04:13:34 INFO - 'HOME': '/home/cltbld', 04:13:34 INFO - 'LANG': 'en_US.UTF-8', 04:13:34 INFO - 'LANGUAGE': 'en_US:en', 04:13:34 INFO - 'LOGNAME': 'cltbld', 04:13:34 INFO - 'MAIL': '/var/mail/cltbld', 04:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:34 INFO - 'MOZ_NO_REMOTE': '1', 04:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:34 INFO - 'NO_EM_RESTART': '1', 04:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:34 INFO - 'PWD': '/builds/slave/test', 04:13:34 INFO - 'SHELL': '/bin/bash', 04:13:34 INFO - 'SHLVL': '1', 04:13:34 INFO - 'TERM': 'linux', 04:13:34 INFO - 'TMOUT': '86400', 04:13:34 INFO - 'USER': 'cltbld', 04:13:34 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:34 INFO - '_': '/tools/buildbot/bin/python'} 04:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 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)) 04:13:35 INFO - Cleaning up... 04:13:35 INFO - Return code: 0 04:13:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:35 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 0x2842030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b03180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, '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': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', '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 04:13:35 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 04:13:35 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 04:13:35 INFO - Using env: {'DISPLAY': ':0', 04:13:35 INFO - 'HOME': '/home/cltbld', 04:13:35 INFO - 'LANG': 'en_US.UTF-8', 04:13:35 INFO - 'LANGUAGE': 'en_US:en', 04:13:35 INFO - 'LOGNAME': 'cltbld', 04:13:35 INFO - 'MAIL': '/var/mail/cltbld', 04:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:35 INFO - 'MOZ_NO_REMOTE': '1', 04:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:35 INFO - 'NO_EM_RESTART': '1', 04:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:35 INFO - 'PWD': '/builds/slave/test', 04:13:35 INFO - 'SHELL': '/bin/bash', 04:13:35 INFO - 'SHLVL': '1', 04:13:35 INFO - 'TERM': 'linux', 04:13:35 INFO - 'TMOUT': '86400', 04:13:35 INFO - 'USER': 'cltbld', 04:13:35 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:35 INFO - '_': '/tools/buildbot/bin/python'} 04:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:35 INFO - Cleaning up... 04:13:35 INFO - Return code: 0 04:13:35 INFO - Running post-action listener: _resource_record_post_action 04:13:35 INFO - Running post-action listener: _start_resource_monitoring 04:13:35 INFO - Starting resource monitoring. 04:13:35 INFO - [mozharness: 2016-08-18 11:13:35.401915Z] Finished create-virtualenv step (success) 04:13:35 INFO - [mozharness: 2016-08-18 11:13:35.402390Z] Running install step. 04:13:35 INFO - Running pre-action listener: _resource_record_pre_action 04:13:35 INFO - Running main action method: install 04:13:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:35 INFO - Reading from file tmpfile_stdout 04:13:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:35 INFO - Reading from file tmpfile_stdout 04:13:35 INFO - Output received: 04:13:35 INFO - Usage: mozinstall [options] installer 04:13:35 INFO - Options: 04:13:35 INFO - -h, --help show this help message and exit 04:13:35 INFO - -d DEST, --destination=DEST 04:13:35 INFO - Directory to install application into. [default: 04:13:35 INFO - "/builds/slave/test"] 04:13:35 INFO - --app=APP Application being installed. [default: firefox] 04:13:35 INFO - mkdir: /builds/slave/test/build/application 04:13:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:13:51 INFO - Reading from file tmpfile_stdout 04:13:51 INFO - Output received: 04:13:51 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:51 INFO - Running post-action listener: _resource_record_post_action 04:13:51 INFO - [mozharness: 2016-08-18 11:13:51.672882Z] Finished install step (success) 04:13:51 INFO - [mozharness: 2016-08-18 11:13:51.673227Z] Running run-tests step. 04:13:51 INFO - Running pre-action listener: _resource_record_pre_action 04:13:51 INFO - Running main action method: run_tests 04:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:51 INFO - Python 2.7.3 04:13:51 INFO - Return code: 0 04:13:51 INFO - grabbing minidump binary from tooltool 04:13:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:51 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 0x2b013b0>, '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 0x2b022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b02740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:51 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 04:13:51 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 04:13:51 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 04:13:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:51 INFO - Return code: 0 04:13:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:13:51 INFO - Using env: {'DISPLAY': ':0', 04:13:51 INFO - 'HOME': '/home/cltbld', 04:13:51 INFO - 'LANG': 'en_US.UTF-8', 04:13:51 INFO - 'LANGUAGE': 'en_US:en', 04:13:51 INFO - 'LOGNAME': 'cltbld', 04:13:51 INFO - 'MAIL': '/var/mail/cltbld', 04:13:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:51 INFO - 'MOZ_NO_REMOTE': '1', 04:13:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:51 INFO - 'NO_EM_RESTART': '1', 04:13:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:51 INFO - 'PWD': '/builds/slave/test', 04:13:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:51 INFO - 'SHELL': '/bin/bash', 04:13:51 INFO - 'SHLVL': '1', 04:13:51 INFO - 'TERM': 'linux', 04:13:51 INFO - 'TMOUT': '86400', 04:13:51 INFO - 'USER': 'cltbld', 04:13:51 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132', 04:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:51 INFO - '_': '/tools/buildbot/bin/python'} 04:13:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:13:51 INFO - mozversion application_buildid: 20160817151514 04:13:51 INFO - mozversion application_changeset: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c 04:13:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:13:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:51 INFO - mozversion application_name: Firefox 04:13:51 INFO - mozversion application_remotingname: firefox-dev 04:13:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:51 INFO - mozversion application_vendor: Mozilla 04:13:51 INFO - mozversion application_version: 50.0a2 04:13:51 INFO - mozversion platform_buildid: 20160817151514 04:13:51 INFO - mozversion platform_changeset: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c 04:13:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:51 INFO - mozversion platform_version: 50.0a2 04:13:51 INFO - using testdate: 1471518831 04:13:51 INFO - actual date: 1471518831 04:13:51 INFO - starting webserver on 'localhost:34053' 04:13:51 INFO - SUITE-START | Running 2 tests 04:13:51 INFO - TEST-START | dromaeo_css 04:13:51 INFO - operating with platform_type : linux_ 04:13:51 INFO - Initialising browser for dromaeo_css test... 04:13:51 INFO - TEST-INFO | started process 15016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFPV43m/profile http://localhost:34053/getInfo.html) 04:13:55 INFO - PROCESS | 15016 | __metrics Screen width/height:1600/1200 04:13:55 INFO - PROCESS | 15016 | colorDepth:24 04:13:55 INFO - PROCESS | 15016 | Browser inner width/height: 1024/697 04:13:55 INFO - PROCESS | 15016 | __metrics 04:13:56 INFO - PROCESS | 15016 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:13:56 INFO - PROCESS | 15016 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:13:57 INFO - TEST-INFO | 15016: exit 0 04:13:57 INFO - Browser initialized. 04:13:57 INFO - Running cycle 1/1 for dromaeo_css test... 04:13:57 INFO - TEST-INFO | started process 15103 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFPV43m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:08 INFO - PROCESS | 15103 | RSS: Main: 145281024 04:14:08 INFO - PROCESS | 15103 | 04:15:00 INFO - PROCESS | 15103 | 1471518900555 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:17:08 INFO - PROCESS | 15103 | Cycle 1(1): loaded http://localhost:34053/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34053/tests/dromaeo/cssquery-ext.html) 04:17:09 INFO - PROCESS | 15103 | RSS: Main: 224202752 04:17:09 INFO - PROCESS | 15103 | 04:20:11 INFO - PROCESS | 15103 | Cycle 1(1): loaded http://localhost:34053/tests/dromaeo/cssquery-ext.html (next: http://localhost:34053/tests/dromaeo/cssquery-jquery.html) 04:20:12 INFO - PROCESS | 15103 | RSS: Main: 229974016 04:20:12 INFO - PROCESS | 15103 | 04:23:14 INFO - PROCESS | 15103 | Cycle 1(1): loaded http://localhost:34053/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34053/tests/dromaeo/cssquery-mootools.html) 04:23:14 INFO - PROCESS | 15103 | RSS: Main: 197996544 04:23:14 INFO - PROCESS | 15103 | 04:26:16 INFO - PROCESS | 15103 | Cycle 1(1): loaded http://localhost:34053/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34053/tests/dromaeo/cssquery-prototype.html) 04:26:17 INFO - PROCESS | 15103 | RSS: Main: 175640576 04:26:17 INFO - PROCESS | 15103 | 04:29:19 INFO - PROCESS | 15103 | Cycle 1(1): loaded http://localhost:34053/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34053/tests/dromaeo/cssquery-yui.html) 04:29:20 INFO - PROCESS | 15103 | RSS: Main: 176574464 04:29:20 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | Cycle 1(1): loaded http://localhost:34053/tests/dromaeo/cssquery-yui.html (next: http://localhost:34053/tests/dromaeo/cssquery-dojo.html) 04:32:23 INFO - PROCESS | 15103 | RSS: Main: 264347648 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | __start_tp_report 04:32:23 INFO - PROCESS | 15103 | _x_x_mozilla_page_load 04:32:23 INFO - PROCESS | 15103 | _x_x_mozilla_page_load_details 04:32:23 INFO - PROCESS | 15103 | |i|pagename|runs| 04:32:23 INFO - PROCESS | 15103 | |0;dojo.html;3184.172115250035;3204.054803832869;3214.100051985444;3231.3052693670857;3250.918727031824;5566.582506312027;5593.300837395325;5603.80386686466;5637.802676906309;5638.041532939405;5531.9723401383035;5593.944060559394;5595.552355811536;5606.915896261549;5629.324481062268;5006.374203224597;5015.9247611285955;5055.848324550269;5100.897982040376;5136.024155410479;4953.975230123844;5086.821961231358;5124.308218390525;5137.5633071188995;5138.768755405997;5005.199168133099;5047.141985862394;5060.822871199509;5080.745962701869;5087.974560127194;5165.586753059746;5169.663971841837;5175.81884634036;5178.352705911761;5205.843824685266;4911.091448082117;4997.875053123665;5057.064443078006;5061.544460998528;5065.341505604285;5447.1829225616075;5466.753996070186;5477.123660214346;5527.889442211133;5539.833804985857;6000.939990600082;6020.939790602081;6031.4270144336215;6042.5165986721395;6045.939540604581;5951.345352011275;5982.970085149546;5996.13056106862;6053.909191362133;6075;5650.576206784466;5692.743826527775;5704.429557044263;5705.515031222351;5708.800191993343;5998.550108741862;6171.290301615282;6176.722047507783;6179.227596550431;6180.629162250189;5736.340320863071;5738.741756620962;5754.367019627837;5884.0585506318785;5886.087656413263;5915.881682366328;5950.107483877367;5951.9107213391835;5953.880922381615;5955.37468565803;5838.591298609056;5905.76376944926;5914.9112763308585;5920.052791553331;5923.881522369615;5870;5893.469587737124;5915.467607915309;5988.730507127189;6028.5478589106;4583.037562111994;4585.151746926829;4585.335126406652;4587.839425620087;4620.3993480847275;3987.302222111119;4091.734037287567;4099.754014759064;4105.322621767435;4118.217538667644;5521.889562208733;5572.888542229214;5584.553235741131;5611.3266408031295;5651.971740141273;5929.703514824328;5953.374895635982;5969.432903874125;5977.402259773988;5990.161377407167;5483.561315094783;5637.943620563824;5639.154126880919;5643.040683083886;5643.5767317451355;4999.325091112653;5047.646664733434;5067.037262920107;5104.617153713412;5129.8717532061255;6238.064290356392;6239.1577137085915;6243.843903902348;6244.313125556276;6257.937420625735;5899.616524925866;6086.421789929949;6087.208662873798;6094.481969032505;6098.969505152623;6173.876522469435;6196.225471816023;6220.844478887883;6231.189945307081;6231.532635052298;5838.737256823369;5902.232709747877;5904.291485021825;5905.498032667271;5931;5875.441833025856;6006.789762358297;6031.788887388921;6032.065029920369;6033.668148251712;5772.971135144466;5827.533797296291;5829.38791426911;5878.088896221093;5922.73347699346;5863.2670916135485;5870.471657550841;5870.9412905870395;5872.031114865999;5873.794417195549;5658.292713410824;5787.074068149076;5821.70891455417;5824.912626310694;5834.445727655866;5781.682007489629;5817.94182058174;5820.417958204315;5876.471117599436;5889.67606781614;5724.713764311851;5734.655920644775;5734.827955161351;5736.368999409978;5737.397573254748 04:32:23 INFO - PROCESS | 15103 | |1;ext.html;609.7957184343245;650.291182610954;664.4916638771341;666.8799616069107;671.345438197757;34959.30081398374;45961.080778384414;47219.76390118053;47258.763706181424;47327.29009064867;48041.51958480414;48444;48541.51458485414;48629;48634.27048594265;8353.498790072585;9300.674476393327;9449.055094490564;9453.669121580746;10925.07136893365;10857.945710271437;11343.602973895873;11344.773104537904;11351.262167959056;11354.886451135506;11285.4921528531;11318.660440186808;11325.093992480583;11341;11348.546058157664;11279.605213817476;11291;11349.716257093556;11351.545938162464;11358.148138889534;11089.001989820874;11150.832737508943;11156.386398748065;11161.051310638606;11163.218574699775;46227.537724622656;46629;46713.76643116763;46723.53276467226;46731.29903051457;3823.636754508317;3908.4918960535233;3930;3937.76373417593;4131.607497287753;34614.850660425924;34890.47664285038;34904.30191396183;35095.824520877235;35184.47223291652;4572.291294849304;4595.517470665565;4618.515055919113;4668.85993420198;4723.4568024677255;269758;269833;271049;271601.64199179073;271817;62023;62145.37854621396;62280;62314.68842655758;62367;39284.80357598194;39957.40063899044;39970.800145999085;41641.58358416438;64418;19427.902860485607;19544;19548.413547593595;19553.315633952745;19555.90222048909;13977.510787122403;14903.180325082225;15083.773743393858;15267.618309542127;15337.923310383376;20618.690719639217;20757.481062973548;20774.169033238803;20860.165593376398;20880.37358879236;7494.475386722877;7643.70057090296;7802.765917022499;7835.764927052198;7909.762707118795;1076.1498416251231;1179.151011271883;1183.2782002978176;1186.887245711656;1233.8519377674556;32501.837490812395;34772.82613586916;34876.65123348734;35080.298394032485;35152.296954060774;1149.126663735567;1149.201305092969;1162.3955543117531;1195.2051885496048;1213.4782043721284;18093.457196284133;18194.63610727778;18256.178471968793;18334.266629334677;18435.26258949627;589024;593614;593696;594068;594175;64037.35962640407;64627.35372646307;64722.676386617764;64798.352016479235;64858;1343.4693296147966;1392.3803907261374;1399.6640806206437;1481.6740317130213;1491.8517335037511;33417;35869.64130358663;36000.27999440049;36038;36081.819590901876;52931;53454.73272633767;53594;53641.19538207008;53678.46321536891;443645;446648;447370;448109;448568;30950.0714978551;31253.84373078211;31334.216644584067;32089.358212835145;32729.18177045593;53043.20435193552;53118;53362;53384.46615533795;55054;44724.32913506365;45214.32178517259;45278.773606131756;46018.53981460143;46651;44083;45578.31632525581;46500.30249546327;46525;46642.300365495226;43732;44369;44759;44818.327725083494;44868.775656121514;37126.6287337134;39120;39239;39674.40488392619;39835;29676.53858418398;29884.402311954073;30016.24959376033;30142.246443839078;30233.395332093674 04:32:23 INFO - PROCESS | 15103 | |2;jquery.html;397.75339289640425;414.6226933490522;420.75806411313494;422.4951183335913;436.85583757360075;15648.608784780386;20477.897610511925;20675.483112922186;20739.585208295844;20947.895260523695;19955.201791928343;20002.799972000277;20026.69959950602;20030.49923751907;20189.59620807583;5492.945070549304;5718.599698021119;5781.306243250816;5945.4946329562035;5968.522518198533;5858.73635686393;5899.439553242435;5906.734196961147;5907.202527658754;5912.408759124075;5653.4911857932775;5878.235829342179;5909.20225769522;5976.940230597704;5984.3417224105315;5819.33077696064;5828.591998560081;5851.561132915027;5871.412858714137;5882.97058514709;5670.4896559309445;5671.432856714337;5712.600117991742;5724.570657200685;5741.110127930177;22399.552008959894;23226.65160022601;23357.7664223359;24116.276511704677;24774.25677229686;2527.2544599343237;2634.3019099938533;2667.8132530722855;2986.79319261293;2992.9551056734167;10601.310914790514;10728.946355268174;10961.451927403597;11085.778284434266;11179.720506987309;1975.7727861295848;2002.7396438462906;2021.858469907107;2024.5242368043498;2056.0979851432235;78286.60856695737;83997;84930;85150.14849851547;85274.5736271321;11572.6528204154;11621.825672614917;11743.765124697458;12111.515539378326;12802.487900483877;16766.245518951677;16784.664306713796;16785.076820774975;16797.748033779502;16817.41139060127;8562.016471175442;9217.907820921704;9253.907460925438;9293.838270216223;9413.152816246571;9062.003179650234;9072.909270907207;9131.680391186277;9292.767680808034;9295.58169882357;4932.605391568666;5032.874178145576;5056.772445239973;5136.998285334324;5214.556762675215;2064.9277275295294;2087.8956052197327;2094.549671820566;2098.9580208395746;2139.1978008246906;982.6658935961807;1033.78530226983;1045.4459136657583;1088.6679562733443;1102.1072930925975;19987.700184497244;20383.694244586342;20581;20783.480412989797;20909.477263068242;1028.0285130551556;1082.5345101606385;1135.3925649777495;1137.4312843578211;1162.9476673549711;11404.087672986141;11676.76646467066;11714.648560543197;11741.76516469683;11771.587994420153;436510;477592;483221;498677;498852;96218;96515;97082.51458742803;98360;98716;1049.6431213387484;1085.8208395614636;1138.2771939818274;1143.1883362812496;1156.1271240213584;17545.859519131216;17945.371911983017;18367.265309387476;18397.81602183961;18639.627207455505;41203.38194927019;41562.765585162466;41893;42485.5751442494;42623;62996.37003629905;63287.36712632815;63930.3606963943;64341.35658643541;64680.35319646743;8267.379946504156;9151.496667683341;9229.400088994194;9367.812643747224;9420.623175073195;13423.194608323533;13669.111507751964;13700.520481783089;13883.583492495241;13931.164130152223;11908.225965312227;12050.879491204976;12058.638240852437;12067.215630983957;12213.084018698455;10512.737181570215;11016.559337626726;11378.658640240807;11679.240849344766;11958.222715523463;11779.998700110244;12178.451969661564;12252.142350035412;12324.50701971911;12349.444275007825;8940.68707595231;9060.275177985613;9130.223930965942;9218.400803947721;9411.529423528933;11924.344161071225;11958.162928594915;12051.33717645538;12077;12148.635540933787 04:32:23 INFO - PROCESS | 15103 | |3;mootools.html;1437.187988786335;1530.563789320044;1588.5313832419436;1787.5888545634505;1906.3137270582579;8590.355723320752;9095.499747513884;9165.358424910251;9331.813363732721;9440.244780417568;9092.863607045883;9244.445333280008;9245.768855778591;9285.210757085639;9353.251739860812;2452.460458699083;2454.2759885833634;2454.7790698837102;2456.2876765737915;2484.1553871683623;2415.7463466336044;2475.6039033754605;2500.4499010217723;2502.78726308264;2502.9374265643323;2432.1001229545004;2450.301664025753;2515.5849284867977;2515.8993640254366;2518.40817407909;2374.82188835838;2395.7484464131276;2398;2416.3355077353867;2427.9514409711883;2360.5632957902844;2363.6690863279155;2364.113457453455;2367.3844800351862;2385.785279324869;9062.637494500212;9388.436693798325;9461.24310055194;9479.905200947971;9490.145886870147;5562.415946325658;5704.286964129484;5738.856528586777;5743.253377060955;5754.597178197528;3513.1568423578155;3528.541289632357;3534.6995505382074;3575.0704816747575;3589.3898037333715;1126.8591426071741;1141.5890279499374;1146.919715619907;1147.23708733692;1151.0848875144281;13423.597292081253;13481.865181348257;13505.527306544223;13514.526991555249;13540.526081587097;4687.867618226013;4794.256890182028;4846.36997190363;4906.31311616374;4965.205567109318;10526.52630631608;10534.36793792375;10539.525721342558;10545.156387488982;10551.103156231806;5645.463680950385;5669.574781891375;5685.948099601587;5690.829275121753;5715.628484148517;4672.836450724209;4754.358161648202;5161.200013997836;5167.328247327823;5255.185446255837;4199.958000420018;4221.430106935586;4244.957550424456;4307.956920430818;4363.38912552243;1275.298585777837;1303.7066660001612;1311.9212847229196;1316.4010375279224;1317.967050823737;1046.4610725476387;1078.52544880254;1084.0677017764715;1087.445402844539;1092.748667806393;6482.805515834532;6544.345565443417;6547.738090476423;6548.836279092966;6626.79236314666;484.0681687751065;497.48758778458244;1105.2318638546294;1155.9826602600967;1183.6922400176015;4414.713043652152;4523.344115103358;4629.120467111238;4658.464276608166;4711.717296962193;12085.818712719316;12528.310942898228;12540.310282934344;12547.435365408579;12569.748605028031;11686.824697629543;12420.316882571546;12490.000799935984;12499.937505312151;12523.749525009449;8801.427907186013;8989.505577193317;9007.189352958265;9010.684626038057;9021.594028268744;6442.8389290268115;6452.645104519296;6485.3514648534765;6551.934480655323;6697.3637504437;10513.89486105129;11124.276922000045;11131.499082541142;11207.943960280147;11265.380404077854;10440.42577658236;10677.14582833356;11181;11298;11418.029467495357;7966.920330796618;8196.713115040944;8230.711925082593;8276.544790036605;8303.501789892625;9159.129882661136;9491.288153388385;9502.85745713801;9519.428834269966;9525.333226674065;8310.252077313071;8468.618912148844;8491.532965686947;8494.32045436376;8680.91319086801;8387.119352467904;8409.789755256168;8437.831243375222;8463.703770368007;8571.657133714582;7421.257874212535;7440.813979650336;7447.92552074494;7481.513701609378;7487.925120748723;5585.106382978704;5591.356993945586;5620.1288800236025;5630.549556035589;5824.475797178275;5816.9127463088935;5869.7945571904775;5876.294844618673;5997.580169388101;6012.158297838394 04:32:23 INFO - PROCESS | 15103 | |4;prototype.html;346.1053177536069;349.7761432683083;350.5285391148904;355.6017260668052;359.4141549274682;5449.128139497681;5459.563234941205;5486.174620070657;5498.862528436786;5503.889922201563;5418.783248670048;5457;5458.61789674722;5470.753816078276;5472.20653005314;3807.714421418391;4130.628243458088;4278.957210427887;4289.764062976535;4309.806058727349;4276.294411422112;4279.951411904088;4285.957140428587;4307.267764480046;4312.116016216668;4178.164367126572;4290.206311832321;4317.352397140438;4319.3952846601505;4329.935050974238;4235.682323825726;4273;4278.679099067551;4284.67864910133;4304.988327742978;3830.616938306175;3846.11539345952;3850.1144736710435;3855.306044911915;3860.6718428933573;5247.737613119329;5394.271773310592;5394.757235924393;5395.460453954613;5402.675839449606;4912.852614421573;4920.409550853885;4924.138275801756;4938.80244790208;4967.7764500597195;5666.546676265889;5667.404922483162;5695.547635352958;5740.4833564978935;5780.855478613026;4086.4483294755123;4247.745135291892;4249.915001699948;4250.957490425118;4257.489101307832;5638.759472915952;5782.942170578241;5920.56607167846;5937.762489500415;6009.56612571909;4329.675274354436;4342.717723347972;4355.651547876162;4362.585554372329;4366.956330436718;4401.559844015573;4412.6690498212765;4412.955870441318;4414.580614841585;4414.713043652152;4861.1979023460735;4973.726445045557;4978.85486338137;4978.90042199154;5020.673656212334;5669.206311116447;5679.545636349082;5684.715764211773;5705.343885453226;5767.336756273083;1497.7304085264757;1508.7929656275155;1513.3870782333172;1525.9694806103817;1527.1905889878383;925.8981512033671;960.841461158915;977.7360112769654;979.2508730820928;980.9362391444675;4794.7842347093765;4888.095702295087;4908.650121216622;4909;4916.877078073006;5123.2827404163445;5123.666961647481;5145.665531740499;5160.277561141444;5194.454582268883;4585.885352866138;4868.123737727243;4919.557239848431;4923.113839508851;4941.876453088629;2722.0200727737997;2724.2644485989067;2746.258510202234;2747.8900843966017;2756.641636587271;5261.736913154327;5300.231466437422;5315.893682126336;5339.279197308391;5350.8127215547265;5234.712090835041;5247.449017853147;5678.744456499385;6004.3395226525045;6009.789657361971;6094.720108777206;6096.908546371808;6118.418750218722;6141.355157708465;6159.84600384994;5661.405552416937;5941.2176347096565;5953.91069133972;6042.217545823904;6044.425779550936;5758.971205143947;5823.301203855564;5835.883282334415;5862.296524416926;5867.73595188209;5853.824385268449;5940.16837642739;5961.612495187799;5963.314218864886;6127.417895299939;4259.93610095855;4267.509236437725;4270.935935960901;4322.351647252937;4340.848070317524;5828.737706803119;5977.940220597912;6007.639541627516;6011.519078473624;6015.428534289411;5737.340205876378;5747.442388137239;5760.135979603093;5779.884402312015;5810.506106980785;5737.56968227393;5740.942590574041;5743.684097374685;5825.417458254141;5827.484853937922;5550.972245138749;5722.456366645162;5726.599138060295;5744;5778.739956701875;5811.4479124483105;5822.563307752021;5835.299764028344;5843.474087332161;5853.619514731699;5430.782768689208;5693.231413759255;5709;5765.56758243125;5786.479216870333 04:32:23 INFO - PROCESS | 15103 | |5;yui.html;815.9102498725143;828.7928017995501;830.323286521485;886.7605746448455;938.9530523473827;16435.671286574263;17582.945023298613;17654.47036588903;17695;17704.557386065353;15721.449749258778;15978.600534986603;16372.754408683879;16572.088535130562;16630.50108496747;738.0995185873239;894.6332003878412;906.3293163059354;920.8434566123775;929.005963618928;918.1323649151554;921.1111277617098;922.8015976565038;926.0646746785761;926.3330402110504;749.6626518066864;924.694850699271;956.1442508954482;967.3857100741045;968.1045033344163;948.2177203806853;953.4803532075035;956.086936975187;956.4357029352681;956.8708224389723;696.4463251714899;697.6197972105215;697.9057827193315;698.5913240754165;698.6471831724997;16502.917485412498;16584.917075414665;16611.6677666446;16617.252223649964;16657.583560410967;15323.157226352547;15467.226638668135;15505;15591.766123508156;15609.531714048597;14002.92998535011;14046.227457489835;14089.238845144357;14203.78694319586;14361.569152925324;4874.1713908635265;4891.682040667345;4920.803167873317;4960.429550601691;5068.670536415121;86319;86434;89955;90342.99999999935;91343;12402.379881005914;12485.812712809315;12494.687632809253;12548.874511254953;12798.61604151877;22623.886880565493;22645.320640380487;22801.31596052121;22808.20171293997;22830.42923926915;9095.272378209727;9133.954330228305;9150.862737058816;9270.675526356545;9296.454834815135;5093.567046800989;5102.515261050195;5181.611379146579;5233.162693968947;5323.015242180131;3550.5384300041346;3653.707703383723;3664.871729489455;3667.1932174921476;3667.559892812826;627.7457629659995;642.9164208652845;666.3037126203129;691.0325544238107;696.989545156823;659.2682122843639;708.3943228539628;761.9466637335444;784.3019712455919;848.3425345357274;10561.735956600993;10723.410212438235;10948.39783811882;11695.532178712927;11758.529658813723;758.9658465369072;778.1867947994359;790.8339248757825;817.8814071959532;829.9709510167115;4936.259561065869;5320.334958130234;5336.199570064521;5347.278117454094;5437.13005919051;75891.62054189693;79568;101621;132996.3350183243;142164.28917855344;69159;70406;95524;112551;121680.39159804145;59978.66269693586;90516.5474172638;91611.54194228986;92407.53796230975;103487;10043.799124017332;10080.69757907264;10086.596536138766;10086.8486972694;10276.486175691036;37478;40658;42196.57803421927;44305.11389772251;48303;51659.741701291256;53122;66965.3303466959;70678.29321706717;70860.29139708537;14445;14780.556583302518;14969.700605988037;15020.849791501945;15034.774478383051;16324.918375408048;16339.836601633831;16795.748063779298;17011.149442527578;17189.31242750276;12625.81061284063;13051.738965220453;14033.719325613227;15264.465743698916;15716.92141539285;14017.789733153806;14133.363998619881;15032.248387580796;15100.169490678118;15625.76561351558;14576.635584110058;14627.110015272694;14846.703065938837;15023.699526009637;15028.474003409829;12166;13042.4130914111;13400.530981415994;13494.595162145151;13682.247476388497;12466.5013399463;12711.110222284351;12758.61724148277;12929;13200.669983250496 04:32:23 INFO - PROCESS | 15103 | __end_tp_report 04:32:23 INFO - PROCESS | 15103 | __start_cc_report 04:32:23 INFO - PROCESS | 15103 | _x_x_mozilla_cycle_collect,1021 04:32:23 INFO - PROCESS | 15103 | __end_cc_report 04:32:23 INFO - PROCESS | 15103 | __startTimestamp1471519943495__endTimestamp 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | ------- Summary: start ------- 04:32:23 INFO - PROCESS | 15103 | Number of tests: 6 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | [#0] dojo.html Cycles:165 Average:5550.02 Median:5738.74 stddev:636.09 (11.1%) stddev-sans-first:610.37 04:32:23 INFO - PROCESS | 15103 | Values: 3184.2 3204.1 3214.1 3231.3 3250.9 5566.6 5593.3 5603.8 5637.8 5638.0 5532.0 5593.9 5595.6 5606.9 5629.3 5006.4 5015.9 5055.8 5100.9 5136.0 4954.0 5086.8 5124.3 5137.6 5138.8 5005.2 5047.1 5060.8 5080.7 5088.0 5165.6 5169.7 5175.8 5178.4 5205.8 4911.1 4997.9 5057.1 5061.5 5065.3 5447.2 5466.8 5477.1 5527.9 5539.8 6000.9 6020.9 6031.4 6042.5 6045.9 5951.3 5983.0 5996.1 6053.9 6075.0 5650.6 5692.7 5704.4 5705.5 5708.8 5998.6 6171.3 6176.7 6179.2 6180.6 5736.3 5738.7 5754.4 5884.1 5886.1 5915.9 5950.1 5951.9 5953.9 5955.4 5838.6 5905.8 5914.9 5920.1 5923.9 5870.0 5893.5 5915.5 5988.7 6028.5 4583.0 4585.2 4585.3 4587.8 4620.4 3987.3 4091.7 4099.8 4105.3 4118.2 5521.9 5572.9 5584.6 5611.3 5652.0 5929.7 5953.4 5969.4 5977.4 5990.2 5483.6 5637.9 5639.2 5643.0 5643.6 4999.3 5047.6 5067.0 5104.6 5129.9 6238.1 6239.2 6243.8 6244.3 6257.9 5899.6 6086.4 6087.2 6094.5 6099.0 6173.9 6196.2 6220.8 6231.2 6231.5 5838.7 5902.2 5904.3 5905.5 5931.0 5875.4 6006.8 6031.8 6032.1 6033.7 5773.0 5827.5 5829.4 5878.1 5922.7 5863.3 5870.5 5870.9 5872.0 5873.8 5658.3 5787.1 5821.7 5824.9 5834.4 5781.7 5817.9 5820.4 5876.5 5889.7 5724.7 5734.7 5734.8 5736.4 5737.4 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | [#1] ext.html Cycles:180 Average:61650.43 Median:39179.50 stddev:121157.82 (309.2%) stddev-sans-first:121411.02 04:32:23 INFO - PROCESS | 15103 | Values: 609.8 650.3 664.5 666.9 671.3 34959.3 45961.1 47219.8 47258.8 47327.3 48041.5 48444.0 48541.5 48629.0 48634.3 8353.5 9300.7 9449.1 9453.7 10925.1 10857.9 11343.6 11344.8 11351.3 11354.9 11285.5 11318.7 11325.1 11341.0 11348.5 11279.6 11291.0 11349.7 11351.5 11358.1 11089.0 11150.8 11156.4 11161.1 11163.2 46227.5 46629.0 46713.8 46723.5 46731.3 3823.6 3908.5 3930.0 3937.8 4131.6 34614.9 34890.5 34904.3 35095.8 35184.5 4572.3 4595.5 4618.5 4668.9 4723.5 269758.0 269833.0 271049.0 271601.6 271817.0 62023.0 62145.4 62280.0 62314.7 62367.0 39284.8 39957.4 39970.8 41641.6 64418.0 19427.9 19544.0 19548.4 19553.3 19555.9 13977.5 14903.2 15083.8 15267.6 15337.9 20618.7 20757.5 20774.2 20860.2 20880.4 7494.5 7643.7 7802.8 7835.8 7909.8 1076.1 1179.2 1183.3 1186.9 1233.9 32501.8 34772.8 34876.7 35080.3 35152.3 1149.1 1149.2 1162.4 1195.2 1213.5 18093.5 18194.6 18256.2 18334.3 18435.3 589024.0 593614.0 593696.0 594068.0 594175.0 64037.4 64627.4 64722.7 64798.4 64858.0 1343.5 1392.4 1399.7 1481.7 1491.9 33417.0 35869.6 36000.3 36038.0 36081.8 52931.0 53454.7 53594.0 53641.2 53678.5 443645.0 446648.0 447370.0 448109.0 448568.0 30950.1 31253.8 31334.2 32089.4 32729.2 53043.2 53118.0 53362.0 53384.5 55054.0 44724.3 45214.3 45278.8 46018.5 46651.0 44083.0 45578.3 46500.3 46525.0 46642.3 43732.0 44369.0 44759.0 44818.3 44868.8 37126.6 39120.0 39239.0 39674.4 39835.0 29676.5 29884.4 30016.2 30142.2 30233.4 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | [#2] jquery.html Cycles:180 Average:29490.20 Median:23292.21 stddev:79280.65 (340.4%) stddev-sans-first:79472.96 04:32:23 INFO - PROCESS | 15103 | Values: 397.8 414.6 420.8 422.5 436.9 15648.6 20477.9 20675.5 20739.6 20947.9 19955.2 20002.8 20026.7 20030.5 20189.6 5492.9 5718.6 5781.3 5945.5 5968.5 5858.7 5899.4 5906.7 5907.2 5912.4 5653.5 5878.2 5909.2 5976.9 5984.3 5819.3 5828.6 5851.6 5871.4 5883.0 5670.5 5671.4 5712.6 5724.6 5741.1 22399.6 23226.7 23357.8 24116.3 24774.3 2527.3 2634.3 2667.8 2986.8 2993.0 10601.3 10728.9 10961.5 11085.8 11179.7 1975.8 2002.7 2021.9 2024.5 2056.1 78286.6 83997.0 84930.0 85150.1 85274.6 11572.7 11621.8 11743.8 12111.5 12802.5 16766.2 16784.7 16785.1 16797.7 16817.4 8562.0 9217.9 9253.9 9293.8 9413.2 9062.0 9072.9 9131.7 9292.8 9295.6 4932.6 5032.9 5056.8 5137.0 5214.6 2064.9 2087.9 2094.5 2099.0 2139.2 982.7 1033.8 1045.4 1088.7 1102.1 19987.7 20383.7 20581.0 20783.5 20909.5 1028.0 1082.5 1135.4 1137.4 1162.9 11404.1 11676.8 11714.6 11741.8 11771.6 436510.0 477592.0 483221.0 498677.0 498852.0 96218.0 96515.0 97082.5 98360.0 98716.0 1049.6 1085.8 1138.3 1143.2 1156.1 17545.9 17945.4 18367.3 18397.8 18639.6 41203.4 41562.8 41893.0 42485.6 42623.0 62996.4 63287.4 63930.4 64341.4 64680.4 8267.4 9151.5 9229.4 9367.8 9420.6 13423.2 13669.1 13700.5 13883.6 13931.2 11908.2 12050.9 12058.6 12067.2 12213.1 10512.7 11016.6 11378.7 11679.2 11958.2 11780.0 12178.5 12252.1 12324.5 12349.4 8940.7 9060.3 9130.2 9218.4 9411.5 11924.3 11958.2 12051.3 12077.0 12148.6 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | [#3] mootools.html Cycles:180 Average:6281.51 Median:4643.79 stddev:3626.88 (78.1%) stddev-sans-first:3618.78 04:32:23 INFO - PROCESS | 15103 | Values: 1437.2 1530.6 1588.5 1787.6 1906.3 8590.4 9095.5 9165.4 9331.8 9440.2 9092.9 9244.4 9245.8 9285.2 9353.3 2452.5 2454.3 2454.8 2456.3 2484.2 2415.7 2475.6 2500.4 2502.8 2502.9 2432.1 2450.3 2515.6 2515.9 2518.4 2374.8 2395.7 2398.0 2416.3 2428.0 2360.6 2363.7 2364.1 2367.4 2385.8 9062.6 9388.4 9461.2 9479.9 9490.1 5562.4 5704.3 5738.9 5743.3 5754.6 3513.2 3528.5 3534.7 3575.1 3589.4 1126.9 1141.6 1146.9 1147.2 1151.1 13423.6 13481.9 13505.5 13514.5 13540.5 4687.9 4794.3 4846.4 4906.3 4965.2 10526.5 10534.4 10539.5 10545.2 10551.1 5645.5 5669.6 5685.9 5690.8 5715.6 4672.8 4754.4 5161.2 5167.3 5255.2 4200.0 4221.4 4245.0 4308.0 4363.4 1275.3 1303.7 1311.9 1316.4 1318.0 1046.5 1078.5 1084.1 1087.4 1092.7 6482.8 6544.3 6547.7 6548.8 6626.8 484.1 497.5 1105.2 1156.0 1183.7 4414.7 4523.3 4629.1 4658.5 4711.7 12085.8 12528.3 12540.3 12547.4 12569.7 11686.8 12420.3 12490.0 12499.9 12523.7 8801.4 8989.5 9007.2 9010.7 9021.6 6442.8 6452.6 6485.4 6551.9 6697.4 10513.9 11124.3 11131.5 11207.9 11265.4 10440.4 10677.1 11181.0 11298.0 11418.0 7966.9 8196.7 8230.7 8276.5 8303.5 9159.1 9491.3 9502.9 9519.4 9525.3 8310.3 8468.6 8491.5 8494.3 8680.9 8387.1 8409.8 8437.8 8463.7 8571.7 7421.3 7440.8 7447.9 7481.5 7487.9 5585.1 5591.4 5620.1 5630.5 5824.5 5816.9 5869.8 5876.3 5997.6 6012.2 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | [#4] prototype.html Cycles:180 Average:4766.18 Median:5327.59 stddev:1391.13 (26.1%) stddev-sans-first:1354.89 04:32:23 INFO - PROCESS | 15103 | Values: 346.1 349.8 350.5 355.6 359.4 5449.1 5459.6 5486.2 5498.9 5503.9 5418.8 5457.0 5458.6 5470.8 5472.2 3807.7 4130.6 4279.0 4289.8 4309.8 4276.3 4280.0 4286.0 4307.3 4312.1 4178.2 4290.2 4317.4 4319.4 4329.9 4235.7 4273.0 4278.7 4284.7 4305.0 3830.6 3846.1 3850.1 3855.3 3860.7 5247.7 5394.3 5394.8 5395.5 5402.7 4912.9 4920.4 4924.1 4938.8 4967.8 5666.5 5667.4 5695.5 5740.5 5780.9 4086.4 4247.7 4249.9 4251.0 4257.5 5638.8 5782.9 5920.6 5937.8 6009.6 4329.7 4342.7 4355.7 4362.6 4367.0 4401.6 4412.7 4413.0 4414.6 4414.7 4861.2 4973.7 4978.9 4978.9 5020.7 5669.2 5679.5 5684.7 5705.3 5767.3 1497.7 1508.8 1513.4 1526.0 1527.2 925.9 960.8 977.7 979.3 980.9 4794.8 4888.1 4908.7 4909.0 4916.9 5123.3 5123.7 5145.7 5160.3 5194.5 4585.9 4868.1 4919.6 4923.1 4941.9 2722.0 2724.3 2746.3 2747.9 2756.6 5261.7 5300.2 5315.9 5339.3 5350.8 5234.7 5247.4 5678.7 6004.3 6009.8 6094.7 6096.9 6118.4 6141.4 6159.8 5661.4 5941.2 5953.9 6042.2 6044.4 5759.0 5823.3 5835.9 5862.3 5867.7 5853.8 5940.2 5961.6 5963.3 6127.4 4259.9 4267.5 4270.9 4322.4 4340.8 5828.7 5977.9 6007.6 6011.5 6015.4 5737.3 5747.4 5760.1 5779.9 5810.5 5737.6 5740.9 5743.7 5825.4 5827.5 5551.0 5722.5 5726.6 5744.0 5778.7 5811.4 5822.6 5835.3 5843.5 5853.6 5430.8 5693.2 5709.0 5765.6 5786.5 04:32:23 INFO - PROCESS | 15103 | 04:32:23 INFO - PROCESS | 15103 | [#5] yui.html Cycles:180 Average:20984.71 Median:20572.78 stddev:29397.86 (142.9%) stddev-sans-first:29441.32 04:32:23 INFO - PROCESS | 15103 | Values: 815.9 828.8 830.3 886.8 939.0 16435.7 17582.9 17654.5 17695.0 17704.6 15721.4 15978.6 16372.8 16572.1 16630.5 738.1 894.6 906.3 920.8 929.0 918.1 921.1 922.8 926.1 926.3 749.7 924.7 956.1 967.4 968.1 948.2 953.5 956.1 956.4 956.9 696.4 697.6 697.9 698.6 698.6 16502.9 16584.9 16611.7 16617.3 16657.6 15323.2 15467.2 15505.0 15591.8 15609.5 14002.9 14046.2 14089.2 14203.8 14361.6 4874.2 4891.7 4920.8 4960.4 5068.7 86319.0 86434.0 89955.0 90343.0 91343.0 12402.4 12485.8 12494.7 12548.9 12798.6 22623.9 22645.3 22801.3 22808.2 22830.4 9095.3 9134.0 9150.9 9270.7 9296.5 5093.6 5102.5 5181.6 5233.2 5323.0 3550.5 3653.7 3664.9 3667.2 3667.6 627.7 642.9 666.3 691.0 697.0 659.3 708.4 761.9 784.3 848.3 10561.7 10723.4 10948.4 11695.5 11758.5 759.0 778.2 790.8 817.9 830.0 4936.3 5320.3 5336.2 5347.3 5437.1 75891.6 79568.0 101621.0 132996.3 142164.3 69159.0 70406.0 95524.0 112551.0 121680.4 59978.7 90516.5 91611.5 92407.5 103487.0 10043.8 10080.7 10086.6 10086.8 10276.5 37478.0 40658.0 42196.6 44305.1 48303.0 51659.7 53122.0 66965.3 70678.3 70860.3 14445.0 14780.6 14969.7 15020.8 15034.8 16324.9 16339.8 16795.7 17011.1 17189.3 12625.8 13051.7 14033.7 15264.5 15716.9 14017.8 14133.4 15032.2 15100.2 15625.8 14576.6 14627.1 14846.7 15023.7 15028.5 12166.0 13042.4 13400.5 13494.6 13682.2 12466.5 12711.1 12758.6 12929.0 13200.7 04:32:23 INFO - PROCESS | 15103 | -------- Summary: end -------- 04:32:23 INFO - PROCESS | 15103 | 04:32:24 INFO - TEST-INFO | 15103: exit 0 04:32:24 INFO - TEST-OK | dromaeo_css | took 1112296ms 04:32:24 INFO - TEST-START | kraken 04:32:24 INFO - operating with platform_type : linux_ 04:32:24 INFO - Initialising browser for kraken test... 04:32:24 INFO - TEST-INFO | started process 15306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpitgI8r/profile http://localhost:34053/getInfo.html) 04:32:27 INFO - PROCESS | 15306 | __metrics Screen width/height:1600/1200 04:32:27 INFO - PROCESS | 15306 | colorDepth:24 04:32:27 INFO - PROCESS | 15306 | Browser inner width/height: 1024/697 04:32:27 INFO - PROCESS | 15306 | __metrics 04:32:28 INFO - PROCESS | 15306 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:32:28 INFO - PROCESS | 15306 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:29 INFO - TEST-INFO | 15306: exit 0 04:32:29 INFO - Browser initialized. 04:32:29 INFO - Running cycle 1/1 for kraken test... 04:32:29 INFO - TEST-INFO | started process 15395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpitgI8r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:41 INFO - PROCESS | 15395 | RSS: Main: 145133568 04:32:41 INFO - PROCESS | 15395 | 04:32:43 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34053/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:44 INFO - PROCESS | 15395 | RSS: Main: 191369216 04:32:44 INFO - PROCESS | 15395 | 04:32:47 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34053/tests/kraken/driver.html?testName=audio-dft) 04:32:47 INFO - PROCESS | 15395 | RSS: Main: 181981184 04:32:47 INFO - PROCESS | 15395 | 04:32:50 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34053/tests/kraken/driver.html?testName=audio-fft) 04:32:51 INFO - PROCESS | 15395 | RSS: Main: 269438976 04:32:51 INFO - PROCESS | 15395 | 04:32:53 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34053/tests/kraken/driver.html?testName=audio-oscillator) 04:32:53 INFO - PROCESS | 15395 | RSS: Main: 175472640 04:32:53 INFO - PROCESS | 15395 | 04:32:55 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34053/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:32:56 INFO - PROCESS | 15395 | RSS: Main: 177475584 04:32:56 INFO - PROCESS | 15395 | 04:32:59 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34053/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:00 INFO - PROCESS | 15395 | RSS: Main: 213188608 04:33:00 INFO - PROCESS | 15395 | 04:33:03 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34053/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:04 INFO - PROCESS | 15395 | RSS: Main: 211566592 04:33:04 INFO - PROCESS | 15395 | 04:33:06 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34053/tests/kraken/driver.html?testName=json-parse-financial) 04:33:07 INFO - PROCESS | 15395 | RSS: Main: 211390464 04:33:07 INFO - PROCESS | 15395 | 04:33:09 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34053/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:09 INFO - PROCESS | 15395 | RSS: Main: 175804416 04:33:09 INFO - PROCESS | 15395 | 04:33:11 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:12 INFO - PROCESS | 15395 | RSS: Main: 181776384 04:33:12 INFO - PROCESS | 15395 | 04:33:13 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:14 INFO - PROCESS | 15395 | RSS: Main: 186322944 04:33:14 INFO - PROCESS | 15395 | 04:33:17 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:17 INFO - PROCESS | 15395 | RSS: Main: 181186560 04:33:17 INFO - PROCESS | 15395 | 04:33:20 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:20 INFO - PROCESS | 15395 | RSS: Main: 184602624 04:33:20 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | Cycle 1(1): loaded http://localhost:34053/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34053/tests/kraken/driver.html?testName=ai-astar) 04:33:22 INFO - PROCESS | 15395 | RSS: Main: 235085824 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | __start_tp_report 04:33:22 INFO - PROCESS | 15395 | _x_x_mozilla_page_load 04:33:22 INFO - PROCESS | 15395 | _x_x_mozilla_page_load_details 04:33:22 INFO - PROCESS | 15395 | |i|pagename|runs| 04:33:22 INFO - PROCESS | 15395 | |0;ai-astar;101;112;110;111;111;110;109;110;112;110 04:33:22 INFO - PROCESS | 15395 | |1;audio-beat-detection;149;116;123;169;116;120;142;116;151;116 04:33:22 INFO - PROCESS | 15395 | |2;audio-dft;161;161;161;161;162;160;160;161;160;160 04:33:22 INFO - PROCESS | 15395 | |3;audio-fft;74;77;75;116;74;76;73;75;124;74 04:33:22 INFO - PROCESS | 15395 | |4;audio-oscillator;83;83;142;83;84;83;115;83;83;137 04:33:22 INFO - PROCESS | 15395 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 04:33:22 INFO - PROCESS | 15395 | |6;imaging-darkroom;146;146;146;146;149;146;145;145;145;146 04:33:22 INFO - PROCESS | 15395 | |7;imaging-desaturate;94;95;95;95;95;95;94;95;95;95 04:33:22 INFO - PROCESS | 15395 | |8;json-parse-financial;52;50;49;50;51;56;53;54;51;50 04:33:22 INFO - PROCESS | 15395 | |9;json-stringify-tinderbox;60;60;60;59;60;59;59;60;60;60 04:33:22 INFO - PROCESS | 15395 | |10;stanford-crypto-aes;72;73;73;73;73;72;71;72;72;81 04:33:22 INFO - PROCESS | 15395 | |11;stanford-crypto-ccm;118;120;117;118;127;116;118;115;156;114 04:33:22 INFO - PROCESS | 15395 | |12;stanford-crypto-pbkdf2;153;156;153;149;149;152;151;149;149;153 04:33:22 INFO - PROCESS | 15395 | |13;stanford-crypto-sha256-iterative;64;62;62;61;61;63;60;61;61;59 04:33:22 INFO - PROCESS | 15395 | __end_tp_report 04:33:22 INFO - PROCESS | 15395 | __start_cc_report 04:33:22 INFO - PROCESS | 15395 | _x_x_mozilla_cycle_collect,1622 04:33:22 INFO - PROCESS | 15395 | __end_cc_report 04:33:22 INFO - PROCESS | 15395 | __startTimestamp1471520002786__endTimestamp 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | ------- Summary: start ------- 04:33:22 INFO - PROCESS | 15395 | Number of tests: 14 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.50 stddev:3.17 (2.9%) stddev-sans-first:1.01 04:33:22 INFO - PROCESS | 15395 | Values: 101.0 112.0 110.0 111.0 111.0 110.0 109.0 110.0 112.0 110.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:132.50 stddev:19.34 (14.6%) stddev-sans-first:19.49 04:33:22 INFO - PROCESS | 15395 | Values: 149.0 116.0 123.0 169.0 116.0 120.0 142.0 116.0 151.0 116.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 04:33:22 INFO - PROCESS | 15395 | Values: 161.0 161.0 161.0 161.0 162.0 160.0 160.0 161.0 160.0 160.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#3] audio-fft Cycles:10 Average:83.80 Median:74.50 stddev:19.21 (25.8%) stddev-sans-first:20.04 04:33:22 INFO - PROCESS | 15395 | Values: 74.0 77.0 75.0 116.0 74.0 76.0 73.0 75.0 124.0 74.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:83.00 stddev:24.25 (29.2%) stddev-sans-first:25.13 04:33:22 INFO - PROCESS | 15395 | Values: 83.0 83.0 142.0 83.0 84.0 83.0 115.0 83.0 83.0 137.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:33:22 INFO - PROCESS | 15395 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.15 (0.8%) stddev-sans-first:1.22 04:33:22 INFO - PROCESS | 15395 | Values: 146.0 146.0 146.0 146.0 149.0 146.0 145.0 145.0 145.0 146.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 04:33:22 INFO - PROCESS | 15395 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:2.17 (4.2%) stddev-sans-first:2.30 04:33:22 INFO - PROCESS | 15395 | Values: 52.0 50.0 49.0 50.0 51.0 56.0 53.0 54.0 51.0 50.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 04:33:22 INFO - PROCESS | 15395 | Values: 60.0 60.0 60.0 59.0 60.0 59.0 59.0 60.0 60.0 60.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.00 stddev:2.82 (3.9%) stddev-sans-first:2.96 04:33:22 INFO - PROCESS | 15395 | Values: 72.0 73.0 73.0 73.0 73.0 72.0 71.0 72.0 72.0 81.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#11] stanford-crypto-ccm Cycles:10 Average:121.90 Median:118.00 stddev:12.50 (10.6%) stddev-sans-first:13.18 04:33:22 INFO - PROCESS | 15395 | Values: 118.0 120.0 117.0 118.0 127.0 116.0 118.0 115.0 156.0 114.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.40 Median:152.50 stddev:2.41 (1.6%) stddev-sans-first:2.49 04:33:22 INFO - PROCESS | 15395 | Values: 153.0 156.0 153.0 149.0 149.0 152.0 151.0 149.0 149.0 153.0 04:33:22 INFO - PROCESS | 15395 | 04:33:22 INFO - PROCESS | 15395 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.43 (2.3%) stddev-sans-first:1.17 04:33:22 INFO - PROCESS | 15395 | Values: 64.0 62.0 62.0 61.0 61.0 63.0 60.0 61.0 61.0 59.0 04:33:22 INFO - PROCESS | 15395 | -------- Summary: end -------- 04:33:22 INFO - PROCESS | 15395 | 04:33:23 INFO - TEST-INFO | 15395: exit 0 04:33:23 INFO - TEST-OK | kraken | took 59453ms 04:33:23 INFO - SUITE-END | took 1171s 04:33:23 INFO - Completed test suite (00:19:31) 04:33:23 INFO - javascript benchmark 04:33:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3184.172115250035, 3204.054803832869, 3214.100051985444, 3231.3052693670857, 3250.918727031824, 5566.582506312027, 5593.300837395325, 5603.80386686466, 5637.802676906309, 5638.041532939405, 5531.9723401383035, 5593.944060559394, 5595.552355811536, 5606.915896261549, 5629.324481062268, 5006.374203224597, 5015.9247611285955, 5055.848324550269, 5100.897982040376, 5136.024155410479, 4953.975230123844, 5086.821961231358, 5124.308218390525, 5137.5633071188995, 5138.768755405997, 5005.199168133099, 5047.141985862394, 5060.822871199509, 5080.745962701869, 5087.974560127194, 5165.586753059746, 5169.663971841837, 5175.81884634036, 5178.352705911761, 5205.843824685266, 4911.091448082117, 4997.875053123665, 5057.064443078006, 5061.544460998528, 5065.341505604285, 5447.1829225616075, 5466.753996070186, 5477.123660214346, 5527.889442211133, 5539.833804985857, 6000.939990600082, 6020.939790602081, 6031.4270144336215, 6042.5165986721395, 6045.939540604581, 5951.345352011275, 5982.970085149546, 5996.13056106862, 6053.909191362133, 6075.0, 5650.576206784466, 5692.743826527775, 5704.429557044263, 5705.515031222351, 5708.800191993343, 5998.550108741862, 6171.290301615282, 6176.722047507783, 6179.227596550431, 6180.629162250189, 5736.340320863071, 5738.741756620962, 5754.367019627837, 5884.0585506318785, 5886.087656413263, 5915.881682366328, 5950.107483877367, 5951.9107213391835, 5953.880922381615, 5955.37468565803, 5838.591298609056, 5905.76376944926, 5914.9112763308585, 5920.052791553331, 5923.881522369615, 5870.0, 5893.469587737124, 5915.467607915309, 5988.730507127189, 6028.5478589106, 4583.037562111994, 4585.151746926829, 4585.335126406652, 4587.839425620087, 4620.3993480847275, 3987.302222111119, 4091.734037287567, 4099.754014759064, 4105.322621767435, 4118.217538667644, 5521.889562208733, 5572.888542229214, 5584.553235741131, 5611.3266408031295, 5651.971740141273, 5929.703514824328, 5953.374895635982, 5969.432903874125, 5977.402259773988, 5990.161377407167, 5483.561315094783, 5637.943620563824, 5639.154126880919, 5643.040683083886, 5643.5767317451355, 4999.325091112653, 5047.646664733434, 5067.037262920107, 5104.617153713412, 5129.8717532061255, 6238.064290356392, 6239.1577137085915, 6243.843903902348, 6244.313125556276, 6257.937420625735, 5899.616524925866, 6086.421789929949, 6087.208662873798, 6094.481969032505, 6098.969505152623, 6173.876522469435, 6196.225471816023, 6220.844478887883, 6231.189945307081, 6231.532635052298, 5838.737256823369, 5902.232709747877, 5904.291485021825, 5905.498032667271, 5931.0, 5875.441833025856, 6006.789762358297, 6031.788887388921, 6032.065029920369, 6033.668148251712, 5772.971135144466, 5827.533797296291, 5829.38791426911, 5878.088896221093, 5922.73347699346, 5863.2670916135485, 5870.471657550841, 5870.9412905870395, 5872.031114865999, 5873.794417195549, 5658.292713410824, 5787.074068149076, 5821.70891455417, 5824.912626310694, 5834.445727655866, 5781.682007489629, 5817.94182058174, 5820.417958204315, 5876.471117599436, 5889.67606781614, 5724.713764311851, 5734.655920644775, 5734.827955161351, 5736.368999409978, 5737.397573254748], "value": 5506.618817918771, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.7957184343245, 650.291182610954, 664.4916638771341, 666.8799616069107, 671.345438197757, 34959.30081398374, 45961.080778384414, 47219.76390118053, 47258.763706181424, 47327.29009064867, 48041.51958480414, 48444.0, 48541.51458485414, 48629.0, 48634.27048594265, 8353.498790072585, 9300.674476393327, 9449.055094490564, 9453.669121580746, 10925.07136893365, 10857.945710271437, 11343.602973895873, 11344.773104537904, 11351.262167959056, 11354.886451135506, 11285.4921528531, 11318.660440186808, 11325.093992480583, 11341.0, 11348.546058157664, 11279.605213817476, 11291.0, 11349.716257093556, 11351.545938162464, 11358.148138889534, 11089.001989820874, 11150.832737508943, 11156.386398748065, 11161.051310638606, 11163.218574699775, 46227.537724622656, 46629.0, 46713.76643116763, 46723.53276467226, 46731.29903051457, 3823.636754508317, 3908.4918960535233, 3930.0, 3937.76373417593, 4131.607497287753, 34614.850660425924, 34890.47664285038, 34904.30191396183, 35095.824520877235, 35184.47223291652, 4572.291294849304, 4595.517470665565, 4618.515055919113, 4668.85993420198, 4723.4568024677255, 269758.0, 269833.0, 271049.0, 271601.64199179073, 271817.0, 62023.0, 62145.37854621396, 62280.0, 62314.68842655758, 62367.0, 39284.80357598194, 39957.40063899044, 39970.800145999085, 41641.58358416438, 64418.0, 19427.902860485607, 19544.0, 19548.413547593595, 19553.315633952745, 19555.90222048909, 13977.510787122403, 14903.180325082225, 15083.773743393858, 15267.618309542127, 15337.923310383376, 20618.690719639217, 20757.481062973548, 20774.169033238803, 20860.165593376398, 20880.37358879236, 7494.475386722877, 7643.70057090296, 7802.765917022499, 7835.764927052198, 7909.762707118795, 1076.1498416251231, 1179.151011271883, 1183.2782002978176, 1186.887245711656, 1233.8519377674556, 32501.837490812395, 34772.82613586916, 34876.65123348734, 35080.298394032485, 35152.296954060774, 1149.126663735567, 1149.201305092969, 1162.3955543117531, 1195.2051885496048, 1213.4782043721284, 18093.457196284133, 18194.63610727778, 18256.178471968793, 18334.266629334677, 18435.26258949627, 589024.0, 593614.0, 593696.0, 594068.0, 594175.0, 64037.35962640407, 64627.35372646307, 64722.676386617764, 64798.352016479235, 64858.0, 1343.4693296147966, 1392.3803907261374, 1399.6640806206437, 1481.6740317130213, 1491.8517335037511, 33417.0, 35869.64130358663, 36000.27999440049, 36038.0, 36081.819590901876, 52931.0, 53454.73272633767, 53594.0, 53641.19538207008, 53678.46321536891, 443645.0, 446648.0, 447370.0, 448109.0, 448568.0, 30950.0714978551, 31253.84373078211, 31334.216644584067, 32089.358212835145, 32729.18177045593, 53043.20435193552, 53118.0, 53362.0, 53384.46615533795, 55054.0, 44724.32913506365, 45214.32178517259, 45278.773606131756, 46018.53981460143, 46651.0, 44083.0, 45578.31632525581, 46500.30249546327, 46525.0, 46642.300365495226, 43732.0, 44369.0, 44759.0, 44818.327725083494, 44868.775656121514, 37126.6287337134, 39120.0, 39239.0, 39674.40488392619, 39835.0, 29676.53858418398, 29884.402311954073, 30016.24959376033, 30142.246443839078, 30233.395332093674], "value": 21860.273088596918, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.75339289640425, 414.6226933490522, 420.75806411313494, 422.4951183335913, 436.85583757360075, 15648.608784780386, 20477.897610511925, 20675.483112922186, 20739.585208295844, 20947.895260523695, 19955.201791928343, 20002.799972000277, 20026.69959950602, 20030.49923751907, 20189.59620807583, 5492.945070549304, 5718.599698021119, 5781.306243250816, 5945.4946329562035, 5968.522518198533, 5858.73635686393, 5899.439553242435, 5906.734196961147, 5907.202527658754, 5912.408759124075, 5653.4911857932775, 5878.235829342179, 5909.20225769522, 5976.940230597704, 5984.3417224105315, 5819.33077696064, 5828.591998560081, 5851.561132915027, 5871.412858714137, 5882.97058514709, 5670.4896559309445, 5671.432856714337, 5712.600117991742, 5724.570657200685, 5741.110127930177, 22399.552008959894, 23226.65160022601, 23357.7664223359, 24116.276511704677, 24774.25677229686, 2527.2544599343237, 2634.3019099938533, 2667.8132530722855, 2986.79319261293, 2992.9551056734167, 10601.310914790514, 10728.946355268174, 10961.451927403597, 11085.778284434266, 11179.720506987309, 1975.7727861295848, 2002.7396438462906, 2021.858469907107, 2024.5242368043498, 2056.0979851432235, 78286.60856695737, 83997.0, 84930.0, 85150.14849851547, 85274.5736271321, 11572.6528204154, 11621.825672614917, 11743.765124697458, 12111.515539378326, 12802.487900483877, 16766.245518951677, 16784.664306713796, 16785.076820774975, 16797.748033779502, 16817.41139060127, 8562.016471175442, 9217.907820921704, 9253.907460925438, 9293.838270216223, 9413.152816246571, 9062.003179650234, 9072.909270907207, 9131.680391186277, 9292.767680808034, 9295.58169882357, 4932.605391568666, 5032.874178145576, 5056.772445239973, 5136.998285334324, 5214.556762675215, 2064.9277275295294, 2087.8956052197327, 2094.549671820566, 2098.9580208395746, 2139.1978008246906, 982.6658935961807, 1033.78530226983, 1045.4459136657583, 1088.6679562733443, 1102.1072930925975, 19987.700184497244, 20383.694244586342, 20581.0, 20783.480412989797, 20909.477263068242, 1028.0285130551556, 1082.5345101606385, 1135.3925649777495, 1137.4312843578211, 1162.9476673549711, 11404.087672986141, 11676.76646467066, 11714.648560543197, 11741.76516469683, 11771.587994420153, 436510.0, 477592.0, 483221.0, 498677.0, 498852.0, 96218.0, 96515.0, 97082.51458742803, 98360.0, 98716.0, 1049.6431213387484, 1085.8208395614636, 1138.2771939818274, 1143.1883362812496, 1156.1271240213584, 17545.859519131216, 17945.371911983017, 18367.265309387476, 18397.81602183961, 18639.627207455505, 41203.38194927019, 41562.765585162466, 41893.0, 42485.5751442494, 42623.0, 62996.37003629905, 63287.36712632815, 63930.3606963943, 64341.35658643541, 64680.35319646743, 8267.379946504156, 9151.496667683341, 9229.400088994194, 9367.812643747224, 9420.623175073195, 13423.194608323533, 13669.111507751964, 13700.520481783089, 13883.583492495241, 13931.164130152223, 11908.225965312227, 12050.879491204976, 12058.638240852437, 12067.215630983957, 12213.084018698455, 10512.737181570215, 11016.559337626726, 11378.658640240807, 11679.240849344766, 11958.222715523463, 11779.998700110244, 12178.451969661564, 12252.142350035412, 12324.50701971911, 12349.444275007825, 8940.68707595231, 9060.275177985613, 9130.223930965942, 9218.400803947721, 9411.529423528933, 11924.344161071225, 11958.162928594915, 12051.33717645538, 12077.0, 12148.635540933787], "value": 9737.804679303652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.187988786335, 1530.563789320044, 1588.5313832419436, 1787.5888545634505, 1906.3137270582579, 8590.355723320752, 9095.499747513884, 9165.358424910251, 9331.813363732721, 9440.244780417568, 9092.863607045883, 9244.445333280008, 9245.768855778591, 9285.210757085639, 9353.251739860812, 2452.460458699083, 2454.2759885833634, 2454.7790698837102, 2456.2876765737915, 2484.1553871683623, 2415.7463466336044, 2475.6039033754605, 2500.4499010217723, 2502.78726308264, 2502.9374265643323, 2432.1001229545004, 2450.301664025753, 2515.5849284867977, 2515.8993640254366, 2518.40817407909, 2374.82188835838, 2395.7484464131276, 2398.0, 2416.3355077353867, 2427.9514409711883, 2360.5632957902844, 2363.6690863279155, 2364.113457453455, 2367.3844800351862, 2385.785279324869, 9062.637494500212, 9388.436693798325, 9461.24310055194, 9479.905200947971, 9490.145886870147, 5562.415946325658, 5704.286964129484, 5738.856528586777, 5743.253377060955, 5754.597178197528, 3513.1568423578155, 3528.541289632357, 3534.6995505382074, 3575.0704816747575, 3589.3898037333715, 1126.8591426071741, 1141.5890279499374, 1146.919715619907, 1147.23708733692, 1151.0848875144281, 13423.597292081253, 13481.865181348257, 13505.527306544223, 13514.526991555249, 13540.526081587097, 4687.867618226013, 4794.256890182028, 4846.36997190363, 4906.31311616374, 4965.205567109318, 10526.52630631608, 10534.36793792375, 10539.525721342558, 10545.156387488982, 10551.103156231806, 5645.463680950385, 5669.574781891375, 5685.948099601587, 5690.829275121753, 5715.628484148517, 4672.836450724209, 4754.358161648202, 5161.200013997836, 5167.328247327823, 5255.185446255837, 4199.958000420018, 4221.430106935586, 4244.957550424456, 4307.956920430818, 4363.38912552243, 1275.298585777837, 1303.7066660001612, 1311.9212847229196, 1316.4010375279224, 1317.967050823737, 1046.4610725476387, 1078.52544880254, 1084.0677017764715, 1087.445402844539, 1092.748667806393, 6482.805515834532, 6544.345565443417, 6547.738090476423, 6548.836279092966, 6626.79236314666, 484.0681687751065, 497.48758778458244, 1105.2318638546294, 1155.9826602600967, 1183.6922400176015, 4414.713043652152, 4523.344115103358, 4629.120467111238, 4658.464276608166, 4711.717296962193, 12085.818712719316, 12528.310942898228, 12540.310282934344, 12547.435365408579, 12569.748605028031, 11686.824697629543, 12420.316882571546, 12490.000799935984, 12499.937505312151, 12523.749525009449, 8801.427907186013, 8989.505577193317, 9007.189352958265, 9010.684626038057, 9021.594028268744, 6442.8389290268115, 6452.645104519296, 6485.3514648534765, 6551.934480655323, 6697.3637504437, 10513.89486105129, 11124.276922000045, 11131.499082541142, 11207.943960280147, 11265.380404077854, 10440.42577658236, 10677.14582833356, 11181.0, 11298.0, 11418.029467495357, 7966.920330796618, 8196.713115040944, 8230.711925082593, 8276.544790036605, 8303.501789892625, 9159.129882661136, 9491.288153388385, 9502.85745713801, 9519.428834269966, 9525.333226674065, 8310.252077313071, 8468.618912148844, 8491.532965686947, 8494.32045436376, 8680.91319086801, 8387.119352467904, 8409.789755256168, 8437.831243375222, 8463.703770368007, 8571.657133714582, 7421.257874212535, 7440.813979650336, 7447.92552074494, 7481.513701609378, 7487.925120748723, 5585.106382978704, 5591.356993945586, 5620.1288800236025, 5630.549556035589, 5824.475797178275, 5816.9127463088935, 5869.7945571904775, 5876.294844618673, 5997.580169388101, 6012.158297838394], "value": 4976.89899037008, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.1053177536069, 349.7761432683083, 350.5285391148904, 355.6017260668052, 359.4141549274682, 5449.128139497681, 5459.563234941205, 5486.174620070657, 5498.862528436786, 5503.889922201563, 5418.783248670048, 5457.0, 5458.61789674722, 5470.753816078276, 5472.20653005314, 3807.714421418391, 4130.628243458088, 4278.957210427887, 4289.764062976535, 4309.806058727349, 4276.294411422112, 4279.951411904088, 4285.957140428587, 4307.267764480046, 4312.116016216668, 4178.164367126572, 4290.206311832321, 4317.352397140438, 4319.3952846601505, 4329.935050974238, 4235.682323825726, 4273.0, 4278.679099067551, 4284.67864910133, 4304.988327742978, 3830.616938306175, 3846.11539345952, 3850.1144736710435, 3855.306044911915, 3860.6718428933573, 5247.737613119329, 5394.271773310592, 5394.757235924393, 5395.460453954613, 5402.675839449606, 4912.852614421573, 4920.409550853885, 4924.138275801756, 4938.80244790208, 4967.7764500597195, 5666.546676265889, 5667.404922483162, 5695.547635352958, 5740.4833564978935, 5780.855478613026, 4086.4483294755123, 4247.745135291892, 4249.915001699948, 4250.957490425118, 4257.489101307832, 5638.759472915952, 5782.942170578241, 5920.56607167846, 5937.762489500415, 6009.56612571909, 4329.675274354436, 4342.717723347972, 4355.651547876162, 4362.585554372329, 4366.956330436718, 4401.559844015573, 4412.6690498212765, 4412.955870441318, 4414.580614841585, 4414.713043652152, 4861.1979023460735, 4973.726445045557, 4978.85486338137, 4978.90042199154, 5020.673656212334, 5669.206311116447, 5679.545636349082, 5684.715764211773, 5705.343885453226, 5767.336756273083, 1497.7304085264757, 1508.7929656275155, 1513.3870782333172, 1525.9694806103817, 1527.1905889878383, 925.8981512033671, 960.841461158915, 977.7360112769654, 979.2508730820928, 980.9362391444675, 4794.7842347093765, 4888.095702295087, 4908.650121216622, 4909.0, 4916.877078073006, 5123.2827404163445, 5123.666961647481, 5145.665531740499, 5160.277561141444, 5194.454582268883, 4585.885352866138, 4868.123737727243, 4919.557239848431, 4923.113839508851, 4941.876453088629, 2722.0200727737997, 2724.2644485989067, 2746.258510202234, 2747.8900843966017, 2756.641636587271, 5261.736913154327, 5300.231466437422, 5315.893682126336, 5339.279197308391, 5350.8127215547265, 5234.712090835041, 5247.449017853147, 5678.744456499385, 6004.3395226525045, 6009.789657361971, 6094.720108777206, 6096.908546371808, 6118.418750218722, 6141.355157708465, 6159.84600384994, 5661.405552416937, 5941.2176347096565, 5953.91069133972, 6042.217545823904, 6044.425779550936, 5758.971205143947, 5823.301203855564, 5835.883282334415, 5862.296524416926, 5867.73595188209, 5853.824385268449, 5940.16837642739, 5961.612495187799, 5963.314218864886, 6127.417895299939, 4259.93610095855, 4267.509236437725, 4270.935935960901, 4322.351647252937, 4340.848070317524, 5828.737706803119, 5977.940220597912, 6007.639541627516, 6011.519078473624, 6015.428534289411, 5737.340205876378, 5747.442388137239, 5760.135979603093, 5779.884402312015, 5810.506106980785, 5737.56968227393, 5740.942590574041, 5743.684097374685, 5825.417458254141, 5827.484853937922, 5550.972245138749, 5722.456366645162, 5726.599138060295, 5744.0, 5778.739956701875, 5811.4479124483105, 5822.563307752021, 5835.299764028344, 5843.474087332161, 5853.619514731699, 5430.782768689208, 5693.231413759255, 5709.0, 5765.56758243125, 5786.479216870333], "value": 4327.640702297241, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.9102498725143, 828.7928017995501, 830.323286521485, 886.7605746448455, 938.9530523473827, 16435.671286574263, 17582.945023298613, 17654.47036588903, 17695.0, 17704.557386065353, 15721.449749258778, 15978.600534986603, 16372.754408683879, 16572.088535130562, 16630.50108496747, 738.0995185873239, 894.6332003878412, 906.3293163059354, 920.8434566123775, 929.005963618928, 918.1323649151554, 921.1111277617098, 922.8015976565038, 926.0646746785761, 926.3330402110504, 749.6626518066864, 924.694850699271, 956.1442508954482, 967.3857100741045, 968.1045033344163, 948.2177203806853, 953.4803532075035, 956.086936975187, 956.4357029352681, 956.8708224389723, 696.4463251714899, 697.6197972105215, 697.9057827193315, 698.5913240754165, 698.6471831724997, 16502.917485412498, 16584.917075414665, 16611.6677666446, 16617.252223649964, 16657.583560410967, 15323.157226352547, 15467.226638668135, 15505.0, 15591.766123508156, 15609.531714048597, 14002.92998535011, 14046.227457489835, 14089.238845144357, 14203.78694319586, 14361.569152925324, 4874.1713908635265, 4891.682040667345, 4920.803167873317, 4960.429550601691, 5068.670536415121, 86319.0, 86434.0, 89955.0, 90342.99999999935, 91343.0, 12402.379881005914, 12485.812712809315, 12494.687632809253, 12548.874511254953, 12798.61604151877, 22623.886880565493, 22645.320640380487, 22801.31596052121, 22808.20171293997, 22830.42923926915, 9095.272378209727, 9133.954330228305, 9150.862737058816, 9270.675526356545, 9296.454834815135, 5093.567046800989, 5102.515261050195, 5181.611379146579, 5233.162693968947, 5323.015242180131, 3550.5384300041346, 3653.707703383723, 3664.871729489455, 3667.1932174921476, 3667.559892812826, 627.7457629659995, 642.9164208652845, 666.3037126203129, 691.0325544238107, 696.989545156823, 659.2682122843639, 708.3943228539628, 761.9466637335444, 784.3019712455919, 848.3425345357274, 10561.735956600993, 10723.410212438235, 10948.39783811882, 11695.532178712927, 11758.529658813723, 758.9658465369072, 778.1867947994359, 790.8339248757825, 817.8814071959532, 829.9709510167115, 4936.259561065869, 5320.334958130234, 5336.199570064521, 5347.278117454094, 5437.13005919051, 75891.62054189693, 79568.0, 101621.0, 132996.3350183243, 142164.28917855344, 69159.0, 70406.0, 95524.0, 112551.0, 121680.39159804145, 59978.66269693586, 90516.5474172638, 91611.54194228986, 92407.53796230975, 103487.0, 10043.799124017332, 10080.69757907264, 10086.596536138766, 10086.8486972694, 10276.486175691036, 37478.0, 40658.0, 42196.57803421927, 44305.11389772251, 48303.0, 51659.741701291256, 53122.0, 66965.3303466959, 70678.29321706717, 70860.29139708537, 14445.0, 14780.556583302518, 14969.700605988037, 15020.849791501945, 15034.774478383051, 16324.918375408048, 16339.836601633831, 16795.748063779298, 17011.149442527578, 17189.31242750276, 12625.81061284063, 13051.738965220453, 14033.719325613227, 15264.465743698916, 15716.92141539285, 14017.789733153806, 14133.363998619881, 15032.248387580796, 15100.169490678118, 15625.76561351558, 14576.635584110058, 14627.110015272694, 14846.703065938837, 15023.699526009637, 15028.474003409829, 12166.0, 13042.4130914111, 13400.530981415994, 13494.595162145151, 13682.247476388497, 12466.5013399463, 12711.110222284351, 12758.61724148277, 12929.0, 13200.669983250496], "value": 8168.093892765888, "unit": "score"}], "name": "dromaeo_css", "value": 7686.516857252821, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 110.0, 111.0, 111.0, 110.0, 109.0, 110.0, 112.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 123.0, 169.0, 116.0, 120.0, 142.0, 116.0, 151.0, 116.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 160.0, 160.0, 161.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 116.0, 74.0, 76.0, 73.0, 75.0, 124.0, 74.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 142.0, 83.0, 84.0, 83.0, 115.0, 83.0, 83.0, 137.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 149.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 49.0, 50.0, 51.0, 56.0, 53.0, 54.0, 51.0, 50.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 73.0, 73.0, 72.0, 71.0, 72.0, 72.0, 81.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 120.0, 117.0, 118.0, 127.0, 116.0, 118.0, 115.0, 156.0, 114.0], "value": 121.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 153.0, 149.0, 149.0, 152.0, 151.0, 149.0, 149.0, 153.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 61.0, 61.0, 63.0, 60.0, 61.0, 61.0, 59.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1460.4000000000003, "alertThreshold": 2.0}]} 04:33:24 INFO - Return code: 0 04:33:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:24 INFO - # TBPL SUCCESS # 04:33:24 INFO - Running post-action listener: _resource_record_post_action 04:33:24 INFO - [mozharness: 2016-08-18 11:33:24.115324Z] Finished run-tests step (success) 04:33:24 INFO - Running post-run listener: _resource_record_post_run 04:33:24 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 330584064; Read time: 192; Write time: 265748 04:33:24 INFO - TinderboxPrint: CPU usage
12.9% 04:33:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 192 04:33:24 INFO - TinderboxPrint: I/O write bytes / time
330,584,064 / 265,748 04:33:24 INFO - TinderboxPrint: CPU idle
8,253.9 (87.0%) 04:33:24 INFO - TinderboxPrint: CPU user
1,185.1 (12.5%) 04:33:24 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79085568; Read time: 0; Write time: 72732 04:33:24 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251146240; Read time: 192; Write time: 193012 04:33:24 INFO - Running post-run listener: _upload_blobber_files 04:33:24 INFO - Blob upload gear active. 04:33:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:24 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 04:33:24 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:33:24 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:33:25 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:25 INFO - (blobuploader) - INFO - Done attempting. 04:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:26 INFO - (blobuploader) - INFO - Done attempting. 04:33:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:32 INFO - (blobuploader) - INFO - Done attempting. 04:33:32 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:32 INFO - Return code: 0 04:33:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4320b5e2131f6c701bb1c3b9131ef0dfde9ec335e9abebd852e1597ebf51a1b9379353e7b601cb98308e01f0e41acb5c5b1a5feaac909c6cdf1ee84b3a8934e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e0f0680a50a0aed3181158f03067d102f18df78150c109d2c59ed17c1a7ca2498c8fadcfddb74ce72c3799a6ea6915c855fed67468e3ac4a6386eb494fd863e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91527b4bd8ceb9de01b069f36c862ad04c46c5743a20433243c303672dc9db3e7eba99883ca350bfac24be48cc777f3fbca01be1491ff2bdf1eef35678e7f54a"} 04:33:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:32 INFO - Contents: 04:33:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4320b5e2131f6c701bb1c3b9131ef0dfde9ec335e9abebd852e1597ebf51a1b9379353e7b601cb98308e01f0e41acb5c5b1a5feaac909c6cdf1ee84b3a8934e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e0f0680a50a0aed3181158f03067d102f18df78150c109d2c59ed17c1a7ca2498c8fadcfddb74ce72c3799a6ea6915c855fed67468e3ac4a6386eb494fd863e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91527b4bd8ceb9de01b069f36c862ad04c46c5743a20433243c303672dc9db3e7eba99883ca350bfac24be48cc777f3fbca01be1491ff2bdf1eef35678e7f54a"} 04:33:32 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:32 INFO - Copying logs to upload dir... 04:33:32 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1682.317939 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-08-18 04:33:32.191301) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:32.199072) ========= 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=0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4320b5e2131f6c701bb1c3b9131ef0dfde9ec335e9abebd852e1597ebf51a1b9379353e7b601cb98308e01f0e41acb5c5b1a5feaac909c6cdf1ee84b3a8934e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e0f0680a50a0aed3181158f03067d102f18df78150c109d2c59ed17c1a7ca2498c8fadcfddb74ce72c3799a6ea6915c855fed67468e3ac4a6386eb494fd863e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91527b4bd8ceb9de01b069f36c862ad04c46c5743a20433243c303672dc9db3e7eba99883ca350bfac24be48cc777f3fbca01be1491ff2bdf1eef35678e7f54a"} build_url:https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4320b5e2131f6c701bb1c3b9131ef0dfde9ec335e9abebd852e1597ebf51a1b9379353e7b601cb98308e01f0e41acb5c5b1a5feaac909c6cdf1ee84b3a8934e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e0f0680a50a0aed3181158f03067d102f18df78150c109d2c59ed17c1a7ca2498c8fadcfddb74ce72c3799a6ea6915c855fed67468e3ac4a6386eb494fd863e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91527b4bd8ceb9de01b069f36c862ad04c46c5743a20433243c303672dc9db3e7eba99883ca350bfac24be48cc777f3fbca01be1491ff2bdf1eef35678e7f54a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:32.226326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:32.226666) ========= 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=0333a256a5e4f052382dd180000001b1-1471517939.268044-693835132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004407 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:32.307441) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:32.310805) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:32.311110) ========= ========= Total master_lag: 0.21 =========