builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1474110507.98 results: success (0) buildid: 20160917004009 builduid: 39c0864104644d618a468a713b19f1a0 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:27.979615) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:27.980091) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:27.980408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:27.995199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:27.995501) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 04:08:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-09-17 04:08:28 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134818 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:28.147641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:28.148334) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013707 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:28.202042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:28.202848) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --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 b9499912b32324acd84792bf02b533b40e98e496 --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=8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 04:08:28,675 truncating revision to first 12 chars 2016-09-17 04:08:28,675 Setting DEBUG logging. 2016-09-17 04:08:28,675 attempt 1/10 2016-09-17 04:08:28,675 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 04:08:29,184 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=0.852250 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:29.074237) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:29.074994) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:29.075925) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:29.076553) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 04:08:29.088047) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-17 04:08:29.088394) ========= /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=8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:08:29 INFO - MultiFileLogger online at 20160917 04:08:29 in /builds/slave/test 04:08:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:08:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:29 INFO - {'append_to_log': False, 04:08:29 INFO - 'base_work_dir': '/builds/slave/test', 04:08:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:08:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:29 INFO - 'branch': 'Mozilla-Aurora', 04:08:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:29 INFO - 'config_files': ('talos/linux_config.py',), 04:08:29 INFO - 'default_actions': ('clobber', 04:08:29 INFO - 'read-buildbot-config', 04:08:29 INFO - 'download-and-extract', 04:08:29 INFO - 'populate-webroot', 04:08:29 INFO - 'create-virtualenv', 04:08:29 INFO - 'install', 04:08:29 INFO - 'run-tests'), 04:08:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:29 INFO - 'download_minidump_stackwalk': True, 04:08:29 INFO - 'download_symbols': 'ondemand', 04:08:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:08:29 INFO - 'tooltool.py': '/tools/tooltool.py', 04:08:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:08:29 INFO - '/tools/misc-python/virtualenv.py')}, 04:08:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:29 INFO - 'installer_path': 'installer.exe', 04:08:29 INFO - 'log_level': 'info', 04:08:29 INFO - 'log_name': 'talos', 04:08:29 INFO - 'log_to_console': True, 04:08:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:08:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:08:29 INFO - 'opt_config_files': (), 04:08:29 INFO - 'pip_index': False, 04:08:29 INFO - 'sps_profile': False, 04:08:29 INFO - 'sps_profile_interval': 0, 04:08:29 INFO - 'suite': 'dromaeojs', 04:08:29 INFO - 'system_bits': '32', 04:08:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:29 INFO - 'title': 'talos-linux64-ix-001', 04:08:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:29 INFO - 'use_talos_json': True, 04:08:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:29 INFO - 'work_dir': 'build'} 04:08:29 INFO - [mozharness: 2016-09-17 11:08:29.658369Z] Running clobber step. 04:08:29 INFO - Running pre-action listener: _resource_record_pre_action 04:08:29 INFO - Running main action method: clobber 04:08:29 INFO - rmtree: /builds/slave/test/build 04:08:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:30 INFO - Running post-action listener: _resource_record_post_action 04:08:30 INFO - [mozharness: 2016-09-17 11:08:30.115867Z] Finished clobber step (success) 04:08:30 INFO - [mozharness: 2016-09-17 11:08:30.115967Z] Running read-buildbot-config step. 04:08:30 INFO - Running pre-action listener: _resource_record_pre_action 04:08:30 INFO - Running main action method: read_buildbot_config 04:08:30 INFO - Using buildbot properties: 04:08:30 INFO - { 04:08:30 INFO - "project": "", 04:08:30 INFO - "product": "firefox", 04:08:30 INFO - "script_repo_revision": "production", 04:08:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:08:30 INFO - "repository": "", 04:08:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:08:30 INFO - "buildid": "20160917004009", 04:08:30 INFO - "stage_platform": "linux64-pgo", 04:08:30 INFO - "basedir": "/builds/slave/test", 04:08:30 INFO - "nightly_build": "True", 04:08:30 INFO - "buildnumber": 522, 04:08:30 INFO - "slavename": "talos-linux64-ix-001", 04:08:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:08:30 INFO - "platform": "ubuntu64_hw", 04:08:30 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:08:30 INFO - "branch": "mozilla-aurora", 04:08:30 INFO - "repo_path": "releases/mozilla-aurora", 04:08:30 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 04:08:30 INFO - "pgo_build": "True", 04:08:30 INFO - "builduid": "39c0864104644d618a468a713b19f1a0", 04:08:30 INFO - "slavebuilddir": "test" 04:08:30 INFO - } 04:08:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:08:30 INFO - Running post-action listener: _resource_record_post_action 04:08:30 INFO - [mozharness: 2016-09-17 11:08:30.118464Z] Finished read-buildbot-config step (success) 04:08:30 INFO - [mozharness: 2016-09-17 11:08:30.118562Z] Running download-and-extract step. 04:08:30 INFO - Running pre-action listener: _resource_record_pre_action 04:08:30 INFO - Running main action method: download_and_extract 04:08:30 INFO - mkdir: /builds/slave/test/build/tests 04:08: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')]} 04:08:30 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:08:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:08:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:08:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:08:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:08:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:08:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:08:30 INFO - retry: Failed, sleeping 30 seconds before retrying 04:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 04:09:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:09:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:09:00 INFO - retry: Failed, sleeping 60 seconds before retrying 04:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 04:10:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:10:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:10:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 04:10:00 INFO - Caught exception: HTTP Error 404: Not Found 04:10:00 INFO - Caught exception: HTTP Error 404: Not Found 04:10:00 INFO - Caught exception: HTTP Error 404: Not Found 04:10:00 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:10:00 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:10:00 INFO - Downloaded 1361 bytes. 04:10:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:10:00 INFO - Using the following test package requirements: 04:10:00 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:10:00 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:10:00 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:10:00 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'jsshell-linux-x86_64.zip'], 04:10:00 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:10:00 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:10:00 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:10:00 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:10:00 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:10:00 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:10:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:10:00 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:00 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:10:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:10:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:10:00 INFO - retry: Failed, sleeping 30 seconds before retrying 04:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:10:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:10:30 INFO - retry: Failed, sleeping 60 seconds before retrying 04:11:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:11:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:11:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:11:30 INFO - Caught exception: HTTP Error 404: Not Found 04:11:30 INFO - Caught exception: HTTP Error 404: Not Found 04:11:30 INFO - Caught exception: HTTP Error 404: Not Found 04:11:30 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:30 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:11:31 INFO - Downloaded 21771059 bytes. 04:11:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:11:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:11:32 INFO - Return code: 0 04:11:32 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:11: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')]} 04:11:32 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:11:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:11:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:11:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:11:32 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:12:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:12:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:12:02 INFO - retry: Failed, sleeping 60 seconds before retrying 04:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:13:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:13:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:13:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:13:03 INFO - Caught exception: HTTP Error 404: Not Found 04:13:03 INFO - Caught exception: HTTP Error 404: Not Found 04:13:03 INFO - Caught exception: HTTP Error 404: Not Found 04:13:03 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:13:03 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:13:03 INFO - Downloaded 21771059 bytes. 04:13:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:13:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:13:05 INFO - Return code: 0 04:13: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')]} 04:13:05 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:13:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:13:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:13:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:13:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:13:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:13:05 INFO - retry: Failed, sleeping 30 seconds before retrying 04:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 04:13:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:13:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:13:35 INFO - retry: Failed, sleeping 60 seconds before retrying 04:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 04:14:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:14:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:14:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 04:14:35 INFO - Caught exception: HTTP Error 404: Not Found 04:14:35 INFO - Caught exception: HTTP Error 404: Not Found 04:14:35 INFO - Caught exception: HTTP Error 404: Not Found 04:14:35 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:14:35 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:14:36 INFO - Downloaded 14413740 bytes. 04:14:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:14:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:14:36 INFO - Return code: 0 04:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:14:36 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:14:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:14:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:14:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:14:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:14:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:14:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:14:36 INFO - retry: Failed, sleeping 30 seconds before retrying 04:15:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 04:15:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:15:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:15:06 INFO - retry: Failed, sleeping 60 seconds before retrying 04:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 04:16:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:16:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:16:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 04:16:06 INFO - Caught exception: HTTP Error 404: Not Found 04:16:06 INFO - Caught exception: HTTP Error 404: Not Found 04:16:06 INFO - Caught exception: HTTP Error 404: Not Found 04:16:06 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:16:06 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:16:07 INFO - Downloaded 63891050 bytes. 04:16:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:16:07 INFO - mkdir: /builds/slave/test/properties 04:16:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:16:07 INFO - Writing to file /builds/slave/test/properties/build_url 04:16:07 INFO - Contents: 04:16:07 INFO - build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:16:08 INFO - Running post-action listener: _resource_record_post_action 04:16:08 INFO - Running post-action listener: set_extra_try_arguments 04:16:08 INFO - [mozharness: 2016-09-17 11:16:08.300909Z] Finished download-and-extract step (success) 04:16:08 INFO - [mozharness: 2016-09-17 11:16:08.301141Z] Running populate-webroot step. 04:16:08 INFO - Running pre-action listener: _resource_record_pre_action 04:16:08 INFO - Running main action method: populate_webroot 04:16:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:16:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:16:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:16:08 INFO - '--tppagecycles', 04:16:08 INFO - '7'], 04:16:08 INFO - 'tests': ['tsvgm']}}, 04:16:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:16:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:16:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:16:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:16:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['damp', 'tps']}, 04:16:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'tests': ['damp', 'tps']}, 04:16:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['dromaeo_dom']}, 04:16:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:16:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['basic_compositor_video']}, 04:16:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:16:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['a11yr', 04:16:08 INFO - 'ts_paint', 04:16:08 INFO - 'tpaint', 04:16:08 INFO - 'sessionrestore', 04:16:08 INFO - 'sessionrestore_no_auto_restore', 04:16:08 INFO - 'tabpaint']}, 04:16:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:16:08 INFO - 'ts_paint', 04:16:08 INFO - 'tpaint', 04:16:08 INFO - 'sessionrestore', 04:16:08 INFO - 'sessionrestore_no_auto_restore', 04:16:08 INFO - 'tabpaint']}, 04:16:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:16:08 INFO - 'ts_paint', 04:16:08 INFO - 'tpaint', 04:16:08 INFO - 'sessionrestore', 04:16:08 INFO - 'sessionrestore_no_auto_restore', 04:16:08 INFO - 'tabpaint']}, 04:16:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:16:08 INFO - 'ts_paint', 04:16:08 INFO - 'tpaint', 04:16:08 INFO - 'sessionrestore', 04:16:08 INFO - 'sessionrestore_no_auto_restore', 04:16:08 INFO - 'tabpaint']}, 04:16:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['a11yr', 04:16:08 INFO - 'ts_paint', 04:16:08 INFO - 'tpaint', 04:16:08 INFO - 'sessionrestore', 04:16:08 INFO - 'sessionrestore_no_auto_restore', 04:16:08 INFO - 'tabpaint']}, 04:16:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['a11yr', 04:16:08 INFO - 'ts_paint', 04:16:08 INFO - 'tpaint', 04:16:08 INFO - 'sessionrestore', 04:16:08 INFO - 'sessionrestore_no_auto_restore', 04:16:08 INFO - 'tabpaint']}, 04:16:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['tsvgx', 04:16:08 INFO - 'tsvgr_opacity', 04:16:08 INFO - 'tart', 04:16:08 INFO - 'tscrollx', 04:16:08 INFO - 'cart']}, 04:16:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:16:08 INFO - 'tsvgr_opacity', 04:16:08 INFO - 'tart', 04:16:08 INFO - 'tscrollx', 04:16:08 INFO - 'cart']}, 04:16:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'talos_options': ['--disable-e10s'], 04:16:08 INFO - 'tests': ['tp5o']}, 04:16:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'tests': ['tp5o']}, 04:16:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'talos_options': ['--disable-e10s', 04:16:08 INFO - '--xperf_path', 04:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:08 INFO - 'tests': ['tp5n']}, 04:16:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:08 INFO - 'talos_options': ['--xperf_path', 04:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:08 INFO - 'tests': ['tp5n']}}, 04:16:08 INFO - 'talos.zip': {'path': '', 04:16:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:16:08 INFO - Running post-action listener: _resource_record_post_action 04:16:08 INFO - [mozharness: 2016-09-17 11:16:08.320384Z] Finished populate-webroot step (success) 04:16:08 INFO - [mozharness: 2016-09-17 11:16:08.320473Z] Running create-virtualenv step. 04:16:08 INFO - Running pre-action listener: _resource_record_pre_action 04:16:08 INFO - Running main action method: create_virtualenv 04:16:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:16:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:16:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:16:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:16:08 INFO - Using real prefix '/usr' 04:16:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:16:08 INFO - Installing distribute.............................................................................................................................................................................................done. 04:16:10 INFO - Installing pip.................done. 04:16:10 INFO - Return code: 0 04:16:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:10 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:16:10 INFO - Using env: {'DISPLAY': ':0', 04:16:10 INFO - 'HOME': '/home/cltbld', 04:16:10 INFO - 'LANG': 'en_US.UTF-8', 04:16:10 INFO - 'LANGUAGE': 'en_US:en', 04:16:10 INFO - 'LOGNAME': 'cltbld', 04:16:10 INFO - 'MAIL': '/var/mail/cltbld', 04:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:10 INFO - 'MOZ_NO_REMOTE': '1', 04:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:10 INFO - 'NO_EM_RESTART': '1', 04:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:10 INFO - 'PWD': '/builds/slave/test', 04:16:10 INFO - 'SHELL': '/bin/bash', 04:16:10 INFO - 'SHLVL': '1', 04:16:10 INFO - 'TERM': 'linux', 04:16:10 INFO - 'TMOUT': '86400', 04:16:10 INFO - 'USER': 'cltbld', 04:16:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:10 INFO - '_': '/tools/buildbot/bin/python'} 04:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:16:11 INFO - Cleaning up... 04:16:11 INFO - Return code: 0 04:16:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:16:11 INFO - Using env: {'DISPLAY': ':0', 04:16:11 INFO - 'HOME': '/home/cltbld', 04:16:11 INFO - 'LANG': 'en_US.UTF-8', 04:16:11 INFO - 'LANGUAGE': 'en_US:en', 04:16:11 INFO - 'LOGNAME': 'cltbld', 04:16:11 INFO - 'MAIL': '/var/mail/cltbld', 04:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:11 INFO - 'MOZ_NO_REMOTE': '1', 04:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:11 INFO - 'NO_EM_RESTART': '1', 04:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:11 INFO - 'PWD': '/builds/slave/test', 04:16:11 INFO - 'SHELL': '/bin/bash', 04:16:11 INFO - 'SHLVL': '1', 04:16:11 INFO - 'TERM': 'linux', 04:16:11 INFO - 'TMOUT': '86400', 04:16:11 INFO - 'USER': 'cltbld', 04:16:11 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:11 INFO - '_': '/tools/buildbot/bin/python'} 04:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:11 INFO - Downloading/unpacking psutil>=3.1.1 04:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:16:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:13 INFO - Installing collected packages: psutil 04:16:13 INFO - Running setup.py install for psutil 04:16:13 INFO - building 'psutil._psutil_linux' extension 04:16:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:16:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:16:13 INFO - building 'psutil._psutil_posix' extension 04:16:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:16:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:16:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:13 INFO - Successfully installed psutil 04:16:13 INFO - Cleaning up... 04:16:13 INFO - Return code: 0 04:16:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:16:13 INFO - Using env: {'DISPLAY': ':0', 04:16:13 INFO - 'HOME': '/home/cltbld', 04:16:13 INFO - 'LANG': 'en_US.UTF-8', 04:16:13 INFO - 'LANGUAGE': 'en_US:en', 04:16:13 INFO - 'LOGNAME': 'cltbld', 04:16:13 INFO - 'MAIL': '/var/mail/cltbld', 04:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:13 INFO - 'MOZ_NO_REMOTE': '1', 04:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:13 INFO - 'NO_EM_RESTART': '1', 04:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:13 INFO - 'PWD': '/builds/slave/test', 04:16:13 INFO - 'SHELL': '/bin/bash', 04:16:13 INFO - 'SHLVL': '1', 04:16:13 INFO - 'TERM': 'linux', 04:16:13 INFO - 'TMOUT': '86400', 04:16:13 INFO - 'USER': 'cltbld', 04:16:13 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:13 INFO - '_': '/tools/buildbot/bin/python'} 04:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:16:16 INFO - Installing collected packages: mozsystemmonitor 04:16:16 INFO - Running setup.py install for mozsystemmonitor 04:16:16 INFO - Successfully installed mozsystemmonitor 04:16:16 INFO - Cleaning up... 04:16:16 INFO - Return code: 0 04:16:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:16 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:16:16 INFO - Using env: {'DISPLAY': ':0', 04:16:16 INFO - 'HOME': '/home/cltbld', 04:16:16 INFO - 'LANG': 'en_US.UTF-8', 04:16:16 INFO - 'LANGUAGE': 'en_US:en', 04:16:16 INFO - 'LOGNAME': 'cltbld', 04:16:16 INFO - 'MAIL': '/var/mail/cltbld', 04:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:16 INFO - 'MOZ_NO_REMOTE': '1', 04:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:16 INFO - 'NO_EM_RESTART': '1', 04:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:16 INFO - 'PWD': '/builds/slave/test', 04:16:16 INFO - 'SHELL': '/bin/bash', 04:16:16 INFO - 'SHLVL': '1', 04:16:16 INFO - 'TERM': 'linux', 04:16:16 INFO - 'TMOUT': '86400', 04:16:16 INFO - 'USER': 'cltbld', 04:16:16 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:16 INFO - '_': '/tools/buildbot/bin/python'} 04:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:16:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:18 INFO - Downloading functools32-3.2.3-2.tar.gz 04:16:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:16:18 INFO - warning: no files found matching '*.txt' 04:16:18 INFO - no previously-included directories found matching 'build' 04:16:18 INFO - no previously-included directories found matching 'dist' 04:16:18 INFO - no previously-included directories found matching '.git*' 04:16:18 INFO - Installing collected packages: jsonschema, functools32 04:16:18 INFO - Running setup.py install for functools32 04:16:18 INFO - warning: no files found matching '*.txt' 04:16:18 INFO - no previously-included directories found matching 'build' 04:16:18 INFO - no previously-included directories found matching 'dist' 04:16:18 INFO - no previously-included directories found matching '.git*' 04:16:18 INFO - Successfully installed jsonschema functools32 04:16:18 INFO - Cleaning up... 04:16:18 INFO - Return code: 0 04:16:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:18 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:16:18 INFO - Using env: {'DISPLAY': ':0', 04:16:18 INFO - 'HOME': '/home/cltbld', 04:16:18 INFO - 'LANG': 'en_US.UTF-8', 04:16:18 INFO - 'LANGUAGE': 'en_US:en', 04:16:18 INFO - 'LOGNAME': 'cltbld', 04:16:18 INFO - 'MAIL': '/var/mail/cltbld', 04:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:18 INFO - 'MOZ_NO_REMOTE': '1', 04:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:18 INFO - 'NO_EM_RESTART': '1', 04:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:18 INFO - 'PWD': '/builds/slave/test', 04:16:18 INFO - 'SHELL': '/bin/bash', 04:16:18 INFO - 'SHLVL': '1', 04:16:18 INFO - 'TERM': 'linux', 04:16:18 INFO - 'TMOUT': '86400', 04:16:18 INFO - 'USER': 'cltbld', 04:16:18 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:18 INFO - '_': '/tools/buildbot/bin/python'} 04:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:16:18 INFO - Cleaning up... 04:16:18 INFO - Return code: 0 04:16:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:18 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:16:18 INFO - Using env: {'DISPLAY': ':0', 04:16:18 INFO - 'HOME': '/home/cltbld', 04:16:18 INFO - 'LANG': 'en_US.UTF-8', 04:16:18 INFO - 'LANGUAGE': 'en_US:en', 04:16:18 INFO - 'LOGNAME': 'cltbld', 04:16:18 INFO - 'MAIL': '/var/mail/cltbld', 04:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:18 INFO - 'MOZ_NO_REMOTE': '1', 04:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:18 INFO - 'NO_EM_RESTART': '1', 04:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:18 INFO - 'PWD': '/builds/slave/test', 04:16:18 INFO - 'SHELL': '/bin/bash', 04:16:18 INFO - 'SHLVL': '1', 04:16:18 INFO - 'TERM': 'linux', 04:16:18 INFO - 'TMOUT': '86400', 04:16:18 INFO - 'USER': 'cltbld', 04:16:18 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:18 INFO - '_': '/tools/buildbot/bin/python'} 04:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:19 INFO - Downloading/unpacking blobuploader==1.2.4 04:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:16:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:16:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:21 INFO - Downloading docopt-0.6.1.tar.gz 04:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:16:21 INFO - Installing collected packages: blobuploader, requests, docopt 04:16:21 INFO - Running setup.py install for blobuploader 04:16:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:16:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:16:21 INFO - Running setup.py install for requests 04:16:22 INFO - Running setup.py install for docopt 04:16:22 INFO - Successfully installed blobuploader requests docopt 04:16:22 INFO - Cleaning up... 04:16:22 INFO - Return code: 0 04:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:22 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:16:22 INFO - Using env: {'DISPLAY': ':0', 04:16:22 INFO - 'HOME': '/home/cltbld', 04:16:22 INFO - 'LANG': 'en_US.UTF-8', 04:16:22 INFO - 'LANGUAGE': 'en_US:en', 04:16:22 INFO - 'LOGNAME': 'cltbld', 04:16:22 INFO - 'MAIL': '/var/mail/cltbld', 04:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:22 INFO - 'MOZ_NO_REMOTE': '1', 04:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:22 INFO - 'NO_EM_RESTART': '1', 04:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:22 INFO - 'PWD': '/builds/slave/test', 04:16:22 INFO - 'SHELL': '/bin/bash', 04:16:22 INFO - 'SHLVL': '1', 04:16:22 INFO - 'TERM': 'linux', 04:16:22 INFO - 'TMOUT': '86400', 04:16:22 INFO - 'USER': 'cltbld', 04:16:22 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:22 INFO - '_': '/tools/buildbot/bin/python'} 04:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:22 INFO - Running setup.py (path:/tmp/pip-8Np9nT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:22 INFO - Running setup.py (path:/tmp/pip-ykXaZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:22 INFO - Running setup.py (path:/tmp/pip-E578gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:22 INFO - Running setup.py (path:/tmp/pip-sSiHi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:22 INFO - Running setup.py (path:/tmp/pip-CdOYpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:23 INFO - Running setup.py (path:/tmp/pip-qUyanL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:23 INFO - Running setup.py (path:/tmp/pip-WJlGjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:23 INFO - Running setup.py (path:/tmp/pip-5hiSsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:23 INFO - Running setup.py (path:/tmp/pip-umrt3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:23 INFO - Running setup.py (path:/tmp/pip-MNqIPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:23 INFO - Running setup.py (path:/tmp/pip-s3MmqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:23 INFO - Running setup.py (path:/tmp/pip-bwpc0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:23 INFO - Running setup.py (path:/tmp/pip-8MrsGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:23 INFO - Running setup.py (path:/tmp/pip-6Sq_SS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:23 INFO - Running setup.py (path:/tmp/pip-ohvA_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:23 INFO - Running setup.py (path:/tmp/pip-w3HB6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:23 INFO - Running setup.py (path:/tmp/pip-F4kyMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:16:23 INFO - Running setup.py install for manifestparser 04:16:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:16:23 INFO - Running setup.py install for mozcrash 04:16:24 INFO - Running setup.py install for mozdebug 04:16:24 INFO - Running setup.py install for mozdevice 04:16:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for mozfile 04:16:24 INFO - Running setup.py install for mozhttpd 04:16:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for mozinfo 04:16:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for mozInstall 04:16:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for mozleak 04:16:24 INFO - Running setup.py install for mozlog 04:16:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for moznetwork 04:16:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozprocess 04:16:25 INFO - Running setup.py install for mozprofile 04:16:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozrunner 04:16:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozscreenshot 04:16:25 INFO - Running setup.py install for moztest 04:16:25 INFO - Running setup.py install for mozversion 04:16:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:16:25 INFO - Cleaning up... 04:16:25 INFO - Return code: 0 04:16:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:25 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:16:25 INFO - Using env: {'DISPLAY': ':0', 04:16:25 INFO - 'HOME': '/home/cltbld', 04:16:25 INFO - 'LANG': 'en_US.UTF-8', 04:16:25 INFO - 'LANGUAGE': 'en_US:en', 04:16:25 INFO - 'LOGNAME': 'cltbld', 04:16:25 INFO - 'MAIL': '/var/mail/cltbld', 04:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:25 INFO - 'MOZ_NO_REMOTE': '1', 04:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:25 INFO - 'NO_EM_RESTART': '1', 04:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:25 INFO - 'PWD': '/builds/slave/test', 04:16:25 INFO - 'SHELL': '/bin/bash', 04:16:25 INFO - 'SHLVL': '1', 04:16:25 INFO - 'TERM': 'linux', 04:16:25 INFO - 'TMOUT': '86400', 04:16:25 INFO - 'USER': 'cltbld', 04:16:25 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:25 INFO - '_': '/tools/buildbot/bin/python'} 04:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:25 INFO - Running setup.py (path:/tmp/pip-OotBys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:25 INFO - Running setup.py (path:/tmp/pip-mqVYmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:26 INFO - Running setup.py (path:/tmp/pip-mINxoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:26 INFO - Running setup.py (path:/tmp/pip-82MkBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:26 INFO - Running setup.py (path:/tmp/pip-4MJ4fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:26 INFO - Running setup.py (path:/tmp/pip-eVvQT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:26 INFO - Running setup.py (path:/tmp/pip-RZV0Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:26 INFO - Running setup.py (path:/tmp/pip-4q6wgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:26 INFO - Running setup.py (path:/tmp/pip-OQ0X9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:26 INFO - Running setup.py (path:/tmp/pip-LjXvDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:26 INFO - Running setup.py (path:/tmp/pip-RpxNcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:26 INFO - Running setup.py (path:/tmp/pip-M8Hm71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:26 INFO - Running setup.py (path:/tmp/pip-0boYpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:26 INFO - Running setup.py (path:/tmp/pip-ipvzxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:26 INFO - Running setup.py (path:/tmp/pip-UCX4X_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:26 INFO - Running setup.py (path:/tmp/pip-5RDRNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:27 INFO - Running setup.py (path:/tmp/pip-5qedRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:16:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:29 INFO - Downloading blessings-1.6.tar.gz 04:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:16:29 INFO - Installing collected packages: blessings 04:16:29 INFO - Running setup.py install for blessings 04:16:29 INFO - Successfully installed blessings 04:16:29 INFO - Cleaning up... 04:16:29 INFO - Return code: 0 04:16:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:16:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:29 INFO - Reading from file tmpfile_stdout 04:16:29 INFO - Current package versions: 04:16:29 INFO - argparse == 1.2.1 04:16:29 INFO - blessings == 1.6 04:16:29 INFO - blobuploader == 1.2.4 04:16:29 INFO - docopt == 0.6.1 04:16:29 INFO - functools32 == 3.2.3-2 04:16:29 INFO - jsonschema == 2.5.1 04:16:29 INFO - manifestparser == 1.1 04:16:29 INFO - mozInstall == 1.12 04:16:29 INFO - mozcrash == 0.17 04:16:29 INFO - mozdebug == 0.1 04:16:29 INFO - mozdevice == 0.48 04:16:29 INFO - mozfile == 1.2 04:16:29 INFO - mozhttpd == 0.7 04:16:29 INFO - mozinfo == 0.9 04:16:29 INFO - mozleak == 0.1 04:16:29 INFO - mozlog == 3.3 04:16:29 INFO - moznetwork == 0.27 04:16:29 INFO - mozprocess == 0.23 04:16:29 INFO - mozprofile == 0.28 04:16:29 INFO - mozrunner == 6.12 04:16:29 INFO - mozscreenshot == 0.1 04:16:29 INFO - mozsystemmonitor == 0.3 04:16:29 INFO - moztest == 0.7 04:16:29 INFO - mozversion == 1.4 04:16:29 INFO - psutil == 3.1.1 04:16:29 INFO - requests == 1.2.3 04:16:29 INFO - wsgiref == 0.1.2 04:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:29 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 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:16:29 INFO - Using env: {'DISPLAY': ':0', 04:16:29 INFO - 'HOME': '/home/cltbld', 04:16:29 INFO - 'LANG': 'en_US.UTF-8', 04:16:29 INFO - 'LANGUAGE': 'en_US:en', 04:16:29 INFO - 'LOGNAME': 'cltbld', 04:16:29 INFO - 'MAIL': '/var/mail/cltbld', 04:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:29 INFO - 'MOZ_NO_REMOTE': '1', 04:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:29 INFO - 'NO_EM_RESTART': '1', 04:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:29 INFO - 'PWD': '/builds/slave/test', 04:16:29 INFO - 'SHELL': '/bin/bash', 04:16:29 INFO - 'SHLVL': '1', 04:16:29 INFO - 'TERM': 'linux', 04:16:29 INFO - 'TMOUT': '86400', 04:16:29 INFO - 'USER': 'cltbld', 04:16:29 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:29 INFO - '_': '/tools/buildbot/bin/python'} 04:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:16:29 INFO - Cleaning up... 04:16:29 INFO - Return code: 0 04:16:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfda030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, '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': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:16: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', 'jsonschema'] in /builds/slave/test/build 04:16: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 jsonschema 04:16:29 INFO - Using env: {'DISPLAY': ':0', 04:16:29 INFO - 'HOME': '/home/cltbld', 04:16:29 INFO - 'LANG': 'en_US.UTF-8', 04:16:29 INFO - 'LANGUAGE': 'en_US:en', 04:16:29 INFO - 'LOGNAME': 'cltbld', 04:16:29 INFO - 'MAIL': '/var/mail/cltbld', 04:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:29 INFO - 'MOZ_NO_REMOTE': '1', 04:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:29 INFO - 'NO_EM_RESTART': '1', 04:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:29 INFO - 'PWD': '/builds/slave/test', 04:16:29 INFO - 'SHELL': '/bin/bash', 04:16:29 INFO - 'SHLVL': '1', 04:16:29 INFO - 'TERM': 'linux', 04:16:29 INFO - 'TMOUT': '86400', 04:16:29 INFO - 'USER': 'cltbld', 04:16:29 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:29 INFO - '_': '/tools/buildbot/bin/python'} 04:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:16:29 INFO - Cleaning up... 04:16:29 INFO - Return code: 0 04:16:29 INFO - Running post-action listener: _resource_record_post_action 04:16:29 INFO - Running post-action listener: _start_resource_monitoring 04:16:29 INFO - Starting resource monitoring. 04:16:29 INFO - [mozharness: 2016-09-17 11:16:29.951109Z] Finished create-virtualenv step (success) 04:16:29 INFO - [mozharness: 2016-09-17 11:16:29.951533Z] Running install step. 04:16:29 INFO - Running pre-action listener: _resource_record_pre_action 04:16:29 INFO - Running main action method: install 04:16:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:30 INFO - Reading from file tmpfile_stdout 04:16:30 INFO - Detecting whether we're running mozinstall >=1.0... 04:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:16:30 INFO - Reading from file tmpfile_stdout 04:16:30 INFO - Output received: 04:16:30 INFO - Usage: mozinstall [options] installer 04:16:30 INFO - Options: 04:16:30 INFO - -h, --help show this help message and exit 04:16:30 INFO - -d DEST, --destination=DEST 04:16:30 INFO - Directory to install application into. [default: 04:16:30 INFO - "/builds/slave/test"] 04:16:30 INFO - --app=APP Application being installed. [default: firefox] 04:16:30 INFO - mkdir: /builds/slave/test/build/application 04:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:16:46 INFO - Reading from file tmpfile_stdout 04:16:46 INFO - Output received: 04:16:46 INFO - /builds/slave/test/build/application/firefox/firefox 04:16:46 INFO - Running post-action listener: _resource_record_post_action 04:16:46 INFO - [mozharness: 2016-09-17 11:16:46.722569Z] Finished install step (success) 04:16:46 INFO - [mozharness: 2016-09-17 11:16:46.722850Z] Running run-tests step. 04:16:46 INFO - Running pre-action listener: _resource_record_pre_action 04:16:46 INFO - Running main action method: run_tests 04:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:16:46 INFO - Python 2.7.3 04:16:46 INFO - Return code: 0 04:16:46 INFO - grabbing minidump binary from tooltool 04:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:46 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 0x12993b0>, '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 0x129a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:16:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:16:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:16:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:16:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:16:46 INFO - Return code: 0 04:16:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:16:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:16:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:16:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:16:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:16:46 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/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:16:46 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/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:16:46 INFO - Using env: {'DISPLAY': ':0', 04:16:46 INFO - 'HOME': '/home/cltbld', 04:16:46 INFO - 'LANG': 'en_US.UTF-8', 04:16:46 INFO - 'LANGUAGE': 'en_US:en', 04:16:46 INFO - 'LOGNAME': 'cltbld', 04:16:46 INFO - 'MAIL': '/var/mail/cltbld', 04:16:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:16:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:46 INFO - 'MOZ_NO_REMOTE': '1', 04:16:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:46 INFO - 'NO_EM_RESTART': '1', 04:16:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:46 INFO - 'PWD': '/builds/slave/test', 04:16:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:16:46 INFO - 'SHELL': '/bin/bash', 04:16:46 INFO - 'SHLVL': '1', 04:16:46 INFO - 'TERM': 'linux', 04:16:46 INFO - 'TMOUT': '86400', 04:16:46 INFO - 'USER': 'cltbld', 04:16:46 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652', 04:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:46 INFO - '_': '/tools/buildbot/bin/python'} 04:16:46 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/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:16:46 INFO - mozversion application_buildid: 20160917004009 04:16:46 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 04:16:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:16:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:16:47 INFO - mozversion application_name: Firefox 04:16:47 INFO - mozversion application_remotingname: firefox-dev 04:16:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:16:47 INFO - mozversion application_vendor: Mozilla 04:16:47 INFO - mozversion application_version: 50.0a2 04:16:47 INFO - mozversion platform_buildid: 20160917004009 04:16:47 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 04:16:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:16:47 INFO - mozversion platform_version: 50.0a2 04:16:47 INFO - using testdate: 1474111007 04:16:47 INFO - actual date: 1474111007 04:16:47 INFO - starting webserver on 'localhost:59835' 04:16:47 INFO - SUITE-START | Running 2 tests 04:16:47 INFO - TEST-START | dromaeo_css 04:16:47 INFO - operating with platform_type : linux_ 04:16:47 INFO - Initialising browser for dromaeo_css test... 04:16:47 INFO - TEST-INFO | started process 23633 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UYoKs/profile http://localhost:59835/getInfo.html) 04:16:51 INFO - PROCESS | 23633 | __metrics Screen width/height:1600/1200 04:16:51 INFO - PROCESS | 23633 | colorDepth:24 04:16:51 INFO - PROCESS | 23633 | Browser inner width/height: 1024/697 04:16:51 INFO - PROCESS | 23633 | __metrics 04:16:52 INFO - PROCESS | 23633 | 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:16:52 INFO - PROCESS | 23633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:52 INFO - TEST-INFO | 23633: exit 0 04:16:52 INFO - Browser initialized. 04:16:52 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:52 INFO - TEST-INFO | started process 23720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UYoKs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:04 INFO - PROCESS | 23720 | RSS: Main: 146669568 04:17:04 INFO - PROCESS | 23720 | 04:17:55 INFO - PROCESS | 23720 | 1474111075631 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:20:04 INFO - PROCESS | 23720 | Cycle 1(1): loaded http://localhost:59835/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59835/tests/dromaeo/cssquery-ext.html) 04:20:06 INFO - PROCESS | 23720 | RSS: Main: 276971520 04:20:06 INFO - PROCESS | 23720 | 04:23:08 INFO - PROCESS | 23720 | Cycle 1(1): loaded http://localhost:59835/tests/dromaeo/cssquery-ext.html (next: http://localhost:59835/tests/dromaeo/cssquery-jquery.html) 04:23:08 INFO - PROCESS | 23720 | RSS: Main: 229097472 04:23:08 INFO - PROCESS | 23720 | 04:26:10 INFO - PROCESS | 23720 | Cycle 1(1): loaded http://localhost:59835/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59835/tests/dromaeo/cssquery-mootools.html) 04:26:11 INFO - PROCESS | 23720 | RSS: Main: 200949760 04:26:11 INFO - PROCESS | 23720 | 04:29:13 INFO - PROCESS | 23720 | Cycle 1(1): loaded http://localhost:59835/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59835/tests/dromaeo/cssquery-prototype.html) 04:29:13 INFO - PROCESS | 23720 | RSS: Main: 175960064 04:29:13 INFO - PROCESS | 23720 | 04:32:16 INFO - PROCESS | 23720 | Cycle 1(1): loaded http://localhost:59835/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59835/tests/dromaeo/cssquery-yui.html) 04:32:17 INFO - PROCESS | 23720 | RSS: Main: 178405376 04:32:17 INFO - PROCESS | 23720 | 04:35:19 INFO - PROCESS | 23720 | Cycle 1(1): loaded http://localhost:59835/tests/dromaeo/cssquery-yui.html (next: http://localhost:59835/tests/dromaeo/cssquery-dojo.html) 04:35:19 INFO - PROCESS | 23720 | RSS: Main: 270057472 04:35:19 INFO - PROCESS | 23720 | 04:35:19 INFO - PROCESS | 23720 | __start_tp_report 04:35:19 INFO - PROCESS | 23720 | _x_x_mozilla_page_load 04:35:19 INFO - PROCESS | 23720 | _x_x_mozilla_page_load_details 04:35:19 INFO - PROCESS | 23720 | |i|pagename|runs| 04:35:19 INFO - PROCESS | 23720 | |0;dojo.html;3210.4221240176767;3214.6463888972226;3240.1089700332413;3279.737620990321;3312.1719570107475;5609.635373700712;5692.2030915671785;5710.057840456319;5713.942860571393;5721.942780572193;5606.915896261559;5659.773609055643;5707.058335374669;5712.657240565569;5727.341355744091;4951.084049450863;4976.825811096613;5175;5192.1173400522;5230;5112.335396398463;5149.30484384607;5164.147915593922;5166.276721259027;5170.457102004292;5126.154184559562;5153.458886816887;5160.587153027767;5188.507091826271;5197.818076367328;5260.579153667697;5265.631405801595;5267.894642107136;5273.419923808378;5306.734663266821;4981.252812078181;5040.067587496325;5119.974400127976;5149.6910185389;5156.690598564098;5505.6696598204235;5509.173623956399;5521.254630624854;5541.030319694038;5554.86112847178;6007.789727359522;6032.849178770522;6034.456898879122;6042.788502402394;6052.878942421127;5992.041273396236;5994.610350327258;6011.038233882601;6043.153958445821;6057.727402266909;5640.576956728261;5661.320641523043;5683.516901063374;5702.971485142589;5715.342735585419;6169.691515424301;6212.658303793335;6217.6891155442045;6217.9689101555105;6228.6574238416415;5789;5902.321233058254;5947.345791962881;5957.344692083867;6032.8793424132145;6085.513158947274;6093;6095.817125486243;6096.634201947897;6099.298580663191;6033.818985430444;6055.818325450244;6056.818295451144;6066.636001839815;6072.089186621954;5859.794907178229;5908.556858235649;5960.344362120163;5964.8807023860145;6047.334793172748;4608.423947006624;4611.608013318838;4612.677112602086;4623.815047398134;4634.83778067766;4103.322951712994;4115.074108325663;4119.279126152911;4128.545859955402;4145.54399016108;5592.972035139798;5650.208970744099;5708.257926469532;5732.16883551891;5779.8555036124435;6135.294441139326;6138.9095594588325;6144.047672610753;6145.539084568675;6154.076888466676;5792.362840087587;5808.6224395414165;5810.360860305363;5828.3006039274715;5838.299404071454;5012.548870601663;5048.091343558195;5064.645474816727;5071.137906555894;5121.923171152435;6230.688465576703;6241.032639940817;6241.7191226394925;6250.374962503804;6276.905846412307;6043.395660433922;6089.238845144357;6095.451409373;6097.237845269341;6098.054801505774;6101.7559297627595;6110.083487476914;6114.541409394402;6146.078088286793;6265.248170219421;5942.405759424023;5950.047992321208;5952.702364881825;5958.374370691015;5999;5953.910691339547;6000.729967151401;6002.759889604367;6004.099385092097;6042.697865106815;5719.656820590778;5839.649621022752;5880.705964701662;5881.823545293648;5907.675077870758;5974.820755377345;5976.073708575178;5978.312494063239;5980.282366116094;5988.730507127103;5906.940930590811;5943.405659434022;5946.970265148647;5949.464548190684;5951.315598706205;5836.358000619844;5855.414458554111;5871.500922421642;5883.882322353615;5932.199153114274;5782.624129431573;5790.971045144747;5800.622959507618;5807.535397168301;5833.358330583547 04:35:20 INFO - PROCESS | 23720 | |1;ext.html;551.2640624765937;604.0606856338394;614.923134608174;630.8328293002354;655.2202878574493;36939.261214775725;37778.62221377785;37814.81092594536;37852.43221351682;46295;44287.335689964595;49062.26406603895;49110.50889491104;49180;49267;9270.304727145458;9427.151556359926;9428.81142377152;9429.198518125968;9431.717048488556;8564.87152692707;9308.953455232715;9408.29437792165;9411.482368469731;10888.346699198033;11102.888971110266;11457.25527840692;11462.426878656033;11469.885301147007;11476.081913446946;11233;11307.660770176826;11310.491027903681;11316.999999999958;11359.148063895242;11196.272242304305;11266.211365204439;11268.549258029669;11277.041451476634;11284.266522676;45885.770571147266;46516.302255466195;46523.5347646526;46623.76688116538;46657.766711166565;3905.8242379092726;3912.471816304791;3942.526896772377;3980.5820388859042;4040.7270111210014;36195.73685997965;36212.50272617332;36381;36789.080272993124;37098.07254818624;4600.930986035212;4670.859874203779;4683;4696.976515117437;4705.435347758256;388965;395843;398012;398160.9999999971;398620;66332.66833665801;68128.3187168122;68235.65882170557;68263.65868170727;68293;39829.402558961636;40460.79769601133;40977.1804563913;42871.7856410716;71272.2872771276;19536;19743.21027158926;19743.703844442345;19745.901270493556;19763;15043.849561504463;15209.239538023054;15220;15221;15247.61880952963;18689.252429902925;20318.28885988983;20887.26894558683;21047.158113675283;21164.576708465745;7304;7306.96346518264;7405.444591655647;7522.736704215326;7681.116671582839;978.3005151316843;1132.7224829916559;1149.5057125436142;1176.688177632928;1202.9278243305255;35607.64392356095;36112.12486314367;36271.27457450836;36322.00228987431;36378.636213637525;1187.2757617853104;1191.368574655434;1192.272713644676;1213.1023042948332;1270.0030476076236;17857.642847143245;18523;18578.535536611424;18643.44069677912;18644.81355186458;638660;645564;645802;645869;646921;66093.669531653;66835;66844.9946501596;66863.33136668669;66958.33041669619;1379.1587131849765;1462.1884853905972;1485.769705695619;1498.6328349554647;1518.119490695394;34356;36454.51781860975;36576.45135323025;36611;36787.816060920595;56961;57722.711386442796;57740.13389799072;57808.71095644495;57812.421875782384;420704;420757;421090;421753;423149;30528.542071868495;31776.841115794272;32093.518597221526;33136.834315828266;33386.833065834515;53370.932581348934;53375;53777.731111345754;53817.73091134519;54692.17961730657;43736.5669329127;44305;45788.31317530173;46737.76631116823;47771;44077.55922440735;45029.774851126844;45217;45532.54467455283;46678.53321466742;45365.31952020656;46151.76924115358;46385.76807115943;46532;46776.298355525374;36493.81753091217;39287;39760.40359394669;39965.60034399735;40512.3923141159;29891.12569851869;30494.151491844477;30564.235894102825;30651.693483064882;30829.537556936215 04:35:20 INFO - PROCESS | 23720 | |2;jquery.html;396.7678907838914;414.450852620278;428.8091799149379;431.8833914842991;441.41071669321457;15369;20318.695219571717;20531.076101575447;21052.684209736864;21684.89157554214;20670.06984685689;20726.067326970286;20843.583128337425;20861.26985555506;21141.788582114175;5849.064149736043;5890.705464726757;5950.9107363389585;5970.402959704038;6023.518118550505;5941.079132734411;5954.4640982311585;5987.191729116557;6001.279846418414;6003.6697981611;5799.768009279624;5892.793752218652;5957.553183511254;5966.254218222723;5974.074018527135;5850.736716847752;5885.087811389241;5962.8807423851495;5963.671998040106;5966.105084237356;5140.948590514085;5238.764255608507;5240.082985477527;5243.895122097575;5246.213068039787;23814.166504172445;24541.754582454305;24561.89471473788;24636.753632463624;25120.623190652153;2817.8168419052695;3217.8069315841126;3226.2095786532213;3256.2998955224502;3332.266901281714;10464.110550603207;15330.15684137372;15373.769393459108;15387.384504619802;15488;2044.2845004248543;2142.3358758784825;2160.902759375832;2179.030331502466;2215.6233440315023;86889.56555217183;88037.11962880417;89178.10821891762;90347.54826225892;91313.54343228308;11873.406329683481;11884.465199066062;12061.577844775391;12110.152289339838;12266.26402415858;16758.58103547421;16918.661626767644;16933.322667093424;16935.153242337834;16977.660446790997;8765.693200737944;8839.46963182211;8897.288216942628;8960.596773145224;9234.630614775333;9074.319426043074;9083.137101975302;9093.590788414538;9215.124563166488;9310.767230819283;4795.304680821261;5006.249062640633;5043.5712964398435;5181.740912954338;5308.681479111266;2035.3359691627809;2080.6046851098545;2117.7035215069595;2130.712353832244;2131.825537951281;800.6997375984006;1023.9334443261164;1071.196602548089;1121.7532142928542;1202.6933132051272;20575.279865204648;20931;20996.845173515605;21046.47383815417;21134.577308453743;1097.9451027448595;1106.6126855600637;1111.699841042914;1120.260627985542;1129.8870112988636;11030.613928512463;11764.470598822902;11909.642710718692;11918.940405297919;11955.521779128738;485154;495181;497017;507790;508863;93994.53002735079;94730.52634736782;96779.51610241903;97207;102554;1059.3008614314515;1100.4882729530646;1112.6050252160528;1117.0672488472055;1117.9105671546256;18125.728114078036;18586;18902.338418155297;18996;19198.520036999187;32764.836175818968;34177.48733769045;41685.79157104195;43267;45017.54982450134;63186.36813631805;63261.367386325546;63802.68098659477;63837.04244436244;65011.34988650242;8418.410711250399;8436.95781521113;8489.87265191035;8709.172628600272;8710.782230444036;12459.501619935101;13593.86406135926;13898.72202555923;14356.06685565435;14731.26343682833;12229.205101668364;12368;12465.314407707301;12812.807807882702;13068.477260909458;12208.572699955459;12548.24710517372;12581.685457863627;12767.48786189558;12872.871271287167;12105.515779368727;12548.749025019632;12578.748425031632;12667.429965651752;12936.159149655243;8351.123132071045;8580.442271252348;9161.816763664556;9164.6792362267;9419.858702119336;11773.17587768848;11880.46537905779;12849.935750321189;12922.353882306035;13047.869521305045 04:35:20 INFO - PROCESS | 23720 | |3;mootools.html;1528.2129703202852;1573.2055312067407;1699.957501062474;1797.6764182447155;1840.5858681796597;8814.25078868296;9480.099390557903;9483.668071617494;9486.952565237172;9493.52532373382;8179.182081791818;8398.45410048348;8398.91601083989;8505.404621676485;9273.72178834636;2404.290734233405;2489.576771948773;2492.1277552856445;2493.501299740059;2502.9249122526353;2472.505498900227;2490.7384724603835;2537.949241015179;2543.5421624107653;2552.4512229870575;2469.7283298837206;2487.639292302615;2503.7245902950663;2538.060917460533;2550.885210165547;2398.7121545414575;2417.3835671903553;2420.9636855447184;2430.9149179778624;2482.391814005562;2414.408469924862;2438.0979037755965;2452.6934133233344;2455.99304285244;2461.95076098477;9368.859467107999;9441.433513989183;9461.38500997433;9464.621415143385;9496.240300775922;5427.511523962862;5600.691961942091;5623.465770751813;5626.7186640668215;5632.887342253132;3700.8149592520535;3741;3749.15643980105;3765.435184722286;3776.8866933992026;1151.6890439581266;1178.281248438452;1191.6305945157028;1195.7967145585185;1202.5911190195288;13138.671533211651;13188.6702832429;13192.340382980812;13200.471981120743;13201.207927524283;4765.142274390643;4816.903661926742;4837.2260438329695;4846.660733748604;5006.123928312531;11010.339379637247;11016.11871050314;11018.1185505161;11028.724281893017;11035.83446248307;5549.5837812164245;5551.2228288039705;5551.861203469945;5557.305336832896;5562.360328562268;4532.252178390595;5066.265391518209;5076.949230507722;5101.362329708786;5128.384593848762;4253.723507971972;4283.507396649363;4316.589923957219;4341;4362.8909277267685;1309.3518708239483;1344.9462021519225;1350.1763924006348;1358.6331690443526;1392.8537503562184;999.5701848205196;1007.279794946602;1015.9898401016043;1040.0171837613534;1056.1603525197486;6219.844503887439;6303.747850086037;6380.234371875312;6426.453751431087;6563.015547667888;468.5689165967318;472.75665001047713;473.8791608139902;483.84033269021137;508.0905179728245;4511.120331535385;4557.886052848639;4570.428696412949;4588.6099681526775;4783.234682450791;11450;11677.065834733201;11768.117391195523;11783.705407364885;11786.116041296937;11076.11391088711;11569.01663358607;11724.70688232801;11729.941350293195;11736.061115110771;8555.743327700178;8744.125587441205;8774.517401542977;8799.29605631548;8812.603432845537;6526.510511711545;6556.606603603799;6579.243387010555;6602.8349291268105;6759.729610815709;10367.792644147226;10788.460576970963;10797.460126993461;10871.23901326931;10965.342079475256;10918.890811091787;11176.664700059011;11186.440677966231;11204.607838725606;11304.943475282571;8143.552104634302;8306.003279606486;8348.916510834813;8427.620757065824;8434;8991.955040224757;9042.502662353634;9082.636694532144;9082.818343633222;9094.408863424122;8066.274035336848;8080.474769139987;8190.959045204736;8300.169983001651;8346.958265208636;7858.6070696466095;7884;7925;8012.198780121941;8286.047104582809;7337.156227033746;7349.522281051714;7396.519226250277;7403.555786652818;7495.40036797044;5471.835844924659;5602.943970560243;5778.046622307273;5783.334916484489;5814.970925145347;5543.52880005204;5568.248286481436;5763.769449221984;5941.405859414024;5962.433568810956 04:35:20 INFO - PROCESS | 23720 | |4;prototype.html;337.29337038903503;338.7933360650003;339.9877088991535;341.55256613835894;347.66450375387745;5441.945580544193;5499.835004949848;5502.147167189087;5531.032069387861;5532.834014979547;5467.261919640848;5467.562594992401;5468.48049435304;5473.2063850741615;5474.698891560963;4058.086930440657;4254.212970600433;4321.524632290446;4355.760433176174;4364.89087772805;4283.507396649394;4288.678349123813;4295.475106337252;4314.697971142022;4329.242382583051;4299.935500967488;4304.24675681756;4307.956920430803;4313.174870459854;4342.261815491358;4059.127287633158;4160.399750156137;4253.744775313491;4282.42187304713;4294;3758.7932663703486;3764.5106136202116;3778.280882198593;3783.1298801275584;3787.3372159872188;5333.120035194188;5340.652857564285;5354.223637572569;5363.839084827422;5381.515663590296;4550.7724613769515;4734.337192793012;4796.424429068499;4822.252550854588;4825.855224343275;5684.54523638108;5691.288588926384;5696.1740547620775;5697.6296540724725;5701.2018317435595;4017.3773065174946;4057.89855253621;4090.5704900985265;4093.16090201508;4107;5817.505512031483;5885.5585831062845;5893.322267939199;5899.174115623816;5913.7930172444185;4124.247194942198;4126.216018956388;4128.236276288896;4142.502899652061;4145.129522800185;4194.895127621834;4197.853075142355;4202.201581699528;4208.789560522023;4213.557576454489;4633.4208223972;4786.282057691375;4794.688345257617;4801.255805350176;4808.783604737795;5762.510186634099;5766.365699773021;5768.653880767168;5771.163181338767;5774.68239246837;1436.9438462729886;1451.6008097773197;1453.8546145385376;1473.977890331646;1474.6165996840898;940.1679513630486;943.5518128888723;953.6900507335143;955.06880791228;963.8409662405625;4959.950400496021;4976.701397916137;4981.452040275567;5022.2466630005065;5037;5243.056249874983;5258.421573626898;5269.706433479076;5310.99091172676;5335.973320133452;4726.621870250372;4889.853304400874;5029.622778291643;5169.327987361619;5454.836354909359;3065.126438964929;3134.670859559759;3137.51368537877;3140.795848269901;3141.575887255237;6117.816465506042;6128.3258841527395;6130.754769809247;6137.404274888561;6143;5889.293185671366;5949.25634295713;6062.242219722535;6098.75604975796;6110.541709371815;6038.365971573009;6073.39266073398;6114.257436630539;6136.478399336017;6150.661713605795;5678.608740858516;5919.437653422918;5934.495567876779;5935.376785437467;5972.8805423892145;5913.793017244376;5916.171735957056;5932.555058370725;5940.138679891305;5948.10778383246;6119.29769169681;6142.078688196806;6193.102000209854;6200.534959877937;6201.193844800147;4193.287141185945;4199.874003779892;4236.4704411948505;4242.830286788618;4246.278132717383;5953.434423729739;5962.970185149046;5965.164876917322;5969.3732158122775;6045.909311360245;5751.009475073022;5876;5896.498797602252;5897.201353587197;5908.615939963888;5755.769769209185;5786.4213578643485;5797.739101740347;5821.7089145543405;5827.76688932438;5795.449432303924;5801.593888427769;5815.156802263733;5816;5822.214001109796;5578.804741834017;5785.652860828363;5852.795152169653;5863.530917526673;5883.94116058834;5698.601097923106;5711.8000869969355;5715.799947001835;5719;5730.828075157752 04:35:20 INFO - PROCESS | 23720 | |5;yui.html;757.356247189889;781.2773184804055;807.370251204061;872.3893274707705;886.7738726624711;16161.353545858166;16446.91776541117;16551.420700275492;16696.666066678674;16810.663786724257;16363.83636163638;16373.91813040933;16400.917995410007;16428.507144785646;16618.33526658932;728.0898876404494;744.1033554566734;746.4327111395352;886.3042511628373;904.3579058868212;738.1253214940286;870.4168207301124;896.050186801992;897.3673560140119;910.1626503616689;955.7562821480512;957.459035644862;960.5869476125264;960.8943016268205;961.322267801202;954.5179684259405;954.7040417470572;955;955.4697143085584;955.8757361543024;565.6577770448902;669.0466085827725;687.2097088348389;688.9655517224119;694.4305669351135;16411.097389643564;16457.177141142896;16457.753133703005;16467;16504.33982640693;14830;15216.391344346213;15443.459478918185;15588.142652154127;15653.843461565353;14049.71900561983;14145.929270353583;14153.82169086586;14326.283685815668;14451.277436128257;4440.81079194791;4451.643868490513;4496.572825581565;4658.068386322749;4676.041411510666;67486.66256668685;70404.29595704029;82297;82451.5877420615;83157;12095.879041209475;12209.755804884031;12259.141860069709;12274.447649855778;12455.501779928883;21943.780562194173;22163.77836221617;22177.1129154832;23054.538909221723;23147.305580832603;8950.104989501127;9038.412503187272;9163.221126204347;9195.126462986005;9240.5841737122;4887.071456423339;5149.304843846033;5207.244949482304;5221.869453263699;5253.001929633358;3683.37382644951;3823.445600387928;3823.789691566935;3825.3305671507374;4038.4749982502085;700.9474289428211;703.0930100170825;710.8009757267973;712.2877122877123;731.436793668872;693.1266604078897;748.76413929612;789.3606178994918;811.2982270336116;843.5992903370973;10781.299215550964;11037.558497660004;11381.544738210545;11564.306141631529;11945.70135746596;681.325487767107;755.3013462547121;755.5051441305953;779.3687113438133;815.2906970935205;5208.166693329049;5430.5927055471;5468.671879687152;5471.945280547223;5492.5605951523785;126978;127362.72637273694;127877;131561;137120;113043.43478282556;114050;114193;115127.42436287932;119038.40480797713;89831.55084224537;98512.50743746379;98758.99999999856;100741.49629251807;102031.4898425503;10012.44931528773;10470.790584188122;10485;10585.629502967358;10597.523111460157;59481.40518594759;59954.100688488834;60287.397126028176;60859.69570152121;61769.691151545754;84666.57666711627;85110;85902.57048714717;88369;88696;13187.274699891597;13249.470021199044;13293.534726284533;13495.25776082325;13516.72966540644;14539.27303634793;14552.781708274173;14631.341589628279;15210.08739475635;15766.842331576538;13674.93162534181;13735.862641373458;14266.358013889192;14731;14880.330385132478;13152.21086734799;13406.061575689608;13555.186688798703;13647.181169129884;14188.787168192692;12278.631641050782;12841.422136003715;12900.29048402347;12929.935350323189;13050.934745326213;11694.70763230892;11897.762044759229;12039.699007524881;12224.938875305566;12662.493500260254;10619.787604247716;11982.940085299517;12024.939875300568;12033.578824741093;12380 04:35:20 INFO - PROCESS | 23720 | __end_tp_report 04:35:20 INFO - PROCESS | 23720 | __start_cc_report 04:35:20 INFO - PROCESS | 23720 | _x_x_mozilla_cycle_collect,1201 04:35:20 INFO - PROCESS | 23720 | __end_cc_report 04:35:20 INFO - PROCESS | 23720 | __startTimestamp1474112119996__endTimestamp 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | ------- Summary: start ------- 04:35:20 INFO - PROCESS | 23720 | Number of tests: 6 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | [#0] dojo.html Cycles:165 Average:5613.58 Median:5838.30 stddev:641.00 (11.0%) stddev-sans-first:614.62 04:35:20 INFO - PROCESS | 23720 | Values: 3210.4 3214.6 3240.1 3279.7 3312.2 5609.6 5692.2 5710.1 5713.9 5721.9 5606.9 5659.8 5707.1 5712.7 5727.3 4951.1 4976.8 5175.0 5192.1 5230.0 5112.3 5149.3 5164.1 5166.3 5170.5 5126.2 5153.5 5160.6 5188.5 5197.8 5260.6 5265.6 5267.9 5273.4 5306.7 4981.3 5040.1 5120.0 5149.7 5156.7 5505.7 5509.2 5521.3 5541.0 5554.9 6007.8 6032.8 6034.5 6042.8 6052.9 5992.0 5994.6 6011.0 6043.2 6057.7 5640.6 5661.3 5683.5 5703.0 5715.3 6169.7 6212.7 6217.7 6218.0 6228.7 5789.0 5902.3 5947.3 5957.3 6032.9 6085.5 6093.0 6095.8 6096.6 6099.3 6033.8 6055.8 6056.8 6066.6 6072.1 5859.8 5908.6 5960.3 5964.9 6047.3 4608.4 4611.6 4612.7 4623.8 4634.8 4103.3 4115.1 4119.3 4128.5 4145.5 5593.0 5650.2 5708.3 5732.2 5779.9 6135.3 6138.9 6144.0 6145.5 6154.1 5792.4 5808.6 5810.4 5828.3 5838.3 5012.5 5048.1 5064.6 5071.1 5121.9 6230.7 6241.0 6241.7 6250.4 6276.9 6043.4 6089.2 6095.5 6097.2 6098.1 6101.8 6110.1 6114.5 6146.1 6265.2 5942.4 5950.0 5952.7 5958.4 5999.0 5953.9 6000.7 6002.8 6004.1 6042.7 5719.7 5839.6 5880.7 5881.8 5907.7 5974.8 5976.1 5978.3 5980.3 5988.7 5906.9 5943.4 5947.0 5949.5 5951.3 5836.4 5855.4 5871.5 5883.9 5932.2 5782.6 5791.0 5800.6 5807.5 5833.4 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | [#1] ext.html Cycles:180 Average:66223.63 Median:219224.70 stddev:132810.27 (60.6%) stddev-sans-first:133091.31 04:35:20 INFO - PROCESS | 23720 | Values: 551.3 604.1 614.9 630.8 655.2 36939.3 37778.6 37814.8 37852.4 46295.0 44287.3 49062.3 49110.5 49180.0 49267.0 9270.3 9427.2 9428.8 9429.2 9431.7 8564.9 9309.0 9408.3 9411.5 10888.3 11102.9 11457.3 11462.4 11469.9 11476.1 11233.0 11307.7 11310.5 11317.0 11359.1 11196.3 11266.2 11268.5 11277.0 11284.3 45885.8 46516.3 46523.5 46623.8 46657.8 3905.8 3912.5 3942.5 3980.6 4040.7 36195.7 36212.5 36381.0 36789.1 37098.1 4600.9 4670.9 4683.0 4697.0 4705.4 388965.0 395843.0 398012.0 398161.0 398620.0 66332.7 68128.3 68235.7 68263.7 68293.0 39829.4 40460.8 40977.2 42871.8 71272.3 19536.0 19743.2 19743.7 19745.9 19763.0 15043.8 15209.2 15220.0 15221.0 15247.6 18689.3 20318.3 20887.3 21047.2 21164.6 7304.0 7307.0 7405.4 7522.7 7681.1 978.3 1132.7 1149.5 1176.7 1202.9 35607.6 36112.1 36271.3 36322.0 36378.6 1187.3 1191.4 1192.3 1213.1 1270.0 17857.6 18523.0 18578.5 18643.4 18644.8 638660.0 645564.0 645802.0 645869.0 646921.0 66093.7 66835.0 66845.0 66863.3 66958.3 1379.2 1462.2 1485.8 1498.6 1518.1 34356.0 36454.5 36576.5 36611.0 36787.8 56961.0 57722.7 57740.1 57808.7 57812.4 420704.0 420757.0 421090.0 421753.0 423149.0 30528.5 31776.8 32093.5 33136.8 33386.8 53370.9 53375.0 53777.7 53817.7 54692.2 43736.6 44305.0 45788.3 46737.8 47771.0 44077.6 45029.8 45217.0 45532.5 46678.5 45365.3 46151.8 46385.8 46532.0 46776.3 36493.8 39287.0 39760.4 39965.6 40512.4 29891.1 30494.2 30564.2 30651.7 30829.5 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | [#2] jquery.html Cycles:180 Average:30359.33 Median:24177.96 stddev:82402.35 (340.8%) stddev-sans-first:82602.80 04:35:20 INFO - PROCESS | 23720 | Values: 396.8 414.5 428.8 431.9 441.4 15369.0 20318.7 20531.1 21052.7 21684.9 20670.1 20726.1 20843.6 20861.3 21141.8 5849.1 5890.7 5950.9 5970.4 6023.5 5941.1 5954.5 5987.2 6001.3 6003.7 5799.8 5892.8 5957.6 5966.3 5974.1 5850.7 5885.1 5962.9 5963.7 5966.1 5140.9 5238.8 5240.1 5243.9 5246.2 23814.2 24541.8 24561.9 24636.8 25120.6 2817.8 3217.8 3226.2 3256.3 3332.3 10464.1 15330.2 15373.8 15387.4 15488.0 2044.3 2142.3 2160.9 2179.0 2215.6 86889.6 88037.1 89178.1 90347.5 91313.5 11873.4 11884.5 12061.6 12110.2 12266.3 16758.6 16918.7 16933.3 16935.2 16977.7 8765.7 8839.5 8897.3 8960.6 9234.6 9074.3 9083.1 9093.6 9215.1 9310.8 4795.3 5006.2 5043.6 5181.7 5308.7 2035.3 2080.6 2117.7 2130.7 2131.8 800.7 1023.9 1071.2 1121.8 1202.7 20575.3 20931.0 20996.8 21046.5 21134.6 1097.9 1106.6 1111.7 1120.3 1129.9 11030.6 11764.5 11909.6 11918.9 11955.5 485154.0 495181.0 497017.0 507790.0 508863.0 93994.5 94730.5 96779.5 97207.0 102554.0 1059.3 1100.5 1112.6 1117.1 1117.9 18125.7 18586.0 18902.3 18996.0 19198.5 32764.8 34177.5 41685.8 43267.0 45017.5 63186.4 63261.4 63802.7 63837.0 65011.3 8418.4 8437.0 8489.9 8709.2 8710.8 12459.5 13593.9 13898.7 14356.1 14731.3 12229.2 12368.0 12465.3 12812.8 13068.5 12208.6 12548.2 12581.7 12767.5 12872.9 12105.5 12548.7 12578.7 12667.4 12936.2 8351.1 8580.4 9161.8 9164.7 9419.9 11773.2 11880.5 12849.9 12922.4 13047.9 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | [#3] mootools.html Cycles:180 Average:6183.83 Median:473.32 stddev:3527.57 (745.3%) stddev-sans-first:3520.12 04:35:20 INFO - PROCESS | 23720 | Values: 1528.2 1573.2 1700.0 1797.7 1840.6 8814.3 9480.1 9483.7 9487.0 9493.5 8179.2 8398.5 8398.9 8505.4 9273.7 2404.3 2489.6 2492.1 2493.5 2502.9 2472.5 2490.7 2537.9 2543.5 2552.5 2469.7 2487.6 2503.7 2538.1 2550.9 2398.7 2417.4 2421.0 2430.9 2482.4 2414.4 2438.1 2452.7 2456.0 2462.0 9368.9 9441.4 9461.4 9464.6 9496.2 5427.5 5600.7 5623.5 5626.7 5632.9 3700.8 3741.0 3749.2 3765.4 3776.9 1151.7 1178.3 1191.6 1195.8 1202.6 13138.7 13188.7 13192.3 13200.5 13201.2 4765.1 4816.9 4837.2 4846.7 5006.1 11010.3 11016.1 11018.1 11028.7 11035.8 5549.6 5551.2 5551.9 5557.3 5562.4 4532.3 5066.3 5076.9 5101.4 5128.4 4253.7 4283.5 4316.6 4341.0 4362.9 1309.4 1344.9 1350.2 1358.6 1392.9 999.6 1007.3 1016.0 1040.0 1056.2 6219.8 6303.7 6380.2 6426.5 6563.0 468.6 472.8 473.9 483.8 508.1 4511.1 4557.9 4570.4 4588.6 4783.2 11450.0 11677.1 11768.1 11783.7 11786.1 11076.1 11569.0 11724.7 11729.9 11736.1 8555.7 8744.1 8774.5 8799.3 8812.6 6526.5 6556.6 6579.2 6602.8 6759.7 10367.8 10788.5 10797.5 10871.2 10965.3 10918.9 11176.7 11186.4 11204.6 11304.9 8143.6 8306.0 8348.9 8427.6 8434.0 8992.0 9042.5 9082.6 9082.8 9094.4 8066.3 8080.5 8191.0 8300.2 8347.0 7858.6 7884.0 7925.0 8012.2 8286.0 7337.2 7349.5 7396.5 7403.6 7495.4 5471.8 5602.9 5778.0 5783.3 5815.0 5543.5 5568.2 5763.8 5941.4 5962.4 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | [#4] prototype.html Cycles:180 Average:4808.37 Median:5461.05 stddev:1426.10 (26.1%) stddev-sans-first:1390.06 04:35:20 INFO - PROCESS | 23720 | Values: 337.3 338.8 340.0 341.6 347.7 5441.9 5499.8 5502.1 5531.0 5532.8 5467.3 5467.6 5468.5 5473.2 5474.7 4058.1 4254.2 4321.5 4355.8 4364.9 4283.5 4288.7 4295.5 4314.7 4329.2 4299.9 4304.2 4308.0 4313.2 4342.3 4059.1 4160.4 4253.7 4282.4 4294.0 3758.8 3764.5 3778.3 3783.1 3787.3 5333.1 5340.7 5354.2 5363.8 5381.5 4550.8 4734.3 4796.4 4822.3 4825.9 5684.5 5691.3 5696.2 5697.6 5701.2 4017.4 4057.9 4090.6 4093.2 4107.0 5817.5 5885.6 5893.3 5899.2 5913.8 4124.2 4126.2 4128.2 4142.5 4145.1 4194.9 4197.9 4202.2 4208.8 4213.6 4633.4 4786.3 4794.7 4801.3 4808.8 5762.5 5766.4 5768.7 5771.2 5774.7 1436.9 1451.6 1453.9 1474.0 1474.6 940.2 943.6 953.7 955.1 963.8 4960.0 4976.7 4981.5 5022.2 5037.0 5243.1 5258.4 5269.7 5311.0 5336.0 4726.6 4889.9 5029.6 5169.3 5454.8 3065.1 3134.7 3137.5 3140.8 3141.6 6117.8 6128.3 6130.8 6137.4 6143.0 5889.3 5949.3 6062.2 6098.8 6110.5 6038.4 6073.4 6114.3 6136.5 6150.7 5678.6 5919.4 5934.5 5935.4 5972.9 5913.8 5916.2 5932.6 5940.1 5948.1 6119.3 6142.1 6193.1 6200.5 6201.2 4193.3 4199.9 4236.5 4242.8 4246.3 5953.4 5963.0 5965.2 5969.4 6045.9 5751.0 5876.0 5896.5 5897.2 5908.6 5755.8 5786.4 5797.7 5821.7 5827.8 5795.4 5801.6 5815.2 5816.0 5822.2 5578.8 5785.7 5852.8 5863.5 5883.9 5698.6 5711.8 5715.8 5719.0 5730.8 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - PROCESS | 23720 | [#5] yui.html Cycles:180 Average:23088.03 Median:23100.92 stddev:33988.47 (147.1%) stddev-sans-first:34042.46 04:35:20 INFO - PROCESS | 23720 | Values: 757.4 781.3 807.4 872.4 886.8 16161.4 16446.9 16551.4 16696.7 16810.7 16363.8 16373.9 16400.9 16428.5 16618.3 728.1 744.1 746.4 886.3 904.4 738.1 870.4 896.1 897.4 910.2 955.8 957.5 960.6 960.9 961.3 954.5 954.7 955.0 955.5 955.9 565.7 669.0 687.2 689.0 694.4 16411.1 16457.2 16457.8 16467.0 16504.3 14830.0 15216.4 15443.5 15588.1 15653.8 14049.7 14145.9 14153.8 14326.3 14451.3 4440.8 4451.6 4496.6 4658.1 4676.0 67486.7 70404.3 82297.0 82451.6 83157.0 12095.9 12209.8 12259.1 12274.4 12455.5 21943.8 22163.8 22177.1 23054.5 23147.3 8950.1 9038.4 9163.2 9195.1 9240.6 4887.1 5149.3 5207.2 5221.9 5253.0 3683.4 3823.4 3823.8 3825.3 4038.5 700.9 703.1 710.8 712.3 731.4 693.1 748.8 789.4 811.3 843.6 10781.3 11037.6 11381.5 11564.3 11945.7 681.3 755.3 755.5 779.4 815.3 5208.2 5430.6 5468.7 5471.9 5492.6 126978.0 127362.7 127877.0 131561.0 137120.0 113043.4 114050.0 114193.0 115127.4 119038.4 89831.6 98512.5 98759.0 100741.5 102031.5 10012.4 10470.8 10485.0 10585.6 10597.5 59481.4 59954.1 60287.4 60859.7 61769.7 84666.6 85110.0 85902.6 88369.0 88696.0 13187.3 13249.5 13293.5 13495.3 13516.7 14539.3 14552.8 14631.3 15210.1 15766.8 13674.9 13735.9 14266.4 14731.0 14880.3 13152.2 13406.1 13555.2 13647.2 14188.8 12278.6 12841.4 12900.3 12929.9 13050.9 11694.7 11897.8 12039.7 12224.9 12662.5 10619.8 11982.9 12024.9 12033.6 12380.0 04:35:20 INFO - PROCESS | 23720 | -------- Summary: end -------- 04:35:20 INFO - PROCESS | 23720 | 04:35:20 INFO - TEST-INFO | 23720: exit 0 04:35:20 INFO - TEST-OK | dromaeo_css | took 1113818ms 04:35:20 INFO - TEST-START | kraken 04:35:20 INFO - operating with platform_type : linux_ 04:35:20 INFO - Initialising browser for kraken test... 04:35:20 INFO - TEST-INFO | started process 23930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9oDKa_/profile http://localhost:59835/getInfo.html) 04:35:24 INFO - PROCESS | 23930 | __metrics Screen width/height:1600/1200 04:35:24 INFO - PROCESS | 23930 | colorDepth:24 04:35:24 INFO - PROCESS | 23930 | Browser inner width/height: 1024/697 04:35:24 INFO - PROCESS | 23930 | __metrics 04:35:25 INFO - PROCESS | 23930 | 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:35:25 INFO - PROCESS | 23930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:35:26 INFO - TEST-INFO | 23930: exit 0 04:35:26 INFO - Browser initialized. 04:35:26 INFO - Running cycle 1/1 for kraken test... 04:35:26 INFO - TEST-INFO | started process 24020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9oDKa_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:38 INFO - PROCESS | 24020 | RSS: Main: 146276352 04:35:38 INFO - PROCESS | 24020 | 04:35:40 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59835/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:41 INFO - PROCESS | 24020 | RSS: Main: 200699904 04:35:41 INFO - PROCESS | 24020 | 04:35:44 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59835/tests/kraken/driver.html?testName=audio-dft) 04:35:44 INFO - PROCESS | 24020 | RSS: Main: 184905728 04:35:44 INFO - PROCESS | 24020 | 04:35:47 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59835/tests/kraken/driver.html?testName=audio-fft) 04:35:48 INFO - PROCESS | 24020 | RSS: Main: 276701184 04:35:48 INFO - PROCESS | 24020 | 04:35:50 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59835/tests/kraken/driver.html?testName=audio-oscillator) 04:35:51 INFO - PROCESS | 24020 | RSS: Main: 181977088 04:35:51 INFO - PROCESS | 24020 | 04:35:53 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59835/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:35:53 INFO - PROCESS | 24020 | RSS: Main: 180862976 04:35:53 INFO - PROCESS | 24020 | 04:35:56 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59835/tests/kraken/driver.html?testName=imaging-darkroom) 04:35:57 INFO - PROCESS | 24020 | RSS: Main: 216637440 04:35:57 INFO - PROCESS | 24020 | 04:36:00 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59835/tests/kraken/driver.html?testName=imaging-desaturate) 04:36:01 INFO - PROCESS | 24020 | RSS: Main: 215474176 04:36:01 INFO - PROCESS | 24020 | 04:36:04 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59835/tests/kraken/driver.html?testName=json-parse-financial) 04:36:04 INFO - PROCESS | 24020 | RSS: Main: 215805952 04:36:04 INFO - PROCESS | 24020 | 04:36:06 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59835/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:36:06 INFO - PROCESS | 24020 | RSS: Main: 177676288 04:36:06 INFO - PROCESS | 24020 | 04:36:08 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:36:09 INFO - PROCESS | 24020 | RSS: Main: 184950784 04:36:09 INFO - PROCESS | 24020 | 04:36:11 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:11 INFO - PROCESS | 24020 | RSS: Main: 187330560 04:36:11 INFO - PROCESS | 24020 | 04:36:14 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:14 INFO - PROCESS | 24020 | RSS: Main: 182169600 04:36:14 INFO - PROCESS | 24020 | 04:36:17 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:18 INFO - PROCESS | 24020 | RSS: Main: 184905728 04:36:18 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | Cycle 1(1): loaded http://localhost:59835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59835/tests/kraken/driver.html?testName=ai-astar) 04:36:19 INFO - PROCESS | 24020 | RSS: Main: 236023808 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | __start_tp_report 04:36:19 INFO - PROCESS | 24020 | _x_x_mozilla_page_load 04:36:19 INFO - PROCESS | 24020 | _x_x_mozilla_page_load_details 04:36:19 INFO - PROCESS | 24020 | |i|pagename|runs| 04:36:19 INFO - PROCESS | 24020 | |0;ai-astar;110;110;119;109;109;108;107;109;109;109 04:36:19 INFO - PROCESS | 24020 | |1;audio-beat-detection;156;117;157;164;116;149;161;118;184;117 04:36:19 INFO - PROCESS | 24020 | |2;audio-dft;161;160;159;160;159;160;160;160;159;159 04:36:19 INFO - PROCESS | 24020 | |3;audio-fft;76;75;76;173;75;75;74;76;177;75 04:36:19 INFO - PROCESS | 24020 | |4;audio-oscillator;85;83;149;83;83;83;140;83;84;151 04:36:19 INFO - PROCESS | 24020 | |5;imaging-gaussian-blur;116;117;116;116;117;116;117;116;117;116 04:36:19 INFO - PROCESS | 24020 | |6;imaging-darkroom;146;146;146;146;147;146;146;147;146;146 04:36:19 INFO - PROCESS | 24020 | |7;imaging-desaturate;95;95;96;94;96;95;95;95;95;95 04:36:19 INFO - PROCESS | 24020 | |8;json-parse-financial;50;49;49;51;50;49;60;50;50;49 04:36:19 INFO - PROCESS | 24020 | |9;json-stringify-tinderbox;62;62;61;59;62;61;60;61;60;60 04:36:19 INFO - PROCESS | 24020 | |10;stanford-crypto-aes;72;72;73;72;76;72;73;73;73;81 04:36:19 INFO - PROCESS | 24020 | |11;stanford-crypto-ccm;129;116;117;116;117;115;116;114;149;117 04:36:19 INFO - PROCESS | 24020 | |12;stanford-crypto-pbkdf2;154;152;161;154;155;152;153;153;152;152 04:36:19 INFO - PROCESS | 24020 | |13;stanford-crypto-sha256-iterative;62;65;71;61;62;64;67;62;62;61 04:36:19 INFO - PROCESS | 24020 | __end_tp_report 04:36:19 INFO - PROCESS | 24020 | __start_cc_report 04:36:19 INFO - PROCESS | 24020 | _x_x_mozilla_cycle_collect,1695 04:36:19 INFO - PROCESS | 24020 | __end_cc_report 04:36:19 INFO - PROCESS | 24020 | __startTimestamp1474112179956__endTimestamp 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | ------- Summary: start ------- 04:36:19 INFO - PROCESS | 24020 | Number of tests: 14 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#0] ai-astar Cycles:10 Average:109.90 Median:109.00 stddev:3.31 (3.0%) stddev-sans-first:3.52 04:36:19 INFO - PROCESS | 24020 | Values: 110.0 110.0 119.0 109.0 109.0 108.0 107.0 109.0 109.0 109.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:156.50 stddev:24.82 (15.9%) stddev-sans-first:25.94 04:36:19 INFO - PROCESS | 24020 | Values: 156.0 117.0 157.0 164.0 116.0 149.0 161.0 118.0 184.0 117.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.53 04:36:19 INFO - PROCESS | 24020 | Values: 161.0 160.0 159.0 160.0 159.0 160.0 160.0 160.0 159.0 159.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#3] audio-fft Cycles:10 Average:95.20 Median:75.00 stddev:42.07 (56.1%) stddev-sans-first:44.05 04:36:19 INFO - PROCESS | 24020 | Values: 76.0 75.0 76.0 173.0 75.0 75.0 74.0 76.0 177.0 75.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:83.00 stddev:30.68 (37.0%) stddev-sans-first:31.89 04:36:19 INFO - PROCESS | 24020 | Values: 85.0 83.0 149.0 83.0 83.0 83.0 140.0 83.0 84.0 151.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:36:19 INFO - PROCESS | 24020 | Values: 116.0 117.0 116.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 04:36:19 INFO - PROCESS | 24020 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:36:19 INFO - PROCESS | 24020 | Values: 95.0 95.0 96.0 94.0 96.0 95.0 95.0 95.0 95.0 95.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.00 stddev:3.33 (6.7%) stddev-sans-first:3.53 04:36:19 INFO - PROCESS | 24020 | Values: 50.0 49.0 49.0 51.0 50.0 49.0 60.0 50.0 50.0 49.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:1.03 (1.7%) stddev-sans-first:1.00 04:36:19 INFO - PROCESS | 24020 | Values: 62.0 62.0 61.0 59.0 62.0 61.0 60.0 61.0 60.0 60.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:2.83 (3.9%) stddev-sans-first:2.93 04:36:19 INFO - PROCESS | 24020 | Values: 72.0 72.0 73.0 72.0 76.0 72.0 73.0 73.0 73.0 81.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:117.00 stddev:10.82 (9.3%) stddev-sans-first:11.05 04:36:19 INFO - PROCESS | 24020 | Values: 129.0 116.0 117.0 116.0 117.0 115.0 116.0 114.0 149.0 117.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.80 Median:153.50 stddev:2.74 (1.8%) stddev-sans-first:2.91 04:36:19 INFO - PROCESS | 24020 | Values: 154.0 152.0 161.0 154.0 155.0 152.0 153.0 153.0 152.0 152.0 04:36:19 INFO - PROCESS | 24020 | 04:36:19 INFO - PROCESS | 24020 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:63.00 stddev:3.20 (5.1%) stddev-sans-first:3.33 04:36:19 INFO - PROCESS | 24020 | Values: 62.0 65.0 71.0 61.0 62.0 64.0 67.0 62.0 62.0 61.0 04:36:19 INFO - PROCESS | 24020 | -------- Summary: end -------- 04:36:19 INFO - PROCESS | 24020 | 04:36:20 INFO - TEST-INFO | 24020: exit 0 04:36:20 INFO - TEST-OK | kraken | took 60156ms 04:36:20 INFO - SUITE-END | took 1173s 04:36:21 INFO - Completed test suite (00:19:34) 04:36:21 INFO - javascript benchmark 04:36:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.4221240176767, 3214.6463888972226, 3240.1089700332413, 3279.737620990321, 3312.1719570107475, 5609.635373700712, 5692.2030915671785, 5710.057840456319, 5713.942860571393, 5721.942780572193, 5606.915896261559, 5659.773609055643, 5707.058335374669, 5712.657240565569, 5727.341355744091, 4951.084049450863, 4976.825811096613, 5175.0, 5192.1173400522, 5230.0, 5112.335396398463, 5149.30484384607, 5164.147915593922, 5166.276721259027, 5170.457102004292, 5126.154184559562, 5153.458886816887, 5160.587153027767, 5188.507091826271, 5197.818076367328, 5260.579153667697, 5265.631405801595, 5267.894642107136, 5273.419923808378, 5306.734663266821, 4981.252812078181, 5040.067587496325, 5119.974400127976, 5149.6910185389, 5156.690598564098, 5505.6696598204235, 5509.173623956399, 5521.254630624854, 5541.030319694038, 5554.86112847178, 6007.789727359522, 6032.849178770522, 6034.456898879122, 6042.788502402394, 6052.878942421127, 5992.041273396236, 5994.610350327258, 6011.038233882601, 6043.153958445821, 6057.727402266909, 5640.576956728261, 5661.320641523043, 5683.516901063374, 5702.971485142589, 5715.342735585419, 6169.691515424301, 6212.658303793335, 6217.6891155442045, 6217.9689101555105, 6228.6574238416415, 5789.0, 5902.321233058254, 5947.345791962881, 5957.344692083867, 6032.8793424132145, 6085.513158947274, 6093.0, 6095.817125486243, 6096.634201947897, 6099.298580663191, 6033.818985430444, 6055.818325450244, 6056.818295451144, 6066.636001839815, 6072.089186621954, 5859.794907178229, 5908.556858235649, 5960.344362120163, 5964.8807023860145, 6047.334793172748, 4608.423947006624, 4611.608013318838, 4612.677112602086, 4623.815047398134, 4634.83778067766, 4103.322951712994, 4115.074108325663, 4119.279126152911, 4128.545859955402, 4145.54399016108, 5592.972035139798, 5650.208970744099, 5708.257926469532, 5732.16883551891, 5779.8555036124435, 6135.294441139326, 6138.9095594588325, 6144.047672610753, 6145.539084568675, 6154.076888466676, 5792.362840087587, 5808.6224395414165, 5810.360860305363, 5828.3006039274715, 5838.299404071454, 5012.548870601663, 5048.091343558195, 5064.645474816727, 5071.137906555894, 5121.923171152435, 6230.688465576703, 6241.032639940817, 6241.7191226394925, 6250.374962503804, 6276.905846412307, 6043.395660433922, 6089.238845144357, 6095.451409373, 6097.237845269341, 6098.054801505774, 6101.7559297627595, 6110.083487476914, 6114.541409394402, 6146.078088286793, 6265.248170219421, 5942.405759424023, 5950.047992321208, 5952.702364881825, 5958.374370691015, 5999.0, 5953.910691339547, 6000.729967151401, 6002.759889604367, 6004.099385092097, 6042.697865106815, 5719.656820590778, 5839.649621022752, 5880.705964701662, 5881.823545293648, 5907.675077870758, 5974.820755377345, 5976.073708575178, 5978.312494063239, 5980.282366116094, 5988.730507127103, 5906.940930590811, 5943.405659434022, 5946.970265148647, 5949.464548190684, 5951.315598706205, 5836.358000619844, 5855.414458554111, 5871.500922421642, 5883.882322353615, 5932.199153114274, 5782.624129431573, 5790.971045144747, 5800.622959507618, 5807.535397168301, 5833.358330583547], "value": 5569.810549917945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.2640624765937, 604.0606856338394, 614.923134608174, 630.8328293002354, 655.2202878574493, 36939.261214775725, 37778.62221377785, 37814.81092594536, 37852.43221351682, 46295.0, 44287.335689964595, 49062.26406603895, 49110.50889491104, 49180.0, 49267.0, 9270.304727145458, 9427.151556359926, 9428.81142377152, 9429.198518125968, 9431.717048488556, 8564.87152692707, 9308.953455232715, 9408.29437792165, 9411.482368469731, 10888.346699198033, 11102.888971110266, 11457.25527840692, 11462.426878656033, 11469.885301147007, 11476.081913446946, 11233.0, 11307.660770176826, 11310.491027903681, 11316.999999999958, 11359.148063895242, 11196.272242304305, 11266.211365204439, 11268.549258029669, 11277.041451476634, 11284.266522676, 45885.770571147266, 46516.302255466195, 46523.5347646526, 46623.76688116538, 46657.766711166565, 3905.8242379092726, 3912.471816304791, 3942.526896772377, 3980.5820388859042, 4040.7270111210014, 36195.73685997965, 36212.50272617332, 36381.0, 36789.080272993124, 37098.07254818624, 4600.930986035212, 4670.859874203779, 4683.0, 4696.976515117437, 4705.435347758256, 388965.0, 395843.0, 398012.0, 398160.9999999971, 398620.0, 66332.66833665801, 68128.3187168122, 68235.65882170557, 68263.65868170727, 68293.0, 39829.402558961636, 40460.79769601133, 40977.1804563913, 42871.7856410716, 71272.2872771276, 19536.0, 19743.21027158926, 19743.703844442345, 19745.901270493556, 19763.0, 15043.849561504463, 15209.239538023054, 15220.0, 15221.0, 15247.61880952963, 18689.252429902925, 20318.28885988983, 20887.26894558683, 21047.158113675283, 21164.576708465745, 7304.0, 7306.96346518264, 7405.444591655647, 7522.736704215326, 7681.116671582839, 978.3005151316843, 1132.7224829916559, 1149.5057125436142, 1176.688177632928, 1202.9278243305255, 35607.64392356095, 36112.12486314367, 36271.27457450836, 36322.00228987431, 36378.636213637525, 1187.2757617853104, 1191.368574655434, 1192.272713644676, 1213.1023042948332, 1270.0030476076236, 17857.642847143245, 18523.0, 18578.535536611424, 18643.44069677912, 18644.81355186458, 638660.0, 645564.0, 645802.0, 645869.0, 646921.0, 66093.669531653, 66835.0, 66844.9946501596, 66863.33136668669, 66958.33041669619, 1379.1587131849765, 1462.1884853905972, 1485.769705695619, 1498.6328349554647, 1518.119490695394, 34356.0, 36454.51781860975, 36576.45135323025, 36611.0, 36787.816060920595, 56961.0, 57722.711386442796, 57740.13389799072, 57808.71095644495, 57812.421875782384, 420704.0, 420757.0, 421090.0, 421753.0, 423149.0, 30528.542071868495, 31776.841115794272, 32093.518597221526, 33136.834315828266, 33386.833065834515, 53370.932581348934, 53375.0, 53777.731111345754, 53817.73091134519, 54692.17961730657, 43736.5669329127, 44305.0, 45788.31317530173, 46737.76631116823, 47771.0, 44077.55922440735, 45029.774851126844, 45217.0, 45532.54467455283, 46678.53321466742, 45365.31952020656, 46151.76924115358, 46385.76807115943, 46532.0, 46776.298355525374, 36493.81753091217, 39287.0, 39760.40359394669, 39965.60034399735, 40512.3923141159, 29891.12569851869, 30494.151491844477, 30564.235894102825, 30651.693483064882, 30829.537556936215], "value": 22152.20368471614, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.7678907838914, 414.450852620278, 428.8091799149379, 431.8833914842991, 441.41071669321457, 15369.0, 20318.695219571717, 20531.076101575447, 21052.684209736864, 21684.89157554214, 20670.06984685689, 20726.067326970286, 20843.583128337425, 20861.26985555506, 21141.788582114175, 5849.064149736043, 5890.705464726757, 5950.9107363389585, 5970.402959704038, 6023.518118550505, 5941.079132734411, 5954.4640982311585, 5987.191729116557, 6001.279846418414, 6003.6697981611, 5799.768009279624, 5892.793752218652, 5957.553183511254, 5966.254218222723, 5974.074018527135, 5850.736716847752, 5885.087811389241, 5962.8807423851495, 5963.671998040106, 5966.105084237356, 5140.948590514085, 5238.764255608507, 5240.082985477527, 5243.895122097575, 5246.213068039787, 23814.166504172445, 24541.754582454305, 24561.89471473788, 24636.753632463624, 25120.623190652153, 2817.8168419052695, 3217.8069315841126, 3226.2095786532213, 3256.2998955224502, 3332.266901281714, 10464.110550603207, 15330.15684137372, 15373.769393459108, 15387.384504619802, 15488.0, 2044.2845004248543, 2142.3358758784825, 2160.902759375832, 2179.030331502466, 2215.6233440315023, 86889.56555217183, 88037.11962880417, 89178.10821891762, 90347.54826225892, 91313.54343228308, 11873.406329683481, 11884.465199066062, 12061.577844775391, 12110.152289339838, 12266.26402415858, 16758.58103547421, 16918.661626767644, 16933.322667093424, 16935.153242337834, 16977.660446790997, 8765.693200737944, 8839.46963182211, 8897.288216942628, 8960.596773145224, 9234.630614775333, 9074.319426043074, 9083.137101975302, 9093.590788414538, 9215.124563166488, 9310.767230819283, 4795.304680821261, 5006.249062640633, 5043.5712964398435, 5181.740912954338, 5308.681479111266, 2035.3359691627809, 2080.6046851098545, 2117.7035215069595, 2130.712353832244, 2131.825537951281, 800.6997375984006, 1023.9334443261164, 1071.196602548089, 1121.7532142928542, 1202.6933132051272, 20575.279865204648, 20931.0, 20996.845173515605, 21046.47383815417, 21134.577308453743, 1097.9451027448595, 1106.6126855600637, 1111.699841042914, 1120.260627985542, 1129.8870112988636, 11030.613928512463, 11764.470598822902, 11909.642710718692, 11918.940405297919, 11955.521779128738, 485154.0, 495181.0, 497017.0, 507790.0, 508863.0, 93994.53002735079, 94730.52634736782, 96779.51610241903, 97207.0, 102554.0, 1059.3008614314515, 1100.4882729530646, 1112.6050252160528, 1117.0672488472055, 1117.9105671546256, 18125.728114078036, 18586.0, 18902.338418155297, 18996.0, 19198.520036999187, 32764.836175818968, 34177.48733769045, 41685.79157104195, 43267.0, 45017.54982450134, 63186.36813631805, 63261.367386325546, 63802.68098659477, 63837.04244436244, 65011.34988650242, 8418.410711250399, 8436.95781521113, 8489.87265191035, 8709.172628600272, 8710.782230444036, 12459.501619935101, 13593.86406135926, 13898.72202555923, 14356.06685565435, 14731.26343682833, 12229.205101668364, 12368.0, 12465.314407707301, 12812.807807882702, 13068.477260909458, 12208.572699955459, 12548.24710517372, 12581.685457863627, 12767.48786189558, 12872.871271287167, 12105.515779368727, 12548.749025019632, 12578.748425031632, 12667.429965651752, 12936.159149655243, 8351.123132071045, 8580.442271252348, 9161.816763664556, 9164.6792362267, 9419.858702119336, 11773.17587768848, 11880.46537905779, 12849.935750321189, 12922.353882306035, 13047.869521305045], "value": 9943.767145121754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.2129703202852, 1573.2055312067407, 1699.957501062474, 1797.6764182447155, 1840.5858681796597, 8814.25078868296, 9480.099390557903, 9483.668071617494, 9486.952565237172, 9493.52532373382, 8179.182081791818, 8398.45410048348, 8398.91601083989, 8505.404621676485, 9273.72178834636, 2404.290734233405, 2489.576771948773, 2492.1277552856445, 2493.501299740059, 2502.9249122526353, 2472.505498900227, 2490.7384724603835, 2537.949241015179, 2543.5421624107653, 2552.4512229870575, 2469.7283298837206, 2487.639292302615, 2503.7245902950663, 2538.060917460533, 2550.885210165547, 2398.7121545414575, 2417.3835671903553, 2420.9636855447184, 2430.9149179778624, 2482.391814005562, 2414.408469924862, 2438.0979037755965, 2452.6934133233344, 2455.99304285244, 2461.95076098477, 9368.859467107999, 9441.433513989183, 9461.38500997433, 9464.621415143385, 9496.240300775922, 5427.511523962862, 5600.691961942091, 5623.465770751813, 5626.7186640668215, 5632.887342253132, 3700.8149592520535, 3741.0, 3749.15643980105, 3765.435184722286, 3776.8866933992026, 1151.6890439581266, 1178.281248438452, 1191.6305945157028, 1195.7967145585185, 1202.5911190195288, 13138.671533211651, 13188.6702832429, 13192.340382980812, 13200.471981120743, 13201.207927524283, 4765.142274390643, 4816.903661926742, 4837.2260438329695, 4846.660733748604, 5006.123928312531, 11010.339379637247, 11016.11871050314, 11018.1185505161, 11028.724281893017, 11035.83446248307, 5549.5837812164245, 5551.2228288039705, 5551.861203469945, 5557.305336832896, 5562.360328562268, 4532.252178390595, 5066.265391518209, 5076.949230507722, 5101.362329708786, 5128.384593848762, 4253.723507971972, 4283.507396649363, 4316.589923957219, 4341.0, 4362.8909277267685, 1309.3518708239483, 1344.9462021519225, 1350.1763924006348, 1358.6331690443526, 1392.8537503562184, 999.5701848205196, 1007.279794946602, 1015.9898401016043, 1040.0171837613534, 1056.1603525197486, 6219.844503887439, 6303.747850086037, 6380.234371875312, 6426.453751431087, 6563.015547667888, 468.5689165967318, 472.75665001047713, 473.8791608139902, 483.84033269021137, 508.0905179728245, 4511.120331535385, 4557.886052848639, 4570.428696412949, 4588.6099681526775, 4783.234682450791, 11450.0, 11677.065834733201, 11768.117391195523, 11783.705407364885, 11786.116041296937, 11076.11391088711, 11569.01663358607, 11724.70688232801, 11729.941350293195, 11736.061115110771, 8555.743327700178, 8744.125587441205, 8774.517401542977, 8799.29605631548, 8812.603432845537, 6526.510511711545, 6556.606603603799, 6579.243387010555, 6602.8349291268105, 6759.729610815709, 10367.792644147226, 10788.460576970963, 10797.460126993461, 10871.23901326931, 10965.342079475256, 10918.890811091787, 11176.664700059011, 11186.440677966231, 11204.607838725606, 11304.943475282571, 8143.552104634302, 8306.003279606486, 8348.916510834813, 8427.620757065824, 8434.0, 8991.955040224757, 9042.502662353634, 9082.636694532144, 9082.818343633222, 9094.408863424122, 8066.274035336848, 8080.474769139987, 8190.959045204736, 8300.169983001651, 8346.958265208636, 7858.6070696466095, 7884.0, 7925.0, 8012.198780121941, 8286.047104582809, 7337.156227033746, 7349.522281051714, 7396.519226250277, 7403.555786652818, 7495.40036797044, 5471.835844924659, 5602.943970560243, 5778.046622307273, 5783.334916484489, 5814.970925145347, 5543.52880005204, 5568.248286481436, 5763.769449221984, 5941.405859414024, 5962.433568810956], "value": 4865.297075306399, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.29337038903503, 338.7933360650003, 339.9877088991535, 341.55256613835894, 347.66450375387745, 5441.945580544193, 5499.835004949848, 5502.147167189087, 5531.032069387861, 5532.834014979547, 5467.261919640848, 5467.562594992401, 5468.48049435304, 5473.2063850741615, 5474.698891560963, 4058.086930440657, 4254.212970600433, 4321.524632290446, 4355.760433176174, 4364.89087772805, 4283.507396649394, 4288.678349123813, 4295.475106337252, 4314.697971142022, 4329.242382583051, 4299.935500967488, 4304.24675681756, 4307.956920430803, 4313.174870459854, 4342.261815491358, 4059.127287633158, 4160.399750156137, 4253.744775313491, 4282.42187304713, 4294.0, 3758.7932663703486, 3764.5106136202116, 3778.280882198593, 3783.1298801275584, 3787.3372159872188, 5333.120035194188, 5340.652857564285, 5354.223637572569, 5363.839084827422, 5381.515663590296, 4550.7724613769515, 4734.337192793012, 4796.424429068499, 4822.252550854588, 4825.855224343275, 5684.54523638108, 5691.288588926384, 5696.1740547620775, 5697.6296540724725, 5701.2018317435595, 4017.3773065174946, 4057.89855253621, 4090.5704900985265, 4093.16090201508, 4107.0, 5817.505512031483, 5885.5585831062845, 5893.322267939199, 5899.174115623816, 5913.7930172444185, 4124.247194942198, 4126.216018956388, 4128.236276288896, 4142.502899652061, 4145.129522800185, 4194.895127621834, 4197.853075142355, 4202.201581699528, 4208.789560522023, 4213.557576454489, 4633.4208223972, 4786.282057691375, 4794.688345257617, 4801.255805350176, 4808.783604737795, 5762.510186634099, 5766.365699773021, 5768.653880767168, 5771.163181338767, 5774.68239246837, 1436.9438462729886, 1451.6008097773197, 1453.8546145385376, 1473.977890331646, 1474.6165996840898, 940.1679513630486, 943.5518128888723, 953.6900507335143, 955.06880791228, 963.8409662405625, 4959.950400496021, 4976.701397916137, 4981.452040275567, 5022.2466630005065, 5037.0, 5243.056249874983, 5258.421573626898, 5269.706433479076, 5310.99091172676, 5335.973320133452, 4726.621870250372, 4889.853304400874, 5029.622778291643, 5169.327987361619, 5454.836354909359, 3065.126438964929, 3134.670859559759, 3137.51368537877, 3140.795848269901, 3141.575887255237, 6117.816465506042, 6128.3258841527395, 6130.754769809247, 6137.404274888561, 6143.0, 5889.293185671366, 5949.25634295713, 6062.242219722535, 6098.75604975796, 6110.541709371815, 6038.365971573009, 6073.39266073398, 6114.257436630539, 6136.478399336017, 6150.661713605795, 5678.608740858516, 5919.437653422918, 5934.495567876779, 5935.376785437467, 5972.8805423892145, 5913.793017244376, 5916.171735957056, 5932.555058370725, 5940.138679891305, 5948.10778383246, 6119.29769169681, 6142.078688196806, 6193.102000209854, 6200.534959877937, 6201.193844800147, 4193.287141185945, 4199.874003779892, 4236.4704411948505, 4242.830286788618, 4246.278132717383, 5953.434423729739, 5962.970185149046, 5965.164876917322, 5969.3732158122775, 6045.909311360245, 5751.009475073022, 5876.0, 5896.498797602252, 5897.201353587197, 5908.615939963888, 5755.769769209185, 5786.4213578643485, 5797.739101740347, 5821.7089145543405, 5827.76688932438, 5795.449432303924, 5801.593888427769, 5815.156802263733, 5816.0, 5822.214001109796, 5578.804741834017, 5785.652860828363, 5852.795152169653, 5863.530917526673, 5883.94116058834, 5698.601097923106, 5711.8000869969355, 5715.799947001835, 5719.0, 5730.828075157752], "value": 4353.45753437702, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.356247189889, 781.2773184804055, 807.370251204061, 872.3893274707705, 886.7738726624711, 16161.353545858166, 16446.91776541117, 16551.420700275492, 16696.666066678674, 16810.663786724257, 16363.83636163638, 16373.91813040933, 16400.917995410007, 16428.507144785646, 16618.33526658932, 728.0898876404494, 744.1033554566734, 746.4327111395352, 886.3042511628373, 904.3579058868212, 738.1253214940286, 870.4168207301124, 896.050186801992, 897.3673560140119, 910.1626503616689, 955.7562821480512, 957.459035644862, 960.5869476125264, 960.8943016268205, 961.322267801202, 954.5179684259405, 954.7040417470572, 955.0, 955.4697143085584, 955.8757361543024, 565.6577770448902, 669.0466085827725, 687.2097088348389, 688.9655517224119, 694.4305669351135, 16411.097389643564, 16457.177141142896, 16457.753133703005, 16467.0, 16504.33982640693, 14830.0, 15216.391344346213, 15443.459478918185, 15588.142652154127, 15653.843461565353, 14049.71900561983, 14145.929270353583, 14153.82169086586, 14326.283685815668, 14451.277436128257, 4440.81079194791, 4451.643868490513, 4496.572825581565, 4658.068386322749, 4676.041411510666, 67486.66256668685, 70404.29595704029, 82297.0, 82451.5877420615, 83157.0, 12095.879041209475, 12209.755804884031, 12259.141860069709, 12274.447649855778, 12455.501779928883, 21943.780562194173, 22163.77836221617, 22177.1129154832, 23054.538909221723, 23147.305580832603, 8950.104989501127, 9038.412503187272, 9163.221126204347, 9195.126462986005, 9240.5841737122, 4887.071456423339, 5149.304843846033, 5207.244949482304, 5221.869453263699, 5253.001929633358, 3683.37382644951, 3823.445600387928, 3823.789691566935, 3825.3305671507374, 4038.4749982502085, 700.9474289428211, 703.0930100170825, 710.8009757267973, 712.2877122877123, 731.436793668872, 693.1266604078897, 748.76413929612, 789.3606178994918, 811.2982270336116, 843.5992903370973, 10781.299215550964, 11037.558497660004, 11381.544738210545, 11564.306141631529, 11945.70135746596, 681.325487767107, 755.3013462547121, 755.5051441305953, 779.3687113438133, 815.2906970935205, 5208.166693329049, 5430.5927055471, 5468.671879687152, 5471.945280547223, 5492.5605951523785, 126978.0, 127362.72637273694, 127877.0, 131561.0, 137120.0, 113043.43478282556, 114050.0, 114193.0, 115127.42436287932, 119038.40480797713, 89831.55084224537, 98512.50743746379, 98758.99999999856, 100741.49629251807, 102031.4898425503, 10012.44931528773, 10470.790584188122, 10485.0, 10585.629502967358, 10597.523111460157, 59481.40518594759, 59954.100688488834, 60287.397126028176, 60859.69570152121, 61769.691151545754, 84666.57666711627, 85110.0, 85902.57048714717, 88369.0, 88696.0, 13187.274699891597, 13249.470021199044, 13293.534726284533, 13495.25776082325, 13516.72966540644, 14539.27303634793, 14552.781708274173, 14631.341589628279, 15210.08739475635, 15766.842331576538, 13674.93162534181, 13735.862641373458, 14266.358013889192, 14731.0, 14880.330385132478, 13152.21086734799, 13406.061575689608, 13555.186688798703, 13647.181169129884, 14188.787168192692, 12278.631641050782, 12841.422136003715, 12900.29048402347, 12929.935350323189, 13050.934745326213, 11694.70763230892, 11897.762044759229, 12039.699007524881, 12224.938875305566, 12662.493500260254, 10619.787604247716, 11982.940085299517, 12024.939875300568, 12033.578824741093, 12380.0], "value": 8219.477598267407, "unit": "score"}], "name": "dromaeo_css", "value": 7731.677171993376, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 119.0, 109.0, 109.0, 108.0, 107.0, 109.0, 109.0, 109.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 117.0, 157.0, 164.0, 116.0, 149.0, 161.0, 118.0, 184.0, 117.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 160.0, 159.0, 160.0, 160.0, 160.0, 159.0, 159.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 173.0, 75.0, 75.0, 74.0, 76.0, 177.0, 75.0], "value": 95.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 149.0, 83.0, 83.0, 83.0, 140.0, 83.0, 84.0, 151.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 51.0, 50.0, 49.0, 60.0, 50.0, 50.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 59.0, 62.0, 61.0, 60.0, 61.0, 60.0, 60.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 72.0, 76.0, 72.0, 73.0, 73.0, 73.0, 81.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 116.0, 117.0, 116.0, 117.0, 115.0, 116.0, 114.0, 149.0, 117.0], "value": 120.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 161.0, 154.0, 155.0, 152.0, 153.0, 153.0, 152.0, 152.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 71.0, 61.0, 62.0, 64.0, 67.0, 62.0, 62.0, 61.0], "value": 63.7, "unit": "score"}], "name": "kraken", "value": 1492.1000000000001, "alertThreshold": 2.0}]} 04:36:21 INFO - Return code: 0 04:36:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:21 INFO - # TBPL SUCCESS # 04:36:21 INFO - Running post-action listener: _resource_record_post_action 04:36:21 INFO - [mozharness: 2016-09-17 11:36:21.264321Z] Finished run-tests step (success) 04:36:21 INFO - Running post-run listener: _resource_record_post_run 04:36:21 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 328392704; Read time: 28; Write time: 174264 04:36:21 INFO - TinderboxPrint: CPU usage
12.9% 04:36:21 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 04:36:21 INFO - TinderboxPrint: I/O write bytes / time
328,392,704 / 174,264 04:36:21 INFO - TinderboxPrint: CPU idle
8,275.9 (87.0%) 04:36:21 INFO - TinderboxPrint: CPU user
1,183.2 (12.4%) 04:36:21 INFO - TinderboxPrint: Swap in / out
0 / 0 04:36:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77197312; Read time: 0; Write time: 56168 04:36:21 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 248700928; Read time: 28; Write time: 117844 04:36:21 INFO - Running post-run listener: _upload_blobber_files 04:36:21 INFO - Blob upload gear active. 04:36:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:21 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:36:21 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:36:21 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:36:22 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:25 INFO - (blobuploader) - INFO - Done attempting. 04:36:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:36:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:36:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:26 INFO - (blobuploader) - INFO - Done attempting. 04:36:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:36:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:36:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:27 INFO - (blobuploader) - INFO - Done attempting. 04:36:27 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:27 INFO - Return code: 0 04:36:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cca977394f2ea87b36a08555115b689167ba68d9d9458e52d4672bf49a787e999625c194b86f1d86421a0141efb560aa9ba7773671ef0fd4b4fde8d5e32eadbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5acafbc35ddf1ec3833c0158c74f848d523319aa67e1ab82b0dcc502bb87473fe505d7b3c59f9a21c4389c5cc49124b7ce3825e611bb4334642c117dcb227d6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c631ec5ee1f42be68b748747b838e1462976768130ded884a6310e6f13f74863c61d6d8ff7a7183badf4129c5005296f59f959511e3563f0ab80a54ee0fccae"} 04:36:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:27 INFO - Contents: 04:36:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cca977394f2ea87b36a08555115b689167ba68d9d9458e52d4672bf49a787e999625c194b86f1d86421a0141efb560aa9ba7773671ef0fd4b4fde8d5e32eadbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5acafbc35ddf1ec3833c0158c74f848d523319aa67e1ab82b0dcc502bb87473fe505d7b3c59f9a21c4389c5cc49124b7ce3825e611bb4334642c117dcb227d6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c631ec5ee1f42be68b748747b838e1462976768130ded884a6310e6f13f74863c61d6d8ff7a7183badf4129c5005296f59f959511e3563f0ab80a54ee0fccae"} 04:36:27 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:27 INFO - Copying logs to upload dir... 04:36:27 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.568963 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-17 04:36:26.678629) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 04:36:26.682146) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652 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/cca977394f2ea87b36a08555115b689167ba68d9d9458e52d4672bf49a787e999625c194b86f1d86421a0141efb560aa9ba7773671ef0fd4b4fde8d5e32eadbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5acafbc35ddf1ec3833c0158c74f848d523319aa67e1ab82b0dcc502bb87473fe505d7b3c59f9a21c4389c5cc49124b7ce3825e611bb4334642c117dcb227d6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c631ec5ee1f42be68b748747b838e1462976768130ded884a6310e6f13f74863c61d6d8ff7a7183badf4129c5005296f59f959511e3563f0ab80a54ee0fccae"} build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011236 build_url: 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/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/cca977394f2ea87b36a08555115b689167ba68d9d9458e52d4672bf49a787e999625c194b86f1d86421a0141efb560aa9ba7773671ef0fd4b4fde8d5e32eadbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5acafbc35ddf1ec3833c0158c74f848d523319aa67e1ab82b0dcc502bb87473fe505d7b3c59f9a21c4389c5cc49124b7ce3825e611bb4334642c117dcb227d6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c631ec5ee1f42be68b748747b838e1462976768130ded884a6310e6f13f74863c61d6d8ff7a7183badf4129c5005296f59f959511e3563f0ab80a54ee0fccae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 04:36:26.712088) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:36:26.712639) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474110507.740448-179674652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 04:36:26.797275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 04:36:26.798062) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 04:36:26.798754) ========= ========= Total master_lag: 0.20 =========