builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1473850122.15 results: success (0) buildid: 20160914004005 builduid: 6e063abc529a4e23ba2801ea66b534fc revision: 7852ac7e74faf5a637189437ce0a9a7f39e6292c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.147962) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.148395) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.148682) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.163018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.163278) ========= 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=4300f881736b422476174874000001bc-1473850121.744833-1478930374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-14 03:48:42-- 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% 35.4M=0s 2016-09-14 03:48:42 (35.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086879 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.263681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.263994) ========= 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=4300f881736b422476174874000001bc-1473850121.744833-1478930374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015652 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:42.313392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-14 03:48:42.313739) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7852ac7e74faf5a637189437ce0a9a7f39e6292c --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 7852ac7e74faf5a637189437ce0a9a7f39e6292c --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=4300f881736b422476174874000001bc-1473850121.744833-1478930374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-14 03:48:42,613 truncating revision to first 12 chars 2016-09-14 03:48:42,613 Setting DEBUG logging. 2016-09-14 03:48:42,613 attempt 1/10 2016-09-14 03:48:42,613 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7852ac7e74fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-14 03:48:43,590 unpacking tar archive at: mozilla-aurora-7852ac7e74fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.308534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-14 03:48:43.639844) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:43.640618) ========= script_repo_revision: 7852ac7e74faf5a637189437ce0a9a7f39e6292c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:43.641645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:43.642335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-14 03:48:43.654820) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-14 03:48:43.655147) ========= /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=4300f881736b422476174874000001bc-1473850121.744833-1478930374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:48:44 INFO - MultiFileLogger online at 20160914 03:48:44 in /builds/slave/test 03:48:44 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 03:48:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:48:44 INFO - {'append_to_log': False, 03:48:44 INFO - 'base_work_dir': '/builds/slave/test', 03:48:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:48:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:48:44 INFO - 'branch': 'Mozilla-Aurora', 03:48:44 INFO - 'buildbot_json_path': 'buildprops.json', 03:48:44 INFO - 'config_files': ('talos/linux_config.py',), 03:48:44 INFO - 'default_actions': ('clobber', 03:48:44 INFO - 'read-buildbot-config', 03:48:44 INFO - 'download-and-extract', 03:48:44 INFO - 'populate-webroot', 03:48:44 INFO - 'create-virtualenv', 03:48:44 INFO - 'install', 03:48:44 INFO - 'run-tests'), 03:48:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:48:44 INFO - 'download_minidump_stackwalk': True, 03:48:44 INFO - 'download_symbols': 'ondemand', 03:48:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:48:44 INFO - 'tooltool.py': '/tools/tooltool.py', 03:48:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:48:44 INFO - '/tools/misc-python/virtualenv.py')}, 03:48:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:48:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:48:44 INFO - 'installer_path': 'installer.exe', 03:48:44 INFO - 'log_level': 'info', 03:48:44 INFO - 'log_name': 'talos', 03:48:44 INFO - 'log_to_console': True, 03:48:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:48:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:48:44 INFO - 'opt_config_files': (), 03:48:44 INFO - 'pip_index': False, 03:48:44 INFO - 'sps_profile': False, 03:48:44 INFO - 'sps_profile_interval': 0, 03:48:44 INFO - 'suite': 'dromaeojs', 03:48:44 INFO - 'system_bits': '32', 03:48:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:48:44 INFO - 'title': 'talos-linux64-ix-045', 03:48:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:48:44 INFO - 'use_talos_json': True, 03:48:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:48:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:48:44 INFO - 'work_dir': 'build'} 03:48:44 INFO - [mozharness: 2016-09-14 10:48:44.039736Z] Running clobber step. 03:48:44 INFO - Running pre-action listener: _resource_record_pre_action 03:48:44 INFO - Running main action method: clobber 03:48:44 INFO - rmtree: /builds/slave/test/build 03:48:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:48:44 INFO - Running post-action listener: _resource_record_post_action 03:48:44 INFO - [mozharness: 2016-09-14 10:48:44.495071Z] Finished clobber step (success) 03:48:44 INFO - [mozharness: 2016-09-14 10:48:44.495173Z] Running read-buildbot-config step. 03:48:44 INFO - Running pre-action listener: _resource_record_pre_action 03:48:44 INFO - Running main action method: read_buildbot_config 03:48:44 INFO - Using buildbot properties: 03:48:44 INFO - { 03:48:44 INFO - "project": "", 03:48:44 INFO - "product": "firefox", 03:48:44 INFO - "script_repo_revision": "production", 03:48:44 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:48:44 INFO - "repository": "", 03:48:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:48:44 INFO - "buildid": "20160914004005", 03:48:44 INFO - "stage_platform": "linux64-pgo", 03:48:44 INFO - "basedir": "/builds/slave/test", 03:48:44 INFO - "nightly_build": "True", 03:48:44 INFO - "buildnumber": 516, 03:48:44 INFO - "slavename": "talos-linux64-ix-045", 03:48:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:48:44 INFO - "platform": "ubuntu64_hw", 03:48:44 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:48:44 INFO - "branch": "mozilla-aurora", 03:48:44 INFO - "repo_path": "releases/mozilla-aurora", 03:48:44 INFO - "revision": "7852ac7e74faf5a637189437ce0a9a7f39e6292c", 03:48:44 INFO - "pgo_build": "True", 03:48:44 INFO - "builduid": "6e063abc529a4e23ba2801ea66b534fc", 03:48:44 INFO - "slavebuilddir": "test" 03:48:44 INFO - } 03:48:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:48:44 INFO - Running post-action listener: _resource_record_post_action 03:48:44 INFO - [mozharness: 2016-09-14 10:48:44.497688Z] Finished read-buildbot-config step (success) 03:48:44 INFO - [mozharness: 2016-09-14 10:48:44.497778Z] Running download-and-extract step. 03:48:44 INFO - Running pre-action listener: _resource_record_pre_action 03:48:44 INFO - Running main action method: download_and_extract 03:48:44 INFO - mkdir: /builds/slave/test/build/tests 03:48:44 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')]} 03:48:44 INFO - https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:48:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:48:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:48:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:48:44 INFO - retry: Failed, sleeping 30 seconds before retrying 03:49:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:49:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:49:14 INFO - retry: Failed, sleeping 60 seconds before retrying 03:50:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:50:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:50:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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! 03:50:14 INFO - Caught exception: HTTP Error 404: Not Found 03:50:14 INFO - Caught exception: HTTP Error 404: Not Found 03:50:14 INFO - Caught exception: HTTP Error 404: Not Found 03:50:14 INFO - trying https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:50:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:50:15 INFO - Downloaded 1361 bytes. 03:50:15 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:15 INFO - Using the following test package requirements: 03:50:15 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:50:15 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:50:15 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:50:15 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'jsshell-linux-x86_64.zip'], 03:50:15 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:50:15 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:50:15 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:50:15 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:50:15 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:50:15 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:50:15 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:50:15 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')]} 03:50:15 INFO - https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:50:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:50:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:50:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:50:15 INFO - retry: Failed, sleeping 30 seconds before retrying 03:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:50:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:50:45 INFO - retry: Failed, sleeping 60 seconds before retrying 03:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:51:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:51:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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! 03:51:45 INFO - Caught exception: HTTP Error 404: Not Found 03:51:45 INFO - Caught exception: HTTP Error 404: Not Found 03:51:45 INFO - Caught exception: HTTP Error 404: Not Found 03:51:45 INFO - trying https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:51:46 INFO - Downloaded 21772186 bytes. 03:51:46 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'] 03:51:46 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 03:51:47 INFO - Return code: 0 03:51:47 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 03:51:47 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')]} 03:51:47 INFO - https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:51:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:51:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:51:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:51:47 INFO - retry: Failed, sleeping 30 seconds before retrying 03:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:52:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:52:17 INFO - retry: Failed, sleeping 60 seconds before retrying 03:53:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:53:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:53:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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! 03:53:17 INFO - Caught exception: HTTP Error 404: Not Found 03:53:17 INFO - Caught exception: HTTP Error 404: Not Found 03:53:17 INFO - Caught exception: HTTP Error 404: Not Found 03:53:17 INFO - trying https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:53:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:53:18 INFO - Downloaded 21772186 bytes. 03:53:18 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'] 03:53:18 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 03:53:19 INFO - Return code: 0 03:53:19 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')]} 03:53:19 INFO - https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:53:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:53:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:53:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:53:19 INFO - retry: Failed, sleeping 30 seconds before retrying 03:53:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:53:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:53:49 INFO - retry: Failed, sleeping 60 seconds before retrying 03:54:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:54:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:54:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:54:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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! 03:54:49 INFO - Caught exception: HTTP Error 404: Not Found 03:54:49 INFO - Caught exception: HTTP Error 404: Not Found 03:54:49 INFO - Caught exception: HTTP Error 404: Not Found 03:54:49 INFO - trying https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:54:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:54:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:54:50 INFO - Downloaded 14413740 bytes. 03:54:50 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'] 03:54:50 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 03:54:50 INFO - Return code: 0 03:54:50 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')]} 03:54:50 INFO - https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:54:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:54:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:54:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:54:50 INFO - retry: Failed, sleeping 30 seconds before retrying 03:55:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:55:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:55:20 INFO - retry: Failed, sleeping 60 seconds before retrying 03:56:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:56:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:56:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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! 03:56:20 INFO - Caught exception: HTTP Error 404: Not Found 03:56:20 INFO - Caught exception: HTTP Error 404: Not Found 03:56:20 INFO - Caught exception: HTTP Error 404: Not Found 03:56:20 INFO - trying https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:56:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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 03:56:22 INFO - Downloaded 63889049 bytes. 03:56:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:22 INFO - mkdir: /builds/slave/test/properties 03:56:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:56:22 INFO - Writing to file /builds/slave/test/properties/build_url 03:56:22 INFO - Contents: 03:56:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:22 INFO - Running post-action listener: _resource_record_post_action 03:56:22 INFO - Running post-action listener: set_extra_try_arguments 03:56:22 INFO - [mozharness: 2016-09-14 10:56:22.779727Z] Finished download-and-extract step (success) 03:56:22 INFO - [mozharness: 2016-09-14 10:56:22.779957Z] Running populate-webroot step. 03:56:22 INFO - Running pre-action listener: _resource_record_pre_action 03:56:22 INFO - Running main action method: populate_webroot 03:56:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:22 INFO - '--tppagecycles', 03:56:22 INFO - '7'], 03:56:22 INFO - 'tests': ['tsvgm']}}, 03:56:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:56:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:56:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:56:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['damp', 'tps']}, 03:56:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'tests': ['damp', 'tps']}, 03:56:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['dromaeo_dom']}, 03:56:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['basic_compositor_video']}, 03:56:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:56:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['a11yr', 03:56:22 INFO - 'ts_paint', 03:56:22 INFO - 'tpaint', 03:56:22 INFO - 'sessionrestore', 03:56:22 INFO - 'sessionrestore_no_auto_restore', 03:56:22 INFO - 'tabpaint']}, 03:56:22 INFO - 'other-e10s': {'tests': ['a11yr', 03:56:22 INFO - 'ts_paint', 03:56:22 INFO - 'tpaint', 03:56:22 INFO - 'sessionrestore', 03:56:22 INFO - 'sessionrestore_no_auto_restore', 03:56:22 INFO - 'tabpaint']}, 03:56:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:56:22 INFO - 'ts_paint', 03:56:22 INFO - 'tpaint', 03:56:22 INFO - 'sessionrestore', 03:56:22 INFO - 'sessionrestore_no_auto_restore', 03:56:22 INFO - 'tabpaint']}, 03:56:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:56:22 INFO - 'ts_paint', 03:56:22 INFO - 'tpaint', 03:56:22 INFO - 'sessionrestore', 03:56:22 INFO - 'sessionrestore_no_auto_restore', 03:56:22 INFO - 'tabpaint']}, 03:56:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['a11yr', 03:56:22 INFO - 'ts_paint', 03:56:22 INFO - 'tpaint', 03:56:22 INFO - 'sessionrestore', 03:56:22 INFO - 'sessionrestore_no_auto_restore', 03:56:22 INFO - 'tabpaint']}, 03:56:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['a11yr', 03:56:22 INFO - 'ts_paint', 03:56:22 INFO - 'tpaint', 03:56:22 INFO - 'sessionrestore', 03:56:22 INFO - 'sessionrestore_no_auto_restore', 03:56:22 INFO - 'tabpaint']}, 03:56:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['tsvgx', 03:56:22 INFO - 'tsvgr_opacity', 03:56:22 INFO - 'tart', 03:56:22 INFO - 'tscrollx', 03:56:22 INFO - 'cart']}, 03:56:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:56:22 INFO - 'tsvgr_opacity', 03:56:22 INFO - 'tart', 03:56:22 INFO - 'tscrollx', 03:56:22 INFO - 'cart']}, 03:56:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'talos_options': ['--disable-e10s'], 03:56:22 INFO - 'tests': ['tp5o']}, 03:56:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'tests': ['tp5o']}, 03:56:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'talos_options': ['--disable-e10s', 03:56:22 INFO - '--xperf_path', 03:56:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:22 INFO - 'tests': ['tp5n']}, 03:56:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:22 INFO - 'talos_options': ['--xperf_path', 03:56:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:22 INFO - 'tests': ['tp5n']}}, 03:56:22 INFO - 'talos.zip': {'path': '', 03:56:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:22 INFO - Running post-action listener: _resource_record_post_action 03:56:22 INFO - [mozharness: 2016-09-14 10:56:22.797298Z] Finished populate-webroot step (success) 03:56:22 INFO - [mozharness: 2016-09-14 10:56:22.797387Z] Running create-virtualenv step. 03:56:22 INFO - Running pre-action listener: _resource_record_pre_action 03:56:22 INFO - Running main action method: create_virtualenv 03:56:22 INFO - Creating virtualenv /builds/slave/test/build/venv 03:56:22 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 03:56:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:56:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:22 INFO - Using real prefix '/usr' 03:56:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:56:23 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:25 INFO - Installing pip.................done. 03:56:25 INFO - Return code: 0 03:56:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:56:25 INFO - Using env: {'DISPLAY': ':0', 03:56:25 INFO - 'HOME': '/home/cltbld', 03:56:25 INFO - 'LANG': 'en_US.UTF-8', 03:56:25 INFO - 'LANGUAGE': 'en_US:en', 03:56:25 INFO - 'LOGNAME': 'cltbld', 03:56:25 INFO - 'MAIL': '/var/mail/cltbld', 03:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:25 INFO - 'MOZ_NO_REMOTE': '1', 03:56:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:25 INFO - 'NO_EM_RESTART': '1', 03:56:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:25 INFO - 'PWD': '/builds/slave/test', 03:56:25 INFO - 'SHELL': '/bin/bash', 03:56:25 INFO - 'SHLVL': '1', 03:56:25 INFO - 'TERM': 'linux', 03:56:25 INFO - 'TMOUT': '86400', 03:56:25 INFO - 'USER': 'cltbld', 03:56:25 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:25 INFO - '_': '/tools/buildbot/bin/python'} 03:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:25 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 03:56:25 INFO - Cleaning up... 03:56:25 INFO - Return code: 0 03:56:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:56:25 INFO - Using env: {'DISPLAY': ':0', 03:56:25 INFO - 'HOME': '/home/cltbld', 03:56:25 INFO - 'LANG': 'en_US.UTF-8', 03:56:25 INFO - 'LANGUAGE': 'en_US:en', 03:56:25 INFO - 'LOGNAME': 'cltbld', 03:56:25 INFO - 'MAIL': '/var/mail/cltbld', 03:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:25 INFO - 'MOZ_NO_REMOTE': '1', 03:56:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:25 INFO - 'NO_EM_RESTART': '1', 03:56:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:25 INFO - 'PWD': '/builds/slave/test', 03:56:25 INFO - 'SHELL': '/bin/bash', 03:56:25 INFO - 'SHLVL': '1', 03:56:25 INFO - 'TERM': 'linux', 03:56:25 INFO - 'TMOUT': '86400', 03:56:25 INFO - 'USER': 'cltbld', 03:56:25 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:25 INFO - '_': '/tools/buildbot/bin/python'} 03:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:25 INFO - Downloading/unpacking psutil>=3.1.1 03:56:25 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 03:56:25 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 03:56:25 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 03:56:25 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 03:56:25 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 03:56:25 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 03:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:27 INFO - Installing collected packages: psutil 03:56:27 INFO - Running setup.py install for psutil 03:56:27 INFO - building 'psutil._psutil_linux' extension 03:56:27 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 03:56:28 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 03:56:28 INFO - building 'psutil._psutil_posix' extension 03:56:28 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 03:56:28 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 03:56:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:28 INFO - Successfully installed psutil 03:56:28 INFO - Cleaning up... 03:56:28 INFO - Return code: 0 03:56:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:56:28 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')]} 03:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:28 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')]} 03:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:28 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:28 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 03:56:28 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 03:56:28 INFO - Using env: {'DISPLAY': ':0', 03:56:28 INFO - 'HOME': '/home/cltbld', 03:56:28 INFO - 'LANG': 'en_US.UTF-8', 03:56:28 INFO - 'LANGUAGE': 'en_US:en', 03:56:28 INFO - 'LOGNAME': 'cltbld', 03:56:28 INFO - 'MAIL': '/var/mail/cltbld', 03:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:28 INFO - 'MOZ_NO_REMOTE': '1', 03:56:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:28 INFO - 'NO_EM_RESTART': '1', 03:56:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:28 INFO - 'PWD': '/builds/slave/test', 03:56:28 INFO - 'SHELL': '/bin/bash', 03:56:28 INFO - 'SHLVL': '1', 03:56:28 INFO - 'TERM': 'linux', 03:56:28 INFO - 'TMOUT': '86400', 03:56:28 INFO - 'USER': 'cltbld', 03:56:28 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:28 INFO - '_': '/tools/buildbot/bin/python'} 03:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:56:28 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 03:56:28 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 03:56:28 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 03:56:28 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 03:56:28 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 03:56:28 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 03:56:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:56:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:56:30 INFO - Installing collected packages: mozsystemmonitor 03:56:30 INFO - Running setup.py install for mozsystemmonitor 03:56:30 INFO - Successfully installed mozsystemmonitor 03:56:30 INFO - Cleaning up... 03:56:30 INFO - Return code: 0 03:56:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:56:30 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')]} 03:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:30 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')]} 03:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:30 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:30 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 03:56:30 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 03:56:30 INFO - Using env: {'DISPLAY': ':0', 03:56:30 INFO - 'HOME': '/home/cltbld', 03:56:30 INFO - 'LANG': 'en_US.UTF-8', 03:56:30 INFO - 'LANGUAGE': 'en_US:en', 03:56:30 INFO - 'LOGNAME': 'cltbld', 03:56:30 INFO - 'MAIL': '/var/mail/cltbld', 03:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:30 INFO - 'MOZ_NO_REMOTE': '1', 03:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:30 INFO - 'NO_EM_RESTART': '1', 03:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:30 INFO - 'PWD': '/builds/slave/test', 03:56:30 INFO - 'SHELL': '/bin/bash', 03:56:30 INFO - 'SHLVL': '1', 03:56:30 INFO - 'TERM': 'linux', 03:56:30 INFO - 'TMOUT': '86400', 03:56:30 INFO - 'USER': 'cltbld', 03:56:30 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:30 INFO - '_': '/tools/buildbot/bin/python'} 03:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:30 INFO - Downloading/unpacking jsonschema==2.5.1 03:56:30 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 03:56:30 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 03:56:30 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 03:56:30 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 03:56:30 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 03:56:30 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 03:56:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:56:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:32 INFO - Downloading functools32-3.2.3-2.tar.gz 03:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:56:32 INFO - warning: no files found matching '*.txt' 03:56:32 INFO - no previously-included directories found matching 'build' 03:56:32 INFO - no previously-included directories found matching 'dist' 03:56:32 INFO - no previously-included directories found matching '.git*' 03:56:33 INFO - Installing collected packages: jsonschema, functools32 03:56:33 INFO - Running setup.py install for functools32 03:56:33 INFO - warning: no files found matching '*.txt' 03:56:33 INFO - no previously-included directories found matching 'build' 03:56:33 INFO - no previously-included directories found matching 'dist' 03:56:33 INFO - no previously-included directories found matching '.git*' 03:56:33 INFO - Successfully installed jsonschema functools32 03:56:33 INFO - Cleaning up... 03:56:33 INFO - Return code: 0 03:56:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:33 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:33 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 03:56:33 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 03:56:33 INFO - Using env: {'DISPLAY': ':0', 03:56:33 INFO - 'HOME': '/home/cltbld', 03:56:33 INFO - 'LANG': 'en_US.UTF-8', 03:56:33 INFO - 'LANGUAGE': 'en_US:en', 03:56:33 INFO - 'LOGNAME': 'cltbld', 03:56:33 INFO - 'MAIL': '/var/mail/cltbld', 03:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:33 INFO - 'MOZ_NO_REMOTE': '1', 03:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:33 INFO - 'NO_EM_RESTART': '1', 03:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:33 INFO - 'PWD': '/builds/slave/test', 03:56:33 INFO - 'SHELL': '/bin/bash', 03:56:33 INFO - 'SHLVL': '1', 03:56:33 INFO - 'TERM': 'linux', 03:56:33 INFO - 'TMOUT': '86400', 03:56:33 INFO - 'USER': 'cltbld', 03:56:33 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:33 INFO - '_': '/tools/buildbot/bin/python'} 03:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:56:33 INFO - Cleaning up... 03:56:33 INFO - Return code: 0 03:56:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:33 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:33 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 03:56:33 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 03:56:33 INFO - Using env: {'DISPLAY': ':0', 03:56:33 INFO - 'HOME': '/home/cltbld', 03:56:33 INFO - 'LANG': 'en_US.UTF-8', 03:56:33 INFO - 'LANGUAGE': 'en_US:en', 03:56:33 INFO - 'LOGNAME': 'cltbld', 03:56:33 INFO - 'MAIL': '/var/mail/cltbld', 03:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:33 INFO - 'MOZ_NO_REMOTE': '1', 03:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:33 INFO - 'NO_EM_RESTART': '1', 03:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:33 INFO - 'PWD': '/builds/slave/test', 03:56:33 INFO - 'SHELL': '/bin/bash', 03:56:33 INFO - 'SHLVL': '1', 03:56:33 INFO - 'TERM': 'linux', 03:56:33 INFO - 'TMOUT': '86400', 03:56:33 INFO - 'USER': 'cltbld', 03:56:33 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:33 INFO - '_': '/tools/buildbot/bin/python'} 03:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:33 INFO - Downloading/unpacking blobuploader==1.2.4 03:56:33 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 03:56:33 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 03:56:33 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 03:56:33 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 03:56:33 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 03:56:33 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 03:56:35 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:56:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:36 INFO - Downloading docopt-0.6.1.tar.gz 03:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:36 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:36 INFO - Running setup.py install for blobuploader 03:56:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:56:36 INFO - Running setup.py install for requests 03:56:36 INFO - Running setup.py install for docopt 03:56:36 INFO - Successfully installed blobuploader requests docopt 03:56:36 INFO - Cleaning up... 03:56:36 INFO - Return code: 0 03:56:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:36 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')]} 03:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:36 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')]} 03:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:36 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:36 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 03:56:36 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 03:56:36 INFO - Using env: {'DISPLAY': ':0', 03:56:36 INFO - 'HOME': '/home/cltbld', 03:56:36 INFO - 'LANG': 'en_US.UTF-8', 03:56:36 INFO - 'LANGUAGE': 'en_US:en', 03:56:36 INFO - 'LOGNAME': 'cltbld', 03:56:36 INFO - 'MAIL': '/var/mail/cltbld', 03:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:36 INFO - 'MOZ_NO_REMOTE': '1', 03:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:36 INFO - 'NO_EM_RESTART': '1', 03:56:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:36 INFO - 'PWD': '/builds/slave/test', 03:56:36 INFO - 'SHELL': '/bin/bash', 03:56:36 INFO - 'SHLVL': '1', 03:56:36 INFO - 'TERM': 'linux', 03:56:36 INFO - 'TMOUT': '86400', 03:56:36 INFO - 'USER': 'cltbld', 03:56:36 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:36 INFO - '_': '/tools/buildbot/bin/python'} 03:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:37 INFO - Running setup.py (path:/tmp/pip-D79HWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:37 INFO - Running setup.py (path:/tmp/pip-UvHiMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:37 INFO - Running setup.py (path:/tmp/pip-da3hBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:37 INFO - Running setup.py (path:/tmp/pip-fb5vJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:37 INFO - Running setup.py (path:/tmp/pip-jDviiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:37 INFO - Running setup.py (path:/tmp/pip-GED6a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:37 INFO - Running setup.py (path:/tmp/pip-7Vb0Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:37 INFO - Running setup.py (path:/tmp/pip-gzTTBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:37 INFO - Running setup.py (path:/tmp/pip-IQqpfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:37 INFO - Running setup.py (path:/tmp/pip-WrlE57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:37 INFO - Running setup.py (path:/tmp/pip-yYQSvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:37 INFO - Running setup.py (path:/tmp/pip-iRHTeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:37 INFO - Running setup.py (path:/tmp/pip-HE0fEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:37 INFO - Running setup.py (path:/tmp/pip-BimiZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:38 INFO - Running setup.py (path:/tmp/pip-scNvPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:38 INFO - Running setup.py (path:/tmp/pip-KDRaE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:38 INFO - Running setup.py (path:/tmp/pip-_1N7do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:56:38 INFO - Running setup.py install for manifestparser 03:56:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:56:38 INFO - Running setup.py install for mozcrash 03:56:38 INFO - Running setup.py install for mozdebug 03:56:38 INFO - Running setup.py install for mozdevice 03:56:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:56:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:56:38 INFO - Running setup.py install for mozfile 03:56:38 INFO - Running setup.py install for mozhttpd 03:56:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:56:38 INFO - Running setup.py install for mozinfo 03:56:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:56:38 INFO - Running setup.py install for mozInstall 03:56:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Running setup.py install for mozleak 03:56:39 INFO - Running setup.py install for mozlog 03:56:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Running setup.py install for moznetwork 03:56:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Running setup.py install for mozprocess 03:56:39 INFO - Running setup.py install for mozprofile 03:56:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Running setup.py install for mozrunner 03:56:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:56:39 INFO - Running setup.py install for mozscreenshot 03:56:39 INFO - Running setup.py install for moztest 03:56:39 INFO - Running setup.py install for mozversion 03:56:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:56:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:56:40 INFO - Cleaning up... 03:56:40 INFO - Return code: 0 03:56:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:40 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')]} 03:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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')]} 03:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:40 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 03:56:40 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 03:56:40 INFO - Using env: {'DISPLAY': ':0', 03:56:40 INFO - 'HOME': '/home/cltbld', 03:56:40 INFO - 'LANG': 'en_US.UTF-8', 03:56:40 INFO - 'LANGUAGE': 'en_US:en', 03:56:40 INFO - 'LOGNAME': 'cltbld', 03:56:40 INFO - 'MAIL': '/var/mail/cltbld', 03:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:40 INFO - 'MOZ_NO_REMOTE': '1', 03:56:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:40 INFO - 'NO_EM_RESTART': '1', 03:56:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:40 INFO - 'PWD': '/builds/slave/test', 03:56:40 INFO - 'SHELL': '/bin/bash', 03:56:40 INFO - 'SHLVL': '1', 03:56:40 INFO - 'TERM': 'linux', 03:56:40 INFO - 'TMOUT': '86400', 03:56:40 INFO - 'USER': 'cltbld', 03:56:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:40 INFO - '_': '/tools/buildbot/bin/python'} 03:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:40 INFO - Running setup.py (path:/tmp/pip-HJFWZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:40 INFO - Running setup.py (path:/tmp/pip-_YOgUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:40 INFO - Running setup.py (path:/tmp/pip-Rcqp0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:40 INFO - Running setup.py (path:/tmp/pip-lbup5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:40 INFO - Running setup.py (path:/tmp/pip-mY0h1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:40 INFO - Running setup.py (path:/tmp/pip-X8JKrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:40 INFO - Running setup.py (path:/tmp/pip-_ujCHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:40 INFO - Running setup.py (path:/tmp/pip-ObJ89N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:40 INFO - Running setup.py (path:/tmp/pip-7tMe7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:40 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)) 03:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:40 INFO - Running setup.py (path:/tmp/pip-nrLmqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:41 INFO - Running setup.py (path:/tmp/pip-g_R4vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:41 INFO - Running setup.py (path:/tmp/pip-BS6ZEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:41 INFO - Running setup.py (path:/tmp/pip-RhMkyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:41 INFO - Running setup.py (path:/tmp/pip-IDCKVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:41 INFO - Running setup.py (path:/tmp/pip-c9X5xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:41 INFO - Running setup.py (path:/tmp/pip-MBj0D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:41 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)) 03:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:41 INFO - Running setup.py (path:/tmp/pip-0yZk0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:41 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)) 03:56:41 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)) 03:56:41 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)) 03:56:41 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)) 03:56:41 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)) 03:56:41 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)) 03:56:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:56:41 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 03:56:41 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 03:56:41 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 03:56:41 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 03:56:41 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 03:56:41 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 03:56:43 INFO - Downloading blessings-1.6.tar.gz 03:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:56:43 INFO - Installing collected packages: blessings 03:56:43 INFO - Running setup.py install for blessings 03:56:43 INFO - Successfully installed blessings 03:56:43 INFO - Cleaning up... 03:56:43 INFO - Return code: 0 03:56:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:56:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:43 INFO - Reading from file tmpfile_stdout 03:56:43 INFO - Current package versions: 03:56:43 INFO - argparse == 1.2.1 03:56:43 INFO - blessings == 1.6 03:56:43 INFO - blobuploader == 1.2.4 03:56:43 INFO - docopt == 0.6.1 03:56:43 INFO - functools32 == 3.2.3-2 03:56:43 INFO - jsonschema == 2.5.1 03:56:43 INFO - manifestparser == 1.1 03:56:43 INFO - mozInstall == 1.12 03:56:43 INFO - mozcrash == 0.17 03:56:43 INFO - mozdebug == 0.1 03:56:43 INFO - mozdevice == 0.48 03:56:43 INFO - mozfile == 1.2 03:56:43 INFO - mozhttpd == 0.7 03:56:43 INFO - mozinfo == 0.9 03:56:43 INFO - mozleak == 0.1 03:56:43 INFO - mozlog == 3.3 03:56:43 INFO - moznetwork == 0.27 03:56:43 INFO - mozprocess == 0.23 03:56:43 INFO - mozprofile == 0.28 03:56:43 INFO - mozrunner == 6.12 03:56:43 INFO - mozscreenshot == 0.1 03:56:43 INFO - mozsystemmonitor == 0.3 03:56:43 INFO - moztest == 0.7 03:56:43 INFO - mozversion == 1.4 03:56:43 INFO - psutil == 3.1.1 03:56:43 INFO - requests == 1.2.3 03:56:43 INFO - wsgiref == 0.1.2 03:56:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:43 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')]} 03:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:43 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')]} 03:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:43 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:43 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 03:56:43 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 03:56:43 INFO - Using env: {'DISPLAY': ':0', 03:56:43 INFO - 'HOME': '/home/cltbld', 03:56:43 INFO - 'LANG': 'en_US.UTF-8', 03:56:43 INFO - 'LANGUAGE': 'en_US:en', 03:56:43 INFO - 'LOGNAME': 'cltbld', 03:56:43 INFO - 'MAIL': '/var/mail/cltbld', 03:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:43 INFO - 'MOZ_NO_REMOTE': '1', 03:56:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:43 INFO - 'NO_EM_RESTART': '1', 03:56:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:43 INFO - 'PWD': '/builds/slave/test', 03:56:43 INFO - 'SHELL': '/bin/bash', 03:56:43 INFO - 'SHLVL': '1', 03:56:43 INFO - 'TERM': 'linux', 03:56:43 INFO - 'TMOUT': '86400', 03:56:43 INFO - 'USER': 'cltbld', 03:56:43 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:43 INFO - '_': '/tools/buildbot/bin/python'} 03:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 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)) 03:56:44 INFO - Cleaning up... 03:56:44 INFO - Return code: 0 03:56:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:56:44 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')]} 03:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:44 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')]} 03:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:44 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 0x244a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ad8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, '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': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', '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 03:56:44 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 03:56:44 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 03:56:44 INFO - Using env: {'DISPLAY': ':0', 03:56:44 INFO - 'HOME': '/home/cltbld', 03:56:44 INFO - 'LANG': 'en_US.UTF-8', 03:56:44 INFO - 'LANGUAGE': 'en_US:en', 03:56:44 INFO - 'LOGNAME': 'cltbld', 03:56:44 INFO - 'MAIL': '/var/mail/cltbld', 03:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:44 INFO - 'MOZ_NO_REMOTE': '1', 03:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:44 INFO - 'NO_EM_RESTART': '1', 03:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:44 INFO - 'PWD': '/builds/slave/test', 03:56:44 INFO - 'SHELL': '/bin/bash', 03:56:44 INFO - 'SHLVL': '1', 03:56:44 INFO - 'TERM': 'linux', 03:56:44 INFO - 'TMOUT': '86400', 03:56:44 INFO - 'USER': 'cltbld', 03:56:44 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:44 INFO - '_': '/tools/buildbot/bin/python'} 03:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:56:44 INFO - Cleaning up... 03:56:44 INFO - Return code: 0 03:56:44 INFO - Running post-action listener: _resource_record_post_action 03:56:44 INFO - Running post-action listener: _start_resource_monitoring 03:56:44 INFO - Starting resource monitoring. 03:56:44 INFO - [mozharness: 2016-09-14 10:56:44.331591Z] Finished create-virtualenv step (success) 03:56:44 INFO - [mozharness: 2016-09-14 10:56:44.332020Z] Running install step. 03:56:44 INFO - Running pre-action listener: _resource_record_pre_action 03:56:44 INFO - Running main action method: install 03:56:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:44 INFO - Reading from file tmpfile_stdout 03:56:44 INFO - Detecting whether we're running mozinstall >=1.0... 03:56:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:56:44 INFO - Reading from file tmpfile_stdout 03:56:44 INFO - Output received: 03:56:44 INFO - Usage: mozinstall [options] installer 03:56:44 INFO - Options: 03:56:44 INFO - -h, --help show this help message and exit 03:56:44 INFO - -d DEST, --destination=DEST 03:56:44 INFO - Directory to install application into. [default: 03:56:44 INFO - "/builds/slave/test"] 03:56:44 INFO - --app=APP Application being installed. [default: firefox] 03:56:44 INFO - mkdir: /builds/slave/test/build/application 03:56:44 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'] 03:56:44 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 03:57:01 INFO - Reading from file tmpfile_stdout 03:57:01 INFO - Output received: 03:57:01 INFO - /builds/slave/test/build/application/firefox/firefox 03:57:01 INFO - Running post-action listener: _resource_record_post_action 03:57:01 INFO - [mozharness: 2016-09-14 10:57:01.328025Z] Finished install step (success) 03:57:01 INFO - [mozharness: 2016-09-14 10:57:01.328300Z] Running run-tests step. 03:57:01 INFO - Running pre-action listener: _resource_record_pre_action 03:57:01 INFO - Running main action method: run_tests 03:57:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:57:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:57:01 INFO - Python 2.7.3 03:57:01 INFO - Return code: 0 03:57:01 INFO - grabbing minidump binary from tooltool 03:57: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')]} 03:57:01 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 0x27093b0>, '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 0x270a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:57:01 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 03:57:01 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 03:57:01 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 03:57:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:57:01 INFO - Return code: 0 03:57:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:57:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:57:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:57:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:57:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:57:01 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/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 03:57:01 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/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 03:57:01 INFO - Using env: {'DISPLAY': ':0', 03:57:01 INFO - 'HOME': '/home/cltbld', 03:57:01 INFO - 'LANG': 'en_US.UTF-8', 03:57:01 INFO - 'LANGUAGE': 'en_US:en', 03:57:01 INFO - 'LOGNAME': 'cltbld', 03:57:01 INFO - 'MAIL': '/var/mail/cltbld', 03:57:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:01 INFO - 'MOZ_NO_REMOTE': '1', 03:57:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:01 INFO - 'NO_EM_RESTART': '1', 03:57:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:01 INFO - 'PWD': '/builds/slave/test', 03:57:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:01 INFO - 'SHELL': '/bin/bash', 03:57:01 INFO - 'SHLVL': '1', 03:57:01 INFO - 'TERM': 'linux', 03:57:01 INFO - 'TMOUT': '86400', 03:57:01 INFO - 'USER': 'cltbld', 03:57:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1473850121.744833-1478930374', 03:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:01 INFO - '_': '/tools/buildbot/bin/python'} 03:57:01 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/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 03:57:01 INFO - mozversion application_buildid: 20160914004005 03:57:01 INFO - mozversion application_changeset: 7852ac7e74faf5a637189437ce0a9a7f39e6292c 03:57:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:57:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:57:01 INFO - mozversion application_name: Firefox 03:57:01 INFO - mozversion application_remotingname: firefox-dev 03:57:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:57:01 INFO - mozversion application_vendor: Mozilla 03:57:01 INFO - mozversion application_version: 50.0a2 03:57:01 INFO - mozversion platform_buildid: 20160914004005 03:57:01 INFO - mozversion platform_changeset: 7852ac7e74faf5a637189437ce0a9a7f39e6292c 03:57:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:57:01 INFO - mozversion platform_version: 50.0a2 03:57:01 INFO - using testdate: 1473850621 03:57:01 INFO - actual date: 1473850621 03:57:01 INFO - starting webserver on 'localhost:44328' 03:57:01 INFO - SUITE-START | Running 2 tests 03:57:01 INFO - TEST-START | dromaeo_css 03:57:01 INFO - operating with platform_type : linux_ 03:57:01 INFO - Initialising browser for dromaeo_css test... 03:57:01 INFO - TEST-INFO | started process 13792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4BjTkK/profile http://localhost:44328/getInfo.html) 03:57:05 INFO - PROCESS | 13792 | __metrics Screen width/height:1600/1200 03:57:05 INFO - PROCESS | 13792 | colorDepth:24 03:57:05 INFO - PROCESS | 13792 | Browser inner width/height: 1024/697 03:57:05 INFO - PROCESS | 13792 | __metrics 03:57:06 INFO - PROCESS | 13792 | 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" 03:57:06 INFO - PROCESS | 13792 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:57:07 INFO - TEST-INFO | 13792: exit 0 03:57:07 INFO - Browser initialized. 03:57:07 INFO - Running cycle 1/1 for dromaeo_css test... 03:57:07 INFO - TEST-INFO | started process 13876 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4BjTkK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:19 INFO - PROCESS | 13876 | RSS: Main: 145768448 03:57:19 INFO - PROCESS | 13876 | 03:58:10 INFO - PROCESS | 13876 | 1473850690640 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:00:19 INFO - PROCESS | 13876 | Cycle 1(1): loaded http://localhost:44328/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44328/tests/dromaeo/cssquery-ext.html) 04:00:21 INFO - PROCESS | 13876 | RSS: Main: 275697664 04:00:21 INFO - PROCESS | 13876 | 04:03:23 INFO - PROCESS | 13876 | Cycle 1(1): loaded http://localhost:44328/tests/dromaeo/cssquery-ext.html (next: http://localhost:44328/tests/dromaeo/cssquery-jquery.html) 04:03:24 INFO - PROCESS | 13876 | RSS: Main: 231219200 04:03:24 INFO - PROCESS | 13876 | 04:06:26 INFO - PROCESS | 13876 | Cycle 1(1): loaded http://localhost:44328/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44328/tests/dromaeo/cssquery-mootools.html) 04:06:26 INFO - PROCESS | 13876 | RSS: Main: 213561344 04:06:26 INFO - PROCESS | 13876 | 04:09:28 INFO - PROCESS | 13876 | Cycle 1(1): loaded http://localhost:44328/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44328/tests/dromaeo/cssquery-prototype.html) 04:09:29 INFO - PROCESS | 13876 | RSS: Main: 221384704 04:09:29 INFO - PROCESS | 13876 | 04:12:31 INFO - PROCESS | 13876 | Cycle 1(1): loaded http://localhost:44328/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44328/tests/dromaeo/cssquery-yui.html) 04:12:32 INFO - PROCESS | 13876 | RSS: Main: 177229824 04:12:32 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | Cycle 1(1): loaded http://localhost:44328/tests/dromaeo/cssquery-yui.html (next: http://localhost:44328/tests/dromaeo/cssquery-dojo.html) 04:15:35 INFO - PROCESS | 13876 | RSS: Main: 266809344 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | __start_tp_report 04:15:35 INFO - PROCESS | 13876 | _x_x_mozilla_page_load 04:15:35 INFO - PROCESS | 13876 | _x_x_mozilla_page_load_details 04:15:35 INFO - PROCESS | 13876 | |i|pagename|runs| 04:15:35 INFO - PROCESS | 13876 | |0;dojo.html;3259.7718159728815;3260.6250281217667;3295.5386245925574;3305.3224089061746;3344.08037789608;5618.690971996543;5721.6853073080965;5728;5735.627184233022;5739.139129130638;5668.829935101943;5708.257926469553;5710.1434784782305;5712.314522257335;5715.3141623005195;5032.9748351258195;5147.845564633067;5158.509941555546;5158.561522270602;5204.661696989702;5199.896002079956;5253.028189784883;5257.947420525803;5268.841934741945;5268.973655131719;5100.566451851597;5141.537261646451;5159.948400515985;5160.716160611165;5263.657862238962;5195.246689230041;5197.636165468419;5238.580913526927;5261.079311120577;5292.92060619091;5038.84883453497;5048.873778155539;5087.87280317995;5122.359705036871;5129.615278854101;5587;5609.803656872031;5619.213310136584;5625.80309689163;5638.520725738317;6130.540209484307;6140.815775526741;6157.7229024694;6160.7535698572;6160.938390616081;6029.095635654643;6088.025915853442;6104.725287362079;6126.540509461808;6174.197354343952;5752.511036561939;5765.625234359753;5766.336871259817;5794.333651630032;5811.128330750379;6332.905006424908;6345.619262844244;6353.205849268841;6355.0785136155;6361.01404282337;5863.941360586339;5949.286085669661;5976.970115149396;5988.371221021817;6088.665123418255;6102.572819902653;6112.663803490762;6114.969425152845;6122.20411346531;6128.509719222451;6082;6099.460754943115;6114.449440885101;6126.578745377165;6129.175413489998;6012.068129439944;6022.156898034279;6031.457168854824;6048.879022419615;6126.479249263863;4632.073585282957;4666.346711460398;4667.276572131325;4673.976630116896;4696.95303046972;4137.91724165521;4163.12574359388;4163.500379954425;4168.958310416857;4172.520160181618;5642.0972644377525;5695.572832037614;5784.132380143013;5831.2127862738835;5834.474897259182;6160.9383906161265;6161.691915404212;6170.660613666201;6181.412765787243;6195.411435913581;5835.44563266489;5840.970795145997;5850.61970971894;5865.501432378211;5868.4424979626865;5067.949320506747;5218.921716174261;5229.712365819841;5333.200019997032;5334.413214546396;6306.873862522815;6348.904766428507;6355.459785918156;6364.49084073273;6372.904406433907;6125.816225513242;6126.816195514141;6133.8466538335115;6134.723937422916;6138.600990935575;6121.361519882331;6265.4361107500545;6300.7479700811455;6361.2048493938255;6367.61794292344;5991.9813631681845;6068.969655151696;6088.726007329592;6096.055111457731;6127.019676851683;6107.816765497042;6165.938340616536;6166.907496387647;6169.074638804215;6173.84565385869;5793.739281732248;5965.73154208053;6010.3088144863905;6037.275526936795;6053.818385448443;5994.730237139426;6045.516358691206;6054.0918862169265;6078.45293923549;6088.299845517822;5987.520998320037;5994.70026498682;5997.070454079625;5997.580169388101;5999.190109335358;5869.765209391748;6038.758449661965;6058.697065146637;6060.030395136933;6094.908576371269;5972.492338151305;5979.701014949323;5983.670898100646;6004.159417681441;6014.48876845473 04:15:35 INFO - PROCESS | 13876 | |1;ext.html;583.4194975998879;644.9322821103787;653.5588477777499;654.6464908949167;671.7984604618613;34479.827600861994;45813.77093114534;46977;47000;47075.293870591966;48095.51904480954;48683.51316486834;48713;48744.75627621857;48790;8295.875561866576;9146.405483643575;9294.302927280447;9301.162895339417;10795.514201860935;10598.523066461988;11056.668299951014;11065.889341106566;11065.889341106607;11068.059214966737;11110.500027498741;11150.776984460306;11158.776824463506;11163.497642606062;11165.441727913612;11039.779204415867;11039.889601104007;11052.281601695864;11088.2238243323;11104.389258590774;10962.671119866416;11016.614418495314;11027.1729620278;11036.227464077518;11041.613543526018;44390.89022774424;44832.3275150873;44838.55161448409;44921;44955.77522112369;3729.179580492287;3850.9614903850884;3931.82306796192;3957.3470377387985;4178.603032711888;34881.127971800655;34890.82554587211;34970.65029349699;35487.64512354895;35537;4523.253663145577;4533.591976722078;4536.319552067183;4634.374359461463;4675.392199014106;403149;403420;404287;405362;406228.9688551568;63060;63485.412864677826;63501;63574;63591.36408635947;35972.460413093824;36494;36506.452403213974;36569.26861462756;64965.67517162478;18153.455396337868;18596.07019649012;18596.907015464836;18609;18692.532686682942;11434.714132146762;13931.930340348234;14218.85781142196;14225.217613031382;14274.643133921527;20812.687809682866;20854.270100546408;20954.79045209559;21046.78953210479;21124.788752112283;7304.707811687579;7506.699732010768;7579.2420757924865;7758.340541054074;7797.220277972157;1014.5282443663584;1048.449563978902;1139.9145064120223;1148.7300484386162;1162.4826952006445;36000.45999310012;36035.819820900724;36374;36422;36581.63418365782;1106.7620461600634;1132.8810474900017;1142.7200335917587;1175.7060734816296;1183.089021453476;17896.9105154476;18764.437066888015;18801.90599046996;18825.529361766065;18946.147423365983;601989;606604.8018559362;607183;608655;609824;65432.67283663646;66075.66962165158;66270.33729662737;66289;66293.33706662968;1308.888744456732;1419;1447.8552144785647;1462.6928345047659;1472.683373074794;34592.65407345894;35224.47163292604;36342.27315453729;36761.448578271884;36869.81565092158;61047;62811.68594157;63081.36918630755;63157;68049;581505;583099;585405;586433;586819;31093.8445307772;31427;31874;33119.337613248084;49142.508574913794;53412.198817017;53689.46310537001;53819.73090134524;54202.45797541974;55405;45440;45532.77233613811;46042.53957460383;46528.302075469575;47498.52501474941;45356.77321613371;45685.54314456813;45708.542914570426;45726.77136614296;46691.06617867691;44484.77757611191;45234.773826131976;45272.773636131606;45804.08391832078;46173.76913115547;38824.6117538821;39577.2084558313;40348.596514034485;40413.1917361657;40757.38863916984;30305.393892122476;30545.847270764392;30759.384812304077;31081.844590776902;31097.8445107773 04:15:35 INFO - PROCESS | 13876 | |2;jquery.html;388.06669958749217;406.94099355593437;410.19806278725116;419.2223425545612;437.130111078953;14529.418823247071;20488;20666.27668031619;20729.689054664173;21180.25869094582;20356.287529936457;20608.793912060875;20624;20650.48373790652;20878.68681969768;5869.647821130746;5869.941300586982;5886.087656413252;5938.821835344925;5975.073863551156;5848.5321174305955;5863.648181109126;5895.322037965646;5902.793402230923;5906.88186236275;5476.315460567429;5712.771489140429;5738.913916291259;5803.100519419476;5810.15752715856;5744.712764361786;5767.106098554752;5802.738876750556;5805.970970145143;5948.7323070461935;5710.942890571083;5911.615744976562;5917.171595976567;5922.615030023035;5923.555733319975;23942.88028559846;24149.758502414927;24153.396165095837;24395.878020609784;24804.50390992188;2544.160427059066;2648.642433271503;2666.973330266692;2739.9726002739917;2906.985465072661;10223.168535524892;10359.741006474824;10405.375677459377;10495.475226238657;14015.50945716895;1938.7964263752244;1958.7257783910263;2062.061761898333;2099.0029735875337;2121.2787652198326;64534.677326613535;68133;77785.61107194485;78070.60964695197;91069;9874.555644995993;10572.312799667998;11268.605598804003;11391.487383067617;11463.254888432391;16047.438339658056;16125.677486450206;16138.919305403399;16153.515394538183;16177.757333640006;8104.311133553596;8343.499390036617;8618.310535157172;8678.86981695275;8827.117288271122;7678.692852285846;7855.410844186595;7890.408219383569;8189.836203275901;8288.419810613319;4772.904541909143;4789.568938795525;4813.302071199727;4841.564259216688;4860.6111511079025;1939.9127039282985;2053.16846677096;2059.4542446251753;2066.5040390306217;2121.968170477444;928.3408779766442;1032.4734385463469;1048.6539441984128;1050.3015494696094;1100.0979197058334;20092.196312147644;20512.487187820425;20880.58238835245;21063.157473701187;21310.893445532984;971.6744890461774;1036.9274150809522;1045.8797238317538;1059.2902755154066;1063.648995831374;10593.682189534326;11462.828057579143;11538.13463990204;11565.884341156649;11582.420878956018;423430;564734;572848;576391;611616;74172;89080.5545972266;99539;106368.46815765872;106728.46635766927;1062.3201151263197;1063.3832377221192;1100.708312297242;1101.3832253937821;1101.8181999970075;17598;18247.726284105484;18404.907975460035;18433.170507326933;18787.34244301443;44238.77880610705;44340.55659443494;44737.77631111954;45173.77413112913;47094;54212.18681719698;56942.43057569371;60497.69751151216;61876;63183.36816631959;8034.558099304594;8198.344132469507;8252.092269850431;8256.422050456411;8516.233538981522;8460.153984601491;11102.167337449562;11764.647060588197;11954.76090478203;12115.091368147248;9706;9793.853092203764;10162.54268557902;10163.89836101659;10387.948060259952;9669;10491.94754026225;10586.4706764663;10776;11129.165312601426;9662.855057174289;10145.594176232868;10520.736981575521;10681.786364272826;10735.94632026835;7753.914451976615;7863.764087077397;7901.486403383762;7959.204079591995;8029.3175080118845;7927.682892684228;9781.657641982496;10039.24705647094;10354.430506321924;10394 04:15:35 INFO - PROCESS | 13876 | |3;mootools.html;1540.5455390659754;1564.7105285522175;1727.5767436977942;1799.1004497751123;1852.7776666799987;9025.954870225647;9764;9764.902350976488;9765.072318129784;9772.706818795448;8001.559914204717;9064.138906803842;9290.674826381079;9309.255259579235;9552.80894382112;2551.5534781413226;2563.8974441022337;2576.9226923192336;2577.2783620586174;2579.6130580412996;2453.926382208537;2545.338212064858;2549.8852551635127;2554.0549996501322;2555.540002799495;2523.028633975914;2529.3929456930387;2536.441982763789;2537.5939849624065;2544.427503811646;2490.140901389009;2490.8256422050463;2496.213692686798;2516.421223118693;2520.8865601047814;2381.8809059547125;2428.3200703802786;2441.426264827764;2454.9754502454925;2459.532688789124;9558.2831287653;9659.613615455373;9664.855027174597;9670.419774813534;9674.90325096747;5577.44225577439;5761.308642962829;5761.798337058225;5765.423457654243;5774.393688662714;3496.17839807645;3638.2359704462096;3709.2210635766523;3709.647583479565;3734.3838266685966;1094.7263184203948;1166.4634268236537;1180.238746008828;1202.2485946283573;1212.1878341511208;13547.525836595672;13556.796648050287;13577.321133943264;13583.864161358359;13588.252646104362;4977.352944117241;4983.202687569971;5007.048660754445;5024.070546948826;5240.397354304304;11451.770964580663;11454.54181832734;11456.94271528637;11473.36896470702;11481.540738370539;5698.458646428583;5707.200991861143;5709.514691251207;5712.0860662293835;5715.771369145187;4582.358469814229;5067.721275329893;5117.232415137759;5138.100832354323;5233.345831771028;4132.40080188371;4182.790860456965;4283.957160428418;4320.179165958458;4323.546027667113;1348.9595312140652;1351.2365513485047;1362.1963041805382;1369.7329020840841;1376.215557132445;1047.7852040331713;1048.2452634103586;1049.1397054415459;1055.1875056206677;1068.6633710381204;6313.715882785194;6376.107344971708;6379.106925030499;6435.646039467781;6460.127882735865;479.9856004319876;485.1727804094011;491.6730374301121;491.7688686317425;492.54439643330494;4182.2681030819485;4191.6856235781825;4334.783260836945;4348.347747837786;4374.759388233678;10601.098906593026;10952.780944381067;10955;10981.835272470918;11032.503537340679;10897.564097436014;10910.345379277269;10918.1811364148;10924.071453926505;10925.07136893357;8234;8324.500529968222;8327.375446841512;8703.260222881127;8712;6377.840553986188;6609.966950165411;6638.867222655616;6724.93275067243;6799.3200679931615;10814.891851081704;10847.457627118454;11030.944845276043;11033.834492482458;11037.834432483358;10510.264281500222;10895.509702063267;11256.268342558034;11319;11324.32054076758;7447.404207663266;8187.5087494750505;8212.137725538732;8411.8738218928;8654.350923680624;9178.586963586788;9180.311476639412;9244.768880777765;9245.491497967676;9341.299402544972;8224.095349511668;8322.334213263046;8342.958285208535;8428.704995325133;8617.138286171332;8139.104698483044;8151.959240203998;8155.306798921921;8183.304419124441;8408.369372297224;7754.6898124074405;7766.8446631065935;7776.92223077762;7828.491148075357;7848.175941526058;5632.788950375689;5644.068728659725;5664.235328230636;5690.8577285566535;5926.1999630049395;5659.54723622101;5706.400827913009;5722.628029178257;5798.971005144947;5835.620684655484 04:15:35 INFO - PROCESS | 13876 | |4;prototype.html;334.2896345266309;334.71381968417;341.0603786568005;341.80709324457644;349.0941008084024;5523.91714124288;5596.2165296858475;5599.272094627698;5607.158926161078;5611.719414029298;5233;5382.461753824626;5399.676019438836;5400.648957817749;5539.390667026634;4191.55988621195;4206.1377417629465;4227.936580951288;4251.4685664291965;4259.70182087254;4295.818649871279;4303.225419424502;4314.97842510787;4317.633001194887;4327.112941846914;4097.651699605537;4253.447051883266;4256.34002738932;4261.659067274626;4264.23243816113;4237.343211802176;4242.172776308621;4242.512111107231;4244.493204912243;4262.808173632194;3734.3464893643504;3748.5314335708035;3764.7929363884973;3765.2093060457337;3780.0549862534367;5315.548178404841;5336.9733151334385;5461.290032295816;5467.480589344046;5481.671099733989;4863.53796389346;4881.1213981483315;4886.877828054292;4898.24077268024;4920.409550853885;5771.624844385102;5799;5800.709964501801;5802.796902108448;5818.359980402152;4051.331530297527;4128.8348466061325;4145.233131870614;4151.771652559108;4158.4178215049915;5979.671118088503;5982.192404025444;5987.281526216838;6003.909941350883;6009.699515024275;4259.1268789897995;4266.2960611499375;4279.186954478639;4281.186574550888;4305.31115021595;4227.260229459832;4296.957030429656;4326.870193894189;4331.935020974688;4344.956550434518;4879.609631229493;4888.877778055577;4904.166291730415;4906.920477494946;4914.164592019365;5807.94192058074;5817.88364232713;5825.4174582542255;5828.854278642983;5895.115732640053;1362.768329384007;1411.3931009665941;1432.4699861051477;1450.9201993890438;1452.8619781120776;963.7253382786013;981.5533932060895;990.4453506036642;993.2848349188573;999.0908273471251;5480.945190548123;5596.944030559724;5597.104463285855;5617.9719101404235;5651.180578816049;5914.4381283777975;5920.2895652521975;5921.851953701192;5941.524678025748;5941.970290148608;5588.636738611977;5614.691191984399;5619.241402410704;5628.746706398222;5633.098704207389;2759.7102304258165;2810.494111059988;2812.9465490192274;2821.4216085702383;2842.928926776806;5396.812701205728;5403.756830942617;5412.648177868426;5417.512423881791;5425.891482170335;5274.340707411574;5313.495217954288;5349.849782296825;5351.518363347317;5392.40683524812;5800.50695690871;6201.937980620226;6251.156093927261;6253.46845518127;6256.687165641791;6051.3041000283765;6066.363031881765;6066.484348830222;6067.481994972214;6100.2984656763265;5858.9121163181735;5907.379725128972;5934.080217566284;5952.886725212487;6015.428534289235;6155.292141403616;6161.291451483136;6191.81424557264;6239.844003899939;6255.71849266775;4219.987203071302;4230.323148296257;4270.252705776539;4275.722078064916;4282.25060614385;5840.094785308284;5954.612950158225;6008.188894499187;6010;6030.7889223876955;5732.219873361333;5879.882402351844;5903.350631430625;5912.290525137011;5956.910646340221;5698.6580805151825;5708.714564271687;5828.970855145697;5835.44563266489;5847.38602446737;5803.361630220588;5809.593328466966;5814.680192589448;5816.592838501264;5834.416558344132;5665.66006039639;5812.041013232769;5828.854278643068;5850;5883.882322353615;5691.4877661010705;5704.3154821421695;5742.731621009594;5749.367569567261;5760.855978600569 04:15:35 INFO - PROCESS | 13876 | |5;yui.html;800.6597196191617;808.1272225995922;814.4258297899981;837.3008537870877;880.5773228850151;15404.768928466083;16182;17425.825741742578;17670.293188272455;17786.199621017036;15973;16169.110698911556;16169.595760105974;16201.270942807601;16217.189140542985;724.9202587715347;838.2581415447312;857.7255278310943;872.9039805621377;875.7722992022088;867.4751775176022;875.741656211416;876.2376732242926;876.3909083187187;876.6186708781701;908.2234689340618;910.1080940678139;911.4759013567225;911.7857303533664;911.803962148138;752.6349720385608;887.7913690282778;903.1465265324272;906.5376657904483;906.8005038891466;662.2020465339255;665.3579295979403;672.3444641474572;672.7813460625316;672.9663516824188;15471.149086800222;15564.37742490299;15567.844321556753;15612.141332226724;15613.609659758484;14911.701765964728;14990.100593964396;15594.142322172276;15672.373105075898;15759.763603545956;14520.854791452055;14543.200123993176;14661.41354345825;14717.779233311401;14841.406343746237;4399.12017596482;4459.977700111511;4535.160995215862;4600.35595016698;4650.906981860344;83300;83882.58058709667;85849;87957;89173.10826891713;11865.110116741107;11937.104717146249;11941.402929853473;12046.51813927433;12124;21694.457638558848;22306.888465557568;22336.664950025763;22413.775862241495;22489.55020899573;9547.115082707436;9574.617015319449;9644.083812037845;9768;9930.2055835533;4848.200046992207;5069.518395752398;5165.457626949192;5322.494363035506;5391.81128660495;4082.530508991504;4232.174725928415;4241.87274381769;4243.957560424418;4258.446401967786;728.0426239495047;728.1735230513406;728.377237461973;732.7142414458366;744.8845428958521;669.2671524680467;713.4149997002512;730.2952650692108;736.0321177651372;745.6048294403975;10203.28576999603;10473.528691208761;10705.518251678694;10769;10833.349999000086;699.9020137180798;723.768394113889;726.2555880222814;746.8879668049727;754.2080815144077;4772.928406073912;5094.388673359221;5134.974325128351;5174.223866420067;5221.55616772571;103859.48070259752;130745.34627326802;132752;134744;136496.3175184118;114812;115326.4233678843;116614;118513;118690;99100.5044974785;99920.50039749754;100629.49685251674;102576;102605.48697256616;9742.269329800434;9798.461084640414;9860.211183105477;9974.451405172786;10043.899561004297;51006;58566.4143358578;59294.70352648209;60632.69683651553;60709.69645151746;73797;74482;75231;75282.62358688172;75572.62213688897;13774.586762397144;13938.233397163254;14100.153990760587;14220.431182752987;14276.57170284893;15103.924480377527;15136.769078295341;15524.767128493308;15950.760738589162;15955;13836.515721949683;14168;14530.85469145295;14618.342174602381;15105.093694378373;14291.35688894023;14351.856481435052;14552.417903284173;14851;14882.33029513696;14538.491152810024;14753.796640352459;14838.406463741761;15294.694106118039;15353.309101090701;11175.273607215506;11245.100391968786;11782.351970641697;12213.87786122163;12383.62849114528;11543.711407214887;11881.940590296994;11896.524139034342;12044.819327710267;12198.75602487963 04:15:35 INFO - PROCESS | 13876 | __end_tp_report 04:15:35 INFO - PROCESS | 13876 | __start_cc_report 04:15:35 INFO - PROCESS | 13876 | _x_x_mozilla_cycle_collect,1164 04:15:35 INFO - PROCESS | 13876 | __end_cc_report 04:15:35 INFO - PROCESS | 13876 | __startTimestamp1473851735351__endTimestamp 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | ------- Summary: start ------- 04:15:35 INFO - PROCESS | 13876 | Number of tests: 6 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | [#0] dojo.html Cycles:165 Average:5688.17 Median:5979.70 stddev:659.40 (11.0%) stddev-sans-first:633.31 04:15:35 INFO - PROCESS | 13876 | Values: 3259.8 3260.6 3295.5 3305.3 3344.1 5618.7 5721.7 5728.0 5735.6 5739.1 5668.8 5708.3 5710.1 5712.3 5715.3 5033.0 5147.8 5158.5 5158.6 5204.7 5199.9 5253.0 5257.9 5268.8 5269.0 5100.6 5141.5 5159.9 5160.7 5263.7 5195.2 5197.6 5238.6 5261.1 5292.9 5038.8 5048.9 5087.9 5122.4 5129.6 5587.0 5609.8 5619.2 5625.8 5638.5 6130.5 6140.8 6157.7 6160.8 6160.9 6029.1 6088.0 6104.7 6126.5 6174.2 5752.5 5765.6 5766.3 5794.3 5811.1 6332.9 6345.6 6353.2 6355.1 6361.0 5863.9 5949.3 5977.0 5988.4 6088.7 6102.6 6112.7 6115.0 6122.2 6128.5 6082.0 6099.5 6114.4 6126.6 6129.2 6012.1 6022.2 6031.5 6048.9 6126.5 4632.1 4666.3 4667.3 4674.0 4697.0 4137.9 4163.1 4163.5 4169.0 4172.5 5642.1 5695.6 5784.1 5831.2 5834.5 6160.9 6161.7 6170.7 6181.4 6195.4 5835.4 5841.0 5850.6 5865.5 5868.4 5067.9 5218.9 5229.7 5333.2 5334.4 6306.9 6348.9 6355.5 6364.5 6372.9 6125.8 6126.8 6133.8 6134.7 6138.6 6121.4 6265.4 6300.7 6361.2 6367.6 5992.0 6069.0 6088.7 6096.1 6127.0 6107.8 6165.9 6166.9 6169.1 6173.8 5793.7 5965.7 6010.3 6037.3 6053.8 5994.7 6045.5 6054.1 6078.5 6088.3 5987.5 5994.7 5997.1 5997.6 5999.2 5869.8 6038.8 6058.7 6060.0 6094.9 5972.5 5979.7 5983.7 6004.2 6014.5 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | [#1] ext.html Cycles:180 Average:69990.17 Median:221363.10 stddev:143477.13 (64.8%) stddev-sans-first:143784.99 04:15:35 INFO - PROCESS | 13876 | Values: 583.4 644.9 653.6 654.6 671.8 34479.8 45813.8 46977.0 47000.0 47075.3 48095.5 48683.5 48713.0 48744.8 48790.0 8295.9 9146.4 9294.3 9301.2 10795.5 10598.5 11056.7 11065.9 11065.9 11068.1 11110.5 11150.8 11158.8 11163.5 11165.4 11039.8 11039.9 11052.3 11088.2 11104.4 10962.7 11016.6 11027.2 11036.2 11041.6 44390.9 44832.3 44838.6 44921.0 44955.8 3729.2 3851.0 3931.8 3957.3 4178.6 34881.1 34890.8 34970.7 35487.6 35537.0 4523.3 4533.6 4536.3 4634.4 4675.4 403149.0 403420.0 404287.0 405362.0 406229.0 63060.0 63485.4 63501.0 63574.0 63591.4 35972.5 36494.0 36506.5 36569.3 64965.7 18153.5 18596.1 18596.9 18609.0 18692.5 11434.7 13931.9 14218.9 14225.2 14274.6 20812.7 20854.3 20954.8 21046.8 21124.8 7304.7 7506.7 7579.2 7758.3 7797.2 1014.5 1048.4 1139.9 1148.7 1162.5 36000.5 36035.8 36374.0 36422.0 36581.6 1106.8 1132.9 1142.7 1175.7 1183.1 17896.9 18764.4 18801.9 18825.5 18946.1 601989.0 606604.8 607183.0 608655.0 609824.0 65432.7 66075.7 66270.3 66289.0 66293.3 1308.9 1419.0 1447.9 1462.7 1472.7 34592.7 35224.5 36342.3 36761.4 36869.8 61047.0 62811.7 63081.4 63157.0 68049.0 581505.0 583099.0 585405.0 586433.0 586819.0 31093.8 31427.0 31874.0 33119.3 49142.5 53412.2 53689.5 53819.7 54202.5 55405.0 45440.0 45532.8 46042.5 46528.3 47498.5 45356.8 45685.5 45708.5 45726.8 46691.1 44484.8 45234.8 45272.8 45804.1 46173.8 38824.6 39577.2 40348.6 40413.2 40757.4 30305.4 30545.8 30759.4 31081.8 31097.8 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | [#2] jquery.html Cycles:180 Average:30751.54 Median:2823.48 stddev:91073.79 (3225.6%) stddev-sans-first:91300.74 04:15:35 INFO - PROCESS | 13876 | Values: 388.1 406.9 410.2 419.2 437.1 14529.4 20488.0 20666.3 20729.7 21180.3 20356.3 20608.8 20624.0 20650.5 20878.7 5869.6 5869.9 5886.1 5938.8 5975.1 5848.5 5863.6 5895.3 5902.8 5906.9 5476.3 5712.8 5738.9 5803.1 5810.2 5744.7 5767.1 5802.7 5806.0 5948.7 5710.9 5911.6 5917.2 5922.6 5923.6 23942.9 24149.8 24153.4 24395.9 24804.5 2544.2 2648.6 2667.0 2740.0 2907.0 10223.2 10359.7 10405.4 10495.5 14015.5 1938.8 1958.7 2062.1 2099.0 2121.3 64534.7 68133.0 77785.6 78070.6 91069.0 9874.6 10572.3 11268.6 11391.5 11463.3 16047.4 16125.7 16138.9 16153.5 16177.8 8104.3 8343.5 8618.3 8678.9 8827.1 7678.7 7855.4 7890.4 8189.8 8288.4 4772.9 4789.6 4813.3 4841.6 4860.6 1939.9 2053.2 2059.5 2066.5 2122.0 928.3 1032.5 1048.7 1050.3 1100.1 20092.2 20512.5 20880.6 21063.2 21310.9 971.7 1036.9 1045.9 1059.3 1063.6 10593.7 11462.8 11538.1 11565.9 11582.4 423430.0 564734.0 572848.0 576391.0 611616.0 74172.0 89080.6 99539.0 106368.5 106728.5 1062.3 1063.4 1100.7 1101.4 1101.8 17598.0 18247.7 18404.9 18433.2 18787.3 44238.8 44340.6 44737.8 45173.8 47094.0 54212.2 56942.4 60497.7 61876.0 63183.4 8034.6 8198.3 8252.1 8256.4 8516.2 8460.2 11102.2 11764.6 11954.8 12115.1 9706.0 9793.9 10162.5 10163.9 10387.9 9669.0 10491.9 10586.5 10776.0 11129.2 9662.9 10145.6 10520.7 10681.8 10735.9 7753.9 7863.8 7901.5 7959.2 8029.3 7927.7 9781.7 10039.2 10354.4 10394.0 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | [#3] mootools.html Cycles:180 Average:6221.47 Median:2531.17 stddev:3539.74 (139.8%) stddev-sans-first:3532.19 04:15:35 INFO - PROCESS | 13876 | Values: 1540.5 1564.7 1727.6 1799.1 1852.8 9026.0 9764.0 9764.9 9765.1 9772.7 8001.6 9064.1 9290.7 9309.3 9552.8 2551.6 2563.9 2576.9 2577.3 2579.6 2453.9 2545.3 2549.9 2554.1 2555.5 2523.0 2529.4 2536.4 2537.6 2544.4 2490.1 2490.8 2496.2 2516.4 2520.9 2381.9 2428.3 2441.4 2455.0 2459.5 9558.3 9659.6 9664.9 9670.4 9674.9 5577.4 5761.3 5761.8 5765.4 5774.4 3496.2 3638.2 3709.2 3709.6 3734.4 1094.7 1166.5 1180.2 1202.2 1212.2 13547.5 13556.8 13577.3 13583.9 13588.3 4977.4 4983.2 5007.0 5024.1 5240.4 11451.8 11454.5 11456.9 11473.4 11481.5 5698.5 5707.2 5709.5 5712.1 5715.8 4582.4 5067.7 5117.2 5138.1 5233.3 4132.4 4182.8 4284.0 4320.2 4323.5 1349.0 1351.2 1362.2 1369.7 1376.2 1047.8 1048.2 1049.1 1055.2 1068.7 6313.7 6376.1 6379.1 6435.6 6460.1 480.0 485.2 491.7 491.8 492.5 4182.3 4191.7 4334.8 4348.3 4374.8 10601.1 10952.8 10955.0 10981.8 11032.5 10897.6 10910.3 10918.2 10924.1 10925.1 8234.0 8324.5 8327.4 8703.3 8712.0 6377.8 6610.0 6638.9 6724.9 6799.3 10814.9 10847.5 11030.9 11033.8 11037.8 10510.3 10895.5 11256.3 11319.0 11324.3 7447.4 8187.5 8212.1 8411.9 8654.4 9178.6 9180.3 9244.8 9245.5 9341.3 8224.1 8322.3 8343.0 8428.7 8617.1 8139.1 8152.0 8155.3 8183.3 8408.4 7754.7 7766.8 7776.9 7828.5 7848.2 5632.8 5644.1 5664.2 5690.9 5926.2 5659.5 5706.4 5722.6 5799.0 5835.6 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | [#4] prototype.html Cycles:180 Average:4842.29 Median:5531.65 stddev:1443.20 (26.1%) stddev-sans-first:1407.03 04:15:35 INFO - PROCESS | 13876 | Values: 334.3 334.7 341.1 341.8 349.1 5523.9 5596.2 5599.3 5607.2 5611.7 5233.0 5382.5 5399.7 5400.6 5539.4 4191.6 4206.1 4227.9 4251.5 4259.7 4295.8 4303.2 4315.0 4317.6 4327.1 4097.7 4253.4 4256.3 4261.7 4264.2 4237.3 4242.2 4242.5 4244.5 4262.8 3734.3 3748.5 3764.8 3765.2 3780.1 5315.5 5337.0 5461.3 5467.5 5481.7 4863.5 4881.1 4886.9 4898.2 4920.4 5771.6 5799.0 5800.7 5802.8 5818.4 4051.3 4128.8 4145.2 4151.8 4158.4 5979.7 5982.2 5987.3 6003.9 6009.7 4259.1 4266.3 4279.2 4281.2 4305.3 4227.3 4297.0 4326.9 4331.9 4345.0 4879.6 4888.9 4904.2 4906.9 4914.2 5807.9 5817.9 5825.4 5828.9 5895.1 1362.8 1411.4 1432.5 1450.9 1452.9 963.7 981.6 990.4 993.3 999.1 5480.9 5596.9 5597.1 5618.0 5651.2 5914.4 5920.3 5921.9 5941.5 5942.0 5588.6 5614.7 5619.2 5628.7 5633.1 2759.7 2810.5 2812.9 2821.4 2842.9 5396.8 5403.8 5412.6 5417.5 5425.9 5274.3 5313.5 5349.8 5351.5 5392.4 5800.5 6201.9 6251.2 6253.5 6256.7 6051.3 6066.4 6066.5 6067.5 6100.3 5858.9 5907.4 5934.1 5952.9 6015.4 6155.3 6161.3 6191.8 6239.8 6255.7 4220.0 4230.3 4270.3 4275.7 4282.3 5840.1 5954.6 6008.2 6010.0 6030.8 5732.2 5879.9 5903.4 5912.3 5956.9 5698.7 5708.7 5829.0 5835.4 5847.4 5803.4 5809.6 5814.7 5816.6 5834.4 5665.7 5812.0 5828.9 5850.0 5883.9 5691.5 5704.3 5742.7 5749.4 5760.9 04:15:35 INFO - PROCESS | 13876 | 04:15:35 INFO - PROCESS | 13876 | [#5] yui.html Cycles:180 Average:23080.26 Median:4237.02 stddev:33907.77 (800.3%) stddev-sans-first:33961.62 04:15:35 INFO - PROCESS | 13876 | Values: 800.7 808.1 814.4 837.3 880.6 15404.8 16182.0 17425.8 17670.3 17786.2 15973.0 16169.1 16169.6 16201.3 16217.2 724.9 838.3 857.7 872.9 875.8 867.5 875.7 876.2 876.4 876.6 908.2 910.1 911.5 911.8 911.8 752.6 887.8 903.1 906.5 906.8 662.2 665.4 672.3 672.8 673.0 15471.1 15564.4 15567.8 15612.1 15613.6 14911.7 14990.1 15594.1 15672.4 15759.8 14520.9 14543.2 14661.4 14717.8 14841.4 4399.1 4460.0 4535.2 4600.4 4650.9 83300.0 83882.6 85849.0 87957.0 89173.1 11865.1 11937.1 11941.4 12046.5 12124.0 21694.5 22306.9 22336.7 22413.8 22489.6 9547.1 9574.6 9644.1 9768.0 9930.2 4848.2 5069.5 5165.5 5322.5 5391.8 4082.5 4232.2 4241.9 4244.0 4258.4 728.0 728.2 728.4 732.7 744.9 669.3 713.4 730.3 736.0 745.6 10203.3 10473.5 10705.5 10769.0 10833.3 699.9 723.8 726.3 746.9 754.2 4772.9 5094.4 5135.0 5174.2 5221.6 103859.5 130745.3 132752.0 134744.0 136496.3 114812.0 115326.4 116614.0 118513.0 118690.0 99100.5 99920.5 100629.5 102576.0 102605.5 9742.3 9798.5 9860.2 9974.5 10043.9 51006.0 58566.4 59294.7 60632.7 60709.7 73797.0 74482.0 75231.0 75282.6 75572.6 13774.6 13938.2 14100.2 14220.4 14276.6 15103.9 15136.8 15524.8 15950.8 15955.0 13836.5 14168.0 14530.9 14618.3 15105.1 14291.4 14351.9 14552.4 14851.0 14882.3 14538.5 14753.8 14838.4 15294.7 15353.3 11175.3 11245.1 11782.4 12213.9 12383.6 11543.7 11881.9 11896.5 12044.8 12198.8 04:15:35 INFO - PROCESS | 13876 | -------- Summary: end -------- 04:15:35 INFO - PROCESS | 13876 | 04:15:36 INFO - TEST-INFO | 13876: exit 0 04:15:36 INFO - TEST-OK | dromaeo_css | took 1114552ms 04:15:36 INFO - TEST-START | kraken 04:15:36 INFO - operating with platform_type : linux_ 04:15:36 INFO - Initialising browser for kraken test... 04:15:36 INFO - TEST-INFO | started process 14080 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiTNI0r/profile http://localhost:44328/getInfo.html) 04:15:40 INFO - PROCESS | 14080 | __metrics Screen width/height:1600/1200 04:15:40 INFO - PROCESS | 14080 | colorDepth:24 04:15:40 INFO - PROCESS | 14080 | Browser inner width/height: 1024/697 04:15:40 INFO - PROCESS | 14080 | __metrics 04:15:41 INFO - PROCESS | 14080 | 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:15:41 INFO - PROCESS | 14080 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:42 INFO - TEST-INFO | 14080: exit 0 04:15:42 INFO - Browser initialized. 04:15:42 INFO - Running cycle 1/1 for kraken test... 04:15:42 INFO - TEST-INFO | started process 14169 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiTNI0r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:53 INFO - PROCESS | 14169 | RSS: Main: 146563072 04:15:53 INFO - PROCESS | 14169 | 04:15:56 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44328/tests/kraken/driver.html?testName=audio-beat-detection) 04:15:57 INFO - PROCESS | 14169 | RSS: Main: 200937472 04:15:57 INFO - PROCESS | 14169 | 04:15:59 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44328/tests/kraken/driver.html?testName=audio-dft) 04:16:00 INFO - PROCESS | 14169 | RSS: Main: 185188352 04:16:00 INFO - PROCESS | 14169 | 04:16:03 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44328/tests/kraken/driver.html?testName=audio-fft) 04:16:03 INFO - PROCESS | 14169 | RSS: Main: 276217856 04:16:03 INFO - PROCESS | 14169 | 04:16:05 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44328/tests/kraken/driver.html?testName=audio-oscillator) 04:16:06 INFO - PROCESS | 14169 | RSS: Main: 181702656 04:16:06 INFO - PROCESS | 14169 | 04:16:08 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44328/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:09 INFO - PROCESS | 14169 | RSS: Main: 180592640 04:16:09 INFO - PROCESS | 14169 | 04:16:12 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44328/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:12 INFO - PROCESS | 14169 | RSS: Main: 216334336 04:16:12 INFO - PROCESS | 14169 | 04:16:16 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44328/tests/kraken/driver.html?testName=imaging-desaturate) 04:16:16 INFO - PROCESS | 14169 | RSS: Main: 215216128 04:16:16 INFO - PROCESS | 14169 | 04:16:19 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44328/tests/kraken/driver.html?testName=json-parse-financial) 04:16:20 INFO - PROCESS | 14169 | RSS: Main: 215875584 04:16:20 INFO - PROCESS | 14169 | 04:16:21 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44328/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:16:22 INFO - PROCESS | 14169 | RSS: Main: 177856512 04:16:22 INFO - PROCESS | 14169 | 04:16:23 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:16:24 INFO - PROCESS | 14169 | RSS: Main: 184115200 04:16:24 INFO - PROCESS | 14169 | 04:16:26 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:16:27 INFO - PROCESS | 14169 | RSS: Main: 186191872 04:16:27 INFO - PROCESS | 14169 | 04:16:29 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:16:30 INFO - PROCESS | 14169 | RSS: Main: 181006336 04:16:30 INFO - PROCESS | 14169 | 04:16:32 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:16:33 INFO - PROCESS | 14169 | RSS: Main: 184328192 04:16:33 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | Cycle 1(1): loaded http://localhost:44328/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44328/tests/kraken/driver.html?testName=ai-astar) 04:16:35 INFO - PROCESS | 14169 | RSS: Main: 235683840 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | __start_tp_report 04:16:35 INFO - PROCESS | 14169 | _x_x_mozilla_page_load 04:16:35 INFO - PROCESS | 14169 | _x_x_mozilla_page_load_details 04:16:35 INFO - PROCESS | 14169 | |i|pagename|runs| 04:16:35 INFO - PROCESS | 14169 | |0;ai-astar;109;108;117;107;110;109;108;110;109;109 04:16:35 INFO - PROCESS | 14169 | |1;audio-beat-detection;141;118;139;176;114;143;143;116;163;116 04:16:35 INFO - PROCESS | 14169 | |2;audio-dft;161;162;161;163;161;161;166;161;160;161 04:16:35 INFO - PROCESS | 14169 | |3;audio-fft;76;75;75;178;76;77;74;74;176;75 04:16:35 INFO - PROCESS | 14169 | |4;audio-oscillator;83;84;144;84;83;83;135;84;85;150 04:16:35 INFO - PROCESS | 14169 | |5;imaging-gaussian-blur;117;118;117;117;116;116;118;117;117;116 04:16:35 INFO - PROCESS | 14169 | |6;imaging-darkroom;146;145;145;146;146;146;147;147;145;146 04:16:35 INFO - PROCESS | 14169 | |7;imaging-desaturate;94;94;95;95;95;95;94;94;95;95 04:16:35 INFO - PROCESS | 14169 | |8;json-parse-financial;52;53;52;52;52;51;52;50;63;58 04:16:35 INFO - PROCESS | 14169 | |9;json-stringify-tinderbox;59;59;59;59;60;60;61;60;59;58 04:16:35 INFO - PROCESS | 14169 | |10;stanford-crypto-aes;83;75;77;73;76;78;71;74;72;73 04:16:35 INFO - PROCESS | 14169 | |11;stanford-crypto-ccm;120;127;119;129;122;121;115;114;159;125 04:16:35 INFO - PROCESS | 14169 | |12;stanford-crypto-pbkdf2;154;154;152;152;151;153;155;153;151;152 04:16:35 INFO - PROCESS | 14169 | |13;stanford-crypto-sha256-iterative;61;62;63;61;61;62;61;61;60;60 04:16:35 INFO - PROCESS | 14169 | __end_tp_report 04:16:35 INFO - PROCESS | 14169 | __start_cc_report 04:16:35 INFO - PROCESS | 14169 | _x_x_mozilla_cycle_collect,1672 04:16:35 INFO - PROCESS | 14169 | __end_cc_report 04:16:35 INFO - PROCESS | 14169 | __startTimestamp1473851795339__endTimestamp 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | ------- Summary: start ------- 04:16:35 INFO - PROCESS | 14169 | Number of tests: 14 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#0] ai-astar Cycles:10 Average:109.60 Median:109.00 stddev:2.76 (2.5%) stddev-sans-first:2.92 04:16:35 INFO - PROCESS | 14169 | Values: 109.0 108.0 117.0 107.0 110.0 109.0 108.0 110.0 109.0 109.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:142.00 stddev:21.24 (15.0%) stddev-sans-first:22.48 04:16:35 INFO - PROCESS | 14169 | Values: 141.0 118.0 139.0 176.0 114.0 143.0 143.0 116.0 163.0 116.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.00 stddev:1.70 (1.1%) stddev-sans-first:1.79 04:16:35 INFO - PROCESS | 14169 | Values: 161.0 162.0 161.0 163.0 161.0 161.0 166.0 161.0 160.0 161.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#3] audio-fft Cycles:10 Average:95.60 Median:75.00 stddev:42.91 (57.2%) stddev-sans-first:44.93 04:16:35 INFO - PROCESS | 14169 | Values: 76.0 75.0 75.0 178.0 76.0 77.0 74.0 74.0 176.0 75.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#4] audio-oscillator Cycles:10 Average:101.50 Median:83.50 stddev:28.86 (34.6%) stddev-sans-first:29.83 04:16:35 INFO - PROCESS | 14169 | Values: 83.0 84.0 144.0 84.0 83.0 83.0 135.0 84.0 85.0 150.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 04:16:35 INFO - PROCESS | 14169 | Values: 117.0 118.0 117.0 117.0 116.0 116.0 118.0 117.0 117.0 116.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 04:16:35 INFO - PROCESS | 14169 | Values: 146.0 145.0 145.0 146.0 146.0 146.0 147.0 147.0 145.0 146.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:16:35 INFO - PROCESS | 14169 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:52.00 stddev:3.95 (7.6%) stddev-sans-first:4.15 04:16:35 INFO - PROCESS | 14169 | Values: 52.0 53.0 52.0 52.0 52.0 51.0 52.0 50.0 63.0 58.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.88 04:16:35 INFO - PROCESS | 14169 | Values: 59.0 59.0 59.0 59.0 60.0 60.0 61.0 60.0 59.0 58.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.50 stddev:3.52 (4.7%) stddev-sans-first:2.35 04:16:35 INFO - PROCESS | 14169 | Values: 83.0 75.0 77.0 73.0 76.0 78.0 71.0 74.0 72.0 73.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:123.50 stddev:12.84 (10.4%) stddev-sans-first:13.48 04:16:35 INFO - PROCESS | 14169 | Values: 120.0 127.0 119.0 129.0 122.0 121.0 115.0 114.0 159.0 125.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.00 stddev:1.34 (0.9%) stddev-sans-first:1.33 04:16:35 INFO - PROCESS | 14169 | Values: 154.0 154.0 152.0 152.0 151.0 153.0 155.0 153.0 151.0 152.0 04:16:35 INFO - PROCESS | 14169 | 04:16:35 INFO - PROCESS | 14169 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 04:16:35 INFO - PROCESS | 14169 | Values: 61.0 62.0 63.0 61.0 61.0 62.0 61.0 61.0 60.0 60.0 04:16:35 INFO - PROCESS | 14169 | -------- Summary: end -------- 04:16:35 INFO - PROCESS | 14169 | 04:16:36 INFO - TEST-INFO | 14169: exit 0 04:16:36 INFO - TEST-OK | kraken | took 60213ms 04:16:36 INFO - SUITE-END | took 1174s 04:16:36 INFO - Completed test suite (00:19:34) 04:16:36 INFO - javascript benchmark 04:16:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.7718159728815, 3260.6250281217667, 3295.5386245925574, 3305.3224089061746, 3344.08037789608, 5618.690971996543, 5721.6853073080965, 5728.0, 5735.627184233022, 5739.139129130638, 5668.829935101943, 5708.257926469553, 5710.1434784782305, 5712.314522257335, 5715.3141623005195, 5032.9748351258195, 5147.845564633067, 5158.509941555546, 5158.561522270602, 5204.661696989702, 5199.896002079956, 5253.028189784883, 5257.947420525803, 5268.841934741945, 5268.973655131719, 5100.566451851597, 5141.537261646451, 5159.948400515985, 5160.716160611165, 5263.657862238962, 5195.246689230041, 5197.636165468419, 5238.580913526927, 5261.079311120577, 5292.92060619091, 5038.84883453497, 5048.873778155539, 5087.87280317995, 5122.359705036871, 5129.615278854101, 5587.0, 5609.803656872031, 5619.213310136584, 5625.80309689163, 5638.520725738317, 6130.540209484307, 6140.815775526741, 6157.7229024694, 6160.7535698572, 6160.938390616081, 6029.095635654643, 6088.025915853442, 6104.725287362079, 6126.540509461808, 6174.197354343952, 5752.511036561939, 5765.625234359753, 5766.336871259817, 5794.333651630032, 5811.128330750379, 6332.905006424908, 6345.619262844244, 6353.205849268841, 6355.0785136155, 6361.01404282337, 5863.941360586339, 5949.286085669661, 5976.970115149396, 5988.371221021817, 6088.665123418255, 6102.572819902653, 6112.663803490762, 6114.969425152845, 6122.20411346531, 6128.509719222451, 6082.0, 6099.460754943115, 6114.449440885101, 6126.578745377165, 6129.175413489998, 6012.068129439944, 6022.156898034279, 6031.457168854824, 6048.879022419615, 6126.479249263863, 4632.073585282957, 4666.346711460398, 4667.276572131325, 4673.976630116896, 4696.95303046972, 4137.91724165521, 4163.12574359388, 4163.500379954425, 4168.958310416857, 4172.520160181618, 5642.0972644377525, 5695.572832037614, 5784.132380143013, 5831.2127862738835, 5834.474897259182, 6160.9383906161265, 6161.691915404212, 6170.660613666201, 6181.412765787243, 6195.411435913581, 5835.44563266489, 5840.970795145997, 5850.61970971894, 5865.501432378211, 5868.4424979626865, 5067.949320506747, 5218.921716174261, 5229.712365819841, 5333.200019997032, 5334.413214546396, 6306.873862522815, 6348.904766428507, 6355.459785918156, 6364.49084073273, 6372.904406433907, 6125.816225513242, 6126.816195514141, 6133.8466538335115, 6134.723937422916, 6138.600990935575, 6121.361519882331, 6265.4361107500545, 6300.7479700811455, 6361.2048493938255, 6367.61794292344, 5991.9813631681845, 6068.969655151696, 6088.726007329592, 6096.055111457731, 6127.019676851683, 6107.816765497042, 6165.938340616536, 6166.907496387647, 6169.074638804215, 6173.84565385869, 5793.739281732248, 5965.73154208053, 6010.3088144863905, 6037.275526936795, 6053.818385448443, 5994.730237139426, 6045.516358691206, 6054.0918862169265, 6078.45293923549, 6088.299845517822, 5987.520998320037, 5994.70026498682, 5997.070454079625, 5997.580169388101, 5999.190109335358, 5869.765209391748, 6038.758449661965, 6058.697065146637, 6060.030395136933, 6094.908576371269, 5972.492338151305, 5979.701014949323, 5983.670898100646, 6004.159417681441, 6014.48876845473], "value": 5642.653067013298, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.4194975998879, 644.9322821103787, 653.5588477777499, 654.6464908949167, 671.7984604618613, 34479.827600861994, 45813.77093114534, 46977.0, 47000.0, 47075.293870591966, 48095.51904480954, 48683.51316486834, 48713.0, 48744.75627621857, 48790.0, 8295.875561866576, 9146.405483643575, 9294.302927280447, 9301.162895339417, 10795.514201860935, 10598.523066461988, 11056.668299951014, 11065.889341106566, 11065.889341106607, 11068.059214966737, 11110.500027498741, 11150.776984460306, 11158.776824463506, 11163.497642606062, 11165.441727913612, 11039.779204415867, 11039.889601104007, 11052.281601695864, 11088.2238243323, 11104.389258590774, 10962.671119866416, 11016.614418495314, 11027.1729620278, 11036.227464077518, 11041.613543526018, 44390.89022774424, 44832.3275150873, 44838.55161448409, 44921.0, 44955.77522112369, 3729.179580492287, 3850.9614903850884, 3931.82306796192, 3957.3470377387985, 4178.603032711888, 34881.127971800655, 34890.82554587211, 34970.65029349699, 35487.64512354895, 35537.0, 4523.253663145577, 4533.591976722078, 4536.319552067183, 4634.374359461463, 4675.392199014106, 403149.0, 403420.0, 404287.0, 405362.0, 406228.9688551568, 63060.0, 63485.412864677826, 63501.0, 63574.0, 63591.36408635947, 35972.460413093824, 36494.0, 36506.452403213974, 36569.26861462756, 64965.67517162478, 18153.455396337868, 18596.07019649012, 18596.907015464836, 18609.0, 18692.532686682942, 11434.714132146762, 13931.930340348234, 14218.85781142196, 14225.217613031382, 14274.643133921527, 20812.687809682866, 20854.270100546408, 20954.79045209559, 21046.78953210479, 21124.788752112283, 7304.707811687579, 7506.699732010768, 7579.2420757924865, 7758.340541054074, 7797.220277972157, 1014.5282443663584, 1048.449563978902, 1139.9145064120223, 1148.7300484386162, 1162.4826952006445, 36000.45999310012, 36035.819820900724, 36374.0, 36422.0, 36581.63418365782, 1106.7620461600634, 1132.8810474900017, 1142.7200335917587, 1175.7060734816296, 1183.089021453476, 17896.9105154476, 18764.437066888015, 18801.90599046996, 18825.529361766065, 18946.147423365983, 601989.0, 606604.8018559362, 607183.0, 608655.0, 609824.0, 65432.67283663646, 66075.66962165158, 66270.33729662737, 66289.0, 66293.33706662968, 1308.888744456732, 1419.0, 1447.8552144785647, 1462.6928345047659, 1472.683373074794, 34592.65407345894, 35224.47163292604, 36342.27315453729, 36761.448578271884, 36869.81565092158, 61047.0, 62811.68594157, 63081.36918630755, 63157.0, 68049.0, 581505.0, 583099.0, 585405.0, 586433.0, 586819.0, 31093.8445307772, 31427.0, 31874.0, 33119.337613248084, 49142.508574913794, 53412.198817017, 53689.46310537001, 53819.73090134524, 54202.45797541974, 55405.0, 45440.0, 45532.77233613811, 46042.53957460383, 46528.302075469575, 47498.52501474941, 45356.77321613371, 45685.54314456813, 45708.542914570426, 45726.77136614296, 46691.06617867691, 44484.77757611191, 45234.773826131976, 45272.773636131606, 45804.08391832078, 46173.76913115547, 38824.6117538821, 39577.2084558313, 40348.596514034485, 40413.1917361657, 40757.38863916984, 30305.393892122476, 30545.847270764392, 30759.384812304077, 31081.844590776902, 31097.8445107773], "value": 22309.882850025457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.06669958749217, 406.94099355593437, 410.19806278725116, 419.2223425545612, 437.130111078953, 14529.418823247071, 20488.0, 20666.27668031619, 20729.689054664173, 21180.25869094582, 20356.287529936457, 20608.793912060875, 20624.0, 20650.48373790652, 20878.68681969768, 5869.647821130746, 5869.941300586982, 5886.087656413252, 5938.821835344925, 5975.073863551156, 5848.5321174305955, 5863.648181109126, 5895.322037965646, 5902.793402230923, 5906.88186236275, 5476.315460567429, 5712.771489140429, 5738.913916291259, 5803.100519419476, 5810.15752715856, 5744.712764361786, 5767.106098554752, 5802.738876750556, 5805.970970145143, 5948.7323070461935, 5710.942890571083, 5911.615744976562, 5917.171595976567, 5922.615030023035, 5923.555733319975, 23942.88028559846, 24149.758502414927, 24153.396165095837, 24395.878020609784, 24804.50390992188, 2544.160427059066, 2648.642433271503, 2666.973330266692, 2739.9726002739917, 2906.985465072661, 10223.168535524892, 10359.741006474824, 10405.375677459377, 10495.475226238657, 14015.50945716895, 1938.7964263752244, 1958.7257783910263, 2062.061761898333, 2099.0029735875337, 2121.2787652198326, 64534.677326613535, 68133.0, 77785.61107194485, 78070.60964695197, 91069.0, 9874.555644995993, 10572.312799667998, 11268.605598804003, 11391.487383067617, 11463.254888432391, 16047.438339658056, 16125.677486450206, 16138.919305403399, 16153.515394538183, 16177.757333640006, 8104.311133553596, 8343.499390036617, 8618.310535157172, 8678.86981695275, 8827.117288271122, 7678.692852285846, 7855.410844186595, 7890.408219383569, 8189.836203275901, 8288.419810613319, 4772.904541909143, 4789.568938795525, 4813.302071199727, 4841.564259216688, 4860.6111511079025, 1939.9127039282985, 2053.16846677096, 2059.4542446251753, 2066.5040390306217, 2121.968170477444, 928.3408779766442, 1032.4734385463469, 1048.6539441984128, 1050.3015494696094, 1100.0979197058334, 20092.196312147644, 20512.487187820425, 20880.58238835245, 21063.157473701187, 21310.893445532984, 971.6744890461774, 1036.9274150809522, 1045.8797238317538, 1059.2902755154066, 1063.648995831374, 10593.682189534326, 11462.828057579143, 11538.13463990204, 11565.884341156649, 11582.420878956018, 423430.0, 564734.0, 572848.0, 576391.0, 611616.0, 74172.0, 89080.5545972266, 99539.0, 106368.46815765872, 106728.46635766927, 1062.3201151263197, 1063.3832377221192, 1100.708312297242, 1101.3832253937821, 1101.8181999970075, 17598.0, 18247.726284105484, 18404.907975460035, 18433.170507326933, 18787.34244301443, 44238.77880610705, 44340.55659443494, 44737.77631111954, 45173.77413112913, 47094.0, 54212.18681719698, 56942.43057569371, 60497.69751151216, 61876.0, 63183.36816631959, 8034.558099304594, 8198.344132469507, 8252.092269850431, 8256.422050456411, 8516.233538981522, 8460.153984601491, 11102.167337449562, 11764.647060588197, 11954.76090478203, 12115.091368147248, 9706.0, 9793.853092203764, 10162.54268557902, 10163.89836101659, 10387.948060259952, 9669.0, 10491.94754026225, 10586.4706764663, 10776.0, 11129.165312601426, 9662.855057174289, 10145.594176232868, 10520.736981575521, 10681.786364272826, 10735.94632026835, 7753.914451976615, 7863.764087077397, 7901.486403383762, 7959.204079591995, 8029.3175080118845, 7927.682892684228, 9781.657641982496, 10039.24705647094, 10354.430506321924, 10394.0], "value": 9324.996498141254, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.5455390659754, 1564.7105285522175, 1727.5767436977942, 1799.1004497751123, 1852.7776666799987, 9025.954870225647, 9764.0, 9764.902350976488, 9765.072318129784, 9772.706818795448, 8001.559914204717, 9064.138906803842, 9290.674826381079, 9309.255259579235, 9552.80894382112, 2551.5534781413226, 2563.8974441022337, 2576.9226923192336, 2577.2783620586174, 2579.6130580412996, 2453.926382208537, 2545.338212064858, 2549.8852551635127, 2554.0549996501322, 2555.540002799495, 2523.028633975914, 2529.3929456930387, 2536.441982763789, 2537.5939849624065, 2544.427503811646, 2490.140901389009, 2490.8256422050463, 2496.213692686798, 2516.421223118693, 2520.8865601047814, 2381.8809059547125, 2428.3200703802786, 2441.426264827764, 2454.9754502454925, 2459.532688789124, 9558.2831287653, 9659.613615455373, 9664.855027174597, 9670.419774813534, 9674.90325096747, 5577.44225577439, 5761.308642962829, 5761.798337058225, 5765.423457654243, 5774.393688662714, 3496.17839807645, 3638.2359704462096, 3709.2210635766523, 3709.647583479565, 3734.3838266685966, 1094.7263184203948, 1166.4634268236537, 1180.238746008828, 1202.2485946283573, 1212.1878341511208, 13547.525836595672, 13556.796648050287, 13577.321133943264, 13583.864161358359, 13588.252646104362, 4977.352944117241, 4983.202687569971, 5007.048660754445, 5024.070546948826, 5240.397354304304, 11451.770964580663, 11454.54181832734, 11456.94271528637, 11473.36896470702, 11481.540738370539, 5698.458646428583, 5707.200991861143, 5709.514691251207, 5712.0860662293835, 5715.771369145187, 4582.358469814229, 5067.721275329893, 5117.232415137759, 5138.100832354323, 5233.345831771028, 4132.40080188371, 4182.790860456965, 4283.957160428418, 4320.179165958458, 4323.546027667113, 1348.9595312140652, 1351.2365513485047, 1362.1963041805382, 1369.7329020840841, 1376.215557132445, 1047.7852040331713, 1048.2452634103586, 1049.1397054415459, 1055.1875056206677, 1068.6633710381204, 6313.715882785194, 6376.107344971708, 6379.106925030499, 6435.646039467781, 6460.127882735865, 479.9856004319876, 485.1727804094011, 491.6730374301121, 491.7688686317425, 492.54439643330494, 4182.2681030819485, 4191.6856235781825, 4334.783260836945, 4348.347747837786, 4374.759388233678, 10601.098906593026, 10952.780944381067, 10955.0, 10981.835272470918, 11032.503537340679, 10897.564097436014, 10910.345379277269, 10918.1811364148, 10924.071453926505, 10925.07136893357, 8234.0, 8324.500529968222, 8327.375446841512, 8703.260222881127, 8712.0, 6377.840553986188, 6609.966950165411, 6638.867222655616, 6724.93275067243, 6799.3200679931615, 10814.891851081704, 10847.457627118454, 11030.944845276043, 11033.834492482458, 11037.834432483358, 10510.264281500222, 10895.509702063267, 11256.268342558034, 11319.0, 11324.32054076758, 7447.404207663266, 8187.5087494750505, 8212.137725538732, 8411.8738218928, 8654.350923680624, 9178.586963586788, 9180.311476639412, 9244.768880777765, 9245.491497967676, 9341.299402544972, 8224.095349511668, 8322.334213263046, 8342.958285208535, 8428.704995325133, 8617.138286171332, 8139.104698483044, 8151.959240203998, 8155.306798921921, 8183.304419124441, 8408.369372297224, 7754.6898124074405, 7766.8446631065935, 7776.92223077762, 7828.491148075357, 7848.175941526058, 5632.788950375689, 5644.068728659725, 5664.235328230636, 5690.8577285566535, 5926.1999630049395, 5659.54723622101, 5706.400827913009, 5722.628029178257, 5798.971005144947, 5835.620684655484], "value": 4898.698992154864, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.2896345266309, 334.71381968417, 341.0603786568005, 341.80709324457644, 349.0941008084024, 5523.91714124288, 5596.2165296858475, 5599.272094627698, 5607.158926161078, 5611.719414029298, 5233.0, 5382.461753824626, 5399.676019438836, 5400.648957817749, 5539.390667026634, 4191.55988621195, 4206.1377417629465, 4227.936580951288, 4251.4685664291965, 4259.70182087254, 4295.818649871279, 4303.225419424502, 4314.97842510787, 4317.633001194887, 4327.112941846914, 4097.651699605537, 4253.447051883266, 4256.34002738932, 4261.659067274626, 4264.23243816113, 4237.343211802176, 4242.172776308621, 4242.512111107231, 4244.493204912243, 4262.808173632194, 3734.3464893643504, 3748.5314335708035, 3764.7929363884973, 3765.2093060457337, 3780.0549862534367, 5315.548178404841, 5336.9733151334385, 5461.290032295816, 5467.480589344046, 5481.671099733989, 4863.53796389346, 4881.1213981483315, 4886.877828054292, 4898.24077268024, 4920.409550853885, 5771.624844385102, 5799.0, 5800.709964501801, 5802.796902108448, 5818.359980402152, 4051.331530297527, 4128.8348466061325, 4145.233131870614, 4151.771652559108, 4158.4178215049915, 5979.671118088503, 5982.192404025444, 5987.281526216838, 6003.909941350883, 6009.699515024275, 4259.1268789897995, 4266.2960611499375, 4279.186954478639, 4281.186574550888, 4305.31115021595, 4227.260229459832, 4296.957030429656, 4326.870193894189, 4331.935020974688, 4344.956550434518, 4879.609631229493, 4888.877778055577, 4904.166291730415, 4906.920477494946, 4914.164592019365, 5807.94192058074, 5817.88364232713, 5825.4174582542255, 5828.854278642983, 5895.115732640053, 1362.768329384007, 1411.3931009665941, 1432.4699861051477, 1450.9201993890438, 1452.8619781120776, 963.7253382786013, 981.5533932060895, 990.4453506036642, 993.2848349188573, 999.0908273471251, 5480.945190548123, 5596.944030559724, 5597.104463285855, 5617.9719101404235, 5651.180578816049, 5914.4381283777975, 5920.2895652521975, 5921.851953701192, 5941.524678025748, 5941.970290148608, 5588.636738611977, 5614.691191984399, 5619.241402410704, 5628.746706398222, 5633.098704207389, 2759.7102304258165, 2810.494111059988, 2812.9465490192274, 2821.4216085702383, 2842.928926776806, 5396.812701205728, 5403.756830942617, 5412.648177868426, 5417.512423881791, 5425.891482170335, 5274.340707411574, 5313.495217954288, 5349.849782296825, 5351.518363347317, 5392.40683524812, 5800.50695690871, 6201.937980620226, 6251.156093927261, 6253.46845518127, 6256.687165641791, 6051.3041000283765, 6066.363031881765, 6066.484348830222, 6067.481994972214, 6100.2984656763265, 5858.9121163181735, 5907.379725128972, 5934.080217566284, 5952.886725212487, 6015.428534289235, 6155.292141403616, 6161.291451483136, 6191.81424557264, 6239.844003899939, 6255.71849266775, 4219.987203071302, 4230.323148296257, 4270.252705776539, 4275.722078064916, 4282.25060614385, 5840.094785308284, 5954.612950158225, 6008.188894499187, 6010.0, 6030.7889223876955, 5732.219873361333, 5879.882402351844, 5903.350631430625, 5912.290525137011, 5956.910646340221, 5698.6580805151825, 5708.714564271687, 5828.970855145697, 5835.44563266489, 5847.38602446737, 5803.361630220588, 5809.593328466966, 5814.680192589448, 5816.592838501264, 5834.416558344132, 5665.66006039639, 5812.041013232769, 5828.854278643068, 5850.0, 5883.882322353615, 5691.4877661010705, 5704.3154821421695, 5742.731621009594, 5749.367569567261, 5760.855978600569], "value": 4378.844994791405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.6597196191617, 808.1272225995922, 814.4258297899981, 837.3008537870877, 880.5773228850151, 15404.768928466083, 16182.0, 17425.825741742578, 17670.293188272455, 17786.199621017036, 15973.0, 16169.110698911556, 16169.595760105974, 16201.270942807601, 16217.189140542985, 724.9202587715347, 838.2581415447312, 857.7255278310943, 872.9039805621377, 875.7722992022088, 867.4751775176022, 875.741656211416, 876.2376732242926, 876.3909083187187, 876.6186708781701, 908.2234689340618, 910.1080940678139, 911.4759013567225, 911.7857303533664, 911.803962148138, 752.6349720385608, 887.7913690282778, 903.1465265324272, 906.5376657904483, 906.8005038891466, 662.2020465339255, 665.3579295979403, 672.3444641474572, 672.7813460625316, 672.9663516824188, 15471.149086800222, 15564.37742490299, 15567.844321556753, 15612.141332226724, 15613.609659758484, 14911.701765964728, 14990.100593964396, 15594.142322172276, 15672.373105075898, 15759.763603545956, 14520.854791452055, 14543.200123993176, 14661.41354345825, 14717.779233311401, 14841.406343746237, 4399.12017596482, 4459.977700111511, 4535.160995215862, 4600.35595016698, 4650.906981860344, 83300.0, 83882.58058709667, 85849.0, 87957.0, 89173.10826891713, 11865.110116741107, 11937.104717146249, 11941.402929853473, 12046.51813927433, 12124.0, 21694.457638558848, 22306.888465557568, 22336.664950025763, 22413.775862241495, 22489.55020899573, 9547.115082707436, 9574.617015319449, 9644.083812037845, 9768.0, 9930.2055835533, 4848.200046992207, 5069.518395752398, 5165.457626949192, 5322.494363035506, 5391.81128660495, 4082.530508991504, 4232.174725928415, 4241.87274381769, 4243.957560424418, 4258.446401967786, 728.0426239495047, 728.1735230513406, 728.377237461973, 732.7142414458366, 744.8845428958521, 669.2671524680467, 713.4149997002512, 730.2952650692108, 736.0321177651372, 745.6048294403975, 10203.28576999603, 10473.528691208761, 10705.518251678694, 10769.0, 10833.349999000086, 699.9020137180798, 723.768394113889, 726.2555880222814, 746.8879668049727, 754.2080815144077, 4772.928406073912, 5094.388673359221, 5134.974325128351, 5174.223866420067, 5221.55616772571, 103859.48070259752, 130745.34627326802, 132752.0, 134744.0, 136496.3175184118, 114812.0, 115326.4233678843, 116614.0, 118513.0, 118690.0, 99100.5044974785, 99920.50039749754, 100629.49685251674, 102576.0, 102605.48697256616, 9742.269329800434, 9798.461084640414, 9860.211183105477, 9974.451405172786, 10043.899561004297, 51006.0, 58566.4143358578, 59294.70352648209, 60632.69683651553, 60709.69645151746, 73797.0, 74482.0, 75231.0, 75282.62358688172, 75572.62213688897, 13774.586762397144, 13938.233397163254, 14100.153990760587, 14220.431182752987, 14276.57170284893, 15103.924480377527, 15136.769078295341, 15524.767128493308, 15950.760738589162, 15955.0, 13836.515721949683, 14168.0, 14530.85469145295, 14618.342174602381, 15105.093694378373, 14291.35688894023, 14351.856481435052, 14552.417903284173, 14851.0, 14882.33029513696, 14538.491152810024, 14753.796640352459, 14838.406463741761, 15294.694106118039, 15353.309101090701, 11175.273607215506, 11245.100391968786, 11782.351970641697, 12213.87786122163, 12383.62849114528, 11543.711407214887, 11881.940590296994, 11896.524139034342, 12044.819327710267, 12198.75602487963], "value": 8223.720495416217, "unit": "score"}], "name": "dromaeo_css", "value": 7691.844140945349, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 117.0, 107.0, 110.0, 109.0, 108.0, 110.0, 109.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 118.0, 139.0, 176.0, 114.0, 143.0, 143.0, 116.0, 163.0, 116.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 163.0, 161.0, 161.0, 166.0, 161.0, 160.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 178.0, 76.0, 77.0, 74.0, 74.0, 176.0, 75.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 144.0, 84.0, 83.0, 83.0, 135.0, 84.0, 85.0, 150.0], "value": 101.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 116.0, 116.0, 118.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 147.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 52.0, 52.0, 51.0, 52.0, 50.0, 63.0, 58.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 61.0, 60.0, 59.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 77.0, 73.0, 76.0, 78.0, 71.0, 74.0, 72.0, 73.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 127.0, 119.0, 129.0, 122.0, 121.0, 115.0, 114.0, 159.0, 125.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 152.0, 152.0, 151.0, 153.0, 155.0, 153.0, 151.0, 152.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 61.0, 61.0, 62.0, 61.0, 61.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1489.8, "alertThreshold": 2.0}]} 04:16:36 INFO - Return code: 0 04:16:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:16:36 INFO - # TBPL SUCCESS # 04:16:36 INFO - Running post-action listener: _resource_record_post_action 04:16:36 INFO - [mozharness: 2016-09-14 11:16:36.646749Z] Finished run-tests step (success) 04:16:36 INFO - Running post-run listener: _resource_record_post_run 04:16:36 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 327553024; Read time: 28; Write time: 339620 04:16:36 INFO - TinderboxPrint: CPU usage
12.9% 04:16:36 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 04:16:36 INFO - TinderboxPrint: I/O write bytes / time
327,553,024 / 339,620 04:16:36 INFO - TinderboxPrint: CPU idle
8,283.0 (87.1%) 04:16:36 INFO - TinderboxPrint: CPU user
1,182.8 (12.4%) 04:16:36 INFO - TinderboxPrint: Swap in / out
0 / 0 04:16:36 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78381056; Read time: 0; Write time: 110892 04:16:36 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 248889344; Read time: 28; Write time: 228712 04:16:37 INFO - Running post-run listener: _upload_blobber_files 04:16:37 INFO - Blob upload gear active. 04:16:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:16:37 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:16:37 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:16:37 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:16:37 INFO - (blobuploader) - INFO - Open directory for files ... 04:16:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:16:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:16:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:38 INFO - (blobuploader) - INFO - Done attempting. 04:16:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:16:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:16:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:41 INFO - (blobuploader) - INFO - Done attempting. 04:16:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:16:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:16:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:42 INFO - (blobuploader) - INFO - Done attempting. 04:16:42 INFO - (blobuploader) - INFO - Iteration through files over. 04:16:42 INFO - Return code: 0 04:16:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:16:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:16:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdcd437b31f82911f38c780046c01e3dcc954793597d4188833fbf104ab35887b6a35298848dcc3927d8e90445cfd1306c13bf8cf29ed3be9e8781a91ee08781", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/643d34da106d058eb3bf2e967cf5883b82df3f5134a9e985203d754c2b65f380867c280d875531963dec7681b530f49241bd13798e5b375ad1c6d2c17f87e4ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a688e280d583b540a3b09f184f414bf25b9cf56da2aee0a315ebd2ea0cbce6549c8cd594b8f34bffed6e81daf06331e97edb27266096d6f202e94bec063006ac"} 04:16:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:16:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:16:42 INFO - Contents: 04:16:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdcd437b31f82911f38c780046c01e3dcc954793597d4188833fbf104ab35887b6a35298848dcc3927d8e90445cfd1306c13bf8cf29ed3be9e8781a91ee08781", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/643d34da106d058eb3bf2e967cf5883b82df3f5134a9e985203d754c2b65f380867c280d875531963dec7681b530f49241bd13798e5b375ad1c6d2c17f87e4ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a688e280d583b540a3b09f184f414bf25b9cf56da2aee0a315ebd2ea0cbce6549c8cd594b8f34bffed6e81daf06331e97edb27266096d6f202e94bec063006ac"} 04:16:42 INFO - Running post-run listener: copy_logs_to_upload_dir 04:16:42 INFO - Copying logs to upload dir... 04:16:42 INFO - mkdir: /builds/slave/test/build/upload/logs 04:16:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.979352 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-14 04:16:42.652197) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-14 04:16:42.656135) ========= 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=4300f881736b422476174874000001bc-1473850121.744833-1478930374 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/cdcd437b31f82911f38c780046c01e3dcc954793597d4188833fbf104ab35887b6a35298848dcc3927d8e90445cfd1306c13bf8cf29ed3be9e8781a91ee08781", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/643d34da106d058eb3bf2e967cf5883b82df3f5134a9e985203d754c2b65f380867c280d875531963dec7681b530f49241bd13798e5b375ad1c6d2c17f87e4ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a688e280d583b540a3b09f184f414bf25b9cf56da2aee0a315ebd2ea0cbce6549c8cd594b8f34bffed6e81daf06331e97edb27266096d6f202e94bec063006ac"} build_url:https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011308 build_url: 'https://queue.taskcluster.net/v1/task/Rp6e7ySwSWqdPvDsHr_9Jg/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/cdcd437b31f82911f38c780046c01e3dcc954793597d4188833fbf104ab35887b6a35298848dcc3927d8e90445cfd1306c13bf8cf29ed3be9e8781a91ee08781", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/643d34da106d058eb3bf2e967cf5883b82df3f5134a9e985203d754c2b65f380867c280d875531963dec7681b530f49241bd13798e5b375ad1c6d2c17f87e4ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a688e280d583b540a3b09f184f414bf25b9cf56da2aee0a315ebd2ea0cbce6549c8cd594b8f34bffed6e81daf06331e97edb27266096d6f202e94bec063006ac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-14 04:16:42.686593) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-14 04:16:42.686943) ========= 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=4300f881736b422476174874000001bc-1473850121.744833-1478930374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-14 04:16:42.771795) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-14 04:16:42.772538) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-14 04:16:42.773157) ========= ========= Total master_lag: 0.18 =========