builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1471354002.22 results: success (0) buildid: 20160816032622 builduid: 1b227b9b41fc4a47bf152b3ea5ffe79e revision: a609f6015009feea8362b149ec175f63a5b14cb8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.223012) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.223447) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.223726) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.279738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.280038) ========= 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-1471353321.234158-246018532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 06:26:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 70.3M=0s 2016-08-16 06:26:42 (70.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136242 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.466656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.466948) ========= 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-1471353321.234158-246018532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019266 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.539024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:42.539365) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a609f6015009feea8362b149ec175f63a5b14cb8 --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 a609f6015009feea8362b149ec175f63a5b14cb8 --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-1471353321.234158-246018532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 06:26:42,663 truncating revision to first 12 chars 2016-08-16 06:26:42,663 Setting DEBUG logging. 2016-08-16 06:26:42,663 attempt 1/10 2016-08-16 06:26:42,663 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a609f6015009?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 06:26:42,970 unpacking tar archive at: mozilla-aurora-a609f6015009/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658119 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:43.235164) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:43.235477) ========= script_repo_revision: a609f6015009feea8362b149ec175f63a5b14cb8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:43.235861) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:43.236128) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 06:26:43.245050) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 12 secs) (at 2016-08-16 06:26:43.245333) ========= /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-1471353321.234158-246018532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:26:43 INFO - MultiFileLogger online at 20160816 06:26:43 in /builds/slave/test 06:26:43 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 06:26:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:26:43 INFO - {'append_to_log': False, 06:26:43 INFO - 'base_work_dir': '/builds/slave/test', 06:26:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:26:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:26:43 INFO - 'branch': 'Mozilla-Aurora', 06:26:43 INFO - 'buildbot_json_path': 'buildprops.json', 06:26:43 INFO - 'config_files': ('talos/linux_config.py',), 06:26:43 INFO - 'default_actions': ('clobber', 06:26:43 INFO - 'read-buildbot-config', 06:26:43 INFO - 'download-and-extract', 06:26:43 INFO - 'populate-webroot', 06:26:43 INFO - 'create-virtualenv', 06:26:43 INFO - 'install', 06:26:43 INFO - 'run-tests'), 06:26:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:26:43 INFO - 'download_minidump_stackwalk': True, 06:26:43 INFO - 'download_symbols': 'ondemand', 06:26:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:26:43 INFO - 'tooltool.py': '/tools/tooltool.py', 06:26:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:26:43 INFO - '/tools/misc-python/virtualenv.py')}, 06:26:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:26:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:26:43 INFO - 'installer_path': 'installer.exe', 06:26:43 INFO - 'log_level': 'info', 06:26:43 INFO - 'log_name': 'talos', 06:26:43 INFO - 'log_to_console': True, 06:26:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:26:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:26:43 INFO - 'opt_config_files': (), 06:26:43 INFO - 'pip_index': False, 06:26:43 INFO - 'sps_profile': False, 06:26:43 INFO - 'sps_profile_interval': 0, 06:26:43 INFO - 'suite': 'dromaeojs', 06:26:43 INFO - 'system_bits': '32', 06:26:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:26:43 INFO - 'title': 'talos-linux64-ix-001', 06:26:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:26:43 INFO - 'use_talos_json': True, 06:26:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:26:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:26:43 INFO - 'work_dir': 'build'} 06:26:43 INFO - [mozharness: 2016-08-16 13:26:43.434363Z] Running clobber step. 06:26:43 INFO - Running pre-action listener: _resource_record_pre_action 06:26:43 INFO - Running main action method: clobber 06:26:43 INFO - rmtree: /builds/slave/test/build 06:26:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:26:43 INFO - Running post-action listener: _resource_record_post_action 06:26:43 INFO - [mozharness: 2016-08-16 13:26:43.889548Z] Finished clobber step (success) 06:26:43 INFO - [mozharness: 2016-08-16 13:26:43.889955Z] Running read-buildbot-config step. 06:26:43 INFO - Running pre-action listener: _resource_record_pre_action 06:26:43 INFO - Running main action method: read_buildbot_config 06:26:43 INFO - Using buildbot properties: 06:26:43 INFO - { 06:26:43 INFO - "project": "", 06:26:43 INFO - "product": "firefox", 06:26:43 INFO - "script_repo_revision": "production", 06:26:43 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:26:43 INFO - "repository": "", 06:26:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:26:43 INFO - "buildid": "20160816032622", 06:26:43 INFO - "pgo_build": "True", 06:26:43 INFO - "basedir": "/builds/slave/test", 06:26:43 INFO - "buildnumber": 470, 06:26:43 INFO - "slavename": "talos-linux64-ix-001", 06:26:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:26:43 INFO - "platform": "ubuntu64_hw", 06:26:43 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:26:43 INFO - "branch": "mozilla-aurora", 06:26:43 INFO - "repo_path": "releases/mozilla-aurora", 06:26:43 INFO - "revision": "a609f6015009feea8362b149ec175f63a5b14cb8", 06:26:43 INFO - "stage_platform": "linux64-pgo", 06:26:43 INFO - "builduid": "1b227b9b41fc4a47bf152b3ea5ffe79e", 06:26:43 INFO - "slavebuilddir": "test" 06:26:43 INFO - } 06:26:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:26:43 INFO - Running post-action listener: _resource_record_post_action 06:26:43 INFO - [mozharness: 2016-08-16 13:26:43.892431Z] Finished read-buildbot-config step (success) 06:26:43 INFO - [mozharness: 2016-08-16 13:26:43.892522Z] Running download-and-extract step. 06:26:43 INFO - Running pre-action listener: _resource_record_pre_action 06:26:43 INFO - Running main action method: download_and_extract 06:26:43 INFO - mkdir: /builds/slave/test/build/tests 06:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:43 INFO - https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:26:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:26:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:26:43 INFO - retry: Failed, sleeping 30 seconds before retrying 06:27:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:27:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:27:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:27:13 INFO - retry: Failed, sleeping 60 seconds before retrying 06:28:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:28:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:28:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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! 06:28:14 INFO - Caught exception: HTTP Error 404: Not Found 06:28:14 INFO - Caught exception: HTTP Error 404: Not Found 06:28:14 INFO - Caught exception: HTTP Error 404: Not Found 06:28:14 INFO - trying https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:14 INFO - Downloading https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:28:14 INFO - Downloaded 1361 bytes. 06:28:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:14 INFO - Using the following test package requirements: 06:28:14 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:28:14 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:28:14 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:28:14 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'jsshell-linux-x86_64.zip'], 06:28:14 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:28:14 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:28:14 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:28:14 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:28:14 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:28:14 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:14 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:28:14 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:28:14 INFO - https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:28:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:28:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:28:14 INFO - retry: Failed, sleeping 30 seconds before retrying 06:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:28:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:28:44 INFO - retry: Failed, sleeping 60 seconds before retrying 06:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:29:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:29:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:29:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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! 06:29:44 INFO - Caught exception: HTTP Error 404: Not Found 06:29:44 INFO - Caught exception: HTTP Error 404: Not Found 06:29:44 INFO - Caught exception: HTTP Error 404: Not Found 06:29:44 INFO - trying https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:29:44 INFO - Downloading https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:29:45 INFO - Downloaded 21765323 bytes. 06:29:45 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'] 06:29:45 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 06:29:46 INFO - Return code: 0 06:29:46 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 06:29: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')]} 06:29:46 INFO - https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:29:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:29:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:29:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:29:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:29:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:29:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:29:46 INFO - retry: Failed, sleeping 30 seconds before retrying 06:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:30:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:30:16 INFO - retry: Failed, sleeping 60 seconds before retrying 06:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:31:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:31:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:31:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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! 06:31:16 INFO - Caught exception: HTTP Error 404: Not Found 06:31:16 INFO - Caught exception: HTTP Error 404: Not Found 06:31:16 INFO - Caught exception: HTTP Error 404: Not Found 06:31:16 INFO - trying https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:31:16 INFO - Downloading https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:31:17 INFO - Downloaded 21765323 bytes. 06:31:17 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'] 06:31:17 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 06:31:19 INFO - Return code: 0 06:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:19 INFO - https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:31:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:31:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:31:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:31:19 INFO - retry: Failed, sleeping 30 seconds before retrying 06:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:31:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:31:49 INFO - retry: Failed, sleeping 60 seconds before retrying 06:32:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:32:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:32:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:32:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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! 06:32:49 INFO - Caught exception: HTTP Error 404: Not Found 06:32:49 INFO - Caught exception: HTTP Error 404: Not Found 06:32:49 INFO - Caught exception: HTTP Error 404: Not Found 06:32:49 INFO - trying https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:32:49 INFO - Downloading https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:32:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:32:50 INFO - Downloaded 14413723 bytes. 06:32:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:32:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:32:50 INFO - Return code: 0 06:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:32:50 INFO - https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:32:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:32:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:32:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:32:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:32:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:32:50 INFO - retry: Failed, sleeping 30 seconds before retrying 06:33:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:33:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:33:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:33:20 INFO - retry: Failed, sleeping 60 seconds before retrying 06:34:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:34:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:34:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:34:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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! 06:34:20 INFO - Caught exception: HTTP Error 404: Not Found 06:34:20 INFO - Caught exception: HTTP Error 404: Not Found 06:34:20 INFO - Caught exception: HTTP Error 404: Not Found 06:34:20 INFO - trying https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:34:20 INFO - Downloading https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:34:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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 06:34:22 INFO - Downloaded 63756469 bytes. 06:34:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:34:22 INFO - mkdir: /builds/slave/test/properties 06:34:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:34:22 INFO - Writing to file /builds/slave/test/properties/build_url 06:34:22 INFO - Contents: 06:34:22 INFO - build_url:https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:34:23 INFO - Running post-action listener: _resource_record_post_action 06:34:23 INFO - Running post-action listener: set_extra_try_arguments 06:34:23 INFO - [mozharness: 2016-08-16 13:34:23.328927Z] Finished download-and-extract step (success) 06:34:23 INFO - [mozharness: 2016-08-16 13:34:23.329161Z] Running populate-webroot step. 06:34:23 INFO - Running pre-action listener: _resource_record_pre_action 06:34:23 INFO - Running main action method: populate_webroot 06:34:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:34:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:34:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:34:23 INFO - '--tppagecycles', 06:34:23 INFO - '7'], 06:34:23 INFO - 'tests': ['tsvgm']}}, 06:34:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:34:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:34:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:34:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:34:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:34:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:34:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['damp', 'tps']}, 06:34:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'tests': ['damp', 'tps']}, 06:34:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['dromaeo_dom']}, 06:34:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:34:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['basic_compositor_video']}, 06:34:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:34:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['a11yr', 06:34:23 INFO - 'ts_paint', 06:34:23 INFO - 'tpaint', 06:34:23 INFO - 'sessionrestore', 06:34:23 INFO - 'sessionrestore_no_auto_restore', 06:34:23 INFO - 'tabpaint']}, 06:34:23 INFO - 'other-e10s': {'tests': ['a11yr', 06:34:23 INFO - 'ts_paint', 06:34:23 INFO - 'tpaint', 06:34:23 INFO - 'sessionrestore', 06:34:23 INFO - 'sessionrestore_no_auto_restore', 06:34:23 INFO - 'tabpaint']}, 06:34:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:34:23 INFO - 'ts_paint', 06:34:23 INFO - 'tpaint', 06:34:23 INFO - 'sessionrestore', 06:34:23 INFO - 'sessionrestore_no_auto_restore', 06:34:23 INFO - 'tabpaint']}, 06:34:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:34:23 INFO - 'ts_paint', 06:34:23 INFO - 'tpaint', 06:34:23 INFO - 'sessionrestore', 06:34:23 INFO - 'sessionrestore_no_auto_restore', 06:34:23 INFO - 'tabpaint']}, 06:34:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['a11yr', 06:34:23 INFO - 'ts_paint', 06:34:23 INFO - 'tpaint', 06:34:23 INFO - 'sessionrestore', 06:34:23 INFO - 'sessionrestore_no_auto_restore', 06:34:23 INFO - 'tabpaint']}, 06:34:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['a11yr', 06:34:23 INFO - 'ts_paint', 06:34:23 INFO - 'tpaint', 06:34:23 INFO - 'sessionrestore', 06:34:23 INFO - 'sessionrestore_no_auto_restore', 06:34:23 INFO - 'tabpaint']}, 06:34:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['tsvgx', 06:34:23 INFO - 'tsvgr_opacity', 06:34:23 INFO - 'tart', 06:34:23 INFO - 'tscrollx', 06:34:23 INFO - 'cart']}, 06:34:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:34:23 INFO - 'tsvgr_opacity', 06:34:23 INFO - 'tart', 06:34:23 INFO - 'tscrollx', 06:34:23 INFO - 'cart']}, 06:34:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'talos_options': ['--disable-e10s'], 06:34:23 INFO - 'tests': ['tp5o']}, 06:34:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'tests': ['tp5o']}, 06:34:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'talos_options': ['--disable-e10s', 06:34:23 INFO - '--xperf_path', 06:34:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:34:23 INFO - 'tests': ['tp5n']}, 06:34:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:23 INFO - 'talos_options': ['--xperf_path', 06:34:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:34:23 INFO - 'tests': ['tp5n']}}, 06:34:23 INFO - 'talos.zip': {'path': '', 06:34:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:34:23 INFO - Running post-action listener: _resource_record_post_action 06:34:23 INFO - [mozharness: 2016-08-16 13:34:23.345438Z] Finished populate-webroot step (success) 06:34:23 INFO - [mozharness: 2016-08-16 13:34:23.345527Z] Running create-virtualenv step. 06:34:23 INFO - Running pre-action listener: _resource_record_pre_action 06:34:23 INFO - Running main action method: create_virtualenv 06:34:23 INFO - Creating virtualenv /builds/slave/test/build/venv 06:34:23 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 06:34:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:34:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:34:23 INFO - Using real prefix '/usr' 06:34:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:34:23 INFO - Installing distribute.............................................................................................................................................................................................done. 06:34:25 INFO - Installing pip.................done. 06:34:25 INFO - Return code: 0 06:34:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:34: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')]} 06:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34: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')]} 06:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:34:25 INFO - Using env: {'DISPLAY': ':0', 06:34:25 INFO - 'HOME': '/home/cltbld', 06:34:25 INFO - 'LANG': 'en_US.UTF-8', 06:34:25 INFO - 'LANGUAGE': 'en_US:en', 06:34:25 INFO - 'LOGNAME': 'cltbld', 06:34:25 INFO - 'MAIL': '/var/mail/cltbld', 06:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:25 INFO - 'MOZ_NO_REMOTE': '1', 06:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:25 INFO - 'NO_EM_RESTART': '1', 06:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:25 INFO - 'PWD': '/builds/slave/test', 06:34:25 INFO - 'SHELL': '/bin/bash', 06:34:25 INFO - 'SHLVL': '1', 06:34:25 INFO - 'TERM': 'linux', 06:34:25 INFO - 'TMOUT': '86400', 06:34:25 INFO - 'USER': 'cltbld', 06:34:25 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:25 INFO - '_': '/tools/buildbot/bin/python'} 06:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:26 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 06:34:26 INFO - Cleaning up... 06:34:26 INFO - Return code: 0 06:34:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:34:26 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')]} 06:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:26 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')]} 06:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:26 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 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:26 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 06:34:26 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 06:34:26 INFO - Using env: {'DISPLAY': ':0', 06:34:26 INFO - 'HOME': '/home/cltbld', 06:34:26 INFO - 'LANG': 'en_US.UTF-8', 06:34:26 INFO - 'LANGUAGE': 'en_US:en', 06:34:26 INFO - 'LOGNAME': 'cltbld', 06:34:26 INFO - 'MAIL': '/var/mail/cltbld', 06:34:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:26 INFO - 'MOZ_NO_REMOTE': '1', 06:34:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:26 INFO - 'NO_EM_RESTART': '1', 06:34:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:26 INFO - 'PWD': '/builds/slave/test', 06:34:26 INFO - 'SHELL': '/bin/bash', 06:34:26 INFO - 'SHLVL': '1', 06:34:26 INFO - 'TERM': 'linux', 06:34:26 INFO - 'TMOUT': '86400', 06:34:26 INFO - 'USER': 'cltbld', 06:34:26 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:26 INFO - '_': '/tools/buildbot/bin/python'} 06:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:26 INFO - Downloading/unpacking psutil>=3.1.1 06:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:34:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:34:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:34:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:34:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:34:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:34:28 INFO - Installing collected packages: psutil 06:34:28 INFO - Running setup.py install for psutil 06:34:28 INFO - building 'psutil._psutil_linux' extension 06:34:28 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 06:34:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:34:28 INFO - building 'psutil._psutil_posix' extension 06:34:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:34:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:34:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:34:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:34:28 INFO - Successfully installed psutil 06:34:28 INFO - Cleaning up... 06:34:28 INFO - Return code: 0 06:34:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:34:28 INFO - Using env: {'DISPLAY': ':0', 06:34:28 INFO - 'HOME': '/home/cltbld', 06:34:28 INFO - 'LANG': 'en_US.UTF-8', 06:34:28 INFO - 'LANGUAGE': 'en_US:en', 06:34:28 INFO - 'LOGNAME': 'cltbld', 06:34:28 INFO - 'MAIL': '/var/mail/cltbld', 06:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:28 INFO - 'MOZ_NO_REMOTE': '1', 06:34:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:28 INFO - 'NO_EM_RESTART': '1', 06:34:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:28 INFO - 'PWD': '/builds/slave/test', 06:34:28 INFO - 'SHELL': '/bin/bash', 06:34:28 INFO - 'SHLVL': '1', 06:34:28 INFO - 'TERM': 'linux', 06:34:28 INFO - 'TMOUT': '86400', 06:34:28 INFO - 'USER': 'cltbld', 06:34:28 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:28 INFO - '_': '/tools/buildbot/bin/python'} 06:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:34:29 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 06:34:29 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 06:34:29 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 06:34:29 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 06:34:29 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 06:34:29 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 06:34:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:34:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:34:31 INFO - Installing collected packages: mozsystemmonitor 06:34:31 INFO - Running setup.py install for mozsystemmonitor 06:34:31 INFO - Successfully installed mozsystemmonitor 06:34:31 INFO - Cleaning up... 06:34:31 INFO - Return code: 0 06:34:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:31 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 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:31 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 06:34:31 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 06:34:31 INFO - Using env: {'DISPLAY': ':0', 06:34:31 INFO - 'HOME': '/home/cltbld', 06:34:31 INFO - 'LANG': 'en_US.UTF-8', 06:34:31 INFO - 'LANGUAGE': 'en_US:en', 06:34:31 INFO - 'LOGNAME': 'cltbld', 06:34:31 INFO - 'MAIL': '/var/mail/cltbld', 06:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:31 INFO - 'MOZ_NO_REMOTE': '1', 06:34:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:31 INFO - 'NO_EM_RESTART': '1', 06:34:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:31 INFO - 'PWD': '/builds/slave/test', 06:34:31 INFO - 'SHELL': '/bin/bash', 06:34:31 INFO - 'SHLVL': '1', 06:34:31 INFO - 'TERM': 'linux', 06:34:31 INFO - 'TMOUT': '86400', 06:34:31 INFO - 'USER': 'cltbld', 06:34:31 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:31 INFO - '_': '/tools/buildbot/bin/python'} 06:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:31 INFO - Downloading/unpacking jsonschema==2.5.1 06:34:31 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 06:34:31 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 06:34:31 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 06:34:31 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 06:34:31 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 06:34:31 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 06:34:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:34:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:34:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:34:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:34:33 INFO - Downloading functools32-3.2.3-2.tar.gz 06:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:34:33 INFO - warning: no files found matching '*.txt' 06:34:33 INFO - no previously-included directories found matching 'build' 06:34:33 INFO - no previously-included directories found matching 'dist' 06:34:33 INFO - no previously-included directories found matching '.git*' 06:34:33 INFO - Installing collected packages: jsonschema, functools32 06:34:33 INFO - Running setup.py install for functools32 06:34:33 INFO - warning: no files found matching '*.txt' 06:34:33 INFO - no previously-included directories found matching 'build' 06:34:33 INFO - no previously-included directories found matching 'dist' 06:34:33 INFO - no previously-included directories found matching '.git*' 06:34:33 INFO - Successfully installed jsonschema functools32 06:34:33 INFO - Cleaning up... 06:34:33 INFO - Return code: 0 06:34:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:34:33 INFO - Using env: {'DISPLAY': ':0', 06:34:33 INFO - 'HOME': '/home/cltbld', 06:34:33 INFO - 'LANG': 'en_US.UTF-8', 06:34:33 INFO - 'LANGUAGE': 'en_US:en', 06:34:33 INFO - 'LOGNAME': 'cltbld', 06:34:33 INFO - 'MAIL': '/var/mail/cltbld', 06:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:33 INFO - 'MOZ_NO_REMOTE': '1', 06:34:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:33 INFO - 'NO_EM_RESTART': '1', 06:34:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:33 INFO - 'PWD': '/builds/slave/test', 06:34:33 INFO - 'SHELL': '/bin/bash', 06:34:33 INFO - 'SHLVL': '1', 06:34:33 INFO - 'TERM': 'linux', 06:34:33 INFO - 'TMOUT': '86400', 06:34:33 INFO - 'USER': 'cltbld', 06:34:33 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:33 INFO - '_': '/tools/buildbot/bin/python'} 06:34:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:34:33 INFO - Cleaning up... 06:34:33 INFO - Return code: 0 06:34:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:34:33 INFO - Using env: {'DISPLAY': ':0', 06:34:33 INFO - 'HOME': '/home/cltbld', 06:34:33 INFO - 'LANG': 'en_US.UTF-8', 06:34:33 INFO - 'LANGUAGE': 'en_US:en', 06:34:33 INFO - 'LOGNAME': 'cltbld', 06:34:33 INFO - 'MAIL': '/var/mail/cltbld', 06:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:33 INFO - 'MOZ_NO_REMOTE': '1', 06:34:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:33 INFO - 'NO_EM_RESTART': '1', 06:34:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:33 INFO - 'PWD': '/builds/slave/test', 06:34:33 INFO - 'SHELL': '/bin/bash', 06:34:33 INFO - 'SHLVL': '1', 06:34:33 INFO - 'TERM': 'linux', 06:34:33 INFO - 'TMOUT': '86400', 06:34:33 INFO - 'USER': 'cltbld', 06:34:33 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:33 INFO - '_': '/tools/buildbot/bin/python'} 06:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:34 INFO - Downloading/unpacking blobuploader==1.2.4 06:34:34 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 06:34:34 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 06:34:34 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 06:34:34 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 06:34:34 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 06:34:34 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 06:34:43 INFO - Downloading blobuploader-1.2.4.tar.gz 06:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:34:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:34:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 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 06:34:43 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 06:34:44 INFO - Downloading docopt-0.6.1.tar.gz 06:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:34:44 INFO - Installing collected packages: blobuploader, requests, docopt 06:34:44 INFO - Running setup.py install for blobuploader 06:34:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:34:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:34:44 INFO - Running setup.py install for requests 06:34:44 INFO - Running setup.py install for docopt 06:34:44 INFO - Successfully installed blobuploader requests docopt 06:34:44 INFO - Cleaning up... 06:34:44 INFO - Return code: 0 06:34:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:44 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 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:44 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 06:34:44 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 06:34:44 INFO - Using env: {'DISPLAY': ':0', 06:34:44 INFO - 'HOME': '/home/cltbld', 06:34:44 INFO - 'LANG': 'en_US.UTF-8', 06:34:44 INFO - 'LANGUAGE': 'en_US:en', 06:34:44 INFO - 'LOGNAME': 'cltbld', 06:34:44 INFO - 'MAIL': '/var/mail/cltbld', 06:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:44 INFO - 'MOZ_NO_REMOTE': '1', 06:34:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:44 INFO - 'NO_EM_RESTART': '1', 06:34:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:44 INFO - 'PWD': '/builds/slave/test', 06:34:44 INFO - 'SHELL': '/bin/bash', 06:34:44 INFO - 'SHLVL': '1', 06:34:44 INFO - 'TERM': 'linux', 06:34:44 INFO - 'TMOUT': '86400', 06:34:44 INFO - 'USER': 'cltbld', 06:34:44 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:44 INFO - '_': '/tools/buildbot/bin/python'} 06:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:34:45 INFO - Running setup.py (path:/tmp/pip-LFkehL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:34:45 INFO - Running setup.py (path:/tmp/pip-AV8jef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:34:45 INFO - Running setup.py (path:/tmp/pip-lps6C0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:34:45 INFO - Running setup.py (path:/tmp/pip-q56vlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:34:45 INFO - Running setup.py (path:/tmp/pip-fao_zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:34:45 INFO - Running setup.py (path:/tmp/pip-OFDX47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:34:45 INFO - Running setup.py (path:/tmp/pip-VEAh1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:34:45 INFO - Running setup.py (path:/tmp/pip-unkIvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:34:45 INFO - Running setup.py (path:/tmp/pip-C_hvZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:34:45 INFO - Running setup.py (path:/tmp/pip-TMVIAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:34:45 INFO - Running setup.py (path:/tmp/pip-tJzftZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:34:45 INFO - Running setup.py (path:/tmp/pip-UDfmw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:34:45 INFO - Running setup.py (path:/tmp/pip-LUspR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:34:46 INFO - Running setup.py (path:/tmp/pip-VahM3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:46 INFO - Running setup.py (path:/tmp/pip-ahg1Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:34:46 INFO - Running setup.py (path:/tmp/pip-PG78uH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:34:46 INFO - Running setup.py (path:/tmp/pip-o6Kstt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:34:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:34:46 INFO - Running setup.py install for manifestparser 06:34:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:34:46 INFO - Running setup.py install for mozcrash 06:34:46 INFO - Running setup.py install for mozdebug 06:34:46 INFO - Running setup.py install for mozdevice 06:34:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:34:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:34:46 INFO - Running setup.py install for mozfile 06:34:46 INFO - Running setup.py install for mozhttpd 06:34:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:34:46 INFO - Running setup.py install for mozinfo 06:34:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Running setup.py install for mozInstall 06:34:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Running setup.py install for mozleak 06:34:47 INFO - Running setup.py install for mozlog 06:34:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Running setup.py install for moznetwork 06:34:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Running setup.py install for mozprocess 06:34:47 INFO - Running setup.py install for mozprofile 06:34:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Running setup.py install for mozrunner 06:34:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:34:47 INFO - Running setup.py install for mozscreenshot 06:34:47 INFO - Running setup.py install for moztest 06:34:47 INFO - Running setup.py install for mozversion 06:34:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:34:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:34:48 INFO - Cleaning up... 06:34:48 INFO - Return code: 0 06:34:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:48 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 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:48 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 06:34:48 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 06:34:48 INFO - Using env: {'DISPLAY': ':0', 06:34:48 INFO - 'HOME': '/home/cltbld', 06:34:48 INFO - 'LANG': 'en_US.UTF-8', 06:34:48 INFO - 'LANGUAGE': 'en_US:en', 06:34:48 INFO - 'LOGNAME': 'cltbld', 06:34:48 INFO - 'MAIL': '/var/mail/cltbld', 06:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:48 INFO - 'MOZ_NO_REMOTE': '1', 06:34:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:48 INFO - 'NO_EM_RESTART': '1', 06:34:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:48 INFO - 'PWD': '/builds/slave/test', 06:34:48 INFO - 'SHELL': '/bin/bash', 06:34:48 INFO - 'SHLVL': '1', 06:34:48 INFO - 'TERM': 'linux', 06:34:48 INFO - 'TMOUT': '86400', 06:34:48 INFO - 'USER': 'cltbld', 06:34:48 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:48 INFO - '_': '/tools/buildbot/bin/python'} 06:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:34:48 INFO - Running setup.py (path:/tmp/pip-CWMNYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:34:48 INFO - Running setup.py (path:/tmp/pip-eiWqIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:34:48 INFO - Running setup.py (path:/tmp/pip-KwkPGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:34:48 INFO - Running setup.py (path:/tmp/pip-Zs3XFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:34:48 INFO - Running setup.py (path:/tmp/pip-qNtH9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:34:48 INFO - Running setup.py (path:/tmp/pip-BQ_mpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:34:48 INFO - Running setup.py (path:/tmp/pip-nHLd2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:34:48 INFO - Running setup.py (path:/tmp/pip-MbIO5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:34:48 INFO - Running setup.py (path:/tmp/pip-2FFZ5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:34:48 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)) 06:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:34:48 INFO - Running setup.py (path:/tmp/pip-7NgBST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:34:49 INFO - Running setup.py (path:/tmp/pip-OcSAxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:34:49 INFO - Running setup.py (path:/tmp/pip-1wV5Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:34:49 INFO - Running setup.py (path:/tmp/pip-T2EBnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:34:49 INFO - Running setup.py (path:/tmp/pip-Nl0Cf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:49 INFO - Running setup.py (path:/tmp/pip-JP4E7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:34:49 INFO - Running setup.py (path:/tmp/pip-HIM8oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:34:49 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)) 06:34:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:34:49 INFO - Running setup.py (path:/tmp/pip-yyU0vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:34:49 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)) 06:34:49 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)) 06:34:49 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)) 06:34:49 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)) 06:34:49 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)) 06:34:49 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)) 06:34:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:34:49 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 06:34:49 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 06:34:49 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 06:34:49 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 06:34:49 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 06:34:49 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 06:34:59 INFO - Downloading blessings-1.6.tar.gz 06:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:34:59 INFO - Installing collected packages: blessings 06:34:59 INFO - Running setup.py install for blessings 06:34:59 INFO - Successfully installed blessings 06:34:59 INFO - Cleaning up... 06:34:59 INFO - Return code: 0 06:34:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:34:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:34:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:34:59 INFO - Reading from file tmpfile_stdout 06:34:59 INFO - Current package versions: 06:34:59 INFO - argparse == 1.2.1 06:34:59 INFO - blessings == 1.6 06:34:59 INFO - blobuploader == 1.2.4 06:34:59 INFO - docopt == 0.6.1 06:34:59 INFO - functools32 == 3.2.3-2 06:34:59 INFO - jsonschema == 2.5.1 06:34:59 INFO - manifestparser == 1.1 06:34:59 INFO - mozInstall == 1.12 06:34:59 INFO - mozcrash == 0.17 06:34:59 INFO - mozdebug == 0.1 06:34:59 INFO - mozdevice == 0.48 06:34:59 INFO - mozfile == 1.2 06:34:59 INFO - mozhttpd == 0.7 06:34:59 INFO - mozinfo == 0.9 06:34:59 INFO - mozleak == 0.1 06:34:59 INFO - mozlog == 3.3 06:34:59 INFO - moznetwork == 0.27 06:34:59 INFO - mozprocess == 0.23 06:34:59 INFO - mozprofile == 0.28 06:34:59 INFO - mozrunner == 6.12 06:34:59 INFO - mozscreenshot == 0.1 06:34:59 INFO - mozsystemmonitor == 0.3 06:34:59 INFO - moztest == 0.7 06:34:59 INFO - mozversion == 1.4 06:34:59 INFO - psutil == 3.1.1 06:34:59 INFO - requests == 1.2.3 06:34:59 INFO - wsgiref == 0.1.2 06:34:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:34:59 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')]} 06:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:59 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')]} 06:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:59 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 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:59 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 06:34:59 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 06:34:59 INFO - Using env: {'DISPLAY': ':0', 06:34:59 INFO - 'HOME': '/home/cltbld', 06:34:59 INFO - 'LANG': 'en_US.UTF-8', 06:34:59 INFO - 'LANGUAGE': 'en_US:en', 06:34:59 INFO - 'LOGNAME': 'cltbld', 06:34:59 INFO - 'MAIL': '/var/mail/cltbld', 06:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:59 INFO - 'MOZ_NO_REMOTE': '1', 06:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:59 INFO - 'NO_EM_RESTART': '1', 06:34:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:59 INFO - 'PWD': '/builds/slave/test', 06:34:59 INFO - 'SHELL': '/bin/bash', 06:34:59 INFO - 'SHLVL': '1', 06:34:59 INFO - 'TERM': 'linux', 06:34:59 INFO - 'TMOUT': '86400', 06:34:59 INFO - 'USER': 'cltbld', 06:34:59 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:59 INFO - '_': '/tools/buildbot/bin/python'} 06:34:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 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)) 06:34:59 INFO - Cleaning up... 06:34:59 INFO - Return code: 0 06:34:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:34:59 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')]} 06:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:59 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')]} 06:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:34:59 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 0x1df9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, '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-1471353321.234158-246018532', '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 06:34:59 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 06:34:59 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 06:34:59 INFO - Using env: {'DISPLAY': ':0', 06:34:59 INFO - 'HOME': '/home/cltbld', 06:34:59 INFO - 'LANG': 'en_US.UTF-8', 06:34:59 INFO - 'LANGUAGE': 'en_US:en', 06:34:59 INFO - 'LOGNAME': 'cltbld', 06:34:59 INFO - 'MAIL': '/var/mail/cltbld', 06:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:59 INFO - 'MOZ_NO_REMOTE': '1', 06:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:59 INFO - 'NO_EM_RESTART': '1', 06:34:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:59 INFO - 'PWD': '/builds/slave/test', 06:34:59 INFO - 'SHELL': '/bin/bash', 06:34:59 INFO - 'SHLVL': '1', 06:34:59 INFO - 'TERM': 'linux', 06:34:59 INFO - 'TMOUT': '86400', 06:34:59 INFO - 'USER': 'cltbld', 06:34:59 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:59 INFO - '_': '/tools/buildbot/bin/python'} 06:34:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:34:59 INFO - Cleaning up... 06:34:59 INFO - Return code: 0 06:34:59 INFO - Running post-action listener: _resource_record_post_action 06:34:59 INFO - Running post-action listener: _start_resource_monitoring 06:34:59 INFO - Starting resource monitoring. 06:34:59 INFO - [mozharness: 2016-08-16 13:34:59.962699Z] Finished create-virtualenv step (success) 06:34:59 INFO - [mozharness: 2016-08-16 13:34:59.963134Z] Running install step. 06:34:59 INFO - Running pre-action listener: _resource_record_pre_action 06:34:59 INFO - Running main action method: install 06:34:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:34:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:35:00 INFO - Reading from file tmpfile_stdout 06:35:00 INFO - Detecting whether we're running mozinstall >=1.0... 06:35:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:35:00 INFO - Reading from file tmpfile_stdout 06:35:00 INFO - Output received: 06:35:00 INFO - Usage: mozinstall [options] installer 06:35:00 INFO - Options: 06:35:00 INFO - -h, --help show this help message and exit 06:35:00 INFO - -d DEST, --destination=DEST 06:35:00 INFO - Directory to install application into. [default: 06:35:00 INFO - "/builds/slave/test"] 06:35:00 INFO - --app=APP Application being installed. [default: firefox] 06:35:00 INFO - mkdir: /builds/slave/test/build/application 06:35:00 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'] 06:35:00 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 06:35:16 INFO - Reading from file tmpfile_stdout 06:35:16 INFO - Output received: 06:35:16 INFO - /builds/slave/test/build/application/firefox/firefox 06:35:16 INFO - Running post-action listener: _resource_record_post_action 06:35:16 INFO - [mozharness: 2016-08-16 13:35:16.234833Z] Finished install step (success) 06:35:16 INFO - [mozharness: 2016-08-16 13:35:16.235174Z] Running run-tests step. 06:35:16 INFO - Running pre-action listener: _resource_record_pre_action 06:35:16 INFO - Running main action method: run_tests 06:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:35:16 INFO - Python 2.7.3 06:35:16 INFO - Return code: 0 06:35:16 INFO - grabbing minidump binary from tooltool 06:35: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')]} 06:35:16 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 0x20b83b0>, '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 0x20b92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:35:16 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 06:35:16 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 06:35:16 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 06:35:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:35:16 INFO - Return code: 0 06:35:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:35:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:35:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:35:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:35:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:35:16 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/INZJ-VwfTGKlb4O9ejOpIw/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 06:35:16 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/INZJ-VwfTGKlb4O9ejOpIw/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 06:35:16 INFO - Using env: {'DISPLAY': ':0', 06:35:16 INFO - 'HOME': '/home/cltbld', 06:35:16 INFO - 'LANG': 'en_US.UTF-8', 06:35:16 INFO - 'LANGUAGE': 'en_US:en', 06:35:16 INFO - 'LOGNAME': 'cltbld', 06:35:16 INFO - 'MAIL': '/var/mail/cltbld', 06:35:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:35:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:16 INFO - 'MOZ_NO_REMOTE': '1', 06:35:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:16 INFO - 'NO_EM_RESTART': '1', 06:35:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:16 INFO - 'PWD': '/builds/slave/test', 06:35:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:35:16 INFO - 'SHELL': '/bin/bash', 06:35:16 INFO - 'SHLVL': '1', 06:35:16 INFO - 'TERM': 'linux', 06:35:16 INFO - 'TMOUT': '86400', 06:35:16 INFO - 'USER': 'cltbld', 06:35:16 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471353321.234158-246018532', 06:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:16 INFO - '_': '/tools/buildbot/bin/python'} 06:35:16 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/INZJ-VwfTGKlb4O9ejOpIw/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 06:35:16 INFO - mozversion application_buildid: 20160816032622 06:35:16 INFO - mozversion application_changeset: a609f6015009feea8362b149ec175f63a5b14cb8 06:35:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:35:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:35:16 INFO - mozversion application_name: Firefox 06:35:16 INFO - mozversion application_remotingname: firefox-dev 06:35:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:35:16 INFO - mozversion application_vendor: Mozilla 06:35:16 INFO - mozversion application_version: 50.0a2 06:35:16 INFO - mozversion platform_buildid: 20160816032622 06:35:16 INFO - mozversion platform_changeset: a609f6015009feea8362b149ec175f63a5b14cb8 06:35:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:35:16 INFO - mozversion platform_version: 50.0a2 06:35:16 INFO - using testdate: 1471354516 06:35:16 INFO - actual date: 1471354516 06:35:16 INFO - starting webserver on 'localhost:45156' 06:35:16 INFO - SUITE-START | Running 2 tests 06:35:16 INFO - TEST-START | dromaeo_css 06:35:16 INFO - operating with platform_type : linux_ 06:35:16 INFO - Initialising browser for dromaeo_css test... 06:35:16 INFO - TEST-INFO | started process 16290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSz5wFq/profile http://localhost:45156/getInfo.html) 06:35:20 INFO - PROCESS | 16290 | __metrics Screen width/height:1600/1200 06:35:20 INFO - PROCESS | 16290 | colorDepth:24 06:35:20 INFO - PROCESS | 16290 | Browser inner width/height: 1024/697 06:35:20 INFO - PROCESS | 16290 | __metrics 06:35:21 INFO - PROCESS | 16290 | 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" 06:35:21 INFO - PROCESS | 16290 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:35:22 INFO - TEST-INFO | 16290: exit 0 06:35:22 INFO - Browser initialized. 06:35:22 INFO - Running cycle 1/1 for dromaeo_css test... 06:35:22 INFO - TEST-INFO | started process 16376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSz5wFq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:35:34 INFO - PROCESS | 16376 | RSS: Main: 145522688 06:35:34 INFO - PROCESS | 16376 | 06:36:25 INFO - PROCESS | 16376 | 1471354584999 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] 06:38:34 INFO - PROCESS | 16376 | Cycle 1(1): loaded http://localhost:45156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45156/tests/dromaeo/cssquery-ext.html) 06:38:35 INFO - PROCESS | 16376 | RSS: Main: 275369984 06:38:35 INFO - PROCESS | 16376 | 06:41:37 INFO - PROCESS | 16376 | Cycle 1(1): loaded http://localhost:45156/tests/dromaeo/cssquery-ext.html (next: http://localhost:45156/tests/dromaeo/cssquery-jquery.html) 06:41:38 INFO - PROCESS | 16376 | RSS: Main: 233246720 06:41:38 INFO - PROCESS | 16376 | 06:44:40 INFO - PROCESS | 16376 | Cycle 1(1): loaded http://localhost:45156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45156/tests/dromaeo/cssquery-mootools.html) 06:44:40 INFO - PROCESS | 16376 | RSS: Main: 174772224 06:44:40 INFO - PROCESS | 16376 | 06:47:42 INFO - PROCESS | 16376 | Cycle 1(1): loaded http://localhost:45156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45156/tests/dromaeo/cssquery-prototype.html) 06:47:43 INFO - PROCESS | 16376 | RSS: Main: 228241408 06:47:43 INFO - PROCESS | 16376 | 06:50:46 INFO - PROCESS | 16376 | Cycle 1(1): loaded http://localhost:45156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45156/tests/dromaeo/cssquery-yui.html) 06:50:46 INFO - PROCESS | 16376 | RSS: Main: 178102272 06:50:46 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | Cycle 1(1): loaded http://localhost:45156/tests/dromaeo/cssquery-yui.html (next: http://localhost:45156/tests/dromaeo/cssquery-dojo.html) 06:53:49 INFO - PROCESS | 16376 | RSS: Main: 262422528 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | __start_tp_report 06:53:49 INFO - PROCESS | 16376 | _x_x_mozilla_page_load 06:53:49 INFO - PROCESS | 16376 | _x_x_mozilla_page_load_details 06:53:49 INFO - PROCESS | 16376 | |i|pagename|runs| 06:53:49 INFO - PROCESS | 16376 | |0;dojo.html;3254.3979363817693;3276.4266253405663;3282.0482060202535;3305.9008229753085;3325.916852078698;5658.660480371175;5773.105168698853;5790.797322093727;5793.044147715622;5806.825795226145;5613.270274864272;5681;5691.743871525781;5693.686847223401;5702.885942281152;5033.471485494026;5123.5388815006645;5129.538341549269;5188.143956247233;5216;5096.770645320969;5167.354080739908;5217.608679349045;5217.660852044605;5232.293640358541;5079.212722028073;5132.846014619548;5152.433232344439;5165.767540460688;5169.379674439054;5256.8685782855355;5260.473952604747;5263.736813159327;5263.76313065911;5297.735113244361;4979.676321039121;5057.848264552069;5092.057969275659;5140.023395554833;5143.27994080829;5600.243967064435;5637.774489020434;5643.5767317451355;5661.60368774186;5665.801696940628;6167.352427995041;6175.104609831594;6180.165677633552;6182.567220294582;6186.133941248228;5938.406159384027;6042.9395706042815;6118.4799292060225;6134.509239260893;6208.627482351028;5761.884762304772;5763.538916886639;5775.682337471438;5782.710864456761;5784.363719990798;6261.092141639437;6268.216472940882;6269.310375858652;6277.7175027123885;6279.7488100476385;5907.793227237026;6063.6664983426335;6106.908396374058;6140.907886381708;6143.201383820164;6190.3190649028575;6201.596896201822;6203.7828675997025;6207.534434917399;6211.689415529206;6097.542684298606;6165.660888651168;6176.907346389808;6183.598066125687;6200.348963358872;5905.498032667186;5958.970205149033;5988.04191329394;6023.879522409527;6042.969785151134;4635.860924172281;4672.76636168197;4675.462321832964;4687.859364219079;4698.718076915396;4170.457840480718;4171.83312667496;4181.623653871166;4182.393552934807;4198.727082739612;5646.096624540054;5760.855978600484;5799.217105690762;5808.07070868659;5832.358440571534;6200.038993955944;6207.844803879939;6211.440970312694;6218.347073557211;6222;5839.328477225174;5843.211166492554;5845.064789633639;5845.09401042839;5869;5104.540591346797;5185.611079169077;5243.685378877203;5278;5318.361796584435;6316.526260530479;6317.589356691892;6338.144350512714;6341.3341599132345;6346.079818426302;6057.606255593372;6097.634141951497;6122.418370254819;6123.357047509948;6127.540434467343;6133.304060594526;6175.44421002112;6257.405546473078;6331.873362532631;6344.429001389897;6049.425304596057;6054.727537260922;6058.757649693963;6077.361877002851;6100.328963813889;6122.510199184144;6133.141360209485;6143.631382117087;6144.846378840565;6144.969275153774;5847.473727364558;5905.527557795452;5929.258842644669;5977;5988.071848863433;5944.138099975393;5996.640201587918;6057.636541807506;6077.756889724362;6100.328963814066;5995.16067750524;6003.6397816131175;6007.939920600738;6010;6010;5827.4172582741385;5881.38245484218;5939.851503712442;6078.7264573093435;6095.603785753912;5896.852578685395;5916.556258280732;5922.170896074467;5929.733162007807;5945.167676525376 06:53:49 INFO - PROCESS | 16376 | |1;ext.html;604.6221111805119;645.1935081148564;659.1661548141604;669.2504395077514;670.4033410264864;34726.47910281345;46219.306710399374;47603.285950710764;47631.285530717025;50785;48248.75875620617;48757.268640970324;48765;48790.75604621981;48822;9341.065893410672;9499.477528735917;9502.097300756452;9506.47714375709;9509.906360768531;8446.155384461536;10994.780104397907;11330.036946859527;11338.376389298584;11706.180567360288;11412;11535;11537.134714896374;11540.769184616303;11546.134039947037;11365.886341136566;11443.370614616193;11445.256058356263;11450.770984580304;11461.484233209525;11271.323720576791;11311.377874216914;11321;11323.660290191307;11359.716007099805;44885.10229795418;45136.77431612854;45225.321620175724;45228.773856130836;45233.321500177524;3623.148560088377;3687.0413692439893;3861.362875125601;3879.495665563487;4075.347944328923;35634.64365356339;35749.46375804365;35765.46351804725;36222.275554489024;37399;4550.704204226748;4567.497575266718;4588.380568623226;4615.446146462413;4795.256735206049;383688;386414;387718;388604;389500;37411.812940935124;61527;62042.68978655078;62043.37956620466;62092.37907620866;37340;39548.802255988536;39627.2074558513;39655.40516892249;39788.005299867735;15472.381104755908;15602.843971560365;15615;15616.609584760472;15621.14083725406;11795.41022948849;11822.290662560274;12710.745785084431;12876.420561074774;12906.67733306675;8979.595918183697;9031.548422578844;9163.95418022919;9173.403728757743;9180.081991800767;3217.2439476722952;3254.2840575073446;3287.852046657906;3308.5698859148156;3355.1108956126645;1057.2070946789909;1094.956201751937;1141.6575027491886;1203.7351782607811;1205.7761372207217;31112.533312000338;31316.217094572818;31603;31997.36005279928;32018;1151.090638395675;1161.105948419729;1178.4461303187659;1218.670958841108;1271.4405661508906;16815.915920420564;17072.658546828992;17114.14429278556;17356.566085847702;17500.5624859377;594236;598718;602353;602531;603335;64460.35539644637;65099;65219.67390163114;65241;65312.67343663251;1323.973520529584;1427.9143251404948;1436.899417040818;1452.4190323870537;1489.6871656951944;33778.32443351168;36765.44851827171;37183.81408092942;37207.44188837224;37619.435708464945;56731.86536269334;57697;57751.42248577628;57766.7111664439;57924.4207557919;540449;550440;550477;551393;552855;48499.51500485091;48703.26945095755;48787.512124878296;49235.26147107868;51732.741336293075;53379.46620533745;53861.46138538564;54054.45945540494;54172.18741718798;54205.45794542162;44883.55116448794;45278;46283.53716462792;46380.30429543492;46801.063978719554;44411.777941110086;44508.33237501375;45075;46272.76863615795;46629.53370466252;45791.77104114458;46170.307445387676;47003;47193.292100619205;47629;38825.417618736305;40868.591314087666;40997.38503922503;41009.589904100576;41786.37320440257;30106.397872042875;30322.545161823033;30544.847275763477;30603.693963060083;30622.387552248383 06:53:49 INFO - PROCESS | 16376 | |2;jquery.html;399.57046175361495;432.69711202158487;432.72089502271035;433.89803396201904;445.93533937579053;16792.580185495368;20606.381808545768;21082.789172108274;21490.570188596237;21506.462338441546;20474.18103275867;20536.691949620767;20538.794612053876;20543.691844622306;20708.48228794277;5589.105743081108;5898.734556944926;6172.783952561661;6218.751249949996;6305.117283580303;6218.067289906505;6225.844353891143;6237.750489980395;6240.469560087398;6386.521010924176;6659.267480577133;6693.39759421652;6696.866062678743;6702.832429189285;6728.394444499994;5774.162746401769;5990.281166260033;5994.8501287467725;6708.731650733965;6725.226598941111;5891.79378721747;5908.320543137508;5918.171455996164;5918.496927761103;5926.911096333559;14583.416663333453;14657.780133298009;14808.777868331983;14995.250237488082;15004.02473839208;2380.190735150057;2510.33476128826;2534.239728081568;2754.820936639112;2992.0874133389307;11094.167937404727;11185.272957257834;11315.151363647677;11479.253848499904;11624.709382265426;2004.5690176611952;2008.8794672319707;2061.938141855732;2075.242536474191;2093.8638988465855;79770.6011469939;79880.20119798844;80353;83778;87714.56142719246;11032.558697652164;11044.33733975964;11207.439628018567;11489.42552872353;11727.706807329714;16847.494575162764;16859.662806743796;16864.07247601394;16910.830891690926;16924.069176195182;8257.38069644779;8259.669613215405;8372.706955256659;8481.40630155883;8487.787805304917;7757.069151701832;7837.098733645704;7863.449558530962;8002.839943201104;8048.637811298506;5063.164577844688;5157.6532677631585;5157.793688252503;5203.713795741195;5306.151015837448;1996.8565227153715;2068.979310206879;2102.3903068110076;2143.614149453082;2158.557495713375;848.7665891879659;924.5746956399997;1046.0742243114898;1077.4989629822014;1175.6179241746295;19989.800101999084;20054.89972550157;20116.89941550312;20169.39491815248;20335.288264910658;1030.8041472120274;1035.295998720877;1066.8399740038901;1077.49357801833;1081.1729027294127;10927;11369.886301137049;11473.71315717097;11497.655070347902;11657.30056196614;529099;542280;542433;549249;550448;90575;92824;93106.0689393111;94874.52562737142;96134.51932740292;1027.987432379106;1071.022557290733;1072.0458791675414;1082.2910993299402;1085.0722632149557;18376.540586485444;18434.815651843848;18613.348532801287;18658.72011919795;19128.04359782033;32818.50772238371;35424.645753542136;43524.347134793636;43704.56295437005;45142.77428612836;60786.696066519384;60966;61308.386916130265;61399.386006141154;63972.36027639664;9078.273738100805;9089.909100908906;9207.263418926335;9293.442393456144;9646.710598682052;10342.069213770797;13799.931000344934;13982.790258146339;14341.9282903589;14424.278786060864;12054.276743395425;12216.633500994983;12217.228501867357;12224.266544007389;12482.438290276777;11698.824517632413;11729.824052639387;11867.228630139014;12142.150049496804;12145.574904878287;11871.881281187423;12017.63947081589;12253.387330633612;12346.444410001392;12554;9126.361154719107;9127.863082053642;9268.58291376903;9333.066693330613;9343.823100145712;11548.653540393801;12065.396730163633;12211.755764884474;12221.083418743452;12315.322657253937 06:53:49 INFO - PROCESS | 16376 | |3;mootools.html;1560.14971840347;1618.1666441782484;1807.4125909079544;1841.539615096226;1887.1979408751276;8557.101504342032;9144.817103657939;9200.493972831491;9998.850017249748;10009.349392289496;9281.628734850614;9655.08276713711;9666.661666841663;9671.806563868719;9674.371165874214;2502.4619706763087;2527.8609676467786;2532.0884481586613;2539.8857051432637;2542.5550528657504;2177.4665207024223;2419.842710223839;2459.9139030133947;2491.838030528019;2555.425029368396;2508.41052352697;2517.974820251792;2520.5715028445115;2532.8353657012235;2535.987320063397;2434.1115492845074;2459.8524088554746;2460.2988148377626;2462.372095115752;2466.2971053249826;2437.573424650679;2455.815813813971;2480.9255722328357;2481.751824817504;2484.8260621756303;9692.854607180898;9952.35309704874;9962.850557241647;9963.402195868199;9978.850317245247;5844.26946631671;5969.37321581232;5994.130851026577;6002.279726432856;6004.909926351108;3737.570179429373;3771.585125636178;3847.672947799412;3874.2832575973534;3903.4144878268203;1269.2574843716345;1281.0392205845617;1286.3761075878192;1312.0946546882622;1316.348407538265;14194.716105677931;14233.857661423357;14236.857631423656;14239.78640320396;14259.215743134124;5031.4465408805;5097.08252514551;5241.0828105081455;5267.104592219332;5313.601479888946;11723.062155027577;11738.06095512357;11758.764824703629;11759.47082381295;11760.58837940668;5962.284525856925;5963.79126730571;5964.343922168558;5966.403359664086;5968.880622387528;5402.216678581583;5453;5463.125899856004;5465.699386533699;5530.308711411074;4469.2849144137435;4496;4521;4557.111363284194;4571.862844114682;1314.9868501315057;1365.9248741319125;1413.9787903181461;1436.6552027513321;1440.9279536023157;1060.3107979813028;1063.4948399510326;1070.8607880975424;1080.897314755097;1085.5223701571283;6645.60126392418;6670.666466676744;6811.284815094443;6870.27862074485;6874.587524748531;1040.3029969920171;1070.218740319571;1088.363307465126;1092.4592326798286;1098.8406681031365;4619.6073333766335;4681.765911704401;4711.363965864564;4786.856394308176;4790.065937142223;11609.245399049203;11707.239029463057;12105.152639315163;12108.091893108052;12112.515499379926;12335.198212116364;12391.566295179626;12403.875961240272;12407.44166512509;12413.689657758448;8498.320134389234;8690.261327787082;8693.261072808882;8701.825963480695;8703.47779133254;6686.097376854257;6699.899501507579;6772.187337519529;6784.423324017514;6913.861722765617;11030.117590592894;11240.662780116609;11528.942355288169;11545.422728863692;11545.48045337945;11358.659240222807;11683;11709.063274938155;11737.706557336134;11867.58463453775;8878.067802880605;9070.365074444726;9128.269738421042;9136.588853501475;9385.249180065442;8994.775130621787;9056.36605437613;9112.635494580407;9339.906600933904;9375.437473751597;6519.576227545384;8186.181381861767;8308.12764659702;8335.08314085438;8453.36599755033;8024.879626805719;8083.434159608771;8144.063432705315;8169.468984515987;8203.261706446448;7516.323530882247;7563;7626.847463050597;7640.923590764021;7774.766756997299;5626.521745651666;5659.830205093854;5701.6864072474755;5726.627769194989;5874.823755287348;5490.423505531861;5515.61390702647;5551.944480555143;5617.550595952396;5638.464345887134 06:53:49 INFO - PROCESS | 16376 | |4;prototype.html;355.6958800225807;362.4092728851971;365.60879858551345;370.9758865673731;371.383503384382;5407.918881216785;5528.585356098299;5567.721613919308;5655;5688.317401911766;5544.972275138629;5546.725842404615;5552.888942221153;5584.91622625661;5586.581006424524;4380.3210502372185;4385.320275357317;4396.7361958282445;4413.5365786592265;4424.446944131983;4136.8965775855695;4312.396264522969;4346.9565304347025;4359.520452750211;4363.170997510463;4311.698181127323;4312.331588603755;4313.309870420733;4316.633086187678;4319.114581510798;4302.505211900624;4315.525292217853;4325.82696692132;4342.30523116303;4344.543822898613;3760.567534733486;3831.1571454280015;3833.773192578376;3837.8656747014;3843.461915331856;5314.468553144693;5318.202269659543;5369.167778994262;5377.381601115843;5387.568994480433;4864.1974074277705;4900.289458028602;4903.166461701519;4904.8473608701925;4908;5431.511163995259;5730.169125476824;5751.971240143815;5758.769649214027;5773.393793651648;4183.5398106208295;4185.016521117535;4186.748795072275;4190.28765109929;4217.557156498554;6049.78825741097;6078.240219972504;6078.936186167403;6080.452759251689;6092;4220.599043090901;4289.24938135825;4304.655627549789;4328.523862375137;4332.956670433256;4295.613394794484;4300.720453170597;4312.784360781949;4335.696501244883;4335.934960975588;4889;4895.996320754237;4902.284200529786;4907.1903135982875;4909.140900342426;5710.5146062585145;5762.9135562966585;5767.653940763567;5778.884422311614;5790.826275211751;1471.6541612721003;1495.9177245251615;1502.5942995391183;1510.9848901510845;1517.1807224099084;927.0122684279843;964.9662261820803;971.5870754929127;972.2027937091658;972.7373609125497;4954.876128096755;4960;4977.477364876709;4980.203167493184;4986.476419975923;5125.769340379692;5190.143626301618;5198.584113270929;5264.894702105937;5269.998700246941;5047.848564543069;5063.822766203239;5065.696058236517;5241.868953276198;5641.435856414326;3126.9530957035663;3159.7472202223767;3161.936761264762;3162.414953233659;3162.588863447737;6105.877882442326;6115.938840611626;6134.2332208473945;6136.048912418582;6183.134361189437;5756.942430575725;6048.788292409745;6054.455099041107;6059.818205453844;6076.513878889678;6134.846628834315;6173.96913015441;6195.535334849904;6199.5660303779205;6253.78111766086;5731.8853622928145;5989.2812862456785;6013.158157857816;6034.24571928509;6034.78878239277;5932.317783454785;5949.3753155918;5955.344912059757;5962.522998160223;5966.403359663999;6081.239845019372;6161.4146656067605;6245.750169993149;6247.156633854372;6275.02737075754;4293.742375457483;4318.654507639444;4339.826406943687;4340.457442819647;4351.282038463618;6063.211782468251;6077.878442431215;6089.360617135137;6090.9390906090375;6094.598242404183;5653.604247702621;5765.971170144123;5827.59206855533;5841.737121829443;5907.172995780508;5797.4782269595935;5817.738201780845;5820.8067346194775;5824.592278540462;5843.620164689281;5780.306363236438;5808.448197421239;5838.299404071538;5840.561957853093;5842.766289348378;5594.328680558359;5824.213731146241;5826.82519524415;5828.300603927556;5841.153032810304;5718.055861007278;5723.42765723441;5730.169125476699;5738.799142030009;5763.538916886723 06:53:49 INFO - PROCESS | 16376 | |5;yui.html;765.2882818978352;773.7330620935777;818.3739439328914;825.4510750351017;830.3191383065887;16173.191340432975;17265.827341726577;17277.568060798487;17319.134043297832;18632.906835465838;16442.917785411057;16731.07979061151;16786.160691965382;16786.916065419686;16801.747973780402;735.2096496266535;865.2040123086775;878.1174919206189;882.1883866842488;901.2609660078738;901.175424486594;910.258139616214;913.0504275553417;913.2146354135439;913.2602591900582;959.8560215967591;965.541367850269;965.5703212070631;966.4394651102343;966.5553845231202;773.9458237923347;912.6851236323457;947.3321308477507;951.219999600328;954.0602506531061;690.0201713566747;690.8272931767058;691.3881215124651;691.6541729135432;691.7509696509253;16964.151792410452;17062.23219955105;17087.914560427118;17108.65782684352;17178.82821171785;14780.852191478163;15106.39574417022;15160.241987900672;15216.239188040665;15436.305366258546;14579.125252484884;14747.115173089649;14784.778228326584;14986.400543978229;15023;4803.495632958524;4838.516148385169;4879.82920597781;4940.209566469383;4980.003999200175;89657;89845;90395;91079;91261;11926.761464770656;12837.679058023437;12931.224126552437;13044.934775326063;13051.934740326238;22148.889255553942;22576.887115564645;22586.548269034854;22612.773872261398;22634.660480092483;8818.823623527494;9046.909530904739;9071.319651026068;9122.86315705265;9214.44713317203;4893.926591101136;4994.200927851526;5024.648274620815;5030.0945829751;5263.947360526346;4162.62536371728;4305.354196870495;4327.978360108243;4337.652987760972;4346;687.5118665747375;688.0367485520212;688.2257460357098;699.105145413871;703.3283214530112;725.9709611615582;733.5525329548971;828.4863384701404;834.2908527751484;838.9454685445427;10871.402072885909;10949.726256843642;11001.22991390595;11097.667069987914;11634.243774154653;668.5086461450829;775.7013549783373;792.9762107136795;809.3444310108831;829.0590180145579;5301.061106831372;5371.597130215249;5544.833654990357;5573.498385145356;5587.580931430159;72838;74388.62805685936;123472;125914.37042814911;130789.34605326912;87248;88206;92055.53972230096;93054;96604.51697741466;71093.64453177701;86632.56683716542;91648.54175729079;92835.99999999865;94065;10698.411587362458;11013.889861101607;11284;11356.0915126788;11411;53602.19596706131;53767.46232537781;54563.45436545742;54956;55695.16457253064;74181;78283.21716782759;79236.60381698054;80187.19812801798;82267.58866205631;13509.18944863272;13762.587122386345;13781;13935.233562154179;13960.441582336998;15911.363545458515;16442.34230630809;16595.91702041482;16627.50117496477;16975.405860794814;14106.78839817383;14467.565973020826;14781.92609036948;15538.300776465312;15615.76576351333;14264.857351426353;14653;14809.25953702289;15061.548153555412;15102.244887755349;14372.425102995763;14661.633459163606;14718.411263549766;14836;14850.554483365517;11802.114841386758;12216.14486985938;12274.4476498556;12321.137520373488;12557.87442125567;12363.62909112692;12482.875171248534;12563;12584.496620135093;12807.295598741786 06:53:49 INFO - PROCESS | 16376 | __end_tp_report 06:53:49 INFO - PROCESS | 16376 | __start_cc_report 06:53:49 INFO - PROCESS | 16376 | _x_x_mozilla_cycle_collect,1172 06:53:49 INFO - PROCESS | 16376 | __end_cc_report 06:53:49 INFO - PROCESS | 16376 | __startTimestamp1471355629428__endTimestamp 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | ------- Summary: start ------- 06:53:49 INFO - PROCESS | 16376 | Number of tests: 6 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | [#0] dojo.html Cycles:165 Average:5684.33 Median:5916.56 stddev:657.72 (11.1%) stddev-sans-first:631.51 06:53:49 INFO - PROCESS | 16376 | Values: 3254.4 3276.4 3282.0 3305.9 3325.9 5658.7 5773.1 5790.8 5793.0 5806.8 5613.3 5681.0 5691.7 5693.7 5702.9 5033.5 5123.5 5129.5 5188.1 5216.0 5096.8 5167.4 5217.6 5217.7 5232.3 5079.2 5132.8 5152.4 5165.8 5169.4 5256.9 5260.5 5263.7 5263.8 5297.7 4979.7 5057.8 5092.1 5140.0 5143.3 5600.2 5637.8 5643.6 5661.6 5665.8 6167.4 6175.1 6180.2 6182.6 6186.1 5938.4 6042.9 6118.5 6134.5 6208.6 5761.9 5763.5 5775.7 5782.7 5784.4 6261.1 6268.2 6269.3 6277.7 6279.7 5907.8 6063.7 6106.9 6140.9 6143.2 6190.3 6201.6 6203.8 6207.5 6211.7 6097.5 6165.7 6176.9 6183.6 6200.3 5905.5 5959.0 5988.0 6023.9 6043.0 4635.9 4672.8 4675.5 4687.9 4698.7 4170.5 4171.8 4181.6 4182.4 4198.7 5646.1 5760.9 5799.2 5808.1 5832.4 6200.0 6207.8 6211.4 6218.3 6222.0 5839.3 5843.2 5845.1 5845.1 5869.0 5104.5 5185.6 5243.7 5278.0 5318.4 6316.5 6317.6 6338.1 6341.3 6346.1 6057.6 6097.6 6122.4 6123.4 6127.5 6133.3 6175.4 6257.4 6331.9 6344.4 6049.4 6054.7 6058.8 6077.4 6100.3 6122.5 6133.1 6143.6 6144.8 6145.0 5847.5 5905.5 5929.3 5977.0 5988.1 5944.1 5996.6 6057.6 6077.8 6100.3 5995.2 6003.6 6007.9 6010.0 6010.0 5827.4 5881.4 5939.9 6078.7 6095.6 5896.9 5916.6 5922.2 5929.7 5945.2 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | [#1] ext.html Cycles:180 Average:67676.17 Median:21931.68 stddev:138343.38 (630.8%) stddev-sans-first:138639.82 06:53:49 INFO - PROCESS | 16376 | Values: 604.6 645.2 659.2 669.3 670.4 34726.5 46219.3 47603.3 47631.3 50785.0 48248.8 48757.3 48765.0 48790.8 48822.0 9341.1 9499.5 9502.1 9506.5 9509.9 8446.2 10994.8 11330.0 11338.4 11706.2 11412.0 11535.0 11537.1 11540.8 11546.1 11365.9 11443.4 11445.3 11450.8 11461.5 11271.3 11311.4 11321.0 11323.7 11359.7 44885.1 45136.8 45225.3 45228.8 45233.3 3623.1 3687.0 3861.4 3879.5 4075.3 35634.6 35749.5 35765.5 36222.3 37399.0 4550.7 4567.5 4588.4 4615.4 4795.3 383688.0 386414.0 387718.0 388604.0 389500.0 37411.8 61527.0 62042.7 62043.4 62092.4 37340.0 39548.8 39627.2 39655.4 39788.0 15472.4 15602.8 15615.0 15616.6 15621.1 11795.4 11822.3 12710.7 12876.4 12906.7 8979.6 9031.5 9164.0 9173.4 9180.1 3217.2 3254.3 3287.9 3308.6 3355.1 1057.2 1095.0 1141.7 1203.7 1205.8 31112.5 31316.2 31603.0 31997.4 32018.0 1151.1 1161.1 1178.4 1218.7 1271.4 16815.9 17072.7 17114.1 17356.6 17500.6 594236.0 598718.0 602353.0 602531.0 603335.0 64460.4 65099.0 65219.7 65241.0 65312.7 1324.0 1427.9 1436.9 1452.4 1489.7 33778.3 36765.4 37183.8 37207.4 37619.4 56731.9 57697.0 57751.4 57766.7 57924.4 540449.0 550440.0 550477.0 551393.0 552855.0 48499.5 48703.3 48787.5 49235.3 51732.7 53379.5 53861.5 54054.5 54172.2 54205.5 44883.6 45278.0 46283.5 46380.3 46801.1 44411.8 44508.3 45075.0 46272.8 46629.5 45791.8 46170.3 47003.0 47193.3 47629.0 38825.4 40868.6 40997.4 41009.6 41786.4 30106.4 30322.5 30544.8 30603.7 30622.4 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | [#2] jquery.html Cycles:180 Average:30803.43 Median:21498.52 stddev:89253.31 (415.2%) stddev-sans-first:89474.50 06:53:49 INFO - PROCESS | 16376 | Values: 399.6 432.7 432.7 433.9 445.9 16792.6 20606.4 21082.8 21490.6 21506.5 20474.2 20536.7 20538.8 20543.7 20708.5 5589.1 5898.7 6172.8 6218.8 6305.1 6218.1 6225.8 6237.8 6240.5 6386.5 6659.3 6693.4 6696.9 6702.8 6728.4 5774.2 5990.3 5994.9 6708.7 6725.2 5891.8 5908.3 5918.2 5918.5 5926.9 14583.4 14657.8 14808.8 14995.3 15004.0 2380.2 2510.3 2534.2 2754.8 2992.1 11094.2 11185.3 11315.2 11479.3 11624.7 2004.6 2008.9 2061.9 2075.2 2093.9 79770.6 79880.2 80353.0 83778.0 87714.6 11032.6 11044.3 11207.4 11489.4 11727.7 16847.5 16859.7 16864.1 16910.8 16924.1 8257.4 8259.7 8372.7 8481.4 8487.8 7757.1 7837.1 7863.4 8002.8 8048.6 5063.2 5157.7 5157.8 5203.7 5306.2 1996.9 2069.0 2102.4 2143.6 2158.6 848.8 924.6 1046.1 1077.5 1175.6 19989.8 20054.9 20116.9 20169.4 20335.3 1030.8 1035.3 1066.8 1077.5 1081.2 10927.0 11369.9 11473.7 11497.7 11657.3 529099.0 542280.0 542433.0 549249.0 550448.0 90575.0 92824.0 93106.1 94874.5 96134.5 1028.0 1071.0 1072.0 1082.3 1085.1 18376.5 18434.8 18613.3 18658.7 19128.0 32818.5 35424.6 43524.3 43704.6 45142.8 60786.7 60966.0 61308.4 61399.4 63972.4 9078.3 9089.9 9207.3 9293.4 9646.7 10342.1 13799.9 13982.8 14341.9 14424.3 12054.3 12216.6 12217.2 12224.3 12482.4 11698.8 11729.8 11867.2 12142.2 12145.6 11871.9 12017.6 12253.4 12346.4 12554.0 9126.4 9127.9 9268.6 9333.1 9343.8 11548.7 12065.4 12211.8 12221.1 12315.3 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | [#3] mootools.html Cycles:180 Average:6454.11 Median:4595.74 stddev:3707.33 (80.7%) stddev-sans-first:3699.49 06:53:49 INFO - PROCESS | 16376 | Values: 1560.1 1618.2 1807.4 1841.5 1887.2 8557.1 9144.8 9200.5 9998.9 10009.3 9281.6 9655.1 9666.7 9671.8 9674.4 2502.5 2527.9 2532.1 2539.9 2542.6 2177.5 2419.8 2459.9 2491.8 2555.4 2508.4 2518.0 2520.6 2532.8 2536.0 2434.1 2459.9 2460.3 2462.4 2466.3 2437.6 2455.8 2480.9 2481.8 2484.8 9692.9 9952.4 9962.9 9963.4 9978.9 5844.3 5969.4 5994.1 6002.3 6004.9 3737.6 3771.6 3847.7 3874.3 3903.4 1269.3 1281.0 1286.4 1312.1 1316.3 14194.7 14233.9 14236.9 14239.8 14259.2 5031.4 5097.1 5241.1 5267.1 5313.6 11723.1 11738.1 11758.8 11759.5 11760.6 5962.3 5963.8 5964.3 5966.4 5968.9 5402.2 5453.0 5463.1 5465.7 5530.3 4469.3 4496.0 4521.0 4557.1 4571.9 1315.0 1365.9 1414.0 1436.7 1440.9 1060.3 1063.5 1070.9 1080.9 1085.5 6645.6 6670.7 6811.3 6870.3 6874.6 1040.3 1070.2 1088.4 1092.5 1098.8 4619.6 4681.8 4711.4 4786.9 4790.1 11609.2 11707.2 12105.2 12108.1 12112.5 12335.2 12391.6 12403.9 12407.4 12413.7 8498.3 8690.3 8693.3 8701.8 8703.5 6686.1 6699.9 6772.2 6784.4 6913.9 11030.1 11240.7 11528.9 11545.4 11545.5 11358.7 11683.0 11709.1 11737.7 11867.6 8878.1 9070.4 9128.3 9136.6 9385.2 8994.8 9056.4 9112.6 9339.9 9375.4 6519.6 8186.2 8308.1 8335.1 8453.4 8024.9 8083.4 8144.1 8169.5 8203.3 7516.3 7563.0 7626.8 7640.9 7774.8 5626.5 5659.8 5701.7 5726.6 5874.8 5490.4 5515.6 5551.9 5617.6 5638.5 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | [#4] prototype.html Cycles:180 Average:4860.25 Median:5480.05 stddev:1423.12 (26.0%) stddev-sans-first:1386.36 06:53:49 INFO - PROCESS | 16376 | Values: 355.7 362.4 365.6 371.0 371.4 5407.9 5528.6 5567.7 5655.0 5688.3 5545.0 5546.7 5552.9 5584.9 5586.6 4380.3 4385.3 4396.7 4413.5 4424.4 4136.9 4312.4 4347.0 4359.5 4363.2 4311.7 4312.3 4313.3 4316.6 4319.1 4302.5 4315.5 4325.8 4342.3 4344.5 3760.6 3831.2 3833.8 3837.9 3843.5 5314.5 5318.2 5369.2 5377.4 5387.6 4864.2 4900.3 4903.2 4904.8 4908.0 5431.5 5730.2 5752.0 5758.8 5773.4 4183.5 4185.0 4186.7 4190.3 4217.6 6049.8 6078.2 6078.9 6080.5 6092.0 4220.6 4289.2 4304.7 4328.5 4333.0 4295.6 4300.7 4312.8 4335.7 4335.9 4889.0 4896.0 4902.3 4907.2 4909.1 5710.5 5762.9 5767.7 5778.9 5790.8 1471.7 1495.9 1502.6 1511.0 1517.2 927.0 965.0 971.6 972.2 972.7 4954.9 4960.0 4977.5 4980.2 4986.5 5125.8 5190.1 5198.6 5264.9 5270.0 5047.8 5063.8 5065.7 5241.9 5641.4 3127.0 3159.7 3161.9 3162.4 3162.6 6105.9 6115.9 6134.2 6136.0 6183.1 5756.9 6048.8 6054.5 6059.8 6076.5 6134.8 6174.0 6195.5 6199.6 6253.8 5731.9 5989.3 6013.2 6034.2 6034.8 5932.3 5949.4 5955.3 5962.5 5966.4 6081.2 6161.4 6245.8 6247.2 6275.0 4293.7 4318.7 4339.8 4340.5 4351.3 6063.2 6077.9 6089.4 6090.9 6094.6 5653.6 5766.0 5827.6 5841.7 5907.2 5797.5 5817.7 5820.8 5824.6 5843.6 5780.3 5808.4 5838.3 5840.6 5842.8 5594.3 5824.2 5826.8 5828.3 5841.2 5718.1 5723.4 5730.2 5738.8 5763.5 06:53:49 INFO - PROCESS | 16376 | 06:53:49 INFO - PROCESS | 16376 | [#5] yui.html Cycles:180 Average:21727.65 Median:4886.88 stddev:29942.99 (612.7%) stddev-sans-first:29985.62 06:53:49 INFO - PROCESS | 16376 | Values: 765.3 773.7 818.4 825.5 830.3 16173.2 17265.8 17277.6 17319.1 18632.9 16442.9 16731.1 16786.2 16786.9 16801.7 735.2 865.2 878.1 882.2 901.3 901.2 910.3 913.1 913.2 913.3 959.9 965.5 965.6 966.4 966.6 773.9 912.7 947.3 951.2 954.1 690.0 690.8 691.4 691.7 691.8 16964.2 17062.2 17087.9 17108.7 17178.8 14780.9 15106.4 15160.2 15216.2 15436.3 14579.1 14747.1 14784.8 14986.4 15023.0 4803.5 4838.5 4879.8 4940.2 4980.0 89657.0 89845.0 90395.0 91079.0 91261.0 11926.8 12837.7 12931.2 13044.9 13051.9 22148.9 22576.9 22586.5 22612.8 22634.7 8818.8 9046.9 9071.3 9122.9 9214.4 4893.9 4994.2 5024.6 5030.1 5263.9 4162.6 4305.4 4328.0 4337.7 4346.0 687.5 688.0 688.2 699.1 703.3 726.0 733.6 828.5 834.3 838.9 10871.4 10949.7 11001.2 11097.7 11634.2 668.5 775.7 793.0 809.3 829.1 5301.1 5371.6 5544.8 5573.5 5587.6 72838.0 74388.6 123472.0 125914.4 130789.3 87248.0 88206.0 92055.5 93054.0 96604.5 71093.6 86632.6 91648.5 92836.0 94065.0 10698.4 11013.9 11284.0 11356.1 11411.0 53602.2 53767.5 54563.5 54956.0 55695.2 74181.0 78283.2 79236.6 80187.2 82267.6 13509.2 13762.6 13781.0 13935.2 13960.4 15911.4 16442.3 16595.9 16627.5 16975.4 14106.8 14467.6 14781.9 15538.3 15615.8 14264.9 14653.0 14809.3 15061.5 15102.2 14372.4 14661.6 14718.4 14836.0 14850.6 11802.1 12216.1 12274.4 12321.1 12557.9 12363.6 12482.9 12563.0 12584.5 12807.3 06:53:49 INFO - PROCESS | 16376 | -------- Summary: end -------- 06:53:49 INFO - PROCESS | 16376 | 06:53:50 INFO - TEST-INFO | 16376: exit 0 06:53:50 INFO - TEST-OK | dromaeo_css | took 1113701ms 06:53:50 INFO - TEST-START | kraken 06:53:50 INFO - operating with platform_type : linux_ 06:53:50 INFO - Initialising browser for kraken test... 06:53:50 INFO - TEST-INFO | started process 16574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnyX_lZ/profile http://localhost:45156/getInfo.html) 06:53:54 INFO - PROCESS | 16574 | __metrics Screen width/height:1600/1200 06:53:54 INFO - PROCESS | 16574 | colorDepth:24 06:53:54 INFO - PROCESS | 16574 | Browser inner width/height: 1024/697 06:53:54 INFO - PROCESS | 16574 | __metrics 06:53:55 INFO - PROCESS | 16574 | 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" 06:53:55 INFO - PROCESS | 16574 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:53:55 INFO - TEST-INFO | 16574: exit 0 06:53:55 INFO - Browser initialized. 06:53:55 INFO - Running cycle 1/1 for kraken test... 06:53:55 INFO - TEST-INFO | started process 16663 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnyX_lZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:54:07 INFO - PROCESS | 16663 | RSS: Main: 145489920 06:54:07 INFO - PROCESS | 16663 | 06:54:10 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45156/tests/kraken/driver.html?testName=audio-beat-detection) 06:54:10 INFO - PROCESS | 16663 | RSS: Main: 191868928 06:54:10 INFO - PROCESS | 16663 | 06:54:13 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45156/tests/kraken/driver.html?testName=audio-dft) 06:54:14 INFO - PROCESS | 16663 | RSS: Main: 182333440 06:54:14 INFO - PROCESS | 16663 | 06:54:16 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45156/tests/kraken/driver.html?testName=audio-fft) 06:54:17 INFO - PROCESS | 16663 | RSS: Main: 269529088 06:54:17 INFO - PROCESS | 16663 | 06:54:19 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45156/tests/kraken/driver.html?testName=audio-oscillator) 06:54:20 INFO - PROCESS | 16663 | RSS: Main: 175443968 06:54:20 INFO - PROCESS | 16663 | 06:54:22 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:54:22 INFO - PROCESS | 16663 | RSS: Main: 176963584 06:54:22 INFO - PROCESS | 16663 | 06:54:25 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45156/tests/kraken/driver.html?testName=imaging-darkroom) 06:54:26 INFO - PROCESS | 16663 | RSS: Main: 213151744 06:54:26 INFO - PROCESS | 16663 | 06:54:29 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45156/tests/kraken/driver.html?testName=imaging-desaturate) 06:54:30 INFO - PROCESS | 16663 | RSS: Main: 213041152 06:54:30 INFO - PROCESS | 16663 | 06:54:33 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45156/tests/kraken/driver.html?testName=json-parse-financial) 06:54:33 INFO - PROCESS | 16663 | RSS: Main: 211767296 06:54:33 INFO - PROCESS | 16663 | 06:54:35 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:54:35 INFO - PROCESS | 16663 | RSS: Main: 176476160 06:54:35 INFO - PROCESS | 16663 | 06:54:37 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:54:38 INFO - PROCESS | 16663 | RSS: Main: 181583872 06:54:38 INFO - PROCESS | 16663 | 06:54:40 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:54:40 INFO - PROCESS | 16663 | RSS: Main: 186310656 06:54:40 INFO - PROCESS | 16663 | 06:54:43 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:54:44 INFO - PROCESS | 16663 | RSS: Main: 180273152 06:54:44 INFO - PROCESS | 16663 | 06:54:46 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:54:47 INFO - PROCESS | 16663 | RSS: Main: 184074240 06:54:47 INFO - PROCESS | 16663 | 06:54:48 INFO - PROCESS | 16663 | Cycle 1(1): loaded http://localhost:45156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45156/tests/kraken/driver.html?testName=ai-astar) 06:54:49 INFO - PROCESS | 16663 | RSS: Main: 235036672 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | __start_tp_report 06:54:49 INFO - PROCESS | 16663 | _x_x_mozilla_page_load 06:54:49 INFO - PROCESS | 16663 | _x_x_mozilla_page_load_details 06:54:49 INFO - PROCESS | 16663 | |i|pagename|runs| 06:54:49 INFO - PROCESS | 16663 | |0;ai-astar;99;109;110;109;110;109;107;109;110;108 06:54:49 INFO - PROCESS | 16663 | |1;audio-beat-detection;146;116;115;166;121;117;145;114;143;114 06:54:49 INFO - PROCESS | 16663 | |2;audio-dft;161;161;162;161;161;162;163;163;160;162 06:54:49 INFO - PROCESS | 16663 | |3;audio-fft;75;77;74;117;76;75;74;75;98;76 06:54:49 INFO - PROCESS | 16663 | |4;audio-oscillator;83;84;123;83;83;83;123;82;83;120 06:54:49 INFO - PROCESS | 16663 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;117 06:54:49 INFO - PROCESS | 16663 | |6;imaging-darkroom;146;146;146;146;146;146;147;146;145;145 06:54:49 INFO - PROCESS | 16663 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;96;94 06:54:49 INFO - PROCESS | 16663 | |8;json-parse-financial;52;51;53;51;51;52;52;51;50;52 06:54:49 INFO - PROCESS | 16663 | |9;json-stringify-tinderbox;59;59;59;59;60;59;59;60;59;62 06:54:49 INFO - PROCESS | 16663 | |10;stanford-crypto-aes;73;82;76;72;75;73;72;75;72;76 06:54:49 INFO - PROCESS | 16663 | |11;stanford-crypto-ccm;125;121;118;117;122;128;123;117;192;128 06:54:49 INFO - PROCESS | 16663 | |12;stanford-crypto-pbkdf2;156;158;153;152;153;157;151;158;165;153 06:54:49 INFO - PROCESS | 16663 | |13;stanford-crypto-sha256-iterative;61;60;61;60;60;60;60;60;63;59 06:54:49 INFO - PROCESS | 16663 | __end_tp_report 06:54:49 INFO - PROCESS | 16663 | __start_cc_report 06:54:49 INFO - PROCESS | 16663 | _x_x_mozilla_cycle_collect,1607 06:54:49 INFO - PROCESS | 16663 | __end_cc_report 06:54:49 INFO - PROCESS | 16663 | __startTimestamp1471355689164__endTimestamp 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | ------- Summary: start ------- 06:54:49 INFO - PROCESS | 16663 | Number of tests: 14 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#0] ai-astar Cycles:10 Average:108.00 Median:109.50 stddev:3.30 (3.0%) stddev-sans-first:1.00 06:54:49 INFO - PROCESS | 16663 | Values: 99.0 109.0 110.0 109.0 110.0 109.0 107.0 109.0 110.0 108.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:132.00 stddev:18.64 (14.1%) stddev-sans-first:18.82 06:54:49 INFO - PROCESS | 16663 | Values: 146.0 116.0 115.0 166.0 121.0 117.0 145.0 114.0 143.0 114.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 06:54:49 INFO - PROCESS | 16663 | Values: 161.0 161.0 162.0 161.0 161.0 162.0 163.0 163.0 160.0 162.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.50 stddev:14.35 (19.0%) stddev-sans-first:15.01 06:54:49 INFO - PROCESS | 16663 | Values: 75.0 77.0 74.0 117.0 76.0 75.0 74.0 75.0 98.0 76.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.00 stddev:18.86 (22.7%) stddev-sans-first:19.53 06:54:49 INFO - PROCESS | 16663 | Values: 83.0 84.0 123.0 83.0 83.0 83.0 123.0 82.0 83.0 120.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:54:49 INFO - PROCESS | 16663 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:54:49 INFO - PROCESS | 16663 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 145.0 145.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 06:54:49 INFO - PROCESS | 16663 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 94.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:0.85 (1.6%) stddev-sans-first:0.88 06:54:49 INFO - PROCESS | 16663 | Values: 52.0 51.0 53.0 51.0 51.0 52.0 52.0 51.0 50.0 52.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 06:54:49 INFO - PROCESS | 16663 | Values: 59.0 59.0 59.0 59.0 60.0 59.0 59.0 60.0 59.0 62.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:3.06 (4.1%) stddev-sans-first:3.19 06:54:49 INFO - PROCESS | 16663 | Values: 73.0 82.0 76.0 72.0 75.0 73.0 72.0 75.0 72.0 76.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:124.00 stddev:22.47 (18.1%) stddev-sans-first:23.79 06:54:49 INFO - PROCESS | 16663 | Values: 125.0 121.0 118.0 117.0 122.0 128.0 123.0 117.0 192.0 128.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.60 Median:156.50 stddev:4.17 (2.7%) stddev-sans-first:4.42 06:54:49 INFO - PROCESS | 16663 | Values: 156.0 158.0 153.0 152.0 153.0 157.0 151.0 158.0 165.0 153.0 06:54:49 INFO - PROCESS | 16663 | 06:54:49 INFO - PROCESS | 16663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.00 stddev:1.07 (1.8%) stddev-sans-first:1.12 06:54:49 INFO - PROCESS | 16663 | Values: 61.0 60.0 61.0 60.0 60.0 60.0 60.0 60.0 63.0 59.0 06:54:49 INFO - PROCESS | 16663 | -------- Summary: end -------- 06:54:49 INFO - PROCESS | 16663 | 06:54:50 INFO - TEST-INFO | 16663: exit 0 06:54:50 INFO - TEST-OK | kraken | took 59916ms 06:54:50 INFO - SUITE-END | took 1173s 06:54:50 INFO - Completed test suite (00:19:33) 06:54:50 INFO - javascript benchmark 06:54:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.3979363817693, 3276.4266253405663, 3282.0482060202535, 3305.9008229753085, 3325.916852078698, 5658.660480371175, 5773.105168698853, 5790.797322093727, 5793.044147715622, 5806.825795226145, 5613.270274864272, 5681.0, 5691.743871525781, 5693.686847223401, 5702.885942281152, 5033.471485494026, 5123.5388815006645, 5129.538341549269, 5188.143956247233, 5216.0, 5096.770645320969, 5167.354080739908, 5217.608679349045, 5217.660852044605, 5232.293640358541, 5079.212722028073, 5132.846014619548, 5152.433232344439, 5165.767540460688, 5169.379674439054, 5256.8685782855355, 5260.473952604747, 5263.736813159327, 5263.76313065911, 5297.735113244361, 4979.676321039121, 5057.848264552069, 5092.057969275659, 5140.023395554833, 5143.27994080829, 5600.243967064435, 5637.774489020434, 5643.5767317451355, 5661.60368774186, 5665.801696940628, 6167.352427995041, 6175.104609831594, 6180.165677633552, 6182.567220294582, 6186.133941248228, 5938.406159384027, 6042.9395706042815, 6118.4799292060225, 6134.509239260893, 6208.627482351028, 5761.884762304772, 5763.538916886639, 5775.682337471438, 5782.710864456761, 5784.363719990798, 6261.092141639437, 6268.216472940882, 6269.310375858652, 6277.7175027123885, 6279.7488100476385, 5907.793227237026, 6063.6664983426335, 6106.908396374058, 6140.907886381708, 6143.201383820164, 6190.3190649028575, 6201.596896201822, 6203.7828675997025, 6207.534434917399, 6211.689415529206, 6097.542684298606, 6165.660888651168, 6176.907346389808, 6183.598066125687, 6200.348963358872, 5905.498032667186, 5958.970205149033, 5988.04191329394, 6023.879522409527, 6042.969785151134, 4635.860924172281, 4672.76636168197, 4675.462321832964, 4687.859364219079, 4698.718076915396, 4170.457840480718, 4171.83312667496, 4181.623653871166, 4182.393552934807, 4198.727082739612, 5646.096624540054, 5760.855978600484, 5799.217105690762, 5808.07070868659, 5832.358440571534, 6200.038993955944, 6207.844803879939, 6211.440970312694, 6218.347073557211, 6222.0, 5839.328477225174, 5843.211166492554, 5845.064789633639, 5845.09401042839, 5869.0, 5104.540591346797, 5185.611079169077, 5243.685378877203, 5278.0, 5318.361796584435, 6316.526260530479, 6317.589356691892, 6338.144350512714, 6341.3341599132345, 6346.079818426302, 6057.606255593372, 6097.634141951497, 6122.418370254819, 6123.357047509948, 6127.540434467343, 6133.304060594526, 6175.44421002112, 6257.405546473078, 6331.873362532631, 6344.429001389897, 6049.425304596057, 6054.727537260922, 6058.757649693963, 6077.361877002851, 6100.328963813889, 6122.510199184144, 6133.141360209485, 6143.631382117087, 6144.846378840565, 6144.969275153774, 5847.473727364558, 5905.527557795452, 5929.258842644669, 5977.0, 5988.071848863433, 5944.138099975393, 5996.640201587918, 6057.636541807506, 6077.756889724362, 6100.328963814066, 5995.16067750524, 6003.6397816131175, 6007.939920600738, 6010.0, 6010.0, 5827.4172582741385, 5881.38245484218, 5939.851503712442, 6078.7264573093435, 6095.603785753912, 5896.852578685395, 5916.556258280732, 5922.170896074467, 5929.733162007807, 5945.167676525376], "value": 5639.034111565763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.6221111805119, 645.1935081148564, 659.1661548141604, 669.2504395077514, 670.4033410264864, 34726.47910281345, 46219.306710399374, 47603.285950710764, 47631.285530717025, 50785.0, 48248.75875620617, 48757.268640970324, 48765.0, 48790.75604621981, 48822.0, 9341.065893410672, 9499.477528735917, 9502.097300756452, 9506.47714375709, 9509.906360768531, 8446.155384461536, 10994.780104397907, 11330.036946859527, 11338.376389298584, 11706.180567360288, 11412.0, 11535.0, 11537.134714896374, 11540.769184616303, 11546.134039947037, 11365.886341136566, 11443.370614616193, 11445.256058356263, 11450.770984580304, 11461.484233209525, 11271.323720576791, 11311.377874216914, 11321.0, 11323.660290191307, 11359.716007099805, 44885.10229795418, 45136.77431612854, 45225.321620175724, 45228.773856130836, 45233.321500177524, 3623.148560088377, 3687.0413692439893, 3861.362875125601, 3879.495665563487, 4075.347944328923, 35634.64365356339, 35749.46375804365, 35765.46351804725, 36222.275554489024, 37399.0, 4550.704204226748, 4567.497575266718, 4588.380568623226, 4615.446146462413, 4795.256735206049, 383688.0, 386414.0, 387718.0, 388604.0, 389500.0, 37411.812940935124, 61527.0, 62042.68978655078, 62043.37956620466, 62092.37907620866, 37340.0, 39548.802255988536, 39627.2074558513, 39655.40516892249, 39788.005299867735, 15472.381104755908, 15602.843971560365, 15615.0, 15616.609584760472, 15621.14083725406, 11795.41022948849, 11822.290662560274, 12710.745785084431, 12876.420561074774, 12906.67733306675, 8979.595918183697, 9031.548422578844, 9163.95418022919, 9173.403728757743, 9180.081991800767, 3217.2439476722952, 3254.2840575073446, 3287.852046657906, 3308.5698859148156, 3355.1108956126645, 1057.2070946789909, 1094.956201751937, 1141.6575027491886, 1203.7351782607811, 1205.7761372207217, 31112.533312000338, 31316.217094572818, 31603.0, 31997.36005279928, 32018.0, 1151.090638395675, 1161.105948419729, 1178.4461303187659, 1218.670958841108, 1271.4405661508906, 16815.915920420564, 17072.658546828992, 17114.14429278556, 17356.566085847702, 17500.5624859377, 594236.0, 598718.0, 602353.0, 602531.0, 603335.0, 64460.35539644637, 65099.0, 65219.67390163114, 65241.0, 65312.67343663251, 1323.973520529584, 1427.9143251404948, 1436.899417040818, 1452.4190323870537, 1489.6871656951944, 33778.32443351168, 36765.44851827171, 37183.81408092942, 37207.44188837224, 37619.435708464945, 56731.86536269334, 57697.0, 57751.42248577628, 57766.7111664439, 57924.4207557919, 540449.0, 550440.0, 550477.0, 551393.0, 552855.0, 48499.51500485091, 48703.26945095755, 48787.512124878296, 49235.26147107868, 51732.741336293075, 53379.46620533745, 53861.46138538564, 54054.45945540494, 54172.18741718798, 54205.45794542162, 44883.55116448794, 45278.0, 46283.53716462792, 46380.30429543492, 46801.063978719554, 44411.777941110086, 44508.33237501375, 45075.0, 46272.76863615795, 46629.53370466252, 45791.77104114458, 46170.307445387676, 47003.0, 47193.292100619205, 47629.0, 38825.417618736305, 40868.591314087666, 40997.38503922503, 41009.589904100576, 41786.37320440257, 30106.397872042875, 30322.545161823033, 30544.847275763477, 30603.693963060083, 30622.387552248383], "value": 21115.567802401893, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.57046175361495, 432.69711202158487, 432.72089502271035, 433.89803396201904, 445.93533937579053, 16792.580185495368, 20606.381808545768, 21082.789172108274, 21490.570188596237, 21506.462338441546, 20474.18103275867, 20536.691949620767, 20538.794612053876, 20543.691844622306, 20708.48228794277, 5589.105743081108, 5898.734556944926, 6172.783952561661, 6218.751249949996, 6305.117283580303, 6218.067289906505, 6225.844353891143, 6237.750489980395, 6240.469560087398, 6386.521010924176, 6659.267480577133, 6693.39759421652, 6696.866062678743, 6702.832429189285, 6728.394444499994, 5774.162746401769, 5990.281166260033, 5994.8501287467725, 6708.731650733965, 6725.226598941111, 5891.79378721747, 5908.320543137508, 5918.171455996164, 5918.496927761103, 5926.911096333559, 14583.416663333453, 14657.780133298009, 14808.777868331983, 14995.250237488082, 15004.02473839208, 2380.190735150057, 2510.33476128826, 2534.239728081568, 2754.820936639112, 2992.0874133389307, 11094.167937404727, 11185.272957257834, 11315.151363647677, 11479.253848499904, 11624.709382265426, 2004.5690176611952, 2008.8794672319707, 2061.938141855732, 2075.242536474191, 2093.8638988465855, 79770.6011469939, 79880.20119798844, 80353.0, 83778.0, 87714.56142719246, 11032.558697652164, 11044.33733975964, 11207.439628018567, 11489.42552872353, 11727.706807329714, 16847.494575162764, 16859.662806743796, 16864.07247601394, 16910.830891690926, 16924.069176195182, 8257.38069644779, 8259.669613215405, 8372.706955256659, 8481.40630155883, 8487.787805304917, 7757.069151701832, 7837.098733645704, 7863.449558530962, 8002.839943201104, 8048.637811298506, 5063.164577844688, 5157.6532677631585, 5157.793688252503, 5203.713795741195, 5306.151015837448, 1996.8565227153715, 2068.979310206879, 2102.3903068110076, 2143.614149453082, 2158.557495713375, 848.7665891879659, 924.5746956399997, 1046.0742243114898, 1077.4989629822014, 1175.6179241746295, 19989.800101999084, 20054.89972550157, 20116.89941550312, 20169.39491815248, 20335.288264910658, 1030.8041472120274, 1035.295998720877, 1066.8399740038901, 1077.49357801833, 1081.1729027294127, 10927.0, 11369.886301137049, 11473.71315717097, 11497.655070347902, 11657.30056196614, 529099.0, 542280.0, 542433.0, 549249.0, 550448.0, 90575.0, 92824.0, 93106.0689393111, 94874.52562737142, 96134.51932740292, 1027.987432379106, 1071.022557290733, 1072.0458791675414, 1082.2910993299402, 1085.0722632149557, 18376.540586485444, 18434.815651843848, 18613.348532801287, 18658.72011919795, 19128.04359782033, 32818.50772238371, 35424.645753542136, 43524.347134793636, 43704.56295437005, 45142.77428612836, 60786.696066519384, 60966.0, 61308.386916130265, 61399.386006141154, 63972.36027639664, 9078.273738100805, 9089.909100908906, 9207.263418926335, 9293.442393456144, 9646.710598682052, 10342.069213770797, 13799.931000344934, 13982.790258146339, 14341.9282903589, 14424.278786060864, 12054.276743395425, 12216.633500994983, 12217.228501867357, 12224.266544007389, 12482.438290276777, 11698.824517632413, 11729.824052639387, 11867.228630139014, 12142.150049496804, 12145.574904878287, 11871.881281187423, 12017.63947081589, 12253.387330633612, 12346.444410001392, 12554.0, 9126.361154719107, 9127.863082053642, 9268.58291376903, 9333.066693330613, 9343.823100145712, 11548.653540393801, 12065.396730163633, 12211.755764884474, 12221.083418743452, 12315.322657253937], "value": 9632.445438782746, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.14971840347, 1618.1666441782484, 1807.4125909079544, 1841.539615096226, 1887.1979408751276, 8557.101504342032, 9144.817103657939, 9200.493972831491, 9998.850017249748, 10009.349392289496, 9281.628734850614, 9655.08276713711, 9666.661666841663, 9671.806563868719, 9674.371165874214, 2502.4619706763087, 2527.8609676467786, 2532.0884481586613, 2539.8857051432637, 2542.5550528657504, 2177.4665207024223, 2419.842710223839, 2459.9139030133947, 2491.838030528019, 2555.425029368396, 2508.41052352697, 2517.974820251792, 2520.5715028445115, 2532.8353657012235, 2535.987320063397, 2434.1115492845074, 2459.8524088554746, 2460.2988148377626, 2462.372095115752, 2466.2971053249826, 2437.573424650679, 2455.815813813971, 2480.9255722328357, 2481.751824817504, 2484.8260621756303, 9692.854607180898, 9952.35309704874, 9962.850557241647, 9963.402195868199, 9978.850317245247, 5844.26946631671, 5969.37321581232, 5994.130851026577, 6002.279726432856, 6004.909926351108, 3737.570179429373, 3771.585125636178, 3847.672947799412, 3874.2832575973534, 3903.4144878268203, 1269.2574843716345, 1281.0392205845617, 1286.3761075878192, 1312.0946546882622, 1316.348407538265, 14194.716105677931, 14233.857661423357, 14236.857631423656, 14239.78640320396, 14259.215743134124, 5031.4465408805, 5097.08252514551, 5241.0828105081455, 5267.104592219332, 5313.601479888946, 11723.062155027577, 11738.06095512357, 11758.764824703629, 11759.47082381295, 11760.58837940668, 5962.284525856925, 5963.79126730571, 5964.343922168558, 5966.403359664086, 5968.880622387528, 5402.216678581583, 5453.0, 5463.125899856004, 5465.699386533699, 5530.308711411074, 4469.2849144137435, 4496.0, 4521.0, 4557.111363284194, 4571.862844114682, 1314.9868501315057, 1365.9248741319125, 1413.9787903181461, 1436.6552027513321, 1440.9279536023157, 1060.3107979813028, 1063.4948399510326, 1070.8607880975424, 1080.897314755097, 1085.5223701571283, 6645.60126392418, 6670.666466676744, 6811.284815094443, 6870.27862074485, 6874.587524748531, 1040.3029969920171, 1070.218740319571, 1088.363307465126, 1092.4592326798286, 1098.8406681031365, 4619.6073333766335, 4681.765911704401, 4711.363965864564, 4786.856394308176, 4790.065937142223, 11609.245399049203, 11707.239029463057, 12105.152639315163, 12108.091893108052, 12112.515499379926, 12335.198212116364, 12391.566295179626, 12403.875961240272, 12407.44166512509, 12413.689657758448, 8498.320134389234, 8690.261327787082, 8693.261072808882, 8701.825963480695, 8703.47779133254, 6686.097376854257, 6699.899501507579, 6772.187337519529, 6784.423324017514, 6913.861722765617, 11030.117590592894, 11240.662780116609, 11528.942355288169, 11545.422728863692, 11545.48045337945, 11358.659240222807, 11683.0, 11709.063274938155, 11737.706557336134, 11867.58463453775, 8878.067802880605, 9070.365074444726, 9128.269738421042, 9136.588853501475, 9385.249180065442, 8994.775130621787, 9056.36605437613, 9112.635494580407, 9339.906600933904, 9375.437473751597, 6519.576227545384, 8186.181381861767, 8308.12764659702, 8335.08314085438, 8453.36599755033, 8024.879626805719, 8083.434159608771, 8144.063432705315, 8169.468984515987, 8203.261706446448, 7516.323530882247, 7563.0, 7626.847463050597, 7640.923590764021, 7774.766756997299, 5626.521745651666, 5659.830205093854, 5701.6864072474755, 5726.627769194989, 5874.823755287348, 5490.423505531861, 5515.61390702647, 5551.944480555143, 5617.550595952396, 5638.464345887134], "value": 5152.270855344784, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.6958800225807, 362.4092728851971, 365.60879858551345, 370.9758865673731, 371.383503384382, 5407.918881216785, 5528.585356098299, 5567.721613919308, 5655.0, 5688.317401911766, 5544.972275138629, 5546.725842404615, 5552.888942221153, 5584.91622625661, 5586.581006424524, 4380.3210502372185, 4385.320275357317, 4396.7361958282445, 4413.5365786592265, 4424.446944131983, 4136.8965775855695, 4312.396264522969, 4346.9565304347025, 4359.520452750211, 4363.170997510463, 4311.698181127323, 4312.331588603755, 4313.309870420733, 4316.633086187678, 4319.114581510798, 4302.505211900624, 4315.525292217853, 4325.82696692132, 4342.30523116303, 4344.543822898613, 3760.567534733486, 3831.1571454280015, 3833.773192578376, 3837.8656747014, 3843.461915331856, 5314.468553144693, 5318.202269659543, 5369.167778994262, 5377.381601115843, 5387.568994480433, 4864.1974074277705, 4900.289458028602, 4903.166461701519, 4904.8473608701925, 4908.0, 5431.511163995259, 5730.169125476824, 5751.971240143815, 5758.769649214027, 5773.393793651648, 4183.5398106208295, 4185.016521117535, 4186.748795072275, 4190.28765109929, 4217.557156498554, 6049.78825741097, 6078.240219972504, 6078.936186167403, 6080.452759251689, 6092.0, 4220.599043090901, 4289.24938135825, 4304.655627549789, 4328.523862375137, 4332.956670433256, 4295.613394794484, 4300.720453170597, 4312.784360781949, 4335.696501244883, 4335.934960975588, 4889.0, 4895.996320754237, 4902.284200529786, 4907.1903135982875, 4909.140900342426, 5710.5146062585145, 5762.9135562966585, 5767.653940763567, 5778.884422311614, 5790.826275211751, 1471.6541612721003, 1495.9177245251615, 1502.5942995391183, 1510.9848901510845, 1517.1807224099084, 927.0122684279843, 964.9662261820803, 971.5870754929127, 972.2027937091658, 972.7373609125497, 4954.876128096755, 4960.0, 4977.477364876709, 4980.203167493184, 4986.476419975923, 5125.769340379692, 5190.143626301618, 5198.584113270929, 5264.894702105937, 5269.998700246941, 5047.848564543069, 5063.822766203239, 5065.696058236517, 5241.868953276198, 5641.435856414326, 3126.9530957035663, 3159.7472202223767, 3161.936761264762, 3162.414953233659, 3162.588863447737, 6105.877882442326, 6115.938840611626, 6134.2332208473945, 6136.048912418582, 6183.134361189437, 5756.942430575725, 6048.788292409745, 6054.455099041107, 6059.818205453844, 6076.513878889678, 6134.846628834315, 6173.96913015441, 6195.535334849904, 6199.5660303779205, 6253.78111766086, 5731.8853622928145, 5989.2812862456785, 6013.158157857816, 6034.24571928509, 6034.78878239277, 5932.317783454785, 5949.3753155918, 5955.344912059757, 5962.522998160223, 5966.403359663999, 6081.239845019372, 6161.4146656067605, 6245.750169993149, 6247.156633854372, 6275.02737075754, 4293.742375457483, 4318.654507639444, 4339.826406943687, 4340.457442819647, 4351.282038463618, 6063.211782468251, 6077.878442431215, 6089.360617135137, 6090.9390906090375, 6094.598242404183, 5653.604247702621, 5765.971170144123, 5827.59206855533, 5841.737121829443, 5907.172995780508, 5797.4782269595935, 5817.738201780845, 5820.8067346194775, 5824.592278540462, 5843.620164689281, 5780.306363236438, 5808.448197421239, 5838.299404071538, 5840.561957853093, 5842.766289348378, 5594.328680558359, 5824.213731146241, 5826.82519524415, 5828.300603927556, 5841.153032810304, 5718.055861007278, 5723.42765723441, 5730.169125476699, 5738.799142030009, 5763.538916886723], "value": 4412.112333987099, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.2882818978352, 773.7330620935777, 818.3739439328914, 825.4510750351017, 830.3191383065887, 16173.191340432975, 17265.827341726577, 17277.568060798487, 17319.134043297832, 18632.906835465838, 16442.917785411057, 16731.07979061151, 16786.160691965382, 16786.916065419686, 16801.747973780402, 735.2096496266535, 865.2040123086775, 878.1174919206189, 882.1883866842488, 901.2609660078738, 901.175424486594, 910.258139616214, 913.0504275553417, 913.2146354135439, 913.2602591900582, 959.8560215967591, 965.541367850269, 965.5703212070631, 966.4394651102343, 966.5553845231202, 773.9458237923347, 912.6851236323457, 947.3321308477507, 951.219999600328, 954.0602506531061, 690.0201713566747, 690.8272931767058, 691.3881215124651, 691.6541729135432, 691.7509696509253, 16964.151792410452, 17062.23219955105, 17087.914560427118, 17108.65782684352, 17178.82821171785, 14780.852191478163, 15106.39574417022, 15160.241987900672, 15216.239188040665, 15436.305366258546, 14579.125252484884, 14747.115173089649, 14784.778228326584, 14986.400543978229, 15023.0, 4803.495632958524, 4838.516148385169, 4879.82920597781, 4940.209566469383, 4980.003999200175, 89657.0, 89845.0, 90395.0, 91079.0, 91261.0, 11926.761464770656, 12837.679058023437, 12931.224126552437, 13044.934775326063, 13051.934740326238, 22148.889255553942, 22576.887115564645, 22586.548269034854, 22612.773872261398, 22634.660480092483, 8818.823623527494, 9046.909530904739, 9071.319651026068, 9122.86315705265, 9214.44713317203, 4893.926591101136, 4994.200927851526, 5024.648274620815, 5030.0945829751, 5263.947360526346, 4162.62536371728, 4305.354196870495, 4327.978360108243, 4337.652987760972, 4346.0, 687.5118665747375, 688.0367485520212, 688.2257460357098, 699.105145413871, 703.3283214530112, 725.9709611615582, 733.5525329548971, 828.4863384701404, 834.2908527751484, 838.9454685445427, 10871.402072885909, 10949.726256843642, 11001.22991390595, 11097.667069987914, 11634.243774154653, 668.5086461450829, 775.7013549783373, 792.9762107136795, 809.3444310108831, 829.0590180145579, 5301.061106831372, 5371.597130215249, 5544.833654990357, 5573.498385145356, 5587.580931430159, 72838.0, 74388.62805685936, 123472.0, 125914.37042814911, 130789.34605326912, 87248.0, 88206.0, 92055.53972230096, 93054.0, 96604.51697741466, 71093.64453177701, 86632.56683716542, 91648.54175729079, 92835.99999999865, 94065.0, 10698.411587362458, 11013.889861101607, 11284.0, 11356.0915126788, 11411.0, 53602.19596706131, 53767.46232537781, 54563.45436545742, 54956.0, 55695.16457253064, 74181.0, 78283.21716782759, 79236.60381698054, 80187.19812801798, 82267.58866205631, 13509.18944863272, 13762.587122386345, 13781.0, 13935.233562154179, 13960.441582336998, 15911.363545458515, 16442.34230630809, 16595.91702041482, 16627.50117496477, 16975.405860794814, 14106.78839817383, 14467.565973020826, 14781.92609036948, 15538.300776465312, 15615.76576351333, 14264.857351426353, 14653.0, 14809.25953702289, 15061.548153555412, 15102.244887755349, 14372.425102995763, 14661.633459163606, 14718.411263549766, 14836.0, 14850.554483365517, 11802.114841386758, 12216.14486985938, 12274.4476498556, 12321.137520373488, 12557.87442125567, 12363.62909112692, 12482.875171248534, 12563.0, 12584.496620135093, 12807.295598741786], "value": 8304.116174348492, "unit": "score"}], "name": "dromaeo_css", "value": 7749.149163910133, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 110.0, 109.0, 110.0, 109.0, 107.0, 109.0, 110.0, 108.0], "value": 108.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 115.0, 166.0, 121.0, 117.0, 145.0, 114.0, 143.0, 114.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 162.0, 163.0, 163.0, 160.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 74.0, 117.0, 76.0, 75.0, 74.0, 75.0, 98.0, 76.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 123.0, 83.0, 83.0, 83.0, 123.0, 82.0, 83.0, 120.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 51.0, 51.0, 52.0, 52.0, 51.0, 50.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 62.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 76.0, 72.0, 75.0, 73.0, 72.0, 75.0, 72.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 118.0, 117.0, 122.0, 128.0, 123.0, 117.0, 192.0, 128.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 153.0, 152.0, 153.0, 157.0, 151.0, 158.0, 165.0, 153.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 63.0, 59.0], "value": 60.4, "unit": "score"}], "name": "kraken", "value": 1464.2999999999997, "alertThreshold": 2.0}]} 06:54:50 INFO - Return code: 0 06:54:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:54:50 INFO - # TBPL SUCCESS # 06:54:50 INFO - Running post-action listener: _resource_record_post_action 06:54:50 INFO - [mozharness: 2016-08-16 13:54:50.481628Z] Finished run-tests step (success) 06:54:50 INFO - Running post-run listener: _resource_record_post_run 06:54:50 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 251060224; Read time: 24; Write time: 138544 06:54:50 INFO - TinderboxPrint: CPU usage
12.9% 06:54:50 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 06:54:50 INFO - TinderboxPrint: I/O write bytes / time
251,060,224 / 138,544 06:54:50 INFO - TinderboxPrint: CPU idle
8,270.8 (87.1%) 06:54:50 INFO - TinderboxPrint: CPU user
1,183.5 (12.5%) 06:54:50 INFO - TinderboxPrint: Swap in / out
0 / 0 06:54:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1900544; Read time: 0; Write time: 160 06:54:50 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 246235136; Read time: 12; Write time: 138112 06:54:51 INFO - Running post-run listener: _upload_blobber_files 06:54:51 INFO - Blob upload gear active. 06:54:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:54:51 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 06:54:51 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'] 06:54:51 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 06:54:51 INFO - (blobuploader) - INFO - Open directory for files ... 06:54:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:54:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:54 INFO - (blobuploader) - INFO - Done attempting. 06:54:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:54:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:54:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:55 INFO - (blobuploader) - INFO - Done attempting. 06:54:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:54:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:54:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:56 INFO - (blobuploader) - INFO - Done attempting. 06:54:56 INFO - (blobuploader) - INFO - Iteration through files over. 06:54:56 INFO - Return code: 0 06:54:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:54:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:54:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/09ccc1cc24255c2bfe37d997d49a53b196a9837da6d040bce346af6548ff28b3e2a07836a5e90e24ed98aa678d82130cc5e35dcc09e755dbff66812189004aed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c63241e64d6086564ae55a07dd9bf3cd67c227c5a599131dc7805bf040ec6fd1655fb44d3b2cd2886bf7fde9b22d121a166d584a1284aa60e2a7c41a6781a482", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2a452ca1b8ca0a09c9e5afb23007282473e9611e2ef6a71450eb0a2f9d85085922d0832f5a2555861b8fdae46c059f6890c00d4e7a5163fb5c040b5373db1f"} 06:54:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:54:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:54:56 INFO - Contents: 06:54:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/09ccc1cc24255c2bfe37d997d49a53b196a9837da6d040bce346af6548ff28b3e2a07836a5e90e24ed98aa678d82130cc5e35dcc09e755dbff66812189004aed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c63241e64d6086564ae55a07dd9bf3cd67c227c5a599131dc7805bf040ec6fd1655fb44d3b2cd2886bf7fde9b22d121a166d584a1284aa60e2a7c41a6781a482", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2a452ca1b8ca0a09c9e5afb23007282473e9611e2ef6a71450eb0a2f9d85085922d0832f5a2555861b8fdae46c059f6890c00d4e7a5163fb5c040b5373db1f"} 06:54:56 INFO - Running post-run listener: copy_logs_to_upload_dir 06:54:56 INFO - Copying logs to upload dir... 06:54:56 INFO - mkdir: /builds/slave/test/build/upload/logs 06:54:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1692.841836 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 12 secs) (at 2016-08-16 06:54:56.107251) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 06:54:56.114524) ========= 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-1471353321.234158-246018532 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/09ccc1cc24255c2bfe37d997d49a53b196a9837da6d040bce346af6548ff28b3e2a07836a5e90e24ed98aa678d82130cc5e35dcc09e755dbff66812189004aed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c63241e64d6086564ae55a07dd9bf3cd67c227c5a599131dc7805bf040ec6fd1655fb44d3b2cd2886bf7fde9b22d121a166d584a1284aa60e2a7c41a6781a482", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2a452ca1b8ca0a09c9e5afb23007282473e9611e2ef6a71450eb0a2f9d85085922d0832f5a2555861b8fdae46c059f6890c00d4e7a5163fb5c040b5373db1f"} build_url:https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/INZJ-VwfTGKlb4O9ejOpIw/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/09ccc1cc24255c2bfe37d997d49a53b196a9837da6d040bce346af6548ff28b3e2a07836a5e90e24ed98aa678d82130cc5e35dcc09e755dbff66812189004aed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c63241e64d6086564ae55a07dd9bf3cd67c227c5a599131dc7805bf040ec6fd1655fb44d3b2cd2886bf7fde9b22d121a166d584a1284aa60e2a7c41a6781a482", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2a452ca1b8ca0a09c9e5afb23007282473e9611e2ef6a71450eb0a2f9d85085922d0832f5a2555861b8fdae46c059f6890c00d4e7a5163fb5c040b5373db1f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 06:54:56.139399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:54:56.139746) ========= 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-1471353321.234158-246018532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:54:56.223738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 06:54:56.224040) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 06:54:56.224323) ========= ========= Total master_lag: 0.25 =========