builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1471689405.59 results: success (0) buildid: 20160820004002 builduid: 726c2e586d0a46d4bf05a8d48ad4531c revision: 6f1c9caedd87771d03d5e2808a4e821136679221 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.595051) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.595485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.595757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.633114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.633376) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-20 03:36:46-- 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% 219K=0.05s 2016-08-20 03:36:46 (219 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.201229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.850078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.850366) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020449 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.899782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:45.900145) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f1c9caedd87771d03d5e2808a4e821136679221 --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 6f1c9caedd87771d03d5e2808a4e821136679221 --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=c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-20 03:36:46,544 truncating revision to first 12 chars 2016-08-20 03:36:46,544 Setting DEBUG logging. 2016-08-20 03:36:46,544 attempt 1/10 2016-08-20 03:36:46,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6f1c9caedd87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-20 03:36:46,855 unpacking tar archive at: mozilla-aurora-6f1c9caedd87/testing/mozharness/ program finished with exit code 0 elapsedTime=0.611177 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:46.526639) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:46.526915) ========= script_repo_revision: 6f1c9caedd87771d03d5e2808a4e821136679221 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:46.527305) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:46.527571) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-20 03:36:46.538531) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-20 03:36:46.538921) ========= /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=c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:36:47 INFO - MultiFileLogger online at 20160820 03:36:47 in /builds/slave/test 03:36:47 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:36:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:36:47 INFO - {'append_to_log': False, 03:36:47 INFO - 'base_work_dir': '/builds/slave/test', 03:36:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:36:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:36:47 INFO - 'branch': 'Mozilla-Aurora', 03:36:47 INFO - 'buildbot_json_path': 'buildprops.json', 03:36:47 INFO - 'config_files': ('talos/linux_config.py',), 03:36:47 INFO - 'default_actions': ('clobber', 03:36:47 INFO - 'read-buildbot-config', 03:36:47 INFO - 'download-and-extract', 03:36:47 INFO - 'populate-webroot', 03:36:47 INFO - 'create-virtualenv', 03:36:47 INFO - 'install', 03:36:47 INFO - 'run-tests'), 03:36:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:36:47 INFO - 'download_minidump_stackwalk': True, 03:36:47 INFO - 'download_symbols': 'ondemand', 03:36:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:36:47 INFO - 'tooltool.py': '/tools/tooltool.py', 03:36:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:36:47 INFO - '/tools/misc-python/virtualenv.py')}, 03:36:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:36:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:36:47 INFO - 'installer_path': 'installer.exe', 03:36:47 INFO - 'log_level': 'info', 03:36:47 INFO - 'log_name': 'talos', 03:36:47 INFO - 'log_to_console': True, 03:36:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:36:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:36:47 INFO - 'opt_config_files': (), 03:36:47 INFO - 'pip_index': False, 03:36:47 INFO - 'sps_profile': False, 03:36:47 INFO - 'sps_profile_interval': 0, 03:36:47 INFO - 'suite': 'dromaeojs', 03:36:47 INFO - 'system_bits': '32', 03:36:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:36:47 INFO - 'title': 'talos-linux64-ix-004', 03:36:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:36:47 INFO - 'use_talos_json': True, 03:36:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:36:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:36:47 INFO - 'work_dir': 'build'} 03:36:47 INFO - [mozharness: 2016-08-20 10:36:47.272975Z] Running clobber step. 03:36:47 INFO - Running pre-action listener: _resource_record_pre_action 03:36:47 INFO - Running main action method: clobber 03:36:47 INFO - rmtree: /builds/slave/test/build 03:36:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:36:47 INFO - Running post-action listener: _resource_record_post_action 03:36:47 INFO - [mozharness: 2016-08-20 10:36:47.735696Z] Finished clobber step (success) 03:36:47 INFO - [mozharness: 2016-08-20 10:36:47.735797Z] Running read-buildbot-config step. 03:36:47 INFO - Running pre-action listener: _resource_record_pre_action 03:36:47 INFO - Running main action method: read_buildbot_config 03:36:47 INFO - Using buildbot properties: 03:36:47 INFO - { 03:36:47 INFO - "project": "", 03:36:47 INFO - "product": "firefox", 03:36:47 INFO - "script_repo_revision": "production", 03:36:47 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:36:47 INFO - "repository": "", 03:36:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:36:47 INFO - "buildid": "20160820004002", 03:36:47 INFO - "stage_platform": "linux64-pgo", 03:36:47 INFO - "basedir": "/builds/slave/test", 03:36:47 INFO - "nightly_build": "True", 03:36:47 INFO - "buildnumber": 479, 03:36:47 INFO - "slavename": "talos-linux64-ix-004", 03:36:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:36:47 INFO - "platform": "ubuntu64_hw", 03:36:47 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:36:47 INFO - "branch": "mozilla-aurora", 03:36:47 INFO - "repo_path": "releases/mozilla-aurora", 03:36:47 INFO - "revision": "6f1c9caedd87771d03d5e2808a4e821136679221", 03:36:47 INFO - "pgo_build": "True", 03:36:47 INFO - "builduid": "726c2e586d0a46d4bf05a8d48ad4531c", 03:36:47 INFO - "slavebuilddir": "test" 03:36:47 INFO - } 03:36:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:36:47 INFO - Running post-action listener: _resource_record_post_action 03:36:47 INFO - [mozharness: 2016-08-20 10:36:47.738337Z] Finished read-buildbot-config step (success) 03:36:47 INFO - [mozharness: 2016-08-20 10:36:47.738427Z] Running download-and-extract step. 03:36:47 INFO - Running pre-action listener: _resource_record_pre_action 03:36:47 INFO - Running main action method: download_and_extract 03:36:47 INFO - mkdir: /builds/slave/test/build/tests 03:36: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:36:47 INFO - https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:36:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:36:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:36:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:36:47 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:37:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:17 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:38:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:17 INFO - Caught exception: HTTP Error 404: Not Found 03:38:17 INFO - Caught exception: HTTP Error 404: Not Found 03:38:17 INFO - Caught exception: HTTP Error 404: Not Found 03:38:17 INFO - trying https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:38:17 INFO - Downloading https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:18 INFO - Downloaded 1361 bytes. 03:38:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:38:18 INFO - Using the following test package requirements: 03:38:18 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:38:18 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:38:18 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:38:18 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'jsshell-linux-x86_64.zip'], 03:38:18 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:38:18 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:38:18 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:38:18 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:38:18 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:38:18 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:38:18 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:38:18 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:18 INFO - https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:38:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:38:18 INFO - retry: Failed, sleeping 30 seconds before retrying 03:38:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:38:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:38:48 INFO - retry: Failed, sleeping 60 seconds before retrying 03:39:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:39:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:39:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:39:48 INFO - Caught exception: HTTP Error 404: Not Found 03:39:48 INFO - Caught exception: HTTP Error 404: Not Found 03:39:48 INFO - Caught exception: HTTP Error 404: Not Found 03:39:48 INFO - trying https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:48 INFO - Downloading https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:39:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:39:49 INFO - Downloaded 21768582 bytes. 03:39:49 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:39:49 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:39:50 INFO - Return code: 0 03:39:50 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:39: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:39:50 INFO - https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:39:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:39:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:39:50 INFO - retry: Failed, sleeping 30 seconds before retrying 03:40:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:40:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:40:20 INFO - retry: Failed, sleeping 60 seconds before retrying 03:41:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:41:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:41:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:20 INFO - Caught exception: HTTP Error 404: Not Found 03:41:20 INFO - Caught exception: HTTP Error 404: Not Found 03:41:20 INFO - Caught exception: HTTP Error 404: Not Found 03:41:20 INFO - trying https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:41:20 INFO - Downloading https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:21 INFO - Downloaded 21768582 bytes. 03:41:21 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:41:21 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:41:22 INFO - Return code: 0 03:41:22 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:41:22 INFO - https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:41:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:41:22 INFO - retry: Failed, sleeping 30 seconds before retrying 03:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:41:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:41:52 INFO - retry: Failed, sleeping 60 seconds before retrying 03:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:42:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:42:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:42:52 INFO - Caught exception: HTTP Error 404: Not Found 03:42:52 INFO - Caught exception: HTTP Error 404: Not Found 03:42:52 INFO - Caught exception: HTTP Error 404: Not Found 03:42:52 INFO - trying https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:52 INFO - Downloading https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:42:54 INFO - Downloaded 14413723 bytes. 03:42:54 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:42:54 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:42:54 INFO - Return code: 0 03:42:54 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:42:54 INFO - https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:42:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:42:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:42:54 INFO - retry: Failed, sleeping 30 seconds before retrying 03:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:43:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:43:24 INFO - retry: Failed, sleeping 60 seconds before retrying 03:44:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:44:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:44:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:44:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:44:24 INFO - Caught exception: HTTP Error 404: Not Found 03:44:24 INFO - Caught exception: HTTP Error 404: Not Found 03:44:24 INFO - Caught exception: HTTP Error 404: Not Found 03:44:24 INFO - trying https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:44:24 INFO - Downloading https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:44:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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:44:26 INFO - Downloaded 63732200 bytes. 03:44:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:44:26 INFO - mkdir: /builds/slave/test/properties 03:44:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:44:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:44:26 INFO - Contents: 03:44:26 INFO - build_url:https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:44:27 INFO - Running post-action listener: _resource_record_post_action 03:44:27 INFO - Running post-action listener: set_extra_try_arguments 03:44:27 INFO - [mozharness: 2016-08-20 10:44:27.344369Z] Finished download-and-extract step (success) 03:44:27 INFO - [mozharness: 2016-08-20 10:44:27.344602Z] Running populate-webroot step. 03:44:27 INFO - Running pre-action listener: _resource_record_pre_action 03:44:27 INFO - Running main action method: populate_webroot 03:44:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:44:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:44:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:44:27 INFO - '--tppagecycles', 03:44:27 INFO - '7'], 03:44:27 INFO - 'tests': ['tsvgm']}}, 03:44:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:44:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:44:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:44:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:44:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['damp', 'tps']}, 03:44:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'tests': ['damp', 'tps']}, 03:44:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['dromaeo_dom']}, 03:44:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:44:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['basic_compositor_video']}, 03:44:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:44:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['a11yr', 03:44:27 INFO - 'ts_paint', 03:44:27 INFO - 'tpaint', 03:44:27 INFO - 'sessionrestore', 03:44:27 INFO - 'sessionrestore_no_auto_restore', 03:44:27 INFO - 'tabpaint']}, 03:44:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:44:27 INFO - 'ts_paint', 03:44:27 INFO - 'tpaint', 03:44:27 INFO - 'sessionrestore', 03:44:27 INFO - 'sessionrestore_no_auto_restore', 03:44:27 INFO - 'tabpaint']}, 03:44:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:44:27 INFO - 'ts_paint', 03:44:27 INFO - 'tpaint', 03:44:27 INFO - 'sessionrestore', 03:44:27 INFO - 'sessionrestore_no_auto_restore', 03:44:27 INFO - 'tabpaint']}, 03:44:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:44:27 INFO - 'ts_paint', 03:44:27 INFO - 'tpaint', 03:44:27 INFO - 'sessionrestore', 03:44:27 INFO - 'sessionrestore_no_auto_restore', 03:44:27 INFO - 'tabpaint']}, 03:44:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['a11yr', 03:44:27 INFO - 'ts_paint', 03:44:27 INFO - 'tpaint', 03:44:27 INFO - 'sessionrestore', 03:44:27 INFO - 'sessionrestore_no_auto_restore', 03:44:27 INFO - 'tabpaint']}, 03:44:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['a11yr', 03:44:27 INFO - 'ts_paint', 03:44:27 INFO - 'tpaint', 03:44:27 INFO - 'sessionrestore', 03:44:27 INFO - 'sessionrestore_no_auto_restore', 03:44:27 INFO - 'tabpaint']}, 03:44:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['tsvgx', 03:44:27 INFO - 'tsvgr_opacity', 03:44:27 INFO - 'tart', 03:44:27 INFO - 'tscrollx', 03:44:27 INFO - 'cart']}, 03:44:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:44:27 INFO - 'tsvgr_opacity', 03:44:27 INFO - 'tart', 03:44:27 INFO - 'tscrollx', 03:44:27 INFO - 'cart']}, 03:44:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'talos_options': ['--disable-e10s'], 03:44:27 INFO - 'tests': ['tp5o']}, 03:44:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'tests': ['tp5o']}, 03:44:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'talos_options': ['--disable-e10s', 03:44:27 INFO - '--xperf_path', 03:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:27 INFO - 'tests': ['tp5n']}, 03:44:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:27 INFO - 'talos_options': ['--xperf_path', 03:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:27 INFO - 'tests': ['tp5n']}}, 03:44:27 INFO - 'talos.zip': {'path': '', 03:44:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:44:27 INFO - Running post-action listener: _resource_record_post_action 03:44:27 INFO - [mozharness: 2016-08-20 10:44:27.366017Z] Finished populate-webroot step (success) 03:44:27 INFO - [mozharness: 2016-08-20 10:44:27.366107Z] Running create-virtualenv step. 03:44:27 INFO - Running pre-action listener: _resource_record_pre_action 03:44:27 INFO - Running main action method: create_virtualenv 03:44:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:44:27 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:44:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:44:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:44:27 INFO - Using real prefix '/usr' 03:44:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:44:27 INFO - Installing distribute.............................................................................................................................................................................................done. 03:44:29 INFO - Installing pip.................done. 03:44:29 INFO - Return code: 0 03:44:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:44:29 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:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:29 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:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:29 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:29 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:44:29 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:44:29 INFO - Using env: {'DISPLAY': ':0', 03:44:29 INFO - 'HOME': '/home/cltbld', 03:44:29 INFO - 'LANG': 'en_US.UTF-8', 03:44:29 INFO - 'LANGUAGE': 'en_US:en', 03:44:29 INFO - 'LOGNAME': 'cltbld', 03:44:29 INFO - 'MAIL': '/var/mail/cltbld', 03:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:29 INFO - 'MOZ_NO_REMOTE': '1', 03:44:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:29 INFO - 'NO_EM_RESTART': '1', 03:44:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:29 INFO - 'PWD': '/builds/slave/test', 03:44:29 INFO - 'SHELL': '/bin/bash', 03:44:29 INFO - 'SHLVL': '1', 03:44:29 INFO - 'TERM': 'linux', 03:44:29 INFO - 'TMOUT': '86400', 03:44:29 INFO - 'USER': 'cltbld', 03:44:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:29 INFO - '_': '/tools/buildbot/bin/python'} 03:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:30 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:44:30 INFO - Cleaning up... 03:44:30 INFO - Return code: 0 03:44:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:44: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:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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', '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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:44: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 psutil>=3.1.1 03:44:30 INFO - Using env: {'DISPLAY': ':0', 03:44:30 INFO - 'HOME': '/home/cltbld', 03:44:30 INFO - 'LANG': 'en_US.UTF-8', 03:44:30 INFO - 'LANGUAGE': 'en_US:en', 03:44:30 INFO - 'LOGNAME': 'cltbld', 03:44:30 INFO - 'MAIL': '/var/mail/cltbld', 03:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:30 INFO - 'MOZ_NO_REMOTE': '1', 03:44:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:30 INFO - 'NO_EM_RESTART': '1', 03:44:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:30 INFO - 'PWD': '/builds/slave/test', 03:44:30 INFO - 'SHELL': '/bin/bash', 03:44:30 INFO - 'SHLVL': '1', 03:44:30 INFO - 'TERM': 'linux', 03:44:30 INFO - 'TMOUT': '86400', 03:44:30 INFO - 'USER': 'cltbld', 03:44:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:30 INFO - '_': '/tools/buildbot/bin/python'} 03:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:30 INFO - Downloading/unpacking psutil>=3.1.1 03:44: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:44: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:44: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:44: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:44: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:44: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:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:44:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:32 INFO - Installing collected packages: psutil 03:44:32 INFO - Running setup.py install for psutil 03:44:32 INFO - building 'psutil._psutil_linux' extension 03:44:32 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:44:32 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:44:32 INFO - building 'psutil._psutil_posix' extension 03:44:32 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:44:32 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:44:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:32 INFO - Successfully installed psutil 03:44:32 INFO - Cleaning up... 03:44:32 INFO - Return code: 0 03:44:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:44:32 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:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:32 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:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:32 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:32 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:44:32 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:44:32 INFO - Using env: {'DISPLAY': ':0', 03:44:32 INFO - 'HOME': '/home/cltbld', 03:44:32 INFO - 'LANG': 'en_US.UTF-8', 03:44:32 INFO - 'LANGUAGE': 'en_US:en', 03:44:32 INFO - 'LOGNAME': 'cltbld', 03:44:32 INFO - 'MAIL': '/var/mail/cltbld', 03:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:32 INFO - 'MOZ_NO_REMOTE': '1', 03:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:32 INFO - 'NO_EM_RESTART': '1', 03:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:32 INFO - 'PWD': '/builds/slave/test', 03:44:32 INFO - 'SHELL': '/bin/bash', 03:44:32 INFO - 'SHLVL': '1', 03:44:32 INFO - 'TERM': 'linux', 03:44:32 INFO - 'TMOUT': '86400', 03:44:32 INFO - 'USER': 'cltbld', 03:44:32 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:32 INFO - '_': '/tools/buildbot/bin/python'} 03:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:44: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:44: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:44: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:44: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:44: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:44: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:44:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:44:35 INFO - Installing collected packages: mozsystemmonitor 03:44:35 INFO - Running setup.py install for mozsystemmonitor 03:44:35 INFO - Successfully installed mozsystemmonitor 03:44:35 INFO - Cleaning up... 03:44:35 INFO - Return code: 0 03:44:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:44:35 INFO - Using env: {'DISPLAY': ':0', 03:44:35 INFO - 'HOME': '/home/cltbld', 03:44:35 INFO - 'LANG': 'en_US.UTF-8', 03:44:35 INFO - 'LANGUAGE': 'en_US:en', 03:44:35 INFO - 'LOGNAME': 'cltbld', 03:44:35 INFO - 'MAIL': '/var/mail/cltbld', 03:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:35 INFO - 'MOZ_NO_REMOTE': '1', 03:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:35 INFO - 'NO_EM_RESTART': '1', 03:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:35 INFO - 'PWD': '/builds/slave/test', 03:44:35 INFO - 'SHELL': '/bin/bash', 03:44:35 INFO - 'SHLVL': '1', 03:44:35 INFO - 'TERM': 'linux', 03:44:35 INFO - 'TMOUT': '86400', 03:44:35 INFO - 'USER': 'cltbld', 03:44:35 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:35 INFO - '_': '/tools/buildbot/bin/python'} 03:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:35 INFO - Downloading/unpacking jsonschema==2.5.1 03:44: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:44: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:44: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:44: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:44: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:44: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:44:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:44:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:44:37 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:44:37 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:44:37 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:44:37 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:44:37 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:44:37 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:44:37 INFO - Downloading functools32-3.2.3-2.tar.gz 03:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:44:37 INFO - warning: no files found matching '*.txt' 03:44:37 INFO - no previously-included directories found matching 'build' 03:44:37 INFO - no previously-included directories found matching 'dist' 03:44:37 INFO - no previously-included directories found matching '.git*' 03:44:37 INFO - Installing collected packages: jsonschema, functools32 03:44:37 INFO - Running setup.py install for functools32 03:44:37 INFO - warning: no files found matching '*.txt' 03:44:37 INFO - no previously-included directories found matching 'build' 03:44:37 INFO - no previously-included directories found matching 'dist' 03:44:37 INFO - no previously-included directories found matching '.git*' 03:44:37 INFO - Successfully installed jsonschema functools32 03:44:37 INFO - Cleaning up... 03:44:37 INFO - Return code: 0 03:44:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:37 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:37 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:44:37 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:44:37 INFO - Using env: {'DISPLAY': ':0', 03:44:37 INFO - 'HOME': '/home/cltbld', 03:44:37 INFO - 'LANG': 'en_US.UTF-8', 03:44:37 INFO - 'LANGUAGE': 'en_US:en', 03:44:37 INFO - 'LOGNAME': 'cltbld', 03:44:37 INFO - 'MAIL': '/var/mail/cltbld', 03:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:37 INFO - 'MOZ_NO_REMOTE': '1', 03:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:37 INFO - 'NO_EM_RESTART': '1', 03:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:37 INFO - 'PWD': '/builds/slave/test', 03:44:37 INFO - 'SHELL': '/bin/bash', 03:44:37 INFO - 'SHLVL': '1', 03:44:37 INFO - 'TERM': 'linux', 03:44:37 INFO - 'TMOUT': '86400', 03:44:37 INFO - 'USER': 'cltbld', 03:44:37 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:37 INFO - '_': '/tools/buildbot/bin/python'} 03:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:44:37 INFO - Cleaning up... 03:44:37 INFO - Return code: 0 03:44:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:37 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:37 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:44:37 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:44:37 INFO - Using env: {'DISPLAY': ':0', 03:44:37 INFO - 'HOME': '/home/cltbld', 03:44:37 INFO - 'LANG': 'en_US.UTF-8', 03:44:37 INFO - 'LANGUAGE': 'en_US:en', 03:44:37 INFO - 'LOGNAME': 'cltbld', 03:44:37 INFO - 'MAIL': '/var/mail/cltbld', 03:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:37 INFO - 'MOZ_NO_REMOTE': '1', 03:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:37 INFO - 'NO_EM_RESTART': '1', 03:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:37 INFO - 'PWD': '/builds/slave/test', 03:44:37 INFO - 'SHELL': '/bin/bash', 03:44:37 INFO - 'SHLVL': '1', 03:44:37 INFO - 'TERM': 'linux', 03:44:37 INFO - 'TMOUT': '86400', 03:44:37 INFO - 'USER': 'cltbld', 03:44:37 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:37 INFO - '_': '/tools/buildbot/bin/python'} 03:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:44:38 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:44:38 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:44:38 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:44:38 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:44:38 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:44:38 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:44:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:44:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:44:40 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:44:40 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:44:40 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:44:40 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:44:40 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:44:40 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:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:44:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:44:40 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:44:40 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:44:40 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:44:40 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:44:40 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:44:40 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:44:40 INFO - Downloading docopt-0.6.1.tar.gz 03:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:44:40 INFO - Installing collected packages: blobuploader, requests, docopt 03:44:40 INFO - Running setup.py install for blobuploader 03:44:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:44:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:44:40 INFO - Running setup.py install for requests 03:44:41 INFO - Running setup.py install for docopt 03:44:41 INFO - Successfully installed blobuploader requests docopt 03:44:41 INFO - Cleaning up... 03:44:41 INFO - Return code: 0 03:44:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:41 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:41 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:44:41 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:44:41 INFO - Using env: {'DISPLAY': ':0', 03:44:41 INFO - 'HOME': '/home/cltbld', 03:44:41 INFO - 'LANG': 'en_US.UTF-8', 03:44:41 INFO - 'LANGUAGE': 'en_US:en', 03:44:41 INFO - 'LOGNAME': 'cltbld', 03:44:41 INFO - 'MAIL': '/var/mail/cltbld', 03:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:41 INFO - 'MOZ_NO_REMOTE': '1', 03:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:41 INFO - 'NO_EM_RESTART': '1', 03:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:41 INFO - 'PWD': '/builds/slave/test', 03:44:41 INFO - 'SHELL': '/bin/bash', 03:44:41 INFO - 'SHLVL': '1', 03:44:41 INFO - 'TERM': 'linux', 03:44:41 INFO - 'TMOUT': '86400', 03:44:41 INFO - 'USER': 'cltbld', 03:44:41 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:41 INFO - '_': '/tools/buildbot/bin/python'} 03:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:41 INFO - Running setup.py (path:/tmp/pip-1YxInA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:41 INFO - Running setup.py (path:/tmp/pip-44AEWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:41 INFO - Running setup.py (path:/tmp/pip-nRvHUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:41 INFO - Running setup.py (path:/tmp/pip-k461MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:41 INFO - Running setup.py (path:/tmp/pip-gxmIjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:42 INFO - Running setup.py (path:/tmp/pip-it4EnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:42 INFO - Running setup.py (path:/tmp/pip-AEIRJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:42 INFO - Running setup.py (path:/tmp/pip-0VWEeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:42 INFO - Running setup.py (path:/tmp/pip-WnwQeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:42 INFO - Running setup.py (path:/tmp/pip-Kjiu6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:42 INFO - Running setup.py (path:/tmp/pip-cUveVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:42 INFO - Running setup.py (path:/tmp/pip-MD7tWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:42 INFO - Running setup.py (path:/tmp/pip-S1kaqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:42 INFO - Running setup.py (path:/tmp/pip-D1CCiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:42 INFO - Running setup.py (path:/tmp/pip-hlJjqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:42 INFO - Running setup.py (path:/tmp/pip-LnoXXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:42 INFO - Running setup.py (path:/tmp/pip-r1IeWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:44:42 INFO - Running setup.py install for manifestparser 03:44:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Running setup.py install for mozcrash 03:44:43 INFO - Running setup.py install for mozdebug 03:44:43 INFO - Running setup.py install for mozdevice 03:44:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozfile 03:44:43 INFO - Running setup.py install for mozhttpd 03:44:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozinfo 03:44:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozInstall 03:44:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozleak 03:44:43 INFO - Running setup.py install for mozlog 03:44:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for moznetwork 03:44:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Running setup.py install for mozprocess 03:44:44 INFO - Running setup.py install for mozprofile 03:44:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Running setup.py install for mozrunner 03:44:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Running setup.py install for mozscreenshot 03:44:44 INFO - Running setup.py install for moztest 03:44:44 INFO - Running setup.py install for mozversion 03:44:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:44:44 INFO - Cleaning up... 03:44:44 INFO - Return code: 0 03:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44: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:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:44 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:44 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:44:44 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:44:44 INFO - Using env: {'DISPLAY': ':0', 03:44:44 INFO - 'HOME': '/home/cltbld', 03:44:44 INFO - 'LANG': 'en_US.UTF-8', 03:44:44 INFO - 'LANGUAGE': 'en_US:en', 03:44:44 INFO - 'LOGNAME': 'cltbld', 03:44:44 INFO - 'MAIL': '/var/mail/cltbld', 03:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:44 INFO - 'MOZ_NO_REMOTE': '1', 03:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:44 INFO - 'NO_EM_RESTART': '1', 03:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:44 INFO - 'PWD': '/builds/slave/test', 03:44:44 INFO - 'SHELL': '/bin/bash', 03:44:44 INFO - 'SHLVL': '1', 03:44:44 INFO - 'TERM': 'linux', 03:44:44 INFO - 'TMOUT': '86400', 03:44:44 INFO - 'USER': 'cltbld', 03:44:44 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:44 INFO - '_': '/tools/buildbot/bin/python'} 03:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:44 INFO - Running setup.py (path:/tmp/pip-oXpxUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:44 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:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:44 INFO - Running setup.py (path:/tmp/pip-ftbtRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:45 INFO - Running setup.py (path:/tmp/pip-X9jgBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:45 INFO - Running setup.py (path:/tmp/pip-ZDo6HU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:45 INFO - Running setup.py (path:/tmp/pip-eokCda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:45 INFO - Running setup.py (path:/tmp/pip-Xkdw3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:45 INFO - Running setup.py (path:/tmp/pip-4MClFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:45 INFO - Running setup.py (path:/tmp/pip-aoriUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:45 INFO - Running setup.py (path:/tmp/pip-wHXLmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:45 INFO - Running setup.py (path:/tmp/pip-1w9o_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:45 INFO - Running setup.py (path:/tmp/pip-j4bLfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:45 INFO - Running setup.py (path:/tmp/pip-_mJOBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:45 INFO - Running setup.py (path:/tmp/pip-NSdmIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:45 INFO - Running setup.py (path:/tmp/pip-Oi1Hib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:45 INFO - Running setup.py (path:/tmp/pip-WpuKWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:45 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:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:45 INFO - Running setup.py (path:/tmp/pip-IpaYBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:46 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:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:46 INFO - Running setup.py (path:/tmp/pip-RwuptB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:47 INFO - Downloading blessings-1.6.tar.gz 03:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:44:48 INFO - Installing collected packages: blessings 03:44:48 INFO - Running setup.py install for blessings 03:44:48 INFO - Successfully installed blessings 03:44:48 INFO - Cleaning up... 03:44:48 INFO - Return code: 0 03:44:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:48 INFO - Reading from file tmpfile_stdout 03:44:48 INFO - Current package versions: 03:44:48 INFO - argparse == 1.2.1 03:44:48 INFO - blessings == 1.6 03:44:48 INFO - blobuploader == 1.2.4 03:44:48 INFO - docopt == 0.6.1 03:44:48 INFO - functools32 == 3.2.3-2 03:44:48 INFO - jsonschema == 2.5.1 03:44:48 INFO - manifestparser == 1.1 03:44:48 INFO - mozInstall == 1.12 03:44:48 INFO - mozcrash == 0.17 03:44:48 INFO - mozdebug == 0.1 03:44:48 INFO - mozdevice == 0.48 03:44:48 INFO - mozfile == 1.2 03:44:48 INFO - mozhttpd == 0.7 03:44:48 INFO - mozinfo == 0.9 03:44:48 INFO - mozleak == 0.1 03:44:48 INFO - mozlog == 3.3 03:44:48 INFO - moznetwork == 0.27 03:44:48 INFO - mozprocess == 0.23 03:44:48 INFO - mozprofile == 0.28 03:44:48 INFO - mozrunner == 6.12 03:44:48 INFO - mozscreenshot == 0.1 03:44:48 INFO - mozsystemmonitor == 0.3 03:44:48 INFO - moztest == 0.7 03:44:48 INFO - mozversion == 1.4 03:44:48 INFO - psutil == 3.1.1 03:44:48 INFO - requests == 1.2.3 03:44:48 INFO - wsgiref == 0.1.2 03:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:48 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:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:48 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:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:48 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:48 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:44:48 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:44:48 INFO - Using env: {'DISPLAY': ':0', 03:44:48 INFO - 'HOME': '/home/cltbld', 03:44:48 INFO - 'LANG': 'en_US.UTF-8', 03:44:48 INFO - 'LANGUAGE': 'en_US:en', 03:44:48 INFO - 'LOGNAME': 'cltbld', 03:44:48 INFO - 'MAIL': '/var/mail/cltbld', 03:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:48 INFO - 'MOZ_NO_REMOTE': '1', 03:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:48 INFO - 'NO_EM_RESTART': '1', 03:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:48 INFO - 'PWD': '/builds/slave/test', 03:44:48 INFO - 'SHELL': '/bin/bash', 03:44:48 INFO - 'SHLVL': '1', 03:44:48 INFO - 'TERM': 'linux', 03:44:48 INFO - 'TMOUT': '86400', 03:44:48 INFO - 'USER': 'cltbld', 03:44:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:48 INFO - '_': '/tools/buildbot/bin/python'} 03:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 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:44:48 INFO - Cleaning up... 03:44:48 INFO - Return code: 0 03:44:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:44:48 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:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:48 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:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:48 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 0xc3b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', '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:44:48 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:44:48 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:44:48 INFO - Using env: {'DISPLAY': ':0', 03:44:48 INFO - 'HOME': '/home/cltbld', 03:44:48 INFO - 'LANG': 'en_US.UTF-8', 03:44:48 INFO - 'LANGUAGE': 'en_US:en', 03:44:48 INFO - 'LOGNAME': 'cltbld', 03:44:48 INFO - 'MAIL': '/var/mail/cltbld', 03:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:48 INFO - 'MOZ_NO_REMOTE': '1', 03:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:48 INFO - 'NO_EM_RESTART': '1', 03:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:48 INFO - 'PWD': '/builds/slave/test', 03:44:48 INFO - 'SHELL': '/bin/bash', 03:44:48 INFO - 'SHLVL': '1', 03:44:48 INFO - 'TERM': 'linux', 03:44:48 INFO - 'TMOUT': '86400', 03:44:48 INFO - 'USER': 'cltbld', 03:44:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:48 INFO - '_': '/tools/buildbot/bin/python'} 03:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:44:48 INFO - Cleaning up... 03:44:48 INFO - Return code: 0 03:44:48 INFO - Running post-action listener: _resource_record_post_action 03:44:48 INFO - Running post-action listener: _start_resource_monitoring 03:44:48 INFO - Starting resource monitoring. 03:44:48 INFO - [mozharness: 2016-08-20 10:44:48.937015Z] Finished create-virtualenv step (success) 03:44:48 INFO - [mozharness: 2016-08-20 10:44:48.937565Z] Running install step. 03:44:48 INFO - Running pre-action listener: _resource_record_pre_action 03:44:48 INFO - Running main action method: install 03:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:49 INFO - Reading from file tmpfile_stdout 03:44:49 INFO - Detecting whether we're running mozinstall >=1.0... 03:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:44:49 INFO - Reading from file tmpfile_stdout 03:44:49 INFO - Output received: 03:44:49 INFO - Usage: mozinstall [options] installer 03:44:49 INFO - Options: 03:44:49 INFO - -h, --help show this help message and exit 03:44:49 INFO - -d DEST, --destination=DEST 03:44:49 INFO - Directory to install application into. [default: 03:44:49 INFO - "/builds/slave/test"] 03:44:49 INFO - --app=APP Application being installed. [default: firefox] 03:44:49 INFO - mkdir: /builds/slave/test/build/application 03:44:49 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:44:49 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:45:05 INFO - Reading from file tmpfile_stdout 03:45:05 INFO - Output received: 03:45:05 INFO - /builds/slave/test/build/application/firefox/firefox 03:45:05 INFO - Running post-action listener: _resource_record_post_action 03:45:05 INFO - [mozharness: 2016-08-20 10:45:05.924790Z] Finished install step (success) 03:45:05 INFO - [mozharness: 2016-08-20 10:45:05.925069Z] Running run-tests step. 03:45:05 INFO - Running pre-action listener: _resource_record_pre_action 03:45:05 INFO - Running main action method: run_tests 03:45:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:45:05 INFO - Python 2.7.3 03:45:05 INFO - Return code: 0 03:45:05 INFO - grabbing minidump binary from tooltool 03:45:05 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:45:05 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 0xefa3b0>, '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 0xefb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefb740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:45:05 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:45:05 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:45:05 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:45:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:45:05 INFO - Return code: 0 03:45:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:45:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:45:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:45:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:45:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:45:06 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/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:45:06 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/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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:45:06 INFO - Using env: {'DISPLAY': ':0', 03:45:06 INFO - 'HOME': '/home/cltbld', 03:45:06 INFO - 'LANG': 'en_US.UTF-8', 03:45:06 INFO - 'LANGUAGE': 'en_US:en', 03:45:06 INFO - 'LOGNAME': 'cltbld', 03:45:06 INFO - 'MAIL': '/var/mail/cltbld', 03:45:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:45:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:06 INFO - 'MOZ_NO_REMOTE': '1', 03:45:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:45:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:06 INFO - 'NO_EM_RESTART': '1', 03:45:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:06 INFO - 'PWD': '/builds/slave/test', 03:45:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:45:06 INFO - 'SHELL': '/bin/bash', 03:45:06 INFO - 'SHLVL': '1', 03:45:06 INFO - 'TERM': 'linux', 03:45:06 INFO - 'TMOUT': '86400', 03:45:06 INFO - 'USER': 'cltbld', 03:45:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790', 03:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:06 INFO - '_': '/tools/buildbot/bin/python'} 03:45:06 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/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:45:06 INFO - mozversion application_buildid: 20160820004002 03:45:06 INFO - mozversion application_changeset: 6f1c9caedd87771d03d5e2808a4e821136679221 03:45:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:45:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:45:06 INFO - mozversion application_name: Firefox 03:45:06 INFO - mozversion application_remotingname: firefox-dev 03:45:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:45:06 INFO - mozversion application_vendor: Mozilla 03:45:06 INFO - mozversion application_version: 50.0a2 03:45:06 INFO - mozversion platform_buildid: 20160820004002 03:45:06 INFO - mozversion platform_changeset: 6f1c9caedd87771d03d5e2808a4e821136679221 03:45:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:45:06 INFO - mozversion platform_version: 50.0a2 03:45:06 INFO - using testdate: 1471689906 03:45:06 INFO - actual date: 1471689906 03:45:06 INFO - starting webserver on 'localhost:37406' 03:45:06 INFO - SUITE-START | Running 2 tests 03:45:06 INFO - TEST-START | dromaeo_css 03:45:06 INFO - operating with platform_type : linux_ 03:45:06 INFO - Initialising browser for dromaeo_css test... 03:45:06 INFO - TEST-INFO | started process 2216 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHZZKi9/profile http://localhost:37406/getInfo.html) 03:45:10 INFO - PROCESS | 2216 | __metrics Screen width/height:1600/1200 03:45:10 INFO - PROCESS | 2216 | colorDepth:24 03:45:10 INFO - PROCESS | 2216 | Browser inner width/height: 1024/697 03:45:10 INFO - PROCESS | 2216 | __metrics 03:45:11 INFO - PROCESS | 2216 | 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:45:11 INFO - PROCESS | 2216 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:45:12 INFO - TEST-INFO | 2216: exit 0 03:45:12 INFO - Browser initialized. 03:45:12 INFO - Running cycle 1/1 for dromaeo_css test... 03:45:12 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHZZKi9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:23 INFO - PROCESS | 2304 | RSS: Main: 145526784 03:45:23 INFO - PROCESS | 2304 | 03:46:14 INFO - PROCESS | 2304 | 1471689974790 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] 03:48:23 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:37406/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37406/tests/dromaeo/cssquery-ext.html) 03:48:25 INFO - PROCESS | 2304 | RSS: Main: 274575360 03:48:25 INFO - PROCESS | 2304 | 03:51:27 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:37406/tests/dromaeo/cssquery-ext.html (next: http://localhost:37406/tests/dromaeo/cssquery-jquery.html) 03:51:28 INFO - PROCESS | 2304 | RSS: Main: 233316352 03:51:28 INFO - PROCESS | 2304 | 03:54:30 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:37406/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37406/tests/dromaeo/cssquery-mootools.html) 03:54:30 INFO - PROCESS | 2304 | RSS: Main: 175054848 03:54:30 INFO - PROCESS | 2304 | 03:57:32 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:37406/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37406/tests/dromaeo/cssquery-prototype.html) 03:57:33 INFO - PROCESS | 2304 | RSS: Main: 173699072 03:57:33 INFO - PROCESS | 2304 | 04:00:35 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:37406/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37406/tests/dromaeo/cssquery-yui.html) 04:00:36 INFO - PROCESS | 2304 | RSS: Main: 178081792 04:00:36 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:37406/tests/dromaeo/cssquery-yui.html (next: http://localhost:37406/tests/dromaeo/cssquery-dojo.html) 04:03:39 INFO - PROCESS | 2304 | RSS: Main: 263421952 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | __start_tp_report 04:03:39 INFO - PROCESS | 2304 | _x_x_mozilla_page_load 04:03:39 INFO - PROCESS | 2304 | _x_x_mozilla_page_load_details 04:03:39 INFO - PROCESS | 2304 | |i|pagename|runs| 04:03:39 INFO - PROCESS | 2304 | |0;dojo.html;3216.919577010575;3267.673232676732;3278.7704860659765;3314.651961544036;3318.336332733454;5508.366537848148;5617.634853734509;5630.859228519289;5651.519620832234;5676.432356764321;5569.777208911639;5613.270274864262;5639.464250896169;5640.266765320503;5673.801416950408;4996.825111121111;5052;5063.03802277568;5092.210707340369;5150.562202212816;5120.615953803461;5200.167973124301;5211.1922651989;5222.216667499886;5223.399309079467;5084.694918304895;5105.540501354877;5123.666961647499;5129.307543481638;5209.7134657593815;5205.9219111713355;5228.241904923765;5236.554892834114;5239.3712754469325;5268.841934741964;4943.085529177114;5007.474215207423;5028.7988480460735;5120.129577971754;5126.512981266774;5538;5554.222408862763;5573.721313934287;5586.664800112007;5600.131979543136;6067.878642427127;6078;6086.817395478142;6088.056351265561;6102;5827.650340979555;5975.820725378246;6013.098035294697;6027.427394397525;6133.26400831903;5634.971825140889;5653.943460565383;5666.716664166775;5675.914861277084;5683.261176047129;6170.382961703794;6173.629582225081;6196.442320191204;6201.472874805602;6203.2556093269095;5773.04744717117;5804.622699524517;5807.622504537276;5812.651240925558;5894.941050589525;5949.929012777741;5976.970115149396;5984.611000284967;5986.042233242661;5989.460948514655;5921.763129474859;5928.466438020599;5929.940700592939;5933.110033494924;5933.228680271537;5802.970985145047;5907.793227237112;5952.315483719428;6012.248468941382;6020.247469066367;4624.745638989821;4648.930266046012;4656.580907718321;4676.532346765296;4681.50844161365;4155.4182414462;4156.4388807510595;4158.688098392633;4166.874993750132;4185.958140418617;5557;5738.512226460798;5763.308402991668;5775.047117225611;5776.826695199151;6142.590862071504;6159.353267906805;6166;6166.599171053867;6168.383161683796;5810.970945145247;5819.563532734977;5823.330317013598;5829.970850145807;5846.035404158266;5104.3364362633365;5146;5172.818951336761;5256.973715131477;5282.788688452496;6218.968905155536;6223.3465486123305;6225;6229.688515574203;6230.158928544679;6011.939880601313;6024.0662697282;6032.306284777307;6059.668728796577;6090.23872015998;6105.9084113737445;6222.719977601109;6236.906446403217;6246.1255424239735;6293.339199383999;5990.46085852258;6005.309389420273;6009.45914867664;6020.27756669185;6036.517078633611;6089.208402907594;6099.84750381244;6103.11504831788;6104.8779024420155;6108.328083910677;5687;5846.064629659234;5877.23595932526;5900.586958912835;5977.581569290108;5895.351511333835;5941.316748573797;5947.494462970523;5951.940480595312;5988.311344195473;5876.941230587639;5888.941110588839;5890.1164825276555;5890.175375447355;5893.410658934073;5905.52755779528;5942.168096466412;5948.940510594839;5993.700314984146;6036.7585296589405;5847;5847.38602446754;5849.707514624167;5854.707264636666;5879.882402351844 04:03:39 INFO - PROCESS | 2304 | |1;ext.html;600.135804441604;637.6014990630856;658.1772784019973;658.5258613798067;659.423004870738;35027.4745878812;38222.426663599996;38340.424893626616;38424.61575384245;38559.61440385595;38970.22059558807;47701.284480732815;49152;49183;53970.46029539703;9243.07569243077;9367.203787678056;9373.297002724823;9383.906160938406;9387.671431499899;9144.908550914506;9216.861747073799;9239.445633262027;9242.86135707965;9245.537723113852;8596.312295016383;8778.29773618109;11067.114630829514;11457.713557161096;11457.94271028648;11343.262687925258;11362;11411.144164187719;11431.942840285745;11439.485223164978;8366.414350995432;11177.105831533458;11228.214025018253;11256.268342557789;11261.436928153642;38135.80932095349;38263;47171.29243061357;48573.51426485725;48708.756456217845;3839.8464061437508;3970.722049456539;4137.8758637240935;4424.181526417591;4547.295169248771;35668.464973025424;35860.82069589661;36164.83011019347;36658.45012324817;36939.26121477583;4375.890602734925;4378.474583050022;4382.627476664487;4537.977310113428;4601.723896566216;370444;374514;374727;376247;380461;58782.41217587855;66729.33270667329;67327.66336168288;67376.6631166841;67392.6630366845;67067;68545;68717.31282687209;75767.62116189495;75770;19253.61492770137;19406.514837129183;19438;19447.3193438229;19473;14651.706965860623;14790.186539740202;14817.407303707947;14818.184999825113;14823.110613363233;17083.889547179395;18524.814751852307;19537;19589.51026224327;19593.510162245773;6034.155218269446;6147.508199344042;6183.258009038854;6261.9373806262265;6359.713812878339;1062.4634559547378;1079.7138758229075;1108.6563165418745;1171.566520387462;1223.4555622747969;34728.65271347305;34892.65107348945;35028.64971350254;35135.4729679055;35512.28975420477;1174.1194104421684;1187.5012494752225;1194.176018547217;1217.8660347361783;1244.3467179730715;17576.736348954775;18522;18564.936785430524;18631.627367452846;18725.719114213298;588767;595442;596417;599727;599753;57285.42714572884;65413.34586654168;65531.34468655348;71213.2878671217;72589.27410725826;1456.788765628999;1463.1879306984727;1490.4932323010228;1499.6400863792612;1500.2573726005696;35240.29519409546;36575;36755.26489470249;36838.87728186146;36851.81574092113;58972;59388;59461.10808337792;59461.40538594559;59520.40479595149;517670;526547;528375;737476;742579;31311.68688313088;31370.84314578504;31487.52768708517;31513.36973260568;31795.36409271848;53780.06787476385;54090;54229.45770542244;54252;54308.728456357465;45076.32385514286;47137;47269.29096063493;47617.761911190224;48233.27650085181;44536.55463445454;45482.54517454915;45752;45859.847140507125;46981;45366.77316613528;46338.53661463477;46487;46634.533654664374;46673.29990050084;38565;40454.79772601118;40476.79761601173;40495.79752101221;40805.59194408018;30435.391292174474;30523.694763052972;30746.69253307528;30883.382332353678;30944.845275773478 04:03:39 INFO - PROCESS | 2304 | |2;jquery.html;402.8831638824741;405.07036351573134;421.76499063010726;432.8679752675434;437.6498800959232;15869;19841.900790496045;21956.780432195672;22060.227892023784;22416.551668966633;20949;21095.15619375223;21143.788562114376;21612.783872161275;21725.13099476023;5478.698671573062;5949.940500594993;6104.877902441948;6190.226221722285;6192.473639740628;6020.608660437079;6038;6053.031514957607;6056.848578785522;6063.029915213567;5753.5397168226655;6023.578349515536;6027.186329845458;6029.155918171459;6056.757729690829;6005.54958378119;6063.029915213545;6083.452489275942;6095.725692343855;6103.511719062464;5808.448197421239;5950.256217972754;5971.940280597182;5996.700164991733;5999.040153575451;13039.282839443827;13178.472861085545;13927.30363481832;14952;15040.548783536511;2275.7269127704617;2303.366574192094;2305.7002589663402;2449.9755002449924;2548.2482667612912;10883.074938630227;10940.3982780947;12057.698557536043;15363;16185.271662775203;1941.6019715958337;1987.3143765400914;2021.1713197588917;2054.578811343686;2094.1309356616985;88495;88771.55614221952;89518.10481895163;89877.55061224652;90999.54500227456;10605.946970265099;11282.548698052151;11400.258983166068;11590.13074019452;12137.575184868663;15409.537713868602;16275.918620406823;16298.348066077224;16982.830171698126;17008.48974530741;8912.643494260286;9110.772230694285;9275.62897484093;9342.53287335644;9368.39105458143;9062.773430664287;9089.590968406437;9111.407758495809;9253.444793312423;9283.58223879927;4896.1921282987905;5103.489651034941;5110.66780659256;5149.150390185652;5205.73971301441;2043.6934459831143;2097.381272524609;2129.975434882471;2160.762316145223;2177.7495588007264;970.9805803883883;1003.6236411345745;1005.3565717940523;1030.0420608833706;1105.4472763618191;20833.374998750063;20915.267965621435;21082.051307691192;21119.683204751942;21189.470263243544;1065.2064212161988;1075.8547596074586;1081.0540776820283;1084.788466249074;1091.2743025887696;11288.661340159808;11656.766864662659;11680.766384672259;11777.646670599896;11910.761784764429;564015.474003376;567914;573220;581710;588419;95723.52138239403;96735.51632241794;98924.50537747265;102085;102386;1063;1077.876044254905;1091.6561283195767;1098.6758906122714;1100.5432745410726;18499.722504162717;18832.24671013144;19002.619947601248;19086.61826763429;19389.709154362412;46378.53621463877;46719.532804671515;46757;47055;47870.28194577015;59895.700521497114;63730;67862.66068669625;69395;69442.65278673574;8760.211580957744;8999.955000225218;9268.304877134377;9325.487098209393;9515.429074255568;13219.603411897659;13258.801117983045;13970.511032113827;14278.2146981917;14646.853531464549;12859.614211573667;12982.740345193231;13066.281354525592;13068;13234.801477978031;12566.497340106293;12615.243085414537;12720.173188742701;12805.935970320088;13091.607251782461;12964.157329773534;13059.281739504419;13111.737765244832;13113.934430327787;13362.532311369056;9186.862197067183;9469.14777670013;9469.52652367365;9506.61973521079;9744.561494732896;13143.211407315592;13168.47326106945;13196.406161722554;13201.20792752438;13205.405756740778 04:03:39 INFO - PROCESS | 2304 | |3;mootools.html;1418.5744276716982;1545.8531439513247;1576.6767812598418;1777.448990812848;1897.1178402043038;7761.767146985592;8542.957285213573;8800.955995220014;8807.91192088079;9074.455532668044;8870;8936.59785309661;8938.687145949893;8946.686865959693;8954.32842536809;2459.127009911478;2465.038634932369;2481.255623313008;2483.292261705414;2485.3413845331;2392.820538459614;2480.355107672009;2487.1916627096175;2491.6013437849947;2491.788198003172;2389.3429306940643;2434.513097380522;2448.8285819992607;2452.4236804350962;2459.2007597530874;2374.968272374818;2396.0296080087505;2398.100712232913;2421.5641184586766;2433.4889673168655;2366.0535785685693;2377.881105944713;2381.547505973877;2381.9642705359433;2386.5942789725614;8826.86759698605;8933.59798809055;9101.089891010912;9223.216026637743;9231.953840230823;5383.4616538346245;5610.85972850682;5617.4382561743905;5619.044762390404;5621.522170615462;3463.2207753255566;3473.426884564044;3488.7034602058857;3499.265154317596;3560.7151427885706;1240.932975765194;1251.5306759965013;1254.956076537326;1280.295837289487;1281.5834853672593;12484.250944943333;12536.874631253662;12545.372731363486;12573.434195461135;12573.554041285277;4464.553544645574;4684;4797.736124513185;4801.447833499193;4806.711597304103;10038.698839034842;10134.341267817568;10147.847782283272;10151.238657100746;10151.746206344753;5505.174223866452;5527.695976721319;5533.142362933751;5536.058869992111;5537;4538.591526762607;4853.490383509751;4972.254161875675;5006.874828129326;5184.144616138295;3920.196359746245;4008.5390180129075;4071.206114807643;4080.0411903202726;4110.09577892869;1174.682835634374;1185.8932696057395;1194.8267501212276;1195.4022988505747;1216.0757824053783;982.8476586129161;1074.9787701683276;1090.7709381030072;1098.527633117767;1105.607509334175;6130.527995213605;6306.558540902184;6328.746850125944;6539.574927629689;6637.933620663828;483.16171442547153;492.2493197873272;492.58376671712347;493.1788572027534;502.14885768622565;4219.07180420307;4360.607545320873;4409.911801764011;4462.174497717932;4629.421322334708;10572.41851698164;11036.392998385005;11055.834162487568;11095.55617775296;11103.555857765761;10238.53926573306;10719.571217151226;11036.227464077438;11036.558537658404;11042.613508527164;8377.790555236168;8603.956980215058;8609.536378815616;8616.48301101936;8622.180892815173;6269.874602508015;6282.371762823864;6455.580387274812;6577.276499584946;6761.560498567577;10338.0695737381;10473.533705281114;10484.42335671515;10614.310069845435;10629.149668026694;10537.63118290856;10710.196735244732;10816.67549973502;10816.891831081903;10824.458777061272;6874.2782007890455;7044.225135235018;7047.541909775849;8273.793155170928;8411.369147314099;7652.961735191288;8118.715844945398;8186.733869980973;8858.29133669292;8879.200871921557;8067.717629883161;8101.311388532041;8141.674333026613;8164.591770411337;8224.588770561568;7843.215678432111;7928.682852685828;8044.074931382966;8079.030516337842;8233.38249631268;7592.392608591189;7716.92283077162;7719.459637825298;7724.806879828049;7731.536107833548;5482.314710661168;5550.69471179089;5586.441355864381;5694.772209111589;5744.425557444222;5726.9713651431475;5743.195952566561;5824.767009319579;5864.794732184353;5936.495397891227 04:03:39 INFO - PROCESS | 2304 | |4;prototype.html;348.2251989323755;356.4475063651341;357.93199292134494;360.4575114452748;365.89754868636777;5544;5622.863311941467;5631.1271752878265;5665.263515742958;5697.743601537931;5530.391656917735;5536.446355364462;5570.86072848179;5605.439456054392;5609.691466969315;4306.332518459643;4328.458942632171;4370.732487578598;4389.297712366022;4405.933910991338;4341.413909122275;4349.760763158025;4350.477942646887;4372.540883207265;4391.714538554999;4322.82708691652;4332.198543269489;4341.044970106587;4343.543927887574;4349.021195356121;4126.339785634299;4289.678274129453;4330.242207613655;4342.32693932441;4374.956250437487;3815.523059617548;3837.884863454101;3854.1328201154797;3854.383298672227;3860.343741563941;5365.141577347606;5429.239906413105;5431.293931788881;5456.508914197741;5482.945170548283;4892.8532144035735;4898.191798353268;4955.157623204028;4978.327925730016;4996.725180115092;5753.137029445575;5770.855728606818;5789.478946894757;5797.217375654275;5803.88392232153;4215.135897141079;4228.175505776375;4232.238197124517;4240.787960601958;4257.680119163069;5683.3464151622675;5829.475347218813;5886.617369870987;6081.756729730805;6098.69506524672;4279.914401711948;4297.634701050446;4326.939899724524;4338.674599405057;4361.389405483234;4381.233284175257;4399.186150562156;4402.141582391467;4408.096340250241;4411.933820992687;4691.272852707836;4738.123447162286;4938.185199442124;4997.325361076281;5005.599552035828;5726.112452569859;5762;5772.884542309131;5805.941940580625;5807.477327040587;1504.7818066380532;1520.657851983317;1524.5731195265344;1531.491480891321;1535.554689140159;978.5254151736402;990.1682586627268;992.925530585209;1040.3133931605105;1060.2313322840878;5600.159976003632;5618.971905140448;5642.210090587321;5655.943440565542;5665.886682266331;5968.492678122321;5983.461488466059;5985.22192115031;5987.251593550806;5989.760409583568;5428.267183930119;5682.516986056232;5713.485786279255;5717.085266357363;5726.226959360516;3150.8739650413727;3179.3164469639137;3189.1070500259966;3216.4049650814673;3226.757993150523;5925.170476133345;6182;6225.906611400833;6226.260445404007;6235.594686345464;6083.239595050619;6084.300305464928;6091.939080609226;6095.177151084636;6117.510599152057;6184.412480814315;6224.78616669745;6225.501959843111;6230.7819226326865;6237.941479311816;5710.143478478261;6012.458878700938;6020.969895150496;6046.3046749624355;6059.818205453844;5964.463198312173;5975.312839023394;5988.520918326436;5997.220361353171;6023.759049637965;6177.722002509988;6213.137991534648;6224.657643829632;6236.158118653923;6240.968795155995;4340.47914250292;4349.7168335341785;4365.73805571654;4366.2577361847925;4383.583559561836;6076.149339092619;6106.664133472702;6122;6137.693115344304;6148.802622636114;5687.857803554944;5901.790133022806;5929.0513517836935;5929.584929054925;5929.792457263975;5907.143464197753;5915.319738229987;5919.200907877382;5939.703014849154;5984.551158663031;5645.774169033357;5817.621854579439;5830.82507524775;5881.676507792113;5928.4960778334325;5890.793822216202;5916.763329466945;5924.6149000314845;5934.554908381802;5942.346341902474;5597.496225339739;5728.997425450446;5769.663286369027;5773.393793651606;5800.216970709069 04:03:39 INFO - PROCESS | 2304 | |5;yui.html;663.161101206973;676.9424598909093;784.0121446976812;884.3897710579699;919.5676837642563;16622.75065874013;16939.576510587212;17878;17880.64238715225;17944.10279486027;16996.745048824276;17076.14619269038;17130.743038854427;17174.22715977781;17180.484585462425;895.2077411490828;918.4213945214506;921.4977837078775;921.7511271956567;922.0134456131908;746.8655641984441;874.9606267717936;903.2773780975226;904.9683261085863;910.622091831889;967.3760424526212;969.1181025267008;970.5535453691275;971.0823272007955;971.1890571372912;962.0523784072683;962.2302158273353;962.9085236902483;965.0976337124811;966.0436168193509;568.3662716071592;581.2037508613226;583.0525396231125;675.3482889012078;692.6848284030754;15307;15457.227138643022;15464.072155670583;15503.844961550352;15561.299741511546;14931.25343732809;15284.541463755993;15576.61058473536;15804.525864224091;15884.126373049477;13116.27860467674;13272.336383180802;13279.86720132796;13489.190648561118;13497.2576508292;4711.976440117812;4721.787519561628;4745.2882067690125;4834.299026641117;4838.371011768448;75765;82938.9999999994;83204.58397707973;84164.57917710433;85116;11355.60255391074;11764.294142351486;12034.217775844718;12089.214201077079;12121;22802.201922932618;22874.8856255721;22993.195238166583;23003.19488817917;23809;8917.687880924137;8984.416012959136;8996.325275604226;9031.187193152648;9103.544822758835;4651.790669419885;4656.976715116402;4668.929966050512;4703.553162449562;4795.328653988445;3686.594474607791;3828.0429892526868;3857.479240302523;3860.8455661773214;3865.40086286626;633.3286716080969;634.0805831544279;659.7525927777084;667.7195577857311;674.8211723893172;711.605059192143;730.5032577847008;733.296035805622;733.7065173930471;751.8796992481177;9592.568334424968;9670.516474176404;9886.505674716236;9927.652532161484;10101.292909496411;697.8325201951596;708.1360739897317;720.6756959368305;730.9524880882726;740.9110906691232;4672.252439609646;4698.506656800987;4707.398839887489;4822.132016237041;4823.324734537167;116876.41561792136;119466.4026679861;119737.00657483458;122219;124127;103349.48325258326;108255.45872270588;109317;109516.4524177374;111691;93395;93901.5304923471;94730;97707;97732.51133744286;9576.377535460173;9599.42403455795;9983.750406239902;10207.89792102099;10297.74255643615;53400.73299633477;53778.73110634422;54351.45648543464;54922;55176.44823551713;78651.60674196592;79435.2056479451;80471;80504;82301;12267.75464490723;12542.561010364594;12594.528525007234;12717.300548469922;12974.156679815782;13947.58157255284;13973.301334933416;14241.35913883857;14328;14329.068610540699;12848.678783030498;13443.46226150943;13750.793738094137;13849.584512464642;14251.857481425468;10651.520681569504;14238.572842814732;14349.497767578503;14837.777433338293;15116.924415377853;14898.627534311729;15081.019733717274;15611.453599123975;15925.68148637044;16291;12134.75730485403;12347.876521235032;12508.437120329789;12510.18683785551;13151.408186631434;12397.690057748268;12862.935685321514;12975.675608109872;13025.544105956626;13185.670358241121 04:03:39 INFO - PROCESS | 2304 | __end_tp_report 04:03:39 INFO - PROCESS | 2304 | __start_cc_report 04:03:39 INFO - PROCESS | 2304 | _x_x_mozilla_cycle_collect,1132 04:03:39 INFO - PROCESS | 2304 | __end_cc_report 04:03:39 INFO - PROCESS | 2304 | __startTimestamp1471691019412__endTimestamp 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | ------- Summary: start ------- 04:03:39 INFO - PROCESS | 2304 | Number of tests: 6 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | [#0] dojo.html Cycles:165 Average:5612.34 Median:5846.06 stddev:631.12 (10.8%) stddev-sans-first:604.43 04:03:39 INFO - PROCESS | 2304 | Values: 3216.9 3267.7 3278.8 3314.7 3318.3 5508.4 5617.6 5630.9 5651.5 5676.4 5569.8 5613.3 5639.5 5640.3 5673.8 4996.8 5052.0 5063.0 5092.2 5150.6 5120.6 5200.2 5211.2 5222.2 5223.4 5084.7 5105.5 5123.7 5129.3 5209.7 5205.9 5228.2 5236.6 5239.4 5268.8 4943.1 5007.5 5028.8 5120.1 5126.5 5538.0 5554.2 5573.7 5586.7 5600.1 6067.9 6078.0 6086.8 6088.1 6102.0 5827.7 5975.8 6013.1 6027.4 6133.3 5635.0 5653.9 5666.7 5675.9 5683.3 6170.4 6173.6 6196.4 6201.5 6203.3 5773.0 5804.6 5807.6 5812.7 5894.9 5949.9 5977.0 5984.6 5986.0 5989.5 5921.8 5928.5 5929.9 5933.1 5933.2 5803.0 5907.8 5952.3 6012.2 6020.2 4624.7 4648.9 4656.6 4676.5 4681.5 4155.4 4156.4 4158.7 4166.9 4186.0 5557.0 5738.5 5763.3 5775.0 5776.8 6142.6 6159.4 6166.0 6166.6 6168.4 5811.0 5819.6 5823.3 5830.0 5846.0 5104.3 5146.0 5172.8 5257.0 5282.8 6219.0 6223.3 6225.0 6229.7 6230.2 6011.9 6024.1 6032.3 6059.7 6090.2 6105.9 6222.7 6236.9 6246.1 6293.3 5990.5 6005.3 6009.5 6020.3 6036.5 6089.2 6099.8 6103.1 6104.9 6108.3 5687.0 5846.1 5877.2 5900.6 5977.6 5895.4 5941.3 5947.5 5951.9 5988.3 5876.9 5888.9 5890.1 5890.2 5893.4 5905.5 5942.2 5948.9 5993.7 6036.8 5847.0 5847.4 5849.7 5854.7 5879.9 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | [#1] ext.html Cycles:180 Average:69971.38 Median:40650.69 stddev:144309.44 (355.0%) stddev-sans-first:144620.27 04:03:39 INFO - PROCESS | 2304 | Values: 600.1 637.6 658.2 658.5 659.4 35027.5 38222.4 38340.4 38424.6 38559.6 38970.2 47701.3 49152.0 49183.0 53970.5 9243.1 9367.2 9373.3 9383.9 9387.7 9144.9 9216.9 9239.4 9242.9 9245.5 8596.3 8778.3 11067.1 11457.7 11457.9 11343.3 11362.0 11411.1 11431.9 11439.5 8366.4 11177.1 11228.2 11256.3 11261.4 38135.8 38263.0 47171.3 48573.5 48708.8 3839.8 3970.7 4137.9 4424.2 4547.3 35668.5 35860.8 36164.8 36658.5 36939.3 4375.9 4378.5 4382.6 4538.0 4601.7 370444.0 374514.0 374727.0 376247.0 380461.0 58782.4 66729.3 67327.7 67376.7 67392.7 67067.0 68545.0 68717.3 75767.6 75770.0 19253.6 19406.5 19438.0 19447.3 19473.0 14651.7 14790.2 14817.4 14818.2 14823.1 17083.9 18524.8 19537.0 19589.5 19593.5 6034.2 6147.5 6183.3 6261.9 6359.7 1062.5 1079.7 1108.7 1171.6 1223.5 34728.7 34892.7 35028.6 35135.5 35512.3 1174.1 1187.5 1194.2 1217.9 1244.3 17576.7 18522.0 18564.9 18631.6 18725.7 588767.0 595442.0 596417.0 599727.0 599753.0 57285.4 65413.3 65531.3 71213.3 72589.3 1456.8 1463.2 1490.5 1499.6 1500.3 35240.3 36575.0 36755.3 36838.9 36851.8 58972.0 59388.0 59461.1 59461.4 59520.4 517670.0 526547.0 528375.0 737476.0 742579.0 31311.7 31370.8 31487.5 31513.4 31795.4 53780.1 54090.0 54229.5 54252.0 54308.7 45076.3 47137.0 47269.3 47617.8 48233.3 44536.6 45482.5 45752.0 45859.8 46981.0 45366.8 46338.5 46487.0 46634.5 46673.3 38565.0 40454.8 40476.8 40495.8 40805.6 30435.4 30523.7 30746.7 30883.4 30944.8 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | [#2] jquery.html Cycles:180 Average:32592.12 Median:21668.96 stddev:94691.17 (437.0%) stddev-sans-first:94925.95 04:03:39 INFO - PROCESS | 2304 | Values: 402.9 405.1 421.8 432.9 437.6 15869.0 19841.9 21956.8 22060.2 22416.6 20949.0 21095.2 21143.8 21612.8 21725.1 5478.7 5949.9 6104.9 6190.2 6192.5 6020.6 6038.0 6053.0 6056.8 6063.0 5753.5 6023.6 6027.2 6029.2 6056.8 6005.5 6063.0 6083.5 6095.7 6103.5 5808.4 5950.3 5971.9 5996.7 5999.0 13039.3 13178.5 13927.3 14952.0 15040.5 2275.7 2303.4 2305.7 2450.0 2548.2 10883.1 10940.4 12057.7 15363.0 16185.3 1941.6 1987.3 2021.2 2054.6 2094.1 88495.0 88771.6 89518.1 89877.6 90999.5 10605.9 11282.5 11400.3 11590.1 12137.6 15409.5 16275.9 16298.3 16982.8 17008.5 8912.6 9110.8 9275.6 9342.5 9368.4 9062.8 9089.6 9111.4 9253.4 9283.6 4896.2 5103.5 5110.7 5149.2 5205.7 2043.7 2097.4 2130.0 2160.8 2177.7 971.0 1003.6 1005.4 1030.0 1105.4 20833.4 20915.3 21082.1 21119.7 21189.5 1065.2 1075.9 1081.1 1084.8 1091.3 11288.7 11656.8 11680.8 11777.6 11910.8 564015.5 567914.0 573220.0 581710.0 588419.0 95723.5 96735.5 98924.5 102085.0 102386.0 1063.0 1077.9 1091.7 1098.7 1100.5 18499.7 18832.2 19002.6 19086.6 19389.7 46378.5 46719.5 46757.0 47055.0 47870.3 59895.7 63730.0 67862.7 69395.0 69442.7 8760.2 9000.0 9268.3 9325.5 9515.4 13219.6 13258.8 13970.5 14278.2 14646.9 12859.6 12982.7 13066.3 13068.0 13234.8 12566.5 12615.2 12720.2 12805.9 13091.6 12964.2 13059.3 13111.7 13113.9 13362.5 9186.9 9469.1 9469.5 9506.6 9744.6 13143.2 13168.5 13196.4 13201.2 13205.4 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | [#3] mootools.html Cycles:180 Average:5982.74 Median:4656.71 stddev:3349.84 (71.9%) stddev-sans-first:3341.67 04:03:39 INFO - PROCESS | 2304 | Values: 1418.6 1545.9 1576.7 1777.4 1897.1 7761.8 8543.0 8801.0 8807.9 9074.5 8870.0 8936.6 8938.7 8946.7 8954.3 2459.1 2465.0 2481.3 2483.3 2485.3 2392.8 2480.4 2487.2 2491.6 2491.8 2389.3 2434.5 2448.8 2452.4 2459.2 2375.0 2396.0 2398.1 2421.6 2433.5 2366.1 2377.9 2381.5 2382.0 2386.6 8826.9 8933.6 9101.1 9223.2 9232.0 5383.5 5610.9 5617.4 5619.0 5621.5 3463.2 3473.4 3488.7 3499.3 3560.7 1240.9 1251.5 1255.0 1280.3 1281.6 12484.3 12536.9 12545.4 12573.4 12573.6 4464.6 4684.0 4797.7 4801.4 4806.7 10038.7 10134.3 10147.8 10151.2 10151.7 5505.2 5527.7 5533.1 5536.1 5537.0 4538.6 4853.5 4972.3 5006.9 5184.1 3920.2 4008.5 4071.2 4080.0 4110.1 1174.7 1185.9 1194.8 1195.4 1216.1 982.8 1075.0 1090.8 1098.5 1105.6 6130.5 6306.6 6328.7 6539.6 6637.9 483.2 492.2 492.6 493.2 502.1 4219.1 4360.6 4409.9 4462.2 4629.4 10572.4 11036.4 11055.8 11095.6 11103.6 10238.5 10719.6 11036.2 11036.6 11042.6 8377.8 8604.0 8609.5 8616.5 8622.2 6269.9 6282.4 6455.6 6577.3 6761.6 10338.1 10473.5 10484.4 10614.3 10629.1 10537.6 10710.2 10816.7 10816.9 10824.5 6874.3 7044.2 7047.5 8273.8 8411.4 7653.0 8118.7 8186.7 8858.3 8879.2 8067.7 8101.3 8141.7 8164.6 8224.6 7843.2 7928.7 8044.1 8079.0 8233.4 7592.4 7716.9 7719.5 7724.8 7731.5 5482.3 5550.7 5586.4 5694.8 5744.4 5727.0 5743.2 5824.8 5864.8 5936.5 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | [#4] prototype.html Cycles:180 Average:4940.47 Median:5650.86 stddev:1446.31 (25.6%) stddev-sans-first:1408.70 04:03:39 INFO - PROCESS | 2304 | Values: 348.2 356.4 357.9 360.5 365.9 5544.0 5622.9 5631.1 5665.3 5697.7 5530.4 5536.4 5570.9 5605.4 5609.7 4306.3 4328.5 4370.7 4389.3 4405.9 4341.4 4349.8 4350.5 4372.5 4391.7 4322.8 4332.2 4341.0 4343.5 4349.0 4126.3 4289.7 4330.2 4342.3 4375.0 3815.5 3837.9 3854.1 3854.4 3860.3 5365.1 5429.2 5431.3 5456.5 5482.9 4892.9 4898.2 4955.2 4978.3 4996.7 5753.1 5770.9 5789.5 5797.2 5803.9 4215.1 4228.2 4232.2 4240.8 4257.7 5683.3 5829.5 5886.6 6081.8 6098.7 4279.9 4297.6 4326.9 4338.7 4361.4 4381.2 4399.2 4402.1 4408.1 4411.9 4691.3 4738.1 4938.2 4997.3 5005.6 5726.1 5762.0 5772.9 5805.9 5807.5 1504.8 1520.7 1524.6 1531.5 1535.6 978.5 990.2 992.9 1040.3 1060.2 5600.2 5619.0 5642.2 5655.9 5665.9 5968.5 5983.5 5985.2 5987.3 5989.8 5428.3 5682.5 5713.5 5717.1 5726.2 3150.9 3179.3 3189.1 3216.4 3226.8 5925.2 6182.0 6225.9 6226.3 6235.6 6083.2 6084.3 6091.9 6095.2 6117.5 6184.4 6224.8 6225.5 6230.8 6237.9 5710.1 6012.5 6021.0 6046.3 6059.8 5964.5 5975.3 5988.5 5997.2 6023.8 6177.7 6213.1 6224.7 6236.2 6241.0 4340.5 4349.7 4365.7 4366.3 4383.6 6076.1 6106.7 6122.0 6137.7 6148.8 5687.9 5901.8 5929.1 5929.6 5929.8 5907.1 5915.3 5919.2 5939.7 5984.6 5645.8 5817.6 5830.8 5881.7 5928.5 5890.8 5916.8 5924.6 5934.6 5942.3 5597.5 5729.0 5769.7 5773.4 5800.2 04:03:39 INFO - PROCESS | 2304 | 04:03:39 INFO - PROCESS | 2304 | [#5] yui.html Cycles:180 Average:22342.43 Median:4670.59 stddev:32239.19 (690.3%) stddev-sans-first:32288.54 04:03:39 INFO - PROCESS | 2304 | Values: 663.2 676.9 784.0 884.4 919.6 16622.8 16939.6 17878.0 17880.6 17944.1 16996.7 17076.1 17130.7 17174.2 17180.5 895.2 918.4 921.5 921.8 922.0 746.9 875.0 903.3 905.0 910.6 967.4 969.1 970.6 971.1 971.2 962.1 962.2 962.9 965.1 966.0 568.4 581.2 583.1 675.3 692.7 15307.0 15457.2 15464.1 15503.8 15561.3 14931.3 15284.5 15576.6 15804.5 15884.1 13116.3 13272.3 13279.9 13489.2 13497.3 4712.0 4721.8 4745.3 4834.3 4838.4 75765.0 82939.0 83204.6 84164.6 85116.0 11355.6 11764.3 12034.2 12089.2 12121.0 22802.2 22874.9 22993.2 23003.2 23809.0 8917.7 8984.4 8996.3 9031.2 9103.5 4651.8 4657.0 4668.9 4703.6 4795.3 3686.6 3828.0 3857.5 3860.8 3865.4 633.3 634.1 659.8 667.7 674.8 711.6 730.5 733.3 733.7 751.9 9592.6 9670.5 9886.5 9927.7 10101.3 697.8 708.1 720.7 731.0 740.9 4672.3 4698.5 4707.4 4822.1 4823.3 116876.4 119466.4 119737.0 122219.0 124127.0 103349.5 108255.5 109317.0 109516.5 111691.0 93395.0 93901.5 94730.0 97707.0 97732.5 9576.4 9599.4 9983.8 10207.9 10297.7 53400.7 53778.7 54351.5 54922.0 55176.4 78651.6 79435.2 80471.0 80504.0 82301.0 12267.8 12542.6 12594.5 12717.3 12974.2 13947.6 13973.3 14241.4 14328.0 14329.1 12848.7 13443.5 13750.8 13849.6 14251.9 10651.5 14238.6 14349.5 14837.8 15116.9 14898.6 15081.0 15611.5 15925.7 16291.0 12134.8 12347.9 12508.4 12510.2 13151.4 12397.7 12862.9 12975.7 13025.5 13185.7 04:03:39 INFO - PROCESS | 2304 | -------- Summary: end -------- 04:03:39 INFO - PROCESS | 2304 | 04:03:40 INFO - TEST-INFO | 2304: exit 0 04:03:40 INFO - TEST-OK | dromaeo_css | took 1114013ms 04:03:40 INFO - TEST-START | kraken 04:03:40 INFO - operating with platform_type : linux_ 04:03:40 INFO - Initialising browser for kraken test... 04:03:40 INFO - TEST-INFO | started process 2595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo4pzQv/profile http://localhost:37406/getInfo.html) 04:03:44 INFO - PROCESS | 2595 | __metrics Screen width/height:1600/1200 04:03:44 INFO - PROCESS | 2595 | colorDepth:24 04:03:44 INFO - PROCESS | 2595 | Browser inner width/height: 1024/697 04:03:44 INFO - PROCESS | 2595 | __metrics 04:03:45 INFO - PROCESS | 2595 | 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:03:45 INFO - PROCESS | 2595 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:03:46 INFO - TEST-INFO | 2595: exit 0 04:03:46 INFO - Browser initialized. 04:03:46 INFO - Running cycle 1/1 for kraken test... 04:03:46 INFO - TEST-INFO | started process 2704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo4pzQv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:03:57 INFO - PROCESS | 2704 | RSS: Main: 145326080 04:03:57 INFO - PROCESS | 2704 | 04:04:00 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37406/tests/kraken/driver.html?testName=audio-beat-detection) 04:04:01 INFO - PROCESS | 2704 | RSS: Main: 192360448 04:04:01 INFO - PROCESS | 2704 | 04:04:03 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37406/tests/kraken/driver.html?testName=audio-dft) 04:04:04 INFO - PROCESS | 2704 | RSS: Main: 181927936 04:04:04 INFO - PROCESS | 2704 | 04:04:07 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37406/tests/kraken/driver.html?testName=audio-fft) 04:04:07 INFO - PROCESS | 2704 | RSS: Main: 269475840 04:04:07 INFO - PROCESS | 2704 | 04:04:09 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37406/tests/kraken/driver.html?testName=audio-oscillator) 04:04:10 INFO - PROCESS | 2704 | RSS: Main: 175706112 04:04:10 INFO - PROCESS | 2704 | 04:04:12 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37406/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:04:13 INFO - PROCESS | 2704 | RSS: Main: 177557504 04:04:13 INFO - PROCESS | 2704 | 04:04:16 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37406/tests/kraken/driver.html?testName=imaging-darkroom) 04:04:16 INFO - PROCESS | 2704 | RSS: Main: 212869120 04:04:16 INFO - PROCESS | 2704 | 04:04:20 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37406/tests/kraken/driver.html?testName=imaging-desaturate) 04:04:20 INFO - PROCESS | 2704 | RSS: Main: 211976192 04:04:20 INFO - PROCESS | 2704 | 04:04:23 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37406/tests/kraken/driver.html?testName=json-parse-financial) 04:04:24 INFO - PROCESS | 2704 | RSS: Main: 212008960 04:04:24 INFO - PROCESS | 2704 | 04:04:25 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37406/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:04:26 INFO - PROCESS | 2704 | RSS: Main: 176484352 04:04:26 INFO - PROCESS | 2704 | 04:04:27 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:04:28 INFO - PROCESS | 2704 | RSS: Main: 181956608 04:04:28 INFO - PROCESS | 2704 | 04:04:30 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:04:31 INFO - PROCESS | 2704 | RSS: Main: 185569280 04:04:31 INFO - PROCESS | 2704 | 04:04:33 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:04:34 INFO - PROCESS | 2704 | RSS: Main: 180764672 04:04:34 INFO - PROCESS | 2704 | 04:04:36 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:04:37 INFO - PROCESS | 2704 | RSS: Main: 183930880 04:04:37 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | Cycle 1(1): loaded http://localhost:37406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37406/tests/kraken/driver.html?testName=ai-astar) 04:04:39 INFO - PROCESS | 2704 | RSS: Main: 235278336 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | __start_tp_report 04:04:39 INFO - PROCESS | 2704 | _x_x_mozilla_page_load 04:04:39 INFO - PROCESS | 2704 | _x_x_mozilla_page_load_details 04:04:39 INFO - PROCESS | 2704 | |i|pagename|runs| 04:04:39 INFO - PROCESS | 2704 | |0;ai-astar;102;111;110;112;111;110;109;110;112;112 04:04:39 INFO - PROCESS | 2704 | |1;audio-beat-detection;149;115;122;184;118;117;142;116;147;115 04:04:39 INFO - PROCESS | 2704 | |2;audio-dft;162;160;162;162;162;161;160;162;160;160 04:04:39 INFO - PROCESS | 2704 | |3;audio-fft;75;75;74;113;75;77;75;76;102;75 04:04:39 INFO - PROCESS | 2704 | |4;audio-oscillator;83;83;128;83;83;83;118;83;84;130 04:04:39 INFO - PROCESS | 2704 | |5;imaging-gaussian-blur;118;117;116;117;118;117;117;116;117;117 04:04:39 INFO - PROCESS | 2704 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;146;146 04:04:39 INFO - PROCESS | 2704 | |7;imaging-desaturate;94;95;96;95;95;94;95;95;95;96 04:04:39 INFO - PROCESS | 2704 | |8;json-parse-financial;53;52;52;53;55;53;52;51;51;52 04:04:39 INFO - PROCESS | 2704 | |9;json-stringify-tinderbox;57;60;59;57;59;58;57;58;59;59 04:04:39 INFO - PROCESS | 2704 | |10;stanford-crypto-aes;73;72;80;73;73;72;78;73;75;76 04:04:39 INFO - PROCESS | 2704 | |11;stanford-crypto-ccm;136;127;116;125;124;114;116;114;158;122 04:04:39 INFO - PROCESS | 2704 | |12;stanford-crypto-pbkdf2;157;151;155;153;154;157;158;159;156;152 04:04:39 INFO - PROCESS | 2704 | |13;stanford-crypto-sha256-iterative;64;67;64;61;63;64;63;61;61;61 04:04:39 INFO - PROCESS | 2704 | __end_tp_report 04:04:39 INFO - PROCESS | 2704 | __start_cc_report 04:04:39 INFO - PROCESS | 2704 | _x_x_mozilla_cycle_collect,1654 04:04:39 INFO - PROCESS | 2704 | __end_cc_report 04:04:39 INFO - PROCESS | 2704 | __startTimestamp1471691079296__endTimestamp 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | ------- Summary: start ------- 04:04:39 INFO - PROCESS | 2704 | Number of tests: 14 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.96 (2.7%) stddev-sans-first:1.09 04:04:39 INFO - PROCESS | 2704 | Values: 102.0 111.0 110.0 112.0 111.0 110.0 109.0 110.0 112.0 112.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:132.00 stddev:22.78 (17.3%) stddev-sans-first:23.37 04:04:39 INFO - PROCESS | 2704 | Values: 149.0 115.0 122.0 184.0 118.0 117.0 142.0 116.0 147.0 115.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#2] audio-dft Cycles:10 Average:161.10 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 04:04:39 INFO - PROCESS | 2704 | Values: 162.0 160.0 162.0 162.0 162.0 161.0 160.0 162.0 160.0 160.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:13.86 (18.5%) stddev-sans-first:14.49 04:04:39 INFO - PROCESS | 2704 | Values: 75.0 75.0 74.0 113.0 75.0 77.0 75.0 76.0 102.0 75.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:83.00 stddev:20.61 (24.8%) stddev-sans-first:21.33 04:04:39 INFO - PROCESS | 2704 | Values: 83.0 83.0 128.0 83.0 83.0 83.0 118.0 83.0 84.0 130.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 04:04:39 INFO - PROCESS | 2704 | Values: 118.0 117.0 116.0 117.0 118.0 117.0 117.0 116.0 117.0 117.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 04:04:39 INFO - PROCESS | 2704 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 04:04:39 INFO - PROCESS | 2704 | Values: 94.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:1.17 (2.2%) stddev-sans-first:1.22 04:04:39 INFO - PROCESS | 2704 | Values: 53.0 52.0 52.0 53.0 55.0 53.0 52.0 51.0 51.0 52.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:59.00 stddev:1.06 (1.8%) stddev-sans-first:1.01 04:04:39 INFO - PROCESS | 2704 | Values: 57.0 60.0 59.0 57.0 59.0 58.0 57.0 58.0 59.0 59.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.00 stddev:2.72 (3.7%) stddev-sans-first:2.83 04:04:39 INFO - PROCESS | 2704 | Values: 73.0 72.0 80.0 73.0 73.0 72.0 78.0 73.0 75.0 76.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:124.50 stddev:13.45 (10.8%) stddev-sans-first:13.68 04:04:39 INFO - PROCESS | 2704 | Values: 136.0 127.0 116.0 125.0 124.0 114.0 116.0 114.0 158.0 122.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:156.50 stddev:2.66 (1.7%) stddev-sans-first:2.74 04:04:39 INFO - PROCESS | 2704 | Values: 157.0 151.0 155.0 153.0 154.0 157.0 158.0 159.0 156.0 152.0 04:04:39 INFO - PROCESS | 2704 | 04:04:39 INFO - PROCESS | 2704 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.50 stddev:1.97 (3.1%) stddev-sans-first:2.05 04:04:39 INFO - PROCESS | 2704 | Values: 64.0 67.0 64.0 61.0 63.0 64.0 63.0 61.0 61.0 61.0 04:04:39 INFO - PROCESS | 2704 | -------- Summary: end -------- 04:04:39 INFO - PROCESS | 2704 | 04:04:40 INFO - TEST-INFO | 2704: exit 0 04:04:40 INFO - TEST-OK | kraken | took 60140ms 04:04:40 INFO - SUITE-END | took 1174s 04:04:40 INFO - Completed test suite (00:19:34) 04:04:40 INFO - javascript benchmark 04:04:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3216.919577010575, 3267.673232676732, 3278.7704860659765, 3314.651961544036, 3318.336332733454, 5508.366537848148, 5617.634853734509, 5630.859228519289, 5651.519620832234, 5676.432356764321, 5569.777208911639, 5613.270274864262, 5639.464250896169, 5640.266765320503, 5673.801416950408, 4996.825111121111, 5052.0, 5063.03802277568, 5092.210707340369, 5150.562202212816, 5120.615953803461, 5200.167973124301, 5211.1922651989, 5222.216667499886, 5223.399309079467, 5084.694918304895, 5105.540501354877, 5123.666961647499, 5129.307543481638, 5209.7134657593815, 5205.9219111713355, 5228.241904923765, 5236.554892834114, 5239.3712754469325, 5268.841934741964, 4943.085529177114, 5007.474215207423, 5028.7988480460735, 5120.129577971754, 5126.512981266774, 5538.0, 5554.222408862763, 5573.721313934287, 5586.664800112007, 5600.131979543136, 6067.878642427127, 6078.0, 6086.817395478142, 6088.056351265561, 6102.0, 5827.650340979555, 5975.820725378246, 6013.098035294697, 6027.427394397525, 6133.26400831903, 5634.971825140889, 5653.943460565383, 5666.716664166775, 5675.914861277084, 5683.261176047129, 6170.382961703794, 6173.629582225081, 6196.442320191204, 6201.472874805602, 6203.2556093269095, 5773.04744717117, 5804.622699524517, 5807.622504537276, 5812.651240925558, 5894.941050589525, 5949.929012777741, 5976.970115149396, 5984.611000284967, 5986.042233242661, 5989.460948514655, 5921.763129474859, 5928.466438020599, 5929.940700592939, 5933.110033494924, 5933.228680271537, 5802.970985145047, 5907.793227237112, 5952.315483719428, 6012.248468941382, 6020.247469066367, 4624.745638989821, 4648.930266046012, 4656.580907718321, 4676.532346765296, 4681.50844161365, 4155.4182414462, 4156.4388807510595, 4158.688098392633, 4166.874993750132, 4185.958140418617, 5557.0, 5738.512226460798, 5763.308402991668, 5775.047117225611, 5776.826695199151, 6142.590862071504, 6159.353267906805, 6166.0, 6166.599171053867, 6168.383161683796, 5810.970945145247, 5819.563532734977, 5823.330317013598, 5829.970850145807, 5846.035404158266, 5104.3364362633365, 5146.0, 5172.818951336761, 5256.973715131477, 5282.788688452496, 6218.968905155536, 6223.3465486123305, 6225.0, 6229.688515574203, 6230.158928544679, 6011.939880601313, 6024.0662697282, 6032.306284777307, 6059.668728796577, 6090.23872015998, 6105.9084113737445, 6222.719977601109, 6236.906446403217, 6246.1255424239735, 6293.339199383999, 5990.46085852258, 6005.309389420273, 6009.45914867664, 6020.27756669185, 6036.517078633611, 6089.208402907594, 6099.84750381244, 6103.11504831788, 6104.8779024420155, 6108.328083910677, 5687.0, 5846.064629659234, 5877.23595932526, 5900.586958912835, 5977.581569290108, 5895.351511333835, 5941.316748573797, 5947.494462970523, 5951.940480595312, 5988.311344195473, 5876.941230587639, 5888.941110588839, 5890.1164825276555, 5890.175375447355, 5893.410658934073, 5905.52755779528, 5942.168096466412, 5948.940510594839, 5993.700314984146, 6036.7585296589405, 5847.0, 5847.38602446754, 5849.707514624167, 5854.707264636666, 5879.882402351844], "value": 5570.12220256736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.135804441604, 637.6014990630856, 658.1772784019973, 658.5258613798067, 659.423004870738, 35027.4745878812, 38222.426663599996, 38340.424893626616, 38424.61575384245, 38559.61440385595, 38970.22059558807, 47701.284480732815, 49152.0, 49183.0, 53970.46029539703, 9243.07569243077, 9367.203787678056, 9373.297002724823, 9383.906160938406, 9387.671431499899, 9144.908550914506, 9216.861747073799, 9239.445633262027, 9242.86135707965, 9245.537723113852, 8596.312295016383, 8778.29773618109, 11067.114630829514, 11457.713557161096, 11457.94271028648, 11343.262687925258, 11362.0, 11411.144164187719, 11431.942840285745, 11439.485223164978, 8366.414350995432, 11177.105831533458, 11228.214025018253, 11256.268342557789, 11261.436928153642, 38135.80932095349, 38263.0, 47171.29243061357, 48573.51426485725, 48708.756456217845, 3839.8464061437508, 3970.722049456539, 4137.8758637240935, 4424.181526417591, 4547.295169248771, 35668.464973025424, 35860.82069589661, 36164.83011019347, 36658.45012324817, 36939.26121477583, 4375.890602734925, 4378.474583050022, 4382.627476664487, 4537.977310113428, 4601.723896566216, 370444.0, 374514.0, 374727.0, 376247.0, 380461.0, 58782.41217587855, 66729.33270667329, 67327.66336168288, 67376.6631166841, 67392.6630366845, 67067.0, 68545.0, 68717.31282687209, 75767.62116189495, 75770.0, 19253.61492770137, 19406.514837129183, 19438.0, 19447.3193438229, 19473.0, 14651.706965860623, 14790.186539740202, 14817.407303707947, 14818.184999825113, 14823.110613363233, 17083.889547179395, 18524.814751852307, 19537.0, 19589.51026224327, 19593.510162245773, 6034.155218269446, 6147.508199344042, 6183.258009038854, 6261.9373806262265, 6359.713812878339, 1062.4634559547378, 1079.7138758229075, 1108.6563165418745, 1171.566520387462, 1223.4555622747969, 34728.65271347305, 34892.65107348945, 35028.64971350254, 35135.4729679055, 35512.28975420477, 1174.1194104421684, 1187.5012494752225, 1194.176018547217, 1217.8660347361783, 1244.3467179730715, 17576.736348954775, 18522.0, 18564.936785430524, 18631.627367452846, 18725.719114213298, 588767.0, 595442.0, 596417.0, 599727.0, 599753.0, 57285.42714572884, 65413.34586654168, 65531.34468655348, 71213.2878671217, 72589.27410725826, 1456.788765628999, 1463.1879306984727, 1490.4932323010228, 1499.6400863792612, 1500.2573726005696, 35240.29519409546, 36575.0, 36755.26489470249, 36838.87728186146, 36851.81574092113, 58972.0, 59388.0, 59461.10808337792, 59461.40538594559, 59520.40479595149, 517670.0, 526547.0, 528375.0, 737476.0, 742579.0, 31311.68688313088, 31370.84314578504, 31487.52768708517, 31513.36973260568, 31795.36409271848, 53780.06787476385, 54090.0, 54229.45770542244, 54252.0, 54308.728456357465, 45076.32385514286, 47137.0, 47269.29096063493, 47617.761911190224, 48233.27650085181, 44536.55463445454, 45482.54517454915, 45752.0, 45859.847140507125, 46981.0, 45366.77316613528, 46338.53661463477, 46487.0, 46634.533654664374, 46673.29990050084, 38565.0, 40454.79772601118, 40476.79761601173, 40495.79752101221, 40805.59194408018, 30435.391292174474, 30523.694763052972, 30746.69253307528, 30883.382332353678, 30944.845275773478], "value": 22236.27057971015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.8831638824741, 405.07036351573134, 421.76499063010726, 432.8679752675434, 437.6498800959232, 15869.0, 19841.900790496045, 21956.780432195672, 22060.227892023784, 22416.551668966633, 20949.0, 21095.15619375223, 21143.788562114376, 21612.783872161275, 21725.13099476023, 5478.698671573062, 5949.940500594993, 6104.877902441948, 6190.226221722285, 6192.473639740628, 6020.608660437079, 6038.0, 6053.031514957607, 6056.848578785522, 6063.029915213567, 5753.5397168226655, 6023.578349515536, 6027.186329845458, 6029.155918171459, 6056.757729690829, 6005.54958378119, 6063.029915213545, 6083.452489275942, 6095.725692343855, 6103.511719062464, 5808.448197421239, 5950.256217972754, 5971.940280597182, 5996.700164991733, 5999.040153575451, 13039.282839443827, 13178.472861085545, 13927.30363481832, 14952.0, 15040.548783536511, 2275.7269127704617, 2303.366574192094, 2305.7002589663402, 2449.9755002449924, 2548.2482667612912, 10883.074938630227, 10940.3982780947, 12057.698557536043, 15363.0, 16185.271662775203, 1941.6019715958337, 1987.3143765400914, 2021.1713197588917, 2054.578811343686, 2094.1309356616985, 88495.0, 88771.55614221952, 89518.10481895163, 89877.55061224652, 90999.54500227456, 10605.946970265099, 11282.548698052151, 11400.258983166068, 11590.13074019452, 12137.575184868663, 15409.537713868602, 16275.918620406823, 16298.348066077224, 16982.830171698126, 17008.48974530741, 8912.643494260286, 9110.772230694285, 9275.62897484093, 9342.53287335644, 9368.39105458143, 9062.773430664287, 9089.590968406437, 9111.407758495809, 9253.444793312423, 9283.58223879927, 4896.1921282987905, 5103.489651034941, 5110.66780659256, 5149.150390185652, 5205.73971301441, 2043.6934459831143, 2097.381272524609, 2129.975434882471, 2160.762316145223, 2177.7495588007264, 970.9805803883883, 1003.6236411345745, 1005.3565717940523, 1030.0420608833706, 1105.4472763618191, 20833.374998750063, 20915.267965621435, 21082.051307691192, 21119.683204751942, 21189.470263243544, 1065.2064212161988, 1075.8547596074586, 1081.0540776820283, 1084.788466249074, 1091.2743025887696, 11288.661340159808, 11656.766864662659, 11680.766384672259, 11777.646670599896, 11910.761784764429, 564015.474003376, 567914.0, 573220.0, 581710.0, 588419.0, 95723.52138239403, 96735.51632241794, 98924.50537747265, 102085.0, 102386.0, 1063.0, 1077.876044254905, 1091.6561283195767, 1098.6758906122714, 1100.5432745410726, 18499.722504162717, 18832.24671013144, 19002.619947601248, 19086.61826763429, 19389.709154362412, 46378.53621463877, 46719.532804671515, 46757.0, 47055.0, 47870.28194577015, 59895.700521497114, 63730.0, 67862.66068669625, 69395.0, 69442.65278673574, 8760.211580957744, 8999.955000225218, 9268.304877134377, 9325.487098209393, 9515.429074255568, 13219.603411897659, 13258.801117983045, 13970.511032113827, 14278.2146981917, 14646.853531464549, 12859.614211573667, 12982.740345193231, 13066.281354525592, 13068.0, 13234.801477978031, 12566.497340106293, 12615.243085414537, 12720.173188742701, 12805.935970320088, 13091.607251782461, 12964.157329773534, 13059.281739504419, 13111.737765244832, 13113.934430327787, 13362.532311369056, 9186.862197067183, 9469.14777670013, 9469.52652367365, 9506.61973521079, 9744.561494732896, 13143.211407315592, 13168.47326106945, 13196.406161722554, 13201.20792752438, 13205.405756740778], "value": 9885.53893766224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.5744276716982, 1545.8531439513247, 1576.6767812598418, 1777.448990812848, 1897.1178402043038, 7761.767146985592, 8542.957285213573, 8800.955995220014, 8807.91192088079, 9074.455532668044, 8870.0, 8936.59785309661, 8938.687145949893, 8946.686865959693, 8954.32842536809, 2459.127009911478, 2465.038634932369, 2481.255623313008, 2483.292261705414, 2485.3413845331, 2392.820538459614, 2480.355107672009, 2487.1916627096175, 2491.6013437849947, 2491.788198003172, 2389.3429306940643, 2434.513097380522, 2448.8285819992607, 2452.4236804350962, 2459.2007597530874, 2374.968272374818, 2396.0296080087505, 2398.100712232913, 2421.5641184586766, 2433.4889673168655, 2366.0535785685693, 2377.881105944713, 2381.547505973877, 2381.9642705359433, 2386.5942789725614, 8826.86759698605, 8933.59798809055, 9101.089891010912, 9223.216026637743, 9231.953840230823, 5383.4616538346245, 5610.85972850682, 5617.4382561743905, 5619.044762390404, 5621.522170615462, 3463.2207753255566, 3473.426884564044, 3488.7034602058857, 3499.265154317596, 3560.7151427885706, 1240.932975765194, 1251.5306759965013, 1254.956076537326, 1280.295837289487, 1281.5834853672593, 12484.250944943333, 12536.874631253662, 12545.372731363486, 12573.434195461135, 12573.554041285277, 4464.553544645574, 4684.0, 4797.736124513185, 4801.447833499193, 4806.711597304103, 10038.698839034842, 10134.341267817568, 10147.847782283272, 10151.238657100746, 10151.746206344753, 5505.174223866452, 5527.695976721319, 5533.142362933751, 5536.058869992111, 5537.0, 4538.591526762607, 4853.490383509751, 4972.254161875675, 5006.874828129326, 5184.144616138295, 3920.196359746245, 4008.5390180129075, 4071.206114807643, 4080.0411903202726, 4110.09577892869, 1174.682835634374, 1185.8932696057395, 1194.8267501212276, 1195.4022988505747, 1216.0757824053783, 982.8476586129161, 1074.9787701683276, 1090.7709381030072, 1098.527633117767, 1105.607509334175, 6130.527995213605, 6306.558540902184, 6328.746850125944, 6539.574927629689, 6637.933620663828, 483.16171442547153, 492.2493197873272, 492.58376671712347, 493.1788572027534, 502.14885768622565, 4219.07180420307, 4360.607545320873, 4409.911801764011, 4462.174497717932, 4629.421322334708, 10572.41851698164, 11036.392998385005, 11055.834162487568, 11095.55617775296, 11103.555857765761, 10238.53926573306, 10719.571217151226, 11036.227464077438, 11036.558537658404, 11042.613508527164, 8377.790555236168, 8603.956980215058, 8609.536378815616, 8616.48301101936, 8622.180892815173, 6269.874602508015, 6282.371762823864, 6455.580387274812, 6577.276499584946, 6761.560498567577, 10338.0695737381, 10473.533705281114, 10484.42335671515, 10614.310069845435, 10629.149668026694, 10537.63118290856, 10710.196735244732, 10816.67549973502, 10816.891831081903, 10824.458777061272, 6874.2782007890455, 7044.225135235018, 7047.541909775849, 8273.793155170928, 8411.369147314099, 7652.961735191288, 8118.715844945398, 8186.733869980973, 8858.29133669292, 8879.200871921557, 8067.717629883161, 8101.311388532041, 8141.674333026613, 8164.591770411337, 8224.588770561568, 7843.215678432111, 7928.682852685828, 8044.074931382966, 8079.030516337842, 8233.38249631268, 7592.392608591189, 7716.92283077162, 7719.459637825298, 7724.806879828049, 7731.536107833548, 5482.314710661168, 5550.69471179089, 5586.441355864381, 5694.772209111589, 5744.425557444222, 5726.9713651431475, 5743.195952566561, 5824.767009319579, 5864.794732184353, 5936.495397891227], "value": 4740.783669457221, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.2251989323755, 356.4475063651341, 357.93199292134494, 360.4575114452748, 365.89754868636777, 5544.0, 5622.863311941467, 5631.1271752878265, 5665.263515742958, 5697.743601537931, 5530.391656917735, 5536.446355364462, 5570.86072848179, 5605.439456054392, 5609.691466969315, 4306.332518459643, 4328.458942632171, 4370.732487578598, 4389.297712366022, 4405.933910991338, 4341.413909122275, 4349.760763158025, 4350.477942646887, 4372.540883207265, 4391.714538554999, 4322.82708691652, 4332.198543269489, 4341.044970106587, 4343.543927887574, 4349.021195356121, 4126.339785634299, 4289.678274129453, 4330.242207613655, 4342.32693932441, 4374.956250437487, 3815.523059617548, 3837.884863454101, 3854.1328201154797, 3854.383298672227, 3860.343741563941, 5365.141577347606, 5429.239906413105, 5431.293931788881, 5456.508914197741, 5482.945170548283, 4892.8532144035735, 4898.191798353268, 4955.157623204028, 4978.327925730016, 4996.725180115092, 5753.137029445575, 5770.855728606818, 5789.478946894757, 5797.217375654275, 5803.88392232153, 4215.135897141079, 4228.175505776375, 4232.238197124517, 4240.787960601958, 4257.680119163069, 5683.3464151622675, 5829.475347218813, 5886.617369870987, 6081.756729730805, 6098.69506524672, 4279.914401711948, 4297.634701050446, 4326.939899724524, 4338.674599405057, 4361.389405483234, 4381.233284175257, 4399.186150562156, 4402.141582391467, 4408.096340250241, 4411.933820992687, 4691.272852707836, 4738.123447162286, 4938.185199442124, 4997.325361076281, 5005.599552035828, 5726.112452569859, 5762.0, 5772.884542309131, 5805.941940580625, 5807.477327040587, 1504.7818066380532, 1520.657851983317, 1524.5731195265344, 1531.491480891321, 1535.554689140159, 978.5254151736402, 990.1682586627268, 992.925530585209, 1040.3133931605105, 1060.2313322840878, 5600.159976003632, 5618.971905140448, 5642.210090587321, 5655.943440565542, 5665.886682266331, 5968.492678122321, 5983.461488466059, 5985.22192115031, 5987.251593550806, 5989.760409583568, 5428.267183930119, 5682.516986056232, 5713.485786279255, 5717.085266357363, 5726.226959360516, 3150.8739650413727, 3179.3164469639137, 3189.1070500259966, 3216.4049650814673, 3226.757993150523, 5925.170476133345, 6182.0, 6225.906611400833, 6226.260445404007, 6235.594686345464, 6083.239595050619, 6084.300305464928, 6091.939080609226, 6095.177151084636, 6117.510599152057, 6184.412480814315, 6224.78616669745, 6225.501959843111, 6230.7819226326865, 6237.941479311816, 5710.143478478261, 6012.458878700938, 6020.969895150496, 6046.3046749624355, 6059.818205453844, 5964.463198312173, 5975.312839023394, 5988.520918326436, 5997.220361353171, 6023.759049637965, 6177.722002509988, 6213.137991534648, 6224.657643829632, 6236.158118653923, 6240.968795155995, 4340.47914250292, 4349.7168335341785, 4365.73805571654, 4366.2577361847925, 4383.583559561836, 6076.149339092619, 6106.664133472702, 6122.0, 6137.693115344304, 6148.802622636114, 5687.857803554944, 5901.790133022806, 5929.0513517836935, 5929.584929054925, 5929.792457263975, 5907.143464197753, 5915.319738229987, 5919.200907877382, 5939.703014849154, 5984.551158663031, 5645.774169033357, 5817.621854579439, 5830.82507524775, 5881.676507792113, 5928.4960778334325, 5890.793822216202, 5916.763329466945, 5924.6149000314845, 5934.554908381802, 5942.346341902474, 5597.496225339739, 5728.997425450446, 5769.663286369027, 5773.393793651606, 5800.216970709069], "value": 4482.981336470702, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.161101206973, 676.9424598909093, 784.0121446976812, 884.3897710579699, 919.5676837642563, 16622.75065874013, 16939.576510587212, 17878.0, 17880.64238715225, 17944.10279486027, 16996.745048824276, 17076.14619269038, 17130.743038854427, 17174.22715977781, 17180.484585462425, 895.2077411490828, 918.4213945214506, 921.4977837078775, 921.7511271956567, 922.0134456131908, 746.8655641984441, 874.9606267717936, 903.2773780975226, 904.9683261085863, 910.622091831889, 967.3760424526212, 969.1181025267008, 970.5535453691275, 971.0823272007955, 971.1890571372912, 962.0523784072683, 962.2302158273353, 962.9085236902483, 965.0976337124811, 966.0436168193509, 568.3662716071592, 581.2037508613226, 583.0525396231125, 675.3482889012078, 692.6848284030754, 15307.0, 15457.227138643022, 15464.072155670583, 15503.844961550352, 15561.299741511546, 14931.25343732809, 15284.541463755993, 15576.61058473536, 15804.525864224091, 15884.126373049477, 13116.27860467674, 13272.336383180802, 13279.86720132796, 13489.190648561118, 13497.2576508292, 4711.976440117812, 4721.787519561628, 4745.2882067690125, 4834.299026641117, 4838.371011768448, 75765.0, 82938.9999999994, 83204.58397707973, 84164.57917710433, 85116.0, 11355.60255391074, 11764.294142351486, 12034.217775844718, 12089.214201077079, 12121.0, 22802.201922932618, 22874.8856255721, 22993.195238166583, 23003.19488817917, 23809.0, 8917.687880924137, 8984.416012959136, 8996.325275604226, 9031.187193152648, 9103.544822758835, 4651.790669419885, 4656.976715116402, 4668.929966050512, 4703.553162449562, 4795.328653988445, 3686.594474607791, 3828.0429892526868, 3857.479240302523, 3860.8455661773214, 3865.40086286626, 633.3286716080969, 634.0805831544279, 659.7525927777084, 667.7195577857311, 674.8211723893172, 711.605059192143, 730.5032577847008, 733.296035805622, 733.7065173930471, 751.8796992481177, 9592.568334424968, 9670.516474176404, 9886.505674716236, 9927.652532161484, 10101.292909496411, 697.8325201951596, 708.1360739897317, 720.6756959368305, 730.9524880882726, 740.9110906691232, 4672.252439609646, 4698.506656800987, 4707.398839887489, 4822.132016237041, 4823.324734537167, 116876.41561792136, 119466.4026679861, 119737.00657483458, 122219.0, 124127.0, 103349.48325258326, 108255.45872270588, 109317.0, 109516.4524177374, 111691.0, 93395.0, 93901.5304923471, 94730.0, 97707.0, 97732.51133744286, 9576.377535460173, 9599.42403455795, 9983.750406239902, 10207.89792102099, 10297.74255643615, 53400.73299633477, 53778.73110634422, 54351.45648543464, 54922.0, 55176.44823551713, 78651.60674196592, 79435.2056479451, 80471.0, 80504.0, 82301.0, 12267.75464490723, 12542.561010364594, 12594.528525007234, 12717.300548469922, 12974.156679815782, 13947.58157255284, 13973.301334933416, 14241.35913883857, 14328.0, 14329.068610540699, 12848.678783030498, 13443.46226150943, 13750.793738094137, 13849.584512464642, 14251.857481425468, 10651.520681569504, 14238.572842814732, 14349.497767578503, 14837.777433338293, 15116.924415377853, 14898.627534311729, 15081.019733717274, 15611.453599123975, 15925.68148637044, 16291.0, 12134.75730485403, 12347.876521235032, 12508.437120329789, 12510.18683785551, 13151.408186631434, 12397.690057748268, 12862.935685321514, 12975.675608109872, 13025.544105956626, 13185.670358241121], "value": 8070.902827540991, "unit": "score"}], "name": "dromaeo_css", "value": 7709.957641209506, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 112.0, 111.0, 110.0, 109.0, 110.0, 112.0, 112.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 122.0, 184.0, 118.0, 117.0, 142.0, 116.0, 147.0, 115.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 162.0, 162.0, 161.0, 160.0, 162.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 113.0, 75.0, 77.0, 75.0, 76.0, 102.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 128.0, 83.0, 83.0, 83.0, 118.0, 83.0, 84.0, 130.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 53.0, 55.0, 53.0, 52.0, 51.0, 51.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 59.0, 57.0, 59.0, 58.0, 57.0, 58.0, 59.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 80.0, 73.0, 73.0, 72.0, 78.0, 73.0, 75.0, 76.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 127.0, 116.0, 125.0, 124.0, 114.0, 116.0, 114.0, 158.0, 122.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 151.0, 155.0, 153.0, 154.0, 157.0, 158.0, 159.0, 156.0, 152.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 64.0, 61.0, 63.0, 64.0, 63.0, 61.0, 61.0, 61.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1467.5000000000002, "alertThreshold": 2.0}]} 04:04:40 INFO - Return code: 0 04:04:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:04:40 INFO - # TBPL SUCCESS # 04:04:40 INFO - Running post-action listener: _resource_record_post_action 04:04:40 INFO - [mozharness: 2016-08-20 11:04:40.603254Z] Finished run-tests step (success) 04:04:40 INFO - Running post-run listener: _resource_record_post_run 04:04:40 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327528448; Read time: 12; Write time: 280624 04:04:40 INFO - TinderboxPrint: CPU usage
12.9% 04:04:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:04:40 INFO - TinderboxPrint: I/O write bytes / time
327,528,448 / 280,624 04:04:40 INFO - TinderboxPrint: CPU idle
8,277.8 (87.1%) 04:04:40 INFO - TinderboxPrint: CPU user
1,182.3 (12.4%) 04:04:40 INFO - TinderboxPrint: Swap in / out
0 / 0 04:04:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78544896; Read time: 0; Write time: 104132 04:04:40 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248365056; Read time: 12; Write time: 176468 04:04:41 INFO - Running post-run listener: _upload_blobber_files 04:04:41 INFO - Blob upload gear active. 04:04:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:04:41 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:04:41 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:04:41 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:04:41 INFO - (blobuploader) - INFO - Open directory for files ... 04:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:04:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:42 INFO - (blobuploader) - INFO - Done attempting. 04:04:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:04:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:04:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:43 INFO - (blobuploader) - INFO - Done attempting. 04:04:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:04:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:04:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:46 INFO - (blobuploader) - INFO - Done attempting. 04:04:46 INFO - (blobuploader) - INFO - Iteration through files over. 04:04:46 INFO - Return code: 0 04:04:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:04:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:04:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47d8aafded7c941840ae2a34cdd00366049e5e8897eeb3cfe271c4138d239e11232428aec66db2b2a4b217d8cf336dffea00fbd34f210a461633e9f2eca4abc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9abb1da4054b6b99d82a85e56ca2480e095eb2d3de487273e35d9482df77a30459f580128865a8ec71a959fb145df580d59b07e4c6de95ecfee7b9368f4ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2953a6faa6f3977d9ddc1a0947e91f58eb276935200ae032f95452cdf6461085e09973a05b4c5e3b6e71751a8a063dd8dd5e6970a51d36476e6fcd431b9a44fe"} 04:04:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:04:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:04:46 INFO - Contents: 04:04:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47d8aafded7c941840ae2a34cdd00366049e5e8897eeb3cfe271c4138d239e11232428aec66db2b2a4b217d8cf336dffea00fbd34f210a461633e9f2eca4abc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9abb1da4054b6b99d82a85e56ca2480e095eb2d3de487273e35d9482df77a30459f580128865a8ec71a959fb145df580d59b07e4c6de95ecfee7b9368f4ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2953a6faa6f3977d9ddc1a0947e91f58eb276935200ae032f95452cdf6461085e09973a05b4c5e3b6e71751a8a063dd8dd5e6970a51d36476e6fcd431b9a44fe"} 04:04:46 INFO - Running post-run listener: copy_logs_to_upload_dir 04:04:46 INFO - Copying logs to upload dir... 04:04:46 INFO - mkdir: /builds/slave/test/build/upload/logs 04:04:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.532868 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-20 04:04:46.082597) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-20 04:04:46.086423) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790 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/47d8aafded7c941840ae2a34cdd00366049e5e8897eeb3cfe271c4138d239e11232428aec66db2b2a4b217d8cf336dffea00fbd34f210a461633e9f2eca4abc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9abb1da4054b6b99d82a85e56ca2480e095eb2d3de487273e35d9482df77a30459f580128865a8ec71a959fb145df580d59b07e4c6de95ecfee7b9368f4ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2953a6faa6f3977d9ddc1a0947e91f58eb276935200ae032f95452cdf6461085e09973a05b4c5e3b6e71751a8a063dd8dd5e6970a51d36476e6fcd431b9a44fe"} build_url:https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/PJt1CMrUTIyf8yhtfbPiVg/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/47d8aafded7c941840ae2a34cdd00366049e5e8897eeb3cfe271c4138d239e11232428aec66db2b2a4b217d8cf336dffea00fbd34f210a461633e9f2eca4abc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9abb1da4054b6b99d82a85e56ca2480e095eb2d3de487273e35d9482df77a30459f580128865a8ec71a959fb145df580d59b07e4c6de95ecfee7b9368f4ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2953a6faa6f3977d9ddc1a0947e91f58eb276935200ae032f95452cdf6461085e09973a05b4c5e3b6e71751a8a063dd8dd5e6970a51d36476e6fcd431b9a44fe"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-20 04:04:46.111686) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 04:04:46.112017) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471689190.156819-745534790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 04:04:46.196993) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-20 04:04:46.197317) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-20 04:04:46.197603) ========= ========= Total master_lag: 0.17 =========