builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1471518330.68 results: success (0) buildid: 20160817221914 builduid: 8a42d84b4c664f7f898715475fc754b5 revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.683557) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.684012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.684292) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.769106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.769444) ========= 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=523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 04:05:30-- 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% 23.9M=0s 2016-08-18 04:05:31 (23.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.189313 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.973408) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:30.973707) ========= 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=523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019511 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.024815) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.025169) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d23f68f5cb77934e673700bfaaaf607893a2df9b --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 d23f68f5cb77934e673700bfaaaf607893a2df9b --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=523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 04:05:31,127 truncating revision to first 12 chars 2016-08-18 04:05:31,127 Setting DEBUG logging. 2016-08-18 04:05:31,127 attempt 1/10 2016-08-18 04:05:31,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d23f68f5cb77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 04:05:31,451 unpacking tar archive at: mozilla-aurora-d23f68f5cb77/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664211 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.706149) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.706463) ========= script_repo_revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.706846) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.707145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:31.837718) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-08-18 04:05:31.838048) ========= /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=523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:05:32 INFO - MultiFileLogger online at 20160818 04:05:32 in /builds/slave/test 04:05:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:05:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:05:32 INFO - {'append_to_log': False, 04:05:32 INFO - 'base_work_dir': '/builds/slave/test', 04:05:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:05:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:05:32 INFO - 'branch': 'Mozilla-Aurora', 04:05:32 INFO - 'buildbot_json_path': 'buildprops.json', 04:05:32 INFO - 'config_files': ('talos/linux_config.py',), 04:05:32 INFO - 'default_actions': ('clobber', 04:05:32 INFO - 'read-buildbot-config', 04:05:32 INFO - 'download-and-extract', 04:05:32 INFO - 'populate-webroot', 04:05:32 INFO - 'create-virtualenv', 04:05:32 INFO - 'install', 04:05:32 INFO - 'run-tests'), 04:05:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:05:32 INFO - 'download_minidump_stackwalk': True, 04:05:32 INFO - 'download_symbols': 'ondemand', 04:05:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:05:32 INFO - 'tooltool.py': '/tools/tooltool.py', 04:05:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:05:32 INFO - '/tools/misc-python/virtualenv.py')}, 04:05:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:05:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:05:32 INFO - 'installer_path': 'installer.exe', 04:05:32 INFO - 'log_level': 'info', 04:05:32 INFO - 'log_name': 'talos', 04:05:32 INFO - 'log_to_console': True, 04:05:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:05:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:05:32 INFO - 'opt_config_files': (), 04:05:32 INFO - 'pip_index': False, 04:05:32 INFO - 'sps_profile': False, 04:05:32 INFO - 'sps_profile_interval': 0, 04:05:32 INFO - 'suite': 'dromaeojs', 04:05:32 INFO - 'system_bits': '32', 04:05:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:05:32 INFO - 'title': 'talos-linux64-ix-065', 04:05:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:05:32 INFO - 'use_talos_json': True, 04:05:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:05:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:05:32 INFO - 'work_dir': 'build'} 04:05:32 INFO - [mozharness: 2016-08-18 11:05:32.032243Z] Running clobber step. 04:05:32 INFO - Running pre-action listener: _resource_record_pre_action 04:05:32 INFO - Running main action method: clobber 04:05:32 INFO - rmtree: /builds/slave/test/build 04:05:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:05:32 INFO - Running post-action listener: _resource_record_post_action 04:05:32 INFO - [mozharness: 2016-08-18 11:05:32.491255Z] Finished clobber step (success) 04:05:32 INFO - [mozharness: 2016-08-18 11:05:32.491355Z] Running read-buildbot-config step. 04:05:32 INFO - Running pre-action listener: _resource_record_pre_action 04:05:32 INFO - Running main action method: read_buildbot_config 04:05:32 INFO - Using buildbot properties: 04:05:32 INFO - { 04:05:32 INFO - "project": "", 04:05:32 INFO - "product": "firefox", 04:05:32 INFO - "script_repo_revision": "production", 04:05:32 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:05:32 INFO - "repository": "", 04:05:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:05:32 INFO - "buildid": "20160817221914", 04:05:32 INFO - "pgo_build": "True", 04:05:32 INFO - "basedir": "/builds/slave/test", 04:05:32 INFO - "buildnumber": 473, 04:05:32 INFO - "slavename": "talos-linux64-ix-065", 04:05:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:05:32 INFO - "platform": "ubuntu64_hw", 04:05:32 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:05:32 INFO - "branch": "mozilla-aurora", 04:05:32 INFO - "repo_path": "releases/mozilla-aurora", 04:05:32 INFO - "revision": "d23f68f5cb77934e673700bfaaaf607893a2df9b", 04:05:32 INFO - "stage_platform": "linux64-pgo", 04:05:32 INFO - "builduid": "8a42d84b4c664f7f898715475fc754b5", 04:05:32 INFO - "slavebuilddir": "test" 04:05:32 INFO - } 04:05:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:05:32 INFO - Running post-action listener: _resource_record_post_action 04:05:32 INFO - [mozharness: 2016-08-18 11:05:32.493837Z] Finished read-buildbot-config step (success) 04:05:32 INFO - [mozharness: 2016-08-18 11:05:32.493927Z] Running download-and-extract step. 04:05:32 INFO - Running pre-action listener: _resource_record_pre_action 04:05:32 INFO - Running main action method: download_and_extract 04:05:32 INFO - mkdir: /builds/slave/test/build/tests 04:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:05:32 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:05:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:05:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:05:32 INFO - retry: Failed, sleeping 30 seconds before retrying 04:06:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 04:06:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:06:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:06:02 INFO - retry: Failed, sleeping 60 seconds before retrying 04:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 04:07:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:07:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 04:07:02 INFO - Caught exception: HTTP Error 404: Not Found 04:07:02 INFO - Caught exception: HTTP Error 404: Not Found 04:07:02 INFO - Caught exception: HTTP Error 404: Not Found 04:07:02 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:02 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:07:03 INFO - Downloaded 1361 bytes. 04:07:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:03 INFO - Using the following test package requirements: 04:07:03 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:03 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:07:03 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:07:03 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'jsshell-linux-x86_64.zip'], 04:07:03 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:07:03 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:03 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:07:03 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:07:03 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:07:03 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:03 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:07:03 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:03 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:07:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:07:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:07:03 INFO - retry: Failed, sleeping 30 seconds before retrying 04:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:07:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:07:33 INFO - retry: Failed, sleeping 60 seconds before retrying 04:08:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:08:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:08:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:08:33 INFO - Caught exception: HTTP Error 404: Not Found 04:08:33 INFO - Caught exception: HTTP Error 404: Not Found 04:08:33 INFO - Caught exception: HTTP Error 404: Not Found 04:08:33 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:33 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:34 INFO - Downloaded 21766304 bytes. 04:08:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:08:35 INFO - Return code: 0 04:08:35 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:35 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:08:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:08:36 INFO - retry: Failed, sleeping 30 seconds before retrying 04:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:09:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:09:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:09:06 INFO - retry: Failed, sleeping 60 seconds before retrying 04:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:10:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:10:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:10:06 INFO - Caught exception: HTTP Error 404: Not Found 04:10:06 INFO - Caught exception: HTTP Error 404: Not Found 04:10:06 INFO - Caught exception: HTTP Error 404: Not Found 04:10:06 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:06 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:10:07 INFO - Downloaded 21766304 bytes. 04:10:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:10:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:10:08 INFO - Return code: 0 04:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:08 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:10:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:10:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:10:08 INFO - retry: Failed, sleeping 30 seconds before retrying 04:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 04:10:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:10:38 INFO - retry: Failed, sleeping 60 seconds before retrying 04:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 04:11:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:11:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 04:11:38 INFO - Caught exception: HTTP Error 404: Not Found 04:11:38 INFO - Caught exception: HTTP Error 404: Not Found 04:11:38 INFO - Caught exception: HTTP Error 404: Not Found 04:11:38 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:38 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:11:39 INFO - Downloaded 14413723 bytes. 04:11:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:11:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:11:39 INFO - Return code: 0 04:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:39 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:11:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:11:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:11:39 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 04:12:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:12:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:12:09 INFO - retry: Failed, sleeping 60 seconds before retrying 04:13:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 04:13:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:13:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 04:13:09 INFO - Caught exception: HTTP Error 404: Not Found 04:13:09 INFO - Caught exception: HTTP Error 404: Not Found 04:13:09 INFO - Caught exception: HTTP Error 404: Not Found 04:13:09 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:09 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:13:15 INFO - Downloaded 63746489 bytes. 04:13:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:15 INFO - mkdir: /builds/slave/test/properties 04:13:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:15 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:15 INFO - Contents: 04:13:15 INFO - build_url:https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:16 INFO - Running post-action listener: _resource_record_post_action 04:13:16 INFO - Running post-action listener: set_extra_try_arguments 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.456264Z] Finished download-and-extract step (success) 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.456498Z] Running populate-webroot step. 04:13:16 INFO - Running pre-action listener: _resource_record_pre_action 04:13:16 INFO - Running main action method: populate_webroot 04:13:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:16 INFO - '--tppagecycles', 04:13:16 INFO - '7'], 04:13:16 INFO - 'tests': ['tsvgm']}}, 04:13:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['damp', 'tps']}, 04:13:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'tests': ['damp', 'tps']}, 04:13:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['dromaeo_dom']}, 04:13:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['basic_compositor_video']}, 04:13:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tsvgx', 04:13:16 INFO - 'tsvgr_opacity', 04:13:16 INFO - 'tart', 04:13:16 INFO - 'tscrollx', 04:13:16 INFO - 'cart']}, 04:13:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:16 INFO - 'tsvgr_opacity', 04:13:16 INFO - 'tart', 04:13:16 INFO - 'tscrollx', 04:13:16 INFO - 'cart']}, 04:13:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tp5o']}, 04:13:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'tests': ['tp5o']}, 04:13:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s', 04:13:16 INFO - '--xperf_path', 04:13:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:16 INFO - 'tests': ['tp5n']}, 04:13:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--xperf_path', 04:13:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:16 INFO - 'tests': ['tp5n']}}, 04:13:16 INFO - 'talos.zip': {'path': '', 04:13:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:16 INFO - Running post-action listener: _resource_record_post_action 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.474395Z] Finished populate-webroot step (success) 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.474486Z] Running create-virtualenv step. 04:13:16 INFO - Running pre-action listener: _resource_record_pre_action 04:13:16 INFO - Running main action method: create_virtualenv 04:13:16 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:13:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:16 INFO - Using real prefix '/usr' 04:13:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:17 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:19 INFO - Installing pip.................done. 04:13:19 INFO - Return code: 0 04:13:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:13:19 INFO - Using env: {'DISPLAY': ':0', 04:13:19 INFO - 'HOME': '/home/cltbld', 04:13:19 INFO - 'LANG': 'en_US.UTF-8', 04:13:19 INFO - 'LANGUAGE': 'en_US:en', 04:13:19 INFO - 'LOGNAME': 'cltbld', 04:13:19 INFO - 'MAIL': '/var/mail/cltbld', 04:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:19 INFO - 'MOZ_NO_REMOTE': '1', 04:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:19 INFO - 'NO_EM_RESTART': '1', 04:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:19 INFO - 'PWD': '/builds/slave/test', 04:13:19 INFO - 'SHELL': '/bin/bash', 04:13:19 INFO - 'SHLVL': '1', 04:13:19 INFO - 'TERM': 'linux', 04:13:19 INFO - 'TMOUT': '86400', 04:13:19 INFO - 'USER': 'cltbld', 04:13:19 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:19 INFO - '_': '/tools/buildbot/bin/python'} 04:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:13:19 INFO - Cleaning up... 04:13:19 INFO - Return code: 0 04:13:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:13:19 INFO - Using env: {'DISPLAY': ':0', 04:13:19 INFO - 'HOME': '/home/cltbld', 04:13:19 INFO - 'LANG': 'en_US.UTF-8', 04:13:19 INFO - 'LANGUAGE': 'en_US:en', 04:13:19 INFO - 'LOGNAME': 'cltbld', 04:13:19 INFO - 'MAIL': '/var/mail/cltbld', 04:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:19 INFO - 'MOZ_NO_REMOTE': '1', 04:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:19 INFO - 'NO_EM_RESTART': '1', 04:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:19 INFO - 'PWD': '/builds/slave/test', 04:13:19 INFO - 'SHELL': '/bin/bash', 04:13:19 INFO - 'SHLVL': '1', 04:13:19 INFO - 'TERM': 'linux', 04:13:19 INFO - 'TMOUT': '86400', 04:13:19 INFO - 'USER': 'cltbld', 04:13:19 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:19 INFO - '_': '/tools/buildbot/bin/python'} 04:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:19 INFO - Downloading/unpacking psutil>=3.1.1 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:21 INFO - Installing collected packages: psutil 04:13:21 INFO - Running setup.py install for psutil 04:13:21 INFO - building 'psutil._psutil_linux' extension 04:13:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:13:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:13:22 INFO - building 'psutil._psutil_posix' extension 04:13:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:13:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:13:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:22 INFO - Successfully installed psutil 04:13:22 INFO - Cleaning up... 04:13:22 INFO - Return code: 0 04:13:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:22 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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:13:22 INFO - Using env: {'DISPLAY': ':0', 04:13:22 INFO - 'HOME': '/home/cltbld', 04:13:22 INFO - 'LANG': 'en_US.UTF-8', 04:13:22 INFO - 'LANGUAGE': 'en_US:en', 04:13:22 INFO - 'LOGNAME': 'cltbld', 04:13:22 INFO - 'MAIL': '/var/mail/cltbld', 04:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:22 INFO - 'MOZ_NO_REMOTE': '1', 04:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:22 INFO - 'NO_EM_RESTART': '1', 04:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:22 INFO - 'PWD': '/builds/slave/test', 04:13:22 INFO - 'SHELL': '/bin/bash', 04:13:22 INFO - 'SHLVL': '1', 04:13:22 INFO - 'TERM': 'linux', 04:13:22 INFO - 'TMOUT': '86400', 04:13:22 INFO - 'USER': 'cltbld', 04:13:22 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:22 INFO - '_': '/tools/buildbot/bin/python'} 04:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:24 INFO - Installing collected packages: mozsystemmonitor 04:13:24 INFO - Running setup.py install for mozsystemmonitor 04:13:24 INFO - Successfully installed mozsystemmonitor 04:13:24 INFO - Cleaning up... 04:13:24 INFO - Return code: 0 04:13:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:13:24 INFO - Using env: {'DISPLAY': ':0', 04:13:24 INFO - 'HOME': '/home/cltbld', 04:13:24 INFO - 'LANG': 'en_US.UTF-8', 04:13:24 INFO - 'LANGUAGE': 'en_US:en', 04:13:24 INFO - 'LOGNAME': 'cltbld', 04:13:24 INFO - 'MAIL': '/var/mail/cltbld', 04:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:24 INFO - 'MOZ_NO_REMOTE': '1', 04:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:24 INFO - 'NO_EM_RESTART': '1', 04:13:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:24 INFO - 'PWD': '/builds/slave/test', 04:13:24 INFO - 'SHELL': '/bin/bash', 04:13:24 INFO - 'SHLVL': '1', 04:13:24 INFO - 'TERM': 'linux', 04:13:24 INFO - 'TMOUT': '86400', 04:13:24 INFO - 'USER': 'cltbld', 04:13:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:24 INFO - '_': '/tools/buildbot/bin/python'} 04:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:24 INFO - Downloading/unpacking jsonschema==2.5.1 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:27 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:27 INFO - warning: no files found matching '*.txt' 04:13:27 INFO - no previously-included directories found matching 'build' 04:13:27 INFO - no previously-included directories found matching 'dist' 04:13:27 INFO - no previously-included directories found matching '.git*' 04:13:27 INFO - Installing collected packages: jsonschema, functools32 04:13:27 INFO - Running setup.py install for functools32 04:13:27 INFO - warning: no files found matching '*.txt' 04:13:27 INFO - no previously-included directories found matching 'build' 04:13:27 INFO - no previously-included directories found matching 'dist' 04:13:27 INFO - no previously-included directories found matching '.git*' 04:13:27 INFO - Successfully installed jsonschema functools32 04:13:27 INFO - Cleaning up... 04:13:27 INFO - Return code: 0 04:13:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:13:27 INFO - Using env: {'DISPLAY': ':0', 04:13:27 INFO - 'HOME': '/home/cltbld', 04:13:27 INFO - 'LANG': 'en_US.UTF-8', 04:13:27 INFO - 'LANGUAGE': 'en_US:en', 04:13:27 INFO - 'LOGNAME': 'cltbld', 04:13:27 INFO - 'MAIL': '/var/mail/cltbld', 04:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:27 INFO - 'MOZ_NO_REMOTE': '1', 04:13:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:27 INFO - 'NO_EM_RESTART': '1', 04:13:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:27 INFO - 'PWD': '/builds/slave/test', 04:13:27 INFO - 'SHELL': '/bin/bash', 04:13:27 INFO - 'SHLVL': '1', 04:13:27 INFO - 'TERM': 'linux', 04:13:27 INFO - 'TMOUT': '86400', 04:13:27 INFO - 'USER': 'cltbld', 04:13:27 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:27 INFO - '_': '/tools/buildbot/bin/python'} 04:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:27 INFO - Cleaning up... 04:13:27 INFO - Return code: 0 04:13:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:13:27 INFO - Using env: {'DISPLAY': ':0', 04:13:27 INFO - 'HOME': '/home/cltbld', 04:13:27 INFO - 'LANG': 'en_US.UTF-8', 04:13:27 INFO - 'LANGUAGE': 'en_US:en', 04:13:27 INFO - 'LOGNAME': 'cltbld', 04:13:27 INFO - 'MAIL': '/var/mail/cltbld', 04:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:27 INFO - 'MOZ_NO_REMOTE': '1', 04:13:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:27 INFO - 'NO_EM_RESTART': '1', 04:13:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:27 INFO - 'PWD': '/builds/slave/test', 04:13:27 INFO - 'SHELL': '/bin/bash', 04:13:27 INFO - 'SHLVL': '1', 04:13:27 INFO - 'TERM': 'linux', 04:13:27 INFO - 'TMOUT': '86400', 04:13:27 INFO - 'USER': 'cltbld', 04:13:27 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:27 INFO - '_': '/tools/buildbot/bin/python'} 04:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:28 INFO - Downloading/unpacking blobuploader==1.2.4 04:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:30 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:30 INFO - Downloading docopt-0.6.1.tar.gz 04:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:30 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:30 INFO - Running setup.py install for blobuploader 04:13:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:31 INFO - Running setup.py install for requests 04:13:31 INFO - Running setup.py install for docopt 04:13:31 INFO - Successfully installed blobuploader requests docopt 04:13:31 INFO - Cleaning up... 04:13:31 INFO - Return code: 0 04:13:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:13:31 INFO - Using env: {'DISPLAY': ':0', 04:13:31 INFO - 'HOME': '/home/cltbld', 04:13:31 INFO - 'LANG': 'en_US.UTF-8', 04:13:31 INFO - 'LANGUAGE': 'en_US:en', 04:13:31 INFO - 'LOGNAME': 'cltbld', 04:13:31 INFO - 'MAIL': '/var/mail/cltbld', 04:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:31 INFO - 'MOZ_NO_REMOTE': '1', 04:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:31 INFO - 'NO_EM_RESTART': '1', 04:13:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:31 INFO - 'PWD': '/builds/slave/test', 04:13:31 INFO - 'SHELL': '/bin/bash', 04:13:31 INFO - 'SHLVL': '1', 04:13:31 INFO - 'TERM': 'linux', 04:13:31 INFO - 'TMOUT': '86400', 04:13:31 INFO - 'USER': 'cltbld', 04:13:31 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:31 INFO - '_': '/tools/buildbot/bin/python'} 04:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:31 INFO - Running setup.py (path:/tmp/pip-jHOGbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:31 INFO - Running setup.py (path:/tmp/pip-dQt9nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:31 INFO - Running setup.py (path:/tmp/pip-gDIisU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:31 INFO - Running setup.py (path:/tmp/pip-bngOjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:32 INFO - Running setup.py (path:/tmp/pip-PIQcbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:32 INFO - Running setup.py (path:/tmp/pip-qOn9hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:32 INFO - Running setup.py (path:/tmp/pip-Jlhdz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:32 INFO - Running setup.py (path:/tmp/pip-dnbT4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:32 INFO - Running setup.py (path:/tmp/pip-yqhxeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:32 INFO - Running setup.py (path:/tmp/pip-Fqo_iJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:32 INFO - Running setup.py (path:/tmp/pip-OMXvvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:32 INFO - Running setup.py (path:/tmp/pip-a0XIdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:32 INFO - Running setup.py (path:/tmp/pip-9wW5Hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:32 INFO - Running setup.py (path:/tmp/pip-RrjC6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:32 INFO - Running setup.py (path:/tmp/pip-gUAl3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:32 INFO - Running setup.py (path:/tmp/pip-0HcfvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:32 INFO - Running setup.py (path:/tmp/pip-GWI4t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:32 INFO - Running setup.py install for manifestparser 04:13:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozcrash 04:13:33 INFO - Running setup.py install for mozdebug 04:13:33 INFO - Running setup.py install for mozdevice 04:13:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozfile 04:13:33 INFO - Running setup.py install for mozhttpd 04:13:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozinfo 04:13:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozInstall 04:13:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozleak 04:13:33 INFO - Running setup.py install for mozlog 04:13:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for moznetwork 04:13:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozprocess 04:13:34 INFO - Running setup.py install for mozprofile 04:13:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozrunner 04:13:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozscreenshot 04:13:34 INFO - Running setup.py install for moztest 04:13:34 INFO - Running setup.py install for mozversion 04:13:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:13:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:13:34 INFO - Using env: {'DISPLAY': ':0', 04:13:34 INFO - 'HOME': '/home/cltbld', 04:13:34 INFO - 'LANG': 'en_US.UTF-8', 04:13:34 INFO - 'LANGUAGE': 'en_US:en', 04:13:34 INFO - 'LOGNAME': 'cltbld', 04:13:34 INFO - 'MAIL': '/var/mail/cltbld', 04:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:34 INFO - 'MOZ_NO_REMOTE': '1', 04:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:34 INFO - 'NO_EM_RESTART': '1', 04:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:34 INFO - 'PWD': '/builds/slave/test', 04:13:34 INFO - 'SHELL': '/bin/bash', 04:13:34 INFO - 'SHLVL': '1', 04:13:34 INFO - 'TERM': 'linux', 04:13:34 INFO - 'TMOUT': '86400', 04:13:34 INFO - 'USER': 'cltbld', 04:13:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:34 INFO - '_': '/tools/buildbot/bin/python'} 04:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:35 INFO - Running setup.py (path:/tmp/pip-AvX_2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:35 INFO - Running setup.py (path:/tmp/pip-MwsxhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:35 INFO - Running setup.py (path:/tmp/pip-RHrIpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:35 INFO - Running setup.py (path:/tmp/pip-hy6To8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:35 INFO - Running setup.py (path:/tmp/pip-s4a9ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:35 INFO - Running setup.py (path:/tmp/pip-z3yV_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:35 INFO - Running setup.py (path:/tmp/pip-lURGwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:35 INFO - Running setup.py (path:/tmp/pip-J3YXdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:35 INFO - Running setup.py (path:/tmp/pip-hL1JVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:35 INFO - Running setup.py (path:/tmp/pip-9Oe53i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:35 INFO - Running setup.py (path:/tmp/pip-ZLkg7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:35 INFO - Running setup.py (path:/tmp/pip-x4JSTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:35 INFO - Running setup.py (path:/tmp/pip-4A9tAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:35 INFO - Running setup.py (path:/tmp/pip-zGTkwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:36 INFO - Running setup.py (path:/tmp/pip-x3j6EG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:36 INFO - Running setup.py (path:/tmp/pip-u1oPWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:36 INFO - Running setup.py (path:/tmp/pip-illRcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:13:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:38 INFO - Downloading blessings-1.6.tar.gz 04:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:38 INFO - Installing collected packages: blessings 04:13:38 INFO - Running setup.py install for blessings 04:13:38 INFO - Successfully installed blessings 04:13:38 INFO - Cleaning up... 04:13:38 INFO - Return code: 0 04:13:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:38 INFO - Reading from file tmpfile_stdout 04:13:38 INFO - Current package versions: 04:13:38 INFO - argparse == 1.2.1 04:13:38 INFO - blessings == 1.6 04:13:38 INFO - blobuploader == 1.2.4 04:13:38 INFO - docopt == 0.6.1 04:13:38 INFO - functools32 == 3.2.3-2 04:13:38 INFO - jsonschema == 2.5.1 04:13:38 INFO - manifestparser == 1.1 04:13:38 INFO - mozInstall == 1.12 04:13:38 INFO - mozcrash == 0.17 04:13:38 INFO - mozdebug == 0.1 04:13:38 INFO - mozdevice == 0.48 04:13:38 INFO - mozfile == 1.2 04:13:38 INFO - mozhttpd == 0.7 04:13:38 INFO - mozinfo == 0.9 04:13:38 INFO - mozleak == 0.1 04:13:38 INFO - mozlog == 3.3 04:13:38 INFO - moznetwork == 0.27 04:13:38 INFO - mozprocess == 0.23 04:13:38 INFO - mozprofile == 0.28 04:13:38 INFO - mozrunner == 6.12 04:13:38 INFO - mozscreenshot == 0.1 04:13:38 INFO - mozsystemmonitor == 0.3 04:13:38 INFO - moztest == 0.7 04:13:38 INFO - mozversion == 1.4 04:13:38 INFO - psutil == 3.1.1 04:13:38 INFO - requests == 1.2.3 04:13:38 INFO - wsgiref == 0.1.2 04:13:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:38 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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:13:38 INFO - Using env: {'DISPLAY': ':0', 04:13:38 INFO - 'HOME': '/home/cltbld', 04:13:38 INFO - 'LANG': 'en_US.UTF-8', 04:13:38 INFO - 'LANGUAGE': 'en_US:en', 04:13:38 INFO - 'LOGNAME': 'cltbld', 04:13:38 INFO - 'MAIL': '/var/mail/cltbld', 04:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:38 INFO - 'MOZ_NO_REMOTE': '1', 04:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:38 INFO - 'NO_EM_RESTART': '1', 04:13:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:38 INFO - 'PWD': '/builds/slave/test', 04:13:38 INFO - 'SHELL': '/bin/bash', 04:13:38 INFO - 'SHLVL': '1', 04:13:38 INFO - 'TERM': 'linux', 04:13:38 INFO - 'TMOUT': '86400', 04:13:38 INFO - 'USER': 'cltbld', 04:13:38 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:38 INFO - '_': '/tools/buildbot/bin/python'} 04:13:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:13:38 INFO - Cleaning up... 04:13:38 INFO - Return code: 0 04:13:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:38 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 0x2044030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2305180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, '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': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:13:38 INFO - Using env: {'DISPLAY': ':0', 04:13:38 INFO - 'HOME': '/home/cltbld', 04:13:38 INFO - 'LANG': 'en_US.UTF-8', 04:13:38 INFO - 'LANGUAGE': 'en_US:en', 04:13:38 INFO - 'LOGNAME': 'cltbld', 04:13:38 INFO - 'MAIL': '/var/mail/cltbld', 04:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:38 INFO - 'MOZ_NO_REMOTE': '1', 04:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:38 INFO - 'NO_EM_RESTART': '1', 04:13:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:38 INFO - 'PWD': '/builds/slave/test', 04:13:38 INFO - 'SHELL': '/bin/bash', 04:13:38 INFO - 'SHLVL': '1', 04:13:38 INFO - 'TERM': 'linux', 04:13:38 INFO - 'TMOUT': '86400', 04:13:38 INFO - 'USER': 'cltbld', 04:13:38 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:38 INFO - '_': '/tools/buildbot/bin/python'} 04:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:39 INFO - Cleaning up... 04:13:39 INFO - Return code: 0 04:13:39 INFO - Running post-action listener: _resource_record_post_action 04:13:39 INFO - Running post-action listener: _start_resource_monitoring 04:13:39 INFO - Starting resource monitoring. 04:13:39 INFO - [mozharness: 2016-08-18 11:13:39.055124Z] Finished create-virtualenv step (success) 04:13:39 INFO - [mozharness: 2016-08-18 11:13:39.055656Z] Running install step. 04:13:39 INFO - Running pre-action listener: _resource_record_pre_action 04:13:39 INFO - Running main action method: install 04:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:39 INFO - Reading from file tmpfile_stdout 04:13:39 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:39 INFO - Reading from file tmpfile_stdout 04:13:39 INFO - Output received: 04:13:39 INFO - Usage: mozinstall [options] installer 04:13:39 INFO - Options: 04:13:39 INFO - -h, --help show this help message and exit 04:13:39 INFO - -d DEST, --destination=DEST 04:13:39 INFO - Directory to install application into. [default: 04:13:39 INFO - "/builds/slave/test"] 04:13:39 INFO - --app=APP Application being installed. [default: firefox] 04:13:39 INFO - mkdir: /builds/slave/test/build/application 04:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:13:55 INFO - Reading from file tmpfile_stdout 04:13:55 INFO - Output received: 04:13:55 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:55 INFO - Running post-action listener: _resource_record_post_action 04:13:55 INFO - [mozharness: 2016-08-18 11:13:55.342483Z] Finished install step (success) 04:13:55 INFO - [mozharness: 2016-08-18 11:13:55.342759Z] Running run-tests step. 04:13:55 INFO - Running pre-action listener: _resource_record_pre_action 04:13:55 INFO - Running main action method: run_tests 04:13:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:55 INFO - Python 2.7.3 04:13:55 INFO - Return code: 0 04:13:55 INFO - grabbing minidump binary from tooltool 04:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:55 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 0x23033b0>, '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 0x23042b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2304740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:13:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:13:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:13:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:55 INFO - Return code: 0 04:13:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13:55 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/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:13:55 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/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:13:55 INFO - Using env: {'DISPLAY': ':0', 04:13:55 INFO - 'HOME': '/home/cltbld', 04:13:55 INFO - 'LANG': 'en_US.UTF-8', 04:13:55 INFO - 'LANGUAGE': 'en_US:en', 04:13:55 INFO - 'LOGNAME': 'cltbld', 04:13:55 INFO - 'MAIL': '/var/mail/cltbld', 04:13:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:55 INFO - 'MOZ_NO_REMOTE': '1', 04:13:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:55 INFO - 'NO_EM_RESTART': '1', 04:13:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:55 INFO - 'PWD': '/builds/slave/test', 04:13:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:55 INFO - 'SHELL': '/bin/bash', 04:13:55 INFO - 'SHLVL': '1', 04:13:55 INFO - 'TERM': 'linux', 04:13:55 INFO - 'TMOUT': '86400', 04:13:55 INFO - 'USER': 'cltbld', 04:13:55 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302', 04:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:55 INFO - '_': '/tools/buildbot/bin/python'} 04:13:55 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/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:13:55 INFO - mozversion application_buildid: 20160817221914 04:13:55 INFO - mozversion application_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 04:13:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:13:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:55 INFO - mozversion application_name: Firefox 04:13:55 INFO - mozversion application_remotingname: firefox-dev 04:13:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:55 INFO - mozversion application_vendor: Mozilla 04:13:55 INFO - mozversion application_version: 50.0a2 04:13:55 INFO - mozversion platform_buildid: 20160817221914 04:13:55 INFO - mozversion platform_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 04:13:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:55 INFO - mozversion platform_version: 50.0a2 04:13:55 INFO - using testdate: 1471518835 04:13:55 INFO - actual date: 1471518835 04:13:55 INFO - starting webserver on 'localhost:51076' 04:13:55 INFO - SUITE-START | Running 2 tests 04:13:55 INFO - TEST-START | dromaeo_css 04:13:55 INFO - operating with platform_type : linux_ 04:13:55 INFO - Initialising browser for dromaeo_css test... 04:13:55 INFO - TEST-INFO | started process 4839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphnky6t/profile http://localhost:51076/getInfo.html) 04:13:59 INFO - PROCESS | 4839 | __metrics Screen width/height:1600/1200 04:13:59 INFO - PROCESS | 4839 | colorDepth:24 04:13:59 INFO - PROCESS | 4839 | Browser inner width/height: 1024/697 04:13:59 INFO - PROCESS | 4839 | __metrics 04:14:00 INFO - PROCESS | 4839 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:14:00 INFO - PROCESS | 4839 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:14:01 INFO - TEST-INFO | 4839: exit 0 04:14:01 INFO - Browser initialized. 04:14:01 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:01 INFO - TEST-INFO | started process 4928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphnky6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:13 INFO - PROCESS | 4928 | RSS: Main: 145088512 04:14:13 INFO - PROCESS | 4928 | 04:15:04 INFO - PROCESS | 4928 | 1471518904235 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:17:13 INFO - PROCESS | 4928 | Cycle 1(1): loaded http://localhost:51076/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51076/tests/dromaeo/cssquery-ext.html) 04:17:14 INFO - PROCESS | 4928 | RSS: Main: 236806144 04:17:14 INFO - PROCESS | 4928 | 04:20:16 INFO - PROCESS | 4928 | Cycle 1(1): loaded http://localhost:51076/tests/dromaeo/cssquery-ext.html (next: http://localhost:51076/tests/dromaeo/cssquery-jquery.html) 04:20:17 INFO - PROCESS | 4928 | RSS: Main: 231968768 04:20:17 INFO - PROCESS | 4928 | 04:23:19 INFO - PROCESS | 4928 | Cycle 1(1): loaded http://localhost:51076/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51076/tests/dromaeo/cssquery-mootools.html) 04:23:19 INFO - PROCESS | 4928 | RSS: Main: 223735808 04:23:19 INFO - PROCESS | 4928 | 04:26:21 INFO - PROCESS | 4928 | Cycle 1(1): loaded http://localhost:51076/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51076/tests/dromaeo/cssquery-prototype.html) 04:26:22 INFO - PROCESS | 4928 | RSS: Main: 221720576 04:26:22 INFO - PROCESS | 4928 | 04:29:25 INFO - PROCESS | 4928 | Cycle 1(1): loaded http://localhost:51076/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51076/tests/dromaeo/cssquery-yui.html) 04:29:25 INFO - PROCESS | 4928 | RSS: Main: 175767552 04:29:25 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | Cycle 1(1): loaded http://localhost:51076/tests/dromaeo/cssquery-yui.html (next: http://localhost:51076/tests/dromaeo/cssquery-dojo.html) 04:32:28 INFO - PROCESS | 4928 | RSS: Main: 265207808 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | __start_tp_report 04:32:28 INFO - PROCESS | 4928 | _x_x_mozilla_page_load 04:32:28 INFO - PROCESS | 4928 | _x_x_mozilla_page_load_details 04:32:28 INFO - PROCESS | 4928 | |i|pagename|runs| 04:32:28 INFO - PROCESS | 4928 | |0;dojo.html;3275.5905511811025;3280.7539434542414;3296.472564389698;3297.109780359303;3318.651541588133;5698;5779.393163717812;5789.739461724222;5795.739191736371;5815.214945982286;5664.065429204186;5754.079347304432;5754.798582049629;5754.971225143879;5761.9135712964335;4991.076650819592;5043.596512279028;5173.3016042834115;5177.508136727014;5223.164293713006;5107.591392688594;5108.616853735966;5134.820281290155;5177.715225662587;5196.350456192976;5147.948520514784;5194.844154675366;5201.661891977028;5207.921881171786;5218.582513398938;5263.394709608406;5270.604704647148;5272.92090618641;5278.313819203518;5308.389535203424;4944.085344211332;5090.236464530313;5129.025485157808;5160.690358578459;5190.377154741417;5698.800541981052;5725.08398656213;5736.139579063132;5736.799212027559;5772.249607550992;6174.043023331391;6178.752849885999;6180.81457556274;6183.907241391383;6191.659458729813;5863.355030946593;6043.456088952015;6083.057126145454;6100.542459315569;6180.103884936704;5726.828195154194;5729.083346664597;5754.4533269339345;5756.079027355603;5758.165066065397;6225.719842607094;6272.655003974825;6293.937060629427;6296.024116261987;6303.842403939847;5767.336756272998;5965.075413310944;6007.339192688801;6023.969880150659;6075.119107729354;6097.4817140543555;6139.614800607407;6144.692765361714;6155.230596175478;6156.876862462816;6134.478569321568;6150.50795936333;6152.446279834837;6154.261488621394;6157.784477543354;5823.4467725566;5839.883202335929;5884.705764711747;5961.344252132262;6141;4571.4514258288555;4572.542745725401;4573.771311434415;4577.8168873244695;4597.632189424905;4106.240345536085;4113.670906327487;4143.440635514227;4146.834126634901;4195.139996300812;5823.213866128103;5825.446582574648;5825.79609713658;5831.562632802556;5850.210221620112;6129.35641757609;6140.083764719945;6163.691815409212;6167.84580385494;6172.9074063889075;5572.554195664337;5683.9715801420725;5798.072308430631;5800.5939584229545;5838.737256823453;5186.740662966837;5186.948130518646;5188.688678679291;5200.8959820803375;5214;6359.8092057238355;6413.839654008687;6419.935800642027;6432.678366081771;6434.935650643527;5869.501092407022;6088.847778805565;6117.675163990694;6187.81436556904;6203.038529028008;6427.3251308611925;6451.548391612542;6463.030545418037;6465.547411681137;6478.028295755675;6119.143319935123;6120.296165940974;6121.3572574879;6122.693865306628;6129.111278864629;5890.2342695449315;6013.939860601338;6014.72933717975;6237;6245.437910588069;5937.495312898452;5995.550333725076;5997.730102145327;6005.249343832021;6032.1856549365275;5907.645541267538;5957.6127551709;5961.672108034099;5964.314103877936;5981.850453738517;5753.338366087954;5833.4749872511675;5857.5313974881055;5909.379515151019;5927.170196172453;6037;6038.033914573647;6052.334243233153;6076.635401875901;6082.391760823883;5872.1778950946045;5873.882522349614;5879.147523609138;5880.14737863016;5896.823095307148 04:32:28 INFO - PROCESS | 4928 | |1;ext.html;609.6555446172912;642.7461152844627;653.261814150011;656.1764984943898;661.9669016549173;36117.63882361176;48110.518894811044;49405;49454.01091978163;49514;50922.74538627302;51334.486655133434;51368.48631513684;51375.48624513754;51429.74285128579;8447.662093516252;9295.256379489641;9451.763705907339;9453.054694530527;9456.81086378272;10991.450427478634;11236.831447528293;11241.1569132315;11575.073994080496;11578.652640420802;11371.829422558669;11430.599929002445;11436.714082147972;11438;11492.655220343404;11081.833772493419;11155.944220278929;11166.994970452657;11182.888171118266;11218.158638102175;11033.393163376011;11050.668479945614;11058.668239952814;11065.059469945065;11078.501467433985;46683.76658116722;46718.53281467176;47119.29321060187;47133;47134;3989.3816458448987;4178.540360560336;4348.239058164809;4485.439320084989;4711.363965864599;35631;35684;35926.82036589827;36240;38059;4747.976260118678;4800.1119792838435;4817.964682250195;4858.805647774085;4869.586085182764;370501;373636;373774;374295;374702.12648936856;38535;67528;68199.65900170467;68281;68299.3170068293;39047.60952390497;41113;41184.79407602943;41369.79315103465;41455;17139.400120995706;17304.65390692179;17328.826711732974;17336.913315433594;17340.046297453762;13092.541761038317;14030.228337441538;14378.784318235235;14493.130412175093;14609.123452592667;9291.814163716688;9302.16280534755;9308.953455232817;9363.110504502061;9388.483633400092;3504.9649503505148;3550.91122721934;3555.5911070227253;3562.9323372018275;3596.3526565218513;1153.4175241503156;1174.900133488663;1192.8270400791837;1206.8008778551616;1232.5870833270956;36184.81907590445;36192.81903590465;36324.81837590848;36349.27301454009;36419.63580364215;1195.2051885496048;1210.5994709232004;1213.5085290457203;1215.9209651372632;1305.9617604004839;17826.643467130583;18837.46070157;18946;19056.904715476612;19058.90470547666;664878;672008;672357;674031;674966;68536;69470.3052969474;69477.3052269481;69535;69547.65226173938;1360.6258278973164;1451.7459444597152;1467.6771110355705;1481.7406953783045;1517.120070359189;32964.1758956028;36790.26419471649;37129;37203.813980929925;37311.81344093262;56255.43744562666;56387.43612563822;56612.43387566072;56838.7158064207;56942.43057569371;430777.84611076745;431422;431942;433052;434055;28276.43447131087;30909.381812364078;31711.682883171798;31841.5223771639;32363.838180808943;53838;54053.45946540484;54117;54667;54684;44931.55068449274;46930.76534617305;47457.762711186224;47648.761756190994;47909.52090479051;44811.327830083224;46043.539564603925;46921.53078469171;46985.53014469812;47859.28211076906;44859.327110094026;45688.54311456843;46072.76963615161;46116.077678445574;46140.077198456514;37972.24055518819;40073.59926400815;40251.59748402479;40855.1828963425;41345.79327103345;30280.84859575688;30311.545326819672;30556.388872221985;30748.231294217825;31020.844895775375 04:32:28 INFO - PROCESS | 4928 | |2;jquery.html;405.33120351420155;412.79979210083087;427;434.14906782705884;441.4768499328294;19600.803991960078;21040.737555746648;21615.45961350967;21931.780682193174;22128.8893555532;16043.35826566936;17434.041127737968;19604.509887252825;20017.099230534626;20162.798372016314;5276.762545685444;5525.226468294442;5525.917111243314;5531.917021244684;5538.0585300498815;5338.225957236198;5374;5412.729363531807;5466.34403871536;5497.120460726284;5471.124620060791;5488.780448782044;5489.451054894518;5504.80733174339;5507.614466987313;5495.890082198353;5508.586855985797;5575.665460072389;5909.054551271819;5978.192943952554;5403.2705584745945;5404.567634589223;5405.027095122877;5410.945890541084;5411.512963833274;14917.254137293201;15035.248237588077;15193.848061519353;15203.923980380026;19661.820290782598;2478.8512689238705;2539.5555777738823;2584.508943300767;2632.144553020276;2653.509100816355;10677.839832402442;11010.889891101067;11130.49912753928;11285.04077153443;13860.82183014445;2043.2133628552965;2115.6509175985943;2129.7870212978733;2166.534195148035;2176.673498975151;71563.28436715619;77592.61203694002;92904.53547732218;93308.53345733296;95154.04845951521;10568.735781605521;11614.128940329509;11868.347240901661;11923.642290731292;11970.700732481757;15461.226938653022;16153.111578863276;16222.351105955866;16839.326426942785;16866.66266674684;8506.617202225914;8639.179277968582;8673.73978780625;8753;9186.851643544556;8759.255463285692;8850.513221772735;8856.64573417056;9001.864972025425;9012.549372531346;5007.223880298559;5077.644564880497;5106.66806657566;5166.715830629351;5281.419043905167;2049.815516603513;2089.937301880946;2102.390306811038;2109.891863056443;2172.1528603844513;949.8955114937352;1003.4280460137797;1015.6902144845745;1035.7565971996576;1073.586669132374;20780.79219207819;21396.679049814265;21398.189551750536;21424.892875535836;21538.56922861534;1044.6709286574705;1049.8372752223265;1067.5783065689166;1077.7844431113651;1089.2756317049073;10674.89325106739;11696.70758231034;11885.509304237386;11967.162298639012;12031.75936481283;517328;520165;521308;522826;526066;97452.51273743586;98858.505707471;101098;101956.49021754843;106205.46897265465;1006.6677996261217;1007.5415685862915;1025.3898930136556;1028.6142696488816;1037.0770014686784;17242.827571724625;18716;18891.90554047276;18970;19380.90309548443;42120.157596847275;42670.57329426666;46556.7672161637;47399.76300118477;48265.75867120642;61829.690851545456;62573.37426625675;63118;63143.36856631375;64744.67627661832;8163.853314920032;8983.101689830964;9041.095890410907;9151.176394124295;9321.813563728823;13947.372368243656;14111.788323174955;14478.420863165355;14629.487967921072;14630.780538291721;11749.177557571229;11886.583969561023;12535;12563.685907852378;12730.299833509245;5647.124695672177;10109.545070471699;11776.470058847544;11944.581939632071;12450.937745311216;11871.228370155912;11906.345151016778;12368.134230603771;12496.187747796364;13152.671183220496;8588.699395521127;8754.956225218833;8965.282777377925;9033.548322583976;9108.453492790453;8812.471251724917;12008.39958002114;12566.685832854251;12776.041796865458;13094.738105238033 04:32:28 INFO - PROCESS | 4928 | |3;mootools.html;1389.083205084644;1407.6058703563006;1514.5380658899037;1650.2986230851893;1897.8576606754498;8186.46787958783;9003.909960900397;9370.625174992992;9395.530223488824;9659.903400966006;9514.6194152234;9540.952295238532;9602.759931001729;9603.567839447223;9632.181264592518;2503.186464399073;2553.8212325137247;2555.8722063896826;2556.463142740027;2556.6292887531295;2472.4560596668703;2563.7051739049966;2567.833090849098;2569.1008147148536;2572.344052266669;2407.7832995030444;2408.3617841272076;2412.2401443545314;2443.1937460637946;2507.285423654259;2441.292025312657;2445.083093839819;2446.2538925627764;2528.9241322760345;2532.987335063313;2433.3794882305074;2459.6802415686025;2468.148488771389;2469.259222233323;2476.950460990788;9300.674476393362;9496.667616633385;9512.762180945463;9621.951890240574;9627.3260871739;5633.60564760467;5800.100984347433;5801.187833703243;5806.59353845231;5812.186293918855;3630.25579756152;3651.1967367179177;3669.1377526281303;3704.4258139988347;3721.9627803721887;1242.9689257768537;1247.4760600547793;1251.5682089679046;1287.0347239570322;1289.226464121529;13943.372548235353;13959.581212563638;14051.43794248229;14059.507917222849;14097.78853317201;4893.437254715682;4920.5325494078725;4921.188003979375;5032.245163225472;5113.539781419616;10754.73113172177;10792.136629069655;10796.298240614335;10798.136149108053;10801.351918884891;5709.400512946081;5730.684812335278;5734.397888221677;5736.311642602831;5739.885202295931;4798.1123492154065;4825.758712064383;5220.060389129917;5283.524482796567;5322.121849894724;4176.477940257468;4185.705365196089;4271.9359209611885;4274.1451709658195;4313.870583882489;1258.0627432562799;1281.9551315704093;1285.736424033071;1300.6683295759522;1304.6412236634812;935.9064093590587;987.9555420006117;1021.5658345203408;1040.1159014837328;1046.6650671785108;6234.470070044097;6369.426751592287;6418.903716444257;6419.743210271537;6733.562318449285;487.3299213581326;496.70694290368857;498.5936461783635;499.91501444754476;504.1253425307089;4405.29515277554;4496.730196188174;4502.16709908668;4514.34541991416;4565.269556870951;12008.519659213709;12291.69270768238;12406.193597416139;12410.503579856704;12414.565490207799;11270.154738394653;12286.631401057804;12314.81527777084;12333.753324933452;12351.567695130627;8752.168543988311;9022.593983270768;9097.863532047024;9098;9113.270938324918;6215.223097112861;6373.362663733589;6432.678366081584;6585.506087043395;6600.669966501751;11053.613123540637;11266.7746645065;11300.039496642878;11432.657020289405;11440.94279528597;10412.427316497666;10962.890371096188;11430.314181149157;11805.763884722086;11819.586314478953;8375.204355586453;8732.864727585373;8795.340349473943;8813.206811387006;8914.37599368064;9410.24718022543;9557;9557.378770379903;9559.61761529531;9564.713058608253;8462.323014158976;8471.025832029396;8501.787455313666;8653.17794809492;8799.736007919773;8121.4721043132;8142.674293028213;8371.539565323965;8474.449160804528;8552.957235213784;7698.0762308523335;7806.843863122819;7843.098043725045;7876.921230787848;7940.722074727357;5547.528460080777;5555.2222688822785;5693.4875861172695;5756.827295181151;5835.2122463466885;5515.503604675599;5543.556515478833;5653.660780353192;5659.886802263849;5772.6536407815665 04:32:28 INFO - PROCESS | 4928 | |4;prototype.html;341.9846106925188;350.9561304836895;355.4384073164401;358.3817914098181;358.87618771523813;5576.302962129734;5617.21359009739;5626.381098079213;5639.774409023645;5656.802011929583;5221.660592061523;5360.624756267063;5399.433059528752;5422.267993820833;5570.498655121039;4179.352200408942;4257.5742425757335;4274.529801721808;4282.207791558571;4338.284183109783;4304.827806887736;4320.222359975203;4322.243607368698;4335.934960975588;4339.39248505208;4179.874603761892;4308.569143085682;4346.304591265398;4347.434833471644;4369.978150109245;4333.718308309982;4353.955050787819;4355.542668019875;4357.346398040288;4360.563943605614;3883.3010058189516;3884.378499440076;3891.7859517726515;3906.824192911326;3908.7654740715648;5178.223266510016;5304.469553044703;5307.681539107666;5364.398266840579;5378.220158077057;4923.236898280772;4935.629827762932;4946.950530494685;4949.935763810762;4950.579200767939;5741.425857414267;5767.625104368202;5771.480566749013;5785.739641716133;5823.6023354395065;4125.690573207022;4218.578142185757;4244.02387450888;4258.127083947783;4329.610335069859;5867.885101830681;5904.940950590482;5922.703864806743;5956.702164891782;5966.880662386728;4356.368326592689;4371.825126994948;4397.758123303249;4410.272305069628;4410.977945110254;4291.892702682396;4442.9777851111185;4453.888652783641;4454.977725111354;4478.283474644041;4926;4927.507249275044;4946.678465899705;4953.380827396575;4958.702477851269;5737.540996720336;5761.971190144022;5762.423757624288;5822.534197264209;5827.504662103769;1427.7001829615897;1458.8687018168416;1502.2638906935522;1511.8261399938929;1525;1050;1061.2677252695614;1064.9467526623794;1076.806174888512;1090.0734375780523;4901.387326584177;4948.653594248368;4993.90012199754;4993.952479483467;5017.84946451607;5187.936473023096;5199.090159222121;5223.869403264873;5236.188390799432;5257.526822585909;4658.394408726844;4939.950600493949;4953.414907229724;4959.553640172402;4980.484855148127;2748.560230363172;2754.5868119782194;2758.2828464599347;2761.8619069046467;2767.169849045278;5310.734463276898;5326;5328.866778330495;5347.465253474699;5382.407935127211;5239.083160446983;5255.554722451357;5270.367555893317;5625.268715067015;6030.366811484819;6173.8204824515415;6178.7219575119225;6185.474234690012;6192.717859534454;6205.007198848162;5976.970115149396;5979.790707325223;5995;6028.547858910511;6030.638161710312;5625.184348269559;5848.53211743068;5867.090600956859;5892.793752218652;5913.822585322448;6167.167432396569;6176.320604733565;6189.43138597477;6192.349803270592;6192.349803270772;4165.708400411942;4252.4259225004225;4252.638525725348;4260.594003978617;4271.551487093935;5945.970270148621;5947.78070495556;5952.851178720567;5960.1357803117435;5967.791127310524;5564.4713752193475;5704.80033198836;5711.7429715662065;5728.312602487728;5728.625129968861;5614.775408983595;5731.337912005639;5749.396313387034;5767.826965191051;5771.451712087345;5644.6330988485615;5738.651416916938;5757.625754325955;5785.595008349375;5795.449432303924;5796.391378905154;5827.6794776286015;5831.387704291158;5834.27071616048;5841.50347220491;5529.695866727368;5704.258446401941;5718.628289161191;5728.424683211983;5781.479666830005 04:32:28 INFO - PROCESS | 4928 | |5;yui.html;790.9802254943629;836.6695155413612;899.1791621965523;904.7964208053188;917.857732051532;16254;17219;17225;17229.827701722963;17420.477385678434;15189.848101518981;15270.923645381785;15891.523254302361;16736.2468688909;16737.581560460996;755.4975940999257;883.730462209026;895.9641614335419;920.3327587499076;924.8335299646061;911.1617312072875;915.0894859614692;916.0610374366262;918.3984490158923;919.2186641354829;946.8343039968013;955.6941778630841;957.0286159548065;957.1051067252107;958.0850287974968;790.6125998260811;929.7582628516634;943.8961714211465;953.5756588318201;955.6225291010047;695.4644923408997;695.5791745993652;699.6571679876874;700.6601798127904;700.8633316503284;15740.842591574052;15749.68500629981;15824.604384890356;15843;15858.36566537337;15973.201339933074;15988.680226395523;15998.840011599852;16058;16269.430569930115;14739.410423583044;14794.704105917928;14877.702445951021;14895.851041489555;14941.252937353089;4754.096721622881;4851.56335929768;4885.584725298354;4923.236898280772;4940.851774446773;68792.65603671949;69703;69813;89663;89852.55073724655;12378.319192444322;12772.297523636289;12825.102242843099;12949.741005180033;13068.738625227443;24493.387665308153;24541.509169816505;24546.26361209131;24556.386090347525;24559.140430084863;9012.954935225413;9122.498262595622;9222.492762898106;9230.538473076454;9612.279079069098;5039.546440820343;5060.924086138711;5098.872528186825;5226.790928362899;5275.340582427197;3686.1337585667347;3796.6013568575454;3840.807959602009;3843.442700808367;3845.9038524036564;679.864027194563;683.9247682754992;689.582802404538;696.4115322552435;721.3796135323618;637.2830565613684;699.622204009829;724.583364565377;746.4028776978396;780.7813812132498;9972.401655900669;9998.650047248311;10136.54385552652;10437.791244175074;10506.474676266156;591.7544219149005;608.4341562347059;681.9556728812711;720.6504845150098;797.9840403191904;5013.724245166478;5119.360079990001;5161.122609156378;5167.0957582422925;5193.818216362409;81291.5935420331;81629;81727.591362044;108501;132200;119555;120203.39898300453;122978.38510807388;123180;123761;102019;104028.47985760022;104259.47870260601;105546.47226763816;108569.45715271532;9743.366681165702;9921.603135874484;9989.850152247867;10025;10092.596296148073;42819;48611.75694121648;49990;51226;51684.48315516797;67175.66412168104;78110.60944695468;78118.2188178111;78723;82571.5871420639;14621.780673289697;14920.328585213474;14932.253387330373;14950.401983920521;15110.924445377703;14872.405103795727;14915.85084149188;15378;15418.229088545751;15702.057876527444;11110.114598903836;13408.865911340761;14867;15390.615234619225;15628.4530041448;14298.642533936734;14928.10431374121;15044.398224070914;15175.544733658007;15359.61600959985;14574.708505830036;15063.322150503034;15236;15674.764878526603;15812.446564370191;11470.353021821076;11615.825762613398;11636.243644163102;12112.542677000663;12140.93929530382;12704.809427858774;12712.872871271169;12917.198591248423;13285.402156903156;13471.932640336736 04:32:28 INFO - PROCESS | 4928 | __end_tp_report 04:32:28 INFO - PROCESS | 4928 | __start_cc_report 04:32:28 INFO - PROCESS | 4928 | _x_x_mozilla_cycle_collect,833 04:32:28 INFO - PROCESS | 4928 | __end_cc_report 04:32:28 INFO - PROCESS | 4928 | __startTimestamp1471519948266__endTimestamp 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | ------- Summary: start ------- 04:32:28 INFO - PROCESS | 4928 | Number of tests: 6 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | [#0] dojo.html Cycles:165 Average:5678.09 Median:5869.50 stddev:667.19 (11.4%) stddev-sans-first:642.07 04:32:28 INFO - PROCESS | 4928 | Values: 3275.6 3280.8 3296.5 3297.1 3318.7 5698.0 5779.4 5789.7 5795.7 5815.2 5664.1 5754.1 5754.8 5755.0 5761.9 4991.1 5043.6 5173.3 5177.5 5223.2 5107.6 5108.6 5134.8 5177.7 5196.4 5147.9 5194.8 5201.7 5207.9 5218.6 5263.4 5270.6 5272.9 5278.3 5308.4 4944.1 5090.2 5129.0 5160.7 5190.4 5698.8 5725.1 5736.1 5736.8 5772.2 6174.0 6178.8 6180.8 6183.9 6191.7 5863.4 6043.5 6083.1 6100.5 6180.1 5726.8 5729.1 5754.5 5756.1 5758.2 6225.7 6272.7 6293.9 6296.0 6303.8 5767.3 5965.1 6007.3 6024.0 6075.1 6097.5 6139.6 6144.7 6155.2 6156.9 6134.5 6150.5 6152.4 6154.3 6157.8 5823.4 5839.9 5884.7 5961.3 6141.0 4571.5 4572.5 4573.8 4577.8 4597.6 4106.2 4113.7 4143.4 4146.8 4195.1 5823.2 5825.4 5825.8 5831.6 5850.2 6129.4 6140.1 6163.7 6167.8 6172.9 5572.6 5684.0 5798.1 5800.6 5838.7 5186.7 5186.9 5188.7 5200.9 5214.0 6359.8 6413.8 6419.9 6432.7 6434.9 5869.5 6088.8 6117.7 6187.8 6203.0 6427.3 6451.5 6463.0 6465.5 6478.0 6119.1 6120.3 6121.4 6122.7 6129.1 5890.2 6013.9 6014.7 6237.0 6245.4 5937.5 5995.6 5997.7 6005.2 6032.2 5907.6 5957.6 5961.7 5964.3 5981.9 5753.3 5833.5 5857.5 5909.4 5927.2 6037.0 6038.0 6052.3 6076.6 6082.4 5872.2 5873.9 5879.1 5880.1 5896.8 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | [#1] ext.html Cycles:180 Average:66278.37 Median:40162.60 stddev:135615.27 (337.7%) stddev-sans-first:135906.08 04:32:28 INFO - PROCESS | 4928 | Values: 609.7 642.7 653.3 656.2 662.0 36117.6 48110.5 49405.0 49454.0 49514.0 50922.7 51334.5 51368.5 51375.5 51429.7 8447.7 9295.3 9451.8 9453.1 9456.8 10991.5 11236.8 11241.2 11575.1 11578.7 11371.8 11430.6 11436.7 11438.0 11492.7 11081.8 11155.9 11167.0 11182.9 11218.2 11033.4 11050.7 11058.7 11065.1 11078.5 46683.8 46718.5 47119.3 47133.0 47134.0 3989.4 4178.5 4348.2 4485.4 4711.4 35631.0 35684.0 35926.8 36240.0 38059.0 4748.0 4800.1 4818.0 4858.8 4869.6 370501.0 373636.0 373774.0 374295.0 374702.1 38535.0 67528.0 68199.7 68281.0 68299.3 39047.6 41113.0 41184.8 41369.8 41455.0 17139.4 17304.7 17328.8 17336.9 17340.0 13092.5 14030.2 14378.8 14493.1 14609.1 9291.8 9302.2 9309.0 9363.1 9388.5 3505.0 3550.9 3555.6 3562.9 3596.4 1153.4 1174.9 1192.8 1206.8 1232.6 36184.8 36192.8 36324.8 36349.3 36419.6 1195.2 1210.6 1213.5 1215.9 1306.0 17826.6 18837.5 18946.0 19056.9 19058.9 664878.0 672008.0 672357.0 674031.0 674966.0 68536.0 69470.3 69477.3 69535.0 69547.7 1360.6 1451.7 1467.7 1481.7 1517.1 32964.2 36790.3 37129.0 37203.8 37311.8 56255.4 56387.4 56612.4 56838.7 56942.4 430777.8 431422.0 431942.0 433052.0 434055.0 28276.4 30909.4 31711.7 31841.5 32363.8 53838.0 54053.5 54117.0 54667.0 54684.0 44931.6 46930.8 47457.8 47648.8 47909.5 44811.3 46043.5 46921.5 46985.5 47859.3 44859.3 45688.5 46072.8 46116.1 46140.1 37972.2 40073.6 40251.6 40855.2 41345.8 30280.8 30311.5 30556.4 30748.2 31020.8 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | [#2] jquery.html Cycles:180 Average:30664.53 Median:2169.34 stddev:86160.17 (3971.7%) stddev-sans-first:86371.91 04:32:28 INFO - PROCESS | 4928 | Values: 405.3 412.8 427.0 434.1 441.5 19600.8 21040.7 21615.5 21931.8 22128.9 16043.4 17434.0 19604.5 20017.1 20162.8 5276.8 5525.2 5525.9 5531.9 5538.1 5338.2 5374.0 5412.7 5466.3 5497.1 5471.1 5488.8 5489.5 5504.8 5507.6 5495.9 5508.6 5575.7 5909.1 5978.2 5403.3 5404.6 5405.0 5410.9 5411.5 14917.3 15035.2 15193.8 15203.9 19661.8 2478.9 2539.6 2584.5 2632.1 2653.5 10677.8 11010.9 11130.5 11285.0 13860.8 2043.2 2115.7 2129.8 2166.5 2176.7 71563.3 77592.6 92904.5 93308.5 95154.0 10568.7 11614.1 11868.3 11923.6 11970.7 15461.2 16153.1 16222.4 16839.3 16866.7 8506.6 8639.2 8673.7 8753.0 9186.9 8759.3 8850.5 8856.6 9001.9 9012.5 5007.2 5077.6 5106.7 5166.7 5281.4 2049.8 2089.9 2102.4 2109.9 2172.2 949.9 1003.4 1015.7 1035.8 1073.6 20780.8 21396.7 21398.2 21424.9 21538.6 1044.7 1049.8 1067.6 1077.8 1089.3 10674.9 11696.7 11885.5 11967.2 12031.8 517328.0 520165.0 521308.0 522826.0 526066.0 97452.5 98858.5 101098.0 101956.5 106205.5 1006.7 1007.5 1025.4 1028.6 1037.1 17242.8 18716.0 18891.9 18970.0 19380.9 42120.2 42670.6 46556.8 47399.8 48265.8 61829.7 62573.4 63118.0 63143.4 64744.7 8163.9 8983.1 9041.1 9151.2 9321.8 13947.4 14111.8 14478.4 14629.5 14630.8 11749.2 11886.6 12535.0 12563.7 12730.3 5647.1 10109.5 11776.5 11944.6 12450.9 11871.2 11906.3 12368.1 12496.2 13152.7 8588.7 8755.0 8965.3 9033.5 9108.5 8812.5 12008.4 12566.7 12776.0 13094.7 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | [#3] mootools.html Cycles:180 Average:6341.85 Median:2690.38 stddev:3703.23 (137.6%) stddev-sans-first:3694.91 04:32:28 INFO - PROCESS | 4928 | Values: 1389.1 1407.6 1514.5 1650.3 1897.9 8186.5 9003.9 9370.6 9395.5 9659.9 9514.6 9541.0 9602.8 9603.6 9632.2 2503.2 2553.8 2555.9 2556.5 2556.6 2472.5 2563.7 2567.8 2569.1 2572.3 2407.8 2408.4 2412.2 2443.2 2507.3 2441.3 2445.1 2446.3 2528.9 2533.0 2433.4 2459.7 2468.1 2469.3 2477.0 9300.7 9496.7 9512.8 9622.0 9627.3 5633.6 5800.1 5801.2 5806.6 5812.2 3630.3 3651.2 3669.1 3704.4 3722.0 1243.0 1247.5 1251.6 1287.0 1289.2 13943.4 13959.6 14051.4 14059.5 14097.8 4893.4 4920.5 4921.2 5032.2 5113.5 10754.7 10792.1 10796.3 10798.1 10801.4 5709.4 5730.7 5734.4 5736.3 5739.9 4798.1 4825.8 5220.1 5283.5 5322.1 4176.5 4185.7 4271.9 4274.1 4313.9 1258.1 1282.0 1285.7 1300.7 1304.6 935.9 988.0 1021.6 1040.1 1046.7 6234.5 6369.4 6418.9 6419.7 6733.6 487.3 496.7 498.6 499.9 504.1 4405.3 4496.7 4502.2 4514.3 4565.3 12008.5 12291.7 12406.2 12410.5 12414.6 11270.2 12286.6 12314.8 12333.8 12351.6 8752.2 9022.6 9097.9 9098.0 9113.3 6215.2 6373.4 6432.7 6585.5 6600.7 11053.6 11266.8 11300.0 11432.7 11440.9 10412.4 10962.9 11430.3 11805.8 11819.6 8375.2 8732.9 8795.3 8813.2 8914.4 9410.2 9557.0 9557.4 9559.6 9564.7 8462.3 8471.0 8501.8 8653.2 8799.7 8121.5 8142.7 8371.5 8474.4 8553.0 7698.1 7806.8 7843.1 7876.9 7940.7 5547.5 5555.2 5693.5 5756.8 5835.2 5515.5 5543.6 5653.7 5659.9 5772.7 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | [#4] prototype.html Cycles:180 Average:4792.90 Median:5237.64 stddev:1391.35 (26.6%) stddev-sans-first:1354.55 04:32:28 INFO - PROCESS | 4928 | Values: 342.0 351.0 355.4 358.4 358.9 5576.3 5617.2 5626.4 5639.8 5656.8 5221.7 5360.6 5399.4 5422.3 5570.5 4179.4 4257.6 4274.5 4282.2 4338.3 4304.8 4320.2 4322.2 4335.9 4339.4 4179.9 4308.6 4346.3 4347.4 4370.0 4333.7 4354.0 4355.5 4357.3 4360.6 3883.3 3884.4 3891.8 3906.8 3908.8 5178.2 5304.5 5307.7 5364.4 5378.2 4923.2 4935.6 4947.0 4949.9 4950.6 5741.4 5767.6 5771.5 5785.7 5823.6 4125.7 4218.6 4244.0 4258.1 4329.6 5867.9 5904.9 5922.7 5956.7 5966.9 4356.4 4371.8 4397.8 4410.3 4411.0 4291.9 4443.0 4453.9 4455.0 4478.3 4926.0 4927.5 4946.7 4953.4 4958.7 5737.5 5762.0 5762.4 5822.5 5827.5 1427.7 1458.9 1502.3 1511.8 1525.0 1050.0 1061.3 1064.9 1076.8 1090.1 4901.4 4948.7 4993.9 4994.0 5017.8 5187.9 5199.1 5223.9 5236.2 5257.5 4658.4 4940.0 4953.4 4959.6 4980.5 2748.6 2754.6 2758.3 2761.9 2767.2 5310.7 5326.0 5328.9 5347.5 5382.4 5239.1 5255.6 5270.4 5625.3 6030.4 6173.8 6178.7 6185.5 6192.7 6205.0 5977.0 5979.8 5995.0 6028.5 6030.6 5625.2 5848.5 5867.1 5892.8 5913.8 6167.2 6176.3 6189.4 6192.3 6192.3 4165.7 4252.4 4252.6 4260.6 4271.6 5946.0 5947.8 5952.9 5960.1 5967.8 5564.5 5704.8 5711.7 5728.3 5728.6 5614.8 5731.3 5749.4 5767.8 5771.5 5644.6 5738.7 5757.6 5785.6 5795.4 5796.4 5827.7 5831.4 5834.3 5841.5 5529.7 5704.3 5718.6 5728.4 5781.5 04:32:28 INFO - PROCESS | 4928 | 04:32:28 INFO - PROCESS | 4928 | [#5] yui.html Cycles:180 Average:22203.04 Median:3844.67 stddev:31833.63 (828.0%) stddev-sans-first:31882.34 04:32:28 INFO - PROCESS | 4928 | Values: 791.0 836.7 899.2 904.8 917.9 16254.0 17219.0 17225.0 17229.8 17420.5 15189.8 15270.9 15891.5 16736.2 16737.6 755.5 883.7 896.0 920.3 924.8 911.2 915.1 916.1 918.4 919.2 946.8 955.7 957.0 957.1 958.1 790.6 929.8 943.9 953.6 955.6 695.5 695.6 699.7 700.7 700.9 15740.8 15749.7 15824.6 15843.0 15858.4 15973.2 15988.7 15998.8 16058.0 16269.4 14739.4 14794.7 14877.7 14895.9 14941.3 4754.1 4851.6 4885.6 4923.2 4940.9 68792.7 69703.0 69813.0 89663.0 89852.6 12378.3 12772.3 12825.1 12949.7 13068.7 24493.4 24541.5 24546.3 24556.4 24559.1 9013.0 9122.5 9222.5 9230.5 9612.3 5039.5 5060.9 5098.9 5226.8 5275.3 3686.1 3796.6 3840.8 3843.4 3845.9 679.9 683.9 689.6 696.4 721.4 637.3 699.6 724.6 746.4 780.8 9972.4 9998.7 10136.5 10437.8 10506.5 591.8 608.4 682.0 720.7 798.0 5013.7 5119.4 5161.1 5167.1 5193.8 81291.6 81629.0 81727.6 108501.0 132200.0 119555.0 120203.4 122978.4 123180.0 123761.0 102019.0 104028.5 104259.5 105546.5 108569.5 9743.4 9921.6 9989.9 10025.0 10092.6 42819.0 48611.8 49990.0 51226.0 51684.5 67175.7 78110.6 78118.2 78723.0 82571.6 14621.8 14920.3 14932.3 14950.4 15110.9 14872.4 14915.9 15378.0 15418.2 15702.1 11110.1 13408.9 14867.0 15390.6 15628.5 14298.6 14928.1 15044.4 15175.5 15359.6 14574.7 15063.3 15236.0 15674.8 15812.4 11470.4 11615.8 11636.2 12112.5 12140.9 12704.8 12712.9 12917.2 13285.4 13471.9 04:32:28 INFO - PROCESS | 4928 | -------- Summary: end -------- 04:32:28 INFO - PROCESS | 4928 | 04:32:29 INFO - TEST-INFO | 4928: exit 0 04:32:29 INFO - TEST-OK | dromaeo_css | took 1113495ms 04:32:29 INFO - TEST-START | kraken 04:32:29 INFO - operating with platform_type : linux_ 04:32:29 INFO - Initialising browser for kraken test... 04:32:29 INFO - TEST-INFO | started process 5132 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ4aIcq/profile http://localhost:51076/getInfo.html) 04:32:33 INFO - PROCESS | 5132 | __metrics Screen width/height:1600/1200 04:32:33 INFO - PROCESS | 5132 | colorDepth:24 04:32:33 INFO - PROCESS | 5132 | Browser inner width/height: 1024/697 04:32:33 INFO - PROCESS | 5132 | __metrics 04:32:33 INFO - PROCESS | 5132 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:32:33 INFO - PROCESS | 5132 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:34 INFO - TEST-INFO | 5132: exit 0 04:32:34 INFO - Browser initialized. 04:32:34 INFO - Running cycle 1/1 for kraken test... 04:32:34 INFO - TEST-INFO | started process 5223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ4aIcq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:46 INFO - PROCESS | 5223 | RSS: Main: 144904192 04:32:46 INFO - PROCESS | 5223 | 04:32:49 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51076/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:49 INFO - PROCESS | 5223 | RSS: Main: 191057920 04:32:49 INFO - PROCESS | 5223 | 04:32:52 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51076/tests/kraken/driver.html?testName=audio-dft) 04:32:53 INFO - PROCESS | 5223 | RSS: Main: 181383168 04:32:53 INFO - PROCESS | 5223 | 04:32:55 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51076/tests/kraken/driver.html?testName=audio-fft) 04:32:56 INFO - PROCESS | 5223 | RSS: Main: 269275136 04:32:56 INFO - PROCESS | 5223 | 04:32:58 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51076/tests/kraken/driver.html?testName=audio-oscillator) 04:32:59 INFO - PROCESS | 5223 | RSS: Main: 175271936 04:32:59 INFO - PROCESS | 5223 | 04:33:01 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51076/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:01 INFO - PROCESS | 5223 | RSS: Main: 176381952 04:33:01 INFO - PROCESS | 5223 | 04:33:04 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51076/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:05 INFO - PROCESS | 5223 | RSS: Main: 212635648 04:33:05 INFO - PROCESS | 5223 | 04:33:08 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51076/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:09 INFO - PROCESS | 5223 | RSS: Main: 211902464 04:33:09 INFO - PROCESS | 5223 | 04:33:12 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51076/tests/kraken/driver.html?testName=json-parse-financial) 04:33:12 INFO - PROCESS | 5223 | RSS: Main: 210837504 04:33:12 INFO - PROCESS | 5223 | 04:33:14 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51076/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:14 INFO - PROCESS | 5223 | RSS: Main: 175345664 04:33:14 INFO - PROCESS | 5223 | 04:33:16 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:17 INFO - PROCESS | 5223 | RSS: Main: 180928512 04:33:17 INFO - PROCESS | 5223 | 04:33:19 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:19 INFO - PROCESS | 5223 | RSS: Main: 186081280 04:33:19 INFO - PROCESS | 5223 | 04:33:22 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:22 INFO - PROCESS | 5223 | RSS: Main: 181547008 04:33:22 INFO - PROCESS | 5223 | 04:33:25 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:26 INFO - PROCESS | 5223 | RSS: Main: 184332288 04:33:26 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | Cycle 1(1): loaded http://localhost:51076/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51076/tests/kraken/driver.html?testName=ai-astar) 04:33:27 INFO - PROCESS | 5223 | RSS: Main: 235614208 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | __start_tp_report 04:33:27 INFO - PROCESS | 5223 | _x_x_mozilla_page_load 04:33:27 INFO - PROCESS | 5223 | _x_x_mozilla_page_load_details 04:33:27 INFO - PROCESS | 5223 | |i|pagename|runs| 04:33:27 INFO - PROCESS | 5223 | |0;ai-astar;101;110;110;112;109;111;111;110;111;109 04:33:27 INFO - PROCESS | 5223 | |1;audio-beat-detection;147;115;116;189;116;116;146;121;155;114 04:33:27 INFO - PROCESS | 5223 | |2;audio-dft;160;162;159;160;162;160;160;161;159;158 04:33:27 INFO - PROCESS | 5223 | |3;audio-fft;75;75;75;111;75;76;75;74;99;75 04:33:27 INFO - PROCESS | 5223 | |4;audio-oscillator;83;83;138;83;83;82;123;82;83;129 04:33:27 INFO - PROCESS | 5223 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 04:33:27 INFO - PROCESS | 5223 | |6;imaging-darkroom;146;145;146;146;147;145;146;146;147;145 04:33:27 INFO - PROCESS | 5223 | |7;imaging-desaturate;95;94;96;94;95;95;95;95;94;96 04:33:27 INFO - PROCESS | 5223 | |8;json-parse-financial;50;50;49;50;50;51;52;49;50;49 04:33:27 INFO - PROCESS | 5223 | |9;json-stringify-tinderbox;61;61;60;60;61;60;61;61;61;60 04:33:27 INFO - PROCESS | 5223 | |10;stanford-crypto-aes;73;72;73;81;73;75;72;77;71;73 04:33:27 INFO - PROCESS | 5223 | |11;stanford-crypto-ccm;118;116;117;115;117;116;117;116;159;119 04:33:27 INFO - PROCESS | 5223 | |12;stanford-crypto-pbkdf2;155;154;157;155;155;155;160;155;156;157 04:33:27 INFO - PROCESS | 5223 | |13;stanford-crypto-sha256-iterative;62;63;62;62;61;61;61;61;62;61 04:33:27 INFO - PROCESS | 5223 | __end_tp_report 04:33:27 INFO - PROCESS | 5223 | __start_cc_report 04:33:27 INFO - PROCESS | 5223 | _x_x_mozilla_cycle_collect,1649 04:33:27 INFO - PROCESS | 5223 | __end_cc_report 04:33:27 INFO - PROCESS | 5223 | __startTimestamp1471520007986__endTimestamp 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | ------- Summary: start ------- 04:33:27 INFO - PROCESS | 5223 | Number of tests: 14 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:3.10 (2.8%) stddev-sans-first:1.00 04:33:27 INFO - PROCESS | 5223 | Values: 101.0 110.0 110.0 112.0 109.0 111.0 111.0 110.0 111.0 109.0 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:133.50 stddev:25.12 (18.8%) stddev-sans-first:26.16 04:33:27 INFO - PROCESS | 5223 | Values: 147.0 115.0 116.0 189.0 116.0 116.0 146.0 121.0 155.0 114.0 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 04:33:27 INFO - PROCESS | 5223 | Values: 160.0 162.0 159.0 160.0 162.0 160.0 160.0 161.0 159.0 158.0 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:12.97 (17.3%) stddev-sans-first:13.57 04:33:27 INFO - PROCESS | 5223 | Values: 75.0 75.0 75.0 111.0 75.0 76.0 75.0 74.0 99.0 75.0 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:83.00 stddev:23.12 (27.9%) stddev-sans-first:23.97 04:33:27 INFO - PROCESS | 5223 | Values: 83.0 83.0 138.0 83.0 83.0 82.0 123.0 82.0 83.0 129.0 04:33:27 INFO - PROCESS | 5223 | 04:33:27 INFO - PROCESS | 5223 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 04:33:28 INFO - PROCESS | 5223 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 04:33:28 INFO - PROCESS | 5223 | Values: 146.0 145.0 146.0 146.0 147.0 145.0 146.0 146.0 147.0 145.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 04:33:28 INFO - PROCESS | 5223 | Values: 95.0 94.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 96.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:0.94 (1.9%) stddev-sans-first:1.00 04:33:28 INFO - PROCESS | 5223 | Values: 50.0 50.0 49.0 50.0 50.0 51.0 52.0 49.0 50.0 49.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 04:33:28 INFO - PROCESS | 5223 | Values: 61.0 61.0 60.0 60.0 61.0 60.0 61.0 61.0 61.0 60.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.00 stddev:2.98 (4.1%) stddev-sans-first:3.14 04:33:28 INFO - PROCESS | 5223 | Values: 73.0 72.0 73.0 81.0 73.0 75.0 72.0 77.0 71.0 73.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:117.00 stddev:13.40 (11.5%) stddev-sans-first:14.17 04:33:28 INFO - PROCESS | 5223 | Values: 118.0 116.0 117.0 115.0 117.0 116.0 117.0 116.0 159.0 119.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.90 Median:155.50 stddev:1.73 (1.1%) stddev-sans-first:1.80 04:33:28 INFO - PROCESS | 5223 | Values: 155.0 154.0 157.0 155.0 155.0 155.0 160.0 155.0 156.0 157.0 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - PROCESS | 5223 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 04:33:28 INFO - PROCESS | 5223 | Values: 62.0 63.0 62.0 62.0 61.0 61.0 61.0 61.0 62.0 61.0 04:33:28 INFO - PROCESS | 5223 | -------- Summary: end -------- 04:33:28 INFO - PROCESS | 5223 | 04:33:28 INFO - TEST-INFO | 5223: exit 0 04:33:28 INFO - TEST-OK | kraken | took 59817ms 04:33:28 INFO - SUITE-END | took 1173s 04:33:29 INFO - Completed test suite (00:19:33) 04:33:29 INFO - javascript benchmark 04:33:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3275.5905511811025, 3280.7539434542414, 3296.472564389698, 3297.109780359303, 3318.651541588133, 5698.0, 5779.393163717812, 5789.739461724222, 5795.739191736371, 5815.214945982286, 5664.065429204186, 5754.079347304432, 5754.798582049629, 5754.971225143879, 5761.9135712964335, 4991.076650819592, 5043.596512279028, 5173.3016042834115, 5177.508136727014, 5223.164293713006, 5107.591392688594, 5108.616853735966, 5134.820281290155, 5177.715225662587, 5196.350456192976, 5147.948520514784, 5194.844154675366, 5201.661891977028, 5207.921881171786, 5218.582513398938, 5263.394709608406, 5270.604704647148, 5272.92090618641, 5278.313819203518, 5308.389535203424, 4944.085344211332, 5090.236464530313, 5129.025485157808, 5160.690358578459, 5190.377154741417, 5698.800541981052, 5725.08398656213, 5736.139579063132, 5736.799212027559, 5772.249607550992, 6174.043023331391, 6178.752849885999, 6180.81457556274, 6183.907241391383, 6191.659458729813, 5863.355030946593, 6043.456088952015, 6083.057126145454, 6100.542459315569, 6180.103884936704, 5726.828195154194, 5729.083346664597, 5754.4533269339345, 5756.079027355603, 5758.165066065397, 6225.719842607094, 6272.655003974825, 6293.937060629427, 6296.024116261987, 6303.842403939847, 5767.336756272998, 5965.075413310944, 6007.339192688801, 6023.969880150659, 6075.119107729354, 6097.4817140543555, 6139.614800607407, 6144.692765361714, 6155.230596175478, 6156.876862462816, 6134.478569321568, 6150.50795936333, 6152.446279834837, 6154.261488621394, 6157.784477543354, 5823.4467725566, 5839.883202335929, 5884.705764711747, 5961.344252132262, 6141.0, 4571.4514258288555, 4572.542745725401, 4573.771311434415, 4577.8168873244695, 4597.632189424905, 4106.240345536085, 4113.670906327487, 4143.440635514227, 4146.834126634901, 4195.139996300812, 5823.213866128103, 5825.446582574648, 5825.79609713658, 5831.562632802556, 5850.210221620112, 6129.35641757609, 6140.083764719945, 6163.691815409212, 6167.84580385494, 6172.9074063889075, 5572.554195664337, 5683.9715801420725, 5798.072308430631, 5800.5939584229545, 5838.737256823453, 5186.740662966837, 5186.948130518646, 5188.688678679291, 5200.8959820803375, 5214.0, 6359.8092057238355, 6413.839654008687, 6419.935800642027, 6432.678366081771, 6434.935650643527, 5869.501092407022, 6088.847778805565, 6117.675163990694, 6187.81436556904, 6203.038529028008, 6427.3251308611925, 6451.548391612542, 6463.030545418037, 6465.547411681137, 6478.028295755675, 6119.143319935123, 6120.296165940974, 6121.3572574879, 6122.693865306628, 6129.111278864629, 5890.2342695449315, 6013.939860601338, 6014.72933717975, 6237.0, 6245.437910588069, 5937.495312898452, 5995.550333725076, 5997.730102145327, 6005.249343832021, 6032.1856549365275, 5907.645541267538, 5957.6127551709, 5961.672108034099, 5964.314103877936, 5981.850453738517, 5753.338366087954, 5833.4749872511675, 5857.5313974881055, 5909.379515151019, 5927.170196172453, 6037.0, 6038.033914573647, 6052.334243233153, 6076.635401875901, 6082.391760823883, 5872.1778950946045, 5873.882522349614, 5879.147523609138, 5880.14737863016, 5896.823095307148], "value": 5631.727178782876, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.6555446172912, 642.7461152844627, 653.261814150011, 656.1764984943898, 661.9669016549173, 36117.63882361176, 48110.518894811044, 49405.0, 49454.01091978163, 49514.0, 50922.74538627302, 51334.486655133434, 51368.48631513684, 51375.48624513754, 51429.74285128579, 8447.662093516252, 9295.256379489641, 9451.763705907339, 9453.054694530527, 9456.81086378272, 10991.450427478634, 11236.831447528293, 11241.1569132315, 11575.073994080496, 11578.652640420802, 11371.829422558669, 11430.599929002445, 11436.714082147972, 11438.0, 11492.655220343404, 11081.833772493419, 11155.944220278929, 11166.994970452657, 11182.888171118266, 11218.158638102175, 11033.393163376011, 11050.668479945614, 11058.668239952814, 11065.059469945065, 11078.501467433985, 46683.76658116722, 46718.53281467176, 47119.29321060187, 47133.0, 47134.0, 3989.3816458448987, 4178.540360560336, 4348.239058164809, 4485.439320084989, 4711.363965864599, 35631.0, 35684.0, 35926.82036589827, 36240.0, 38059.0, 4747.976260118678, 4800.1119792838435, 4817.964682250195, 4858.805647774085, 4869.586085182764, 370501.0, 373636.0, 373774.0, 374295.0, 374702.12648936856, 38535.0, 67528.0, 68199.65900170467, 68281.0, 68299.3170068293, 39047.60952390497, 41113.0, 41184.79407602943, 41369.79315103465, 41455.0, 17139.400120995706, 17304.65390692179, 17328.826711732974, 17336.913315433594, 17340.046297453762, 13092.541761038317, 14030.228337441538, 14378.784318235235, 14493.130412175093, 14609.123452592667, 9291.814163716688, 9302.16280534755, 9308.953455232817, 9363.110504502061, 9388.483633400092, 3504.9649503505148, 3550.91122721934, 3555.5911070227253, 3562.9323372018275, 3596.3526565218513, 1153.4175241503156, 1174.900133488663, 1192.8270400791837, 1206.8008778551616, 1232.5870833270956, 36184.81907590445, 36192.81903590465, 36324.81837590848, 36349.27301454009, 36419.63580364215, 1195.2051885496048, 1210.5994709232004, 1213.5085290457203, 1215.9209651372632, 1305.9617604004839, 17826.643467130583, 18837.46070157, 18946.0, 19056.904715476612, 19058.90470547666, 664878.0, 672008.0, 672357.0, 674031.0, 674966.0, 68536.0, 69470.3052969474, 69477.3052269481, 69535.0, 69547.65226173938, 1360.6258278973164, 1451.7459444597152, 1467.6771110355705, 1481.7406953783045, 1517.120070359189, 32964.1758956028, 36790.26419471649, 37129.0, 37203.813980929925, 37311.81344093262, 56255.43744562666, 56387.43612563822, 56612.43387566072, 56838.7158064207, 56942.43057569371, 430777.84611076745, 431422.0, 431942.0, 433052.0, 434055.0, 28276.43447131087, 30909.381812364078, 31711.682883171798, 31841.5223771639, 32363.838180808943, 53838.0, 54053.45946540484, 54117.0, 54667.0, 54684.0, 44931.55068449274, 46930.76534617305, 47457.762711186224, 47648.761756190994, 47909.52090479051, 44811.327830083224, 46043.539564603925, 46921.53078469171, 46985.53014469812, 47859.28211076906, 44859.327110094026, 45688.54311456843, 46072.76963615161, 46116.077678445574, 46140.077198456514, 37972.24055518819, 40073.59926400815, 40251.59748402479, 40855.1828963425, 41345.79327103345, 30280.84859575688, 30311.545326819672, 30556.388872221985, 30748.231294217825, 31020.844895775375], "value": 21389.63095859077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.33120351420155, 412.79979210083087, 427.0, 434.14906782705884, 441.4768499328294, 19600.803991960078, 21040.737555746648, 21615.45961350967, 21931.780682193174, 22128.8893555532, 16043.35826566936, 17434.041127737968, 19604.509887252825, 20017.099230534626, 20162.798372016314, 5276.762545685444, 5525.226468294442, 5525.917111243314, 5531.917021244684, 5538.0585300498815, 5338.225957236198, 5374.0, 5412.729363531807, 5466.34403871536, 5497.120460726284, 5471.124620060791, 5488.780448782044, 5489.451054894518, 5504.80733174339, 5507.614466987313, 5495.890082198353, 5508.586855985797, 5575.665460072389, 5909.054551271819, 5978.192943952554, 5403.2705584745945, 5404.567634589223, 5405.027095122877, 5410.945890541084, 5411.512963833274, 14917.254137293201, 15035.248237588077, 15193.848061519353, 15203.923980380026, 19661.820290782598, 2478.8512689238705, 2539.5555777738823, 2584.508943300767, 2632.144553020276, 2653.509100816355, 10677.839832402442, 11010.889891101067, 11130.49912753928, 11285.04077153443, 13860.82183014445, 2043.2133628552965, 2115.6509175985943, 2129.7870212978733, 2166.534195148035, 2176.673498975151, 71563.28436715619, 77592.61203694002, 92904.53547732218, 93308.53345733296, 95154.04845951521, 10568.735781605521, 11614.128940329509, 11868.347240901661, 11923.642290731292, 11970.700732481757, 15461.226938653022, 16153.111578863276, 16222.351105955866, 16839.326426942785, 16866.66266674684, 8506.617202225914, 8639.179277968582, 8673.73978780625, 8753.0, 9186.851643544556, 8759.255463285692, 8850.513221772735, 8856.64573417056, 9001.864972025425, 9012.549372531346, 5007.223880298559, 5077.644564880497, 5106.66806657566, 5166.715830629351, 5281.419043905167, 2049.815516603513, 2089.937301880946, 2102.390306811038, 2109.891863056443, 2172.1528603844513, 949.8955114937352, 1003.4280460137797, 1015.6902144845745, 1035.7565971996576, 1073.586669132374, 20780.79219207819, 21396.679049814265, 21398.189551750536, 21424.892875535836, 21538.56922861534, 1044.6709286574705, 1049.8372752223265, 1067.5783065689166, 1077.7844431113651, 1089.2756317049073, 10674.89325106739, 11696.70758231034, 11885.509304237386, 11967.162298639012, 12031.75936481283, 517328.0, 520165.0, 521308.0, 522826.0, 526066.0, 97452.51273743586, 98858.505707471, 101098.0, 101956.49021754843, 106205.46897265465, 1006.6677996261217, 1007.5415685862915, 1025.3898930136556, 1028.6142696488816, 1037.0770014686784, 17242.827571724625, 18716.0, 18891.90554047276, 18970.0, 19380.90309548443, 42120.157596847275, 42670.57329426666, 46556.7672161637, 47399.76300118477, 48265.75867120642, 61829.690851545456, 62573.37426625675, 63118.0, 63143.36856631375, 64744.67627661832, 8163.853314920032, 8983.101689830964, 9041.095890410907, 9151.176394124295, 9321.813563728823, 13947.372368243656, 14111.788323174955, 14478.420863165355, 14629.487967921072, 14630.780538291721, 11749.177557571229, 11886.583969561023, 12535.0, 12563.685907852378, 12730.299833509245, 5647.124695672177, 10109.545070471699, 11776.470058847544, 11944.581939632071, 12450.937745311216, 11871.228370155912, 11906.345151016778, 12368.134230603771, 12496.187747796364, 13152.671183220496, 8588.699395521127, 8754.956225218833, 8965.282777377925, 9033.548322583976, 9108.453492790453, 8812.471251724917, 12008.39958002114, 12566.685832854251, 12776.041796865458, 13094.738105238033], "value": 9555.378410002173, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.083205084644, 1407.6058703563006, 1514.5380658899037, 1650.2986230851893, 1897.8576606754498, 8186.46787958783, 9003.909960900397, 9370.625174992992, 9395.530223488824, 9659.903400966006, 9514.6194152234, 9540.952295238532, 9602.759931001729, 9603.567839447223, 9632.181264592518, 2503.186464399073, 2553.8212325137247, 2555.8722063896826, 2556.463142740027, 2556.6292887531295, 2472.4560596668703, 2563.7051739049966, 2567.833090849098, 2569.1008147148536, 2572.344052266669, 2407.7832995030444, 2408.3617841272076, 2412.2401443545314, 2443.1937460637946, 2507.285423654259, 2441.292025312657, 2445.083093839819, 2446.2538925627764, 2528.9241322760345, 2532.987335063313, 2433.3794882305074, 2459.6802415686025, 2468.148488771389, 2469.259222233323, 2476.950460990788, 9300.674476393362, 9496.667616633385, 9512.762180945463, 9621.951890240574, 9627.3260871739, 5633.60564760467, 5800.100984347433, 5801.187833703243, 5806.59353845231, 5812.186293918855, 3630.25579756152, 3651.1967367179177, 3669.1377526281303, 3704.4258139988347, 3721.9627803721887, 1242.9689257768537, 1247.4760600547793, 1251.5682089679046, 1287.0347239570322, 1289.226464121529, 13943.372548235353, 13959.581212563638, 14051.43794248229, 14059.507917222849, 14097.78853317201, 4893.437254715682, 4920.5325494078725, 4921.188003979375, 5032.245163225472, 5113.539781419616, 10754.73113172177, 10792.136629069655, 10796.298240614335, 10798.136149108053, 10801.351918884891, 5709.400512946081, 5730.684812335278, 5734.397888221677, 5736.311642602831, 5739.885202295931, 4798.1123492154065, 4825.758712064383, 5220.060389129917, 5283.524482796567, 5322.121849894724, 4176.477940257468, 4185.705365196089, 4271.9359209611885, 4274.1451709658195, 4313.870583882489, 1258.0627432562799, 1281.9551315704093, 1285.736424033071, 1300.6683295759522, 1304.6412236634812, 935.9064093590587, 987.9555420006117, 1021.5658345203408, 1040.1159014837328, 1046.6650671785108, 6234.470070044097, 6369.426751592287, 6418.903716444257, 6419.743210271537, 6733.562318449285, 487.3299213581326, 496.70694290368857, 498.5936461783635, 499.91501444754476, 504.1253425307089, 4405.29515277554, 4496.730196188174, 4502.16709908668, 4514.34541991416, 4565.269556870951, 12008.519659213709, 12291.69270768238, 12406.193597416139, 12410.503579856704, 12414.565490207799, 11270.154738394653, 12286.631401057804, 12314.81527777084, 12333.753324933452, 12351.567695130627, 8752.168543988311, 9022.593983270768, 9097.863532047024, 9098.0, 9113.270938324918, 6215.223097112861, 6373.362663733589, 6432.678366081584, 6585.506087043395, 6600.669966501751, 11053.613123540637, 11266.7746645065, 11300.039496642878, 11432.657020289405, 11440.94279528597, 10412.427316497666, 10962.890371096188, 11430.314181149157, 11805.763884722086, 11819.586314478953, 8375.204355586453, 8732.864727585373, 8795.340349473943, 8813.206811387006, 8914.37599368064, 9410.24718022543, 9557.0, 9557.378770379903, 9559.61761529531, 9564.713058608253, 8462.323014158976, 8471.025832029396, 8501.787455313666, 8653.17794809492, 8799.736007919773, 8121.4721043132, 8142.674293028213, 8371.539565323965, 8474.449160804528, 8552.957235213784, 7698.0762308523335, 7806.843863122819, 7843.098043725045, 7876.921230787848, 7940.722074727357, 5547.528460080777, 5555.2222688822785, 5693.4875861172695, 5756.827295181151, 5835.2122463466885, 5515.503604675599, 5543.556515478833, 5653.660780353192, 5659.886802263849, 5772.6536407815665], "value": 4943.483678780378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.9846106925188, 350.9561304836895, 355.4384073164401, 358.3817914098181, 358.87618771523813, 5576.302962129734, 5617.21359009739, 5626.381098079213, 5639.774409023645, 5656.802011929583, 5221.660592061523, 5360.624756267063, 5399.433059528752, 5422.267993820833, 5570.498655121039, 4179.352200408942, 4257.5742425757335, 4274.529801721808, 4282.207791558571, 4338.284183109783, 4304.827806887736, 4320.222359975203, 4322.243607368698, 4335.934960975588, 4339.39248505208, 4179.874603761892, 4308.569143085682, 4346.304591265398, 4347.434833471644, 4369.978150109245, 4333.718308309982, 4353.955050787819, 4355.542668019875, 4357.346398040288, 4360.563943605614, 3883.3010058189516, 3884.378499440076, 3891.7859517726515, 3906.824192911326, 3908.7654740715648, 5178.223266510016, 5304.469553044703, 5307.681539107666, 5364.398266840579, 5378.220158077057, 4923.236898280772, 4935.629827762932, 4946.950530494685, 4949.935763810762, 4950.579200767939, 5741.425857414267, 5767.625104368202, 5771.480566749013, 5785.739641716133, 5823.6023354395065, 4125.690573207022, 4218.578142185757, 4244.02387450888, 4258.127083947783, 4329.610335069859, 5867.885101830681, 5904.940950590482, 5922.703864806743, 5956.702164891782, 5966.880662386728, 4356.368326592689, 4371.825126994948, 4397.758123303249, 4410.272305069628, 4410.977945110254, 4291.892702682396, 4442.9777851111185, 4453.888652783641, 4454.977725111354, 4478.283474644041, 4926.0, 4927.507249275044, 4946.678465899705, 4953.380827396575, 4958.702477851269, 5737.540996720336, 5761.971190144022, 5762.423757624288, 5822.534197264209, 5827.504662103769, 1427.7001829615897, 1458.8687018168416, 1502.2638906935522, 1511.8261399938929, 1525.0, 1050.0, 1061.2677252695614, 1064.9467526623794, 1076.806174888512, 1090.0734375780523, 4901.387326584177, 4948.653594248368, 4993.90012199754, 4993.952479483467, 5017.84946451607, 5187.936473023096, 5199.090159222121, 5223.869403264873, 5236.188390799432, 5257.526822585909, 4658.394408726844, 4939.950600493949, 4953.414907229724, 4959.553640172402, 4980.484855148127, 2748.560230363172, 2754.5868119782194, 2758.2828464599347, 2761.8619069046467, 2767.169849045278, 5310.734463276898, 5326.0, 5328.866778330495, 5347.465253474699, 5382.407935127211, 5239.083160446983, 5255.554722451357, 5270.367555893317, 5625.268715067015, 6030.366811484819, 6173.8204824515415, 6178.7219575119225, 6185.474234690012, 6192.717859534454, 6205.007198848162, 5976.970115149396, 5979.790707325223, 5995.0, 6028.547858910511, 6030.638161710312, 5625.184348269559, 5848.53211743068, 5867.090600956859, 5892.793752218652, 5913.822585322448, 6167.167432396569, 6176.320604733565, 6189.43138597477, 6192.349803270592, 6192.349803270772, 4165.708400411942, 4252.4259225004225, 4252.638525725348, 4260.594003978617, 4271.551487093935, 5945.970270148621, 5947.78070495556, 5952.851178720567, 5960.1357803117435, 5967.791127310524, 5564.4713752193475, 5704.80033198836, 5711.7429715662065, 5728.312602487728, 5728.625129968861, 5614.775408983595, 5731.337912005639, 5749.396313387034, 5767.826965191051, 5771.451712087345, 5644.6330988485615, 5738.651416916938, 5757.625754325955, 5785.595008349375, 5795.449432303924, 5796.391378905154, 5827.6794776286015, 5831.387704291158, 5834.27071616048, 5841.50347220491, 5529.695866727368, 5704.258446401941, 5718.628289161191, 5728.424683211983, 5781.479666830005], "value": 4359.259824863638, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.9802254943629, 836.6695155413612, 899.1791621965523, 904.7964208053188, 917.857732051532, 16254.0, 17219.0, 17225.0, 17229.827701722963, 17420.477385678434, 15189.848101518981, 15270.923645381785, 15891.523254302361, 16736.2468688909, 16737.581560460996, 755.4975940999257, 883.730462209026, 895.9641614335419, 920.3327587499076, 924.8335299646061, 911.1617312072875, 915.0894859614692, 916.0610374366262, 918.3984490158923, 919.2186641354829, 946.8343039968013, 955.6941778630841, 957.0286159548065, 957.1051067252107, 958.0850287974968, 790.6125998260811, 929.7582628516634, 943.8961714211465, 953.5756588318201, 955.6225291010047, 695.4644923408997, 695.5791745993652, 699.6571679876874, 700.6601798127904, 700.8633316503284, 15740.842591574052, 15749.68500629981, 15824.604384890356, 15843.0, 15858.36566537337, 15973.201339933074, 15988.680226395523, 15998.840011599852, 16058.0, 16269.430569930115, 14739.410423583044, 14794.704105917928, 14877.702445951021, 14895.851041489555, 14941.252937353089, 4754.096721622881, 4851.56335929768, 4885.584725298354, 4923.236898280772, 4940.851774446773, 68792.65603671949, 69703.0, 69813.0, 89663.0, 89852.55073724655, 12378.319192444322, 12772.297523636289, 12825.102242843099, 12949.741005180033, 13068.738625227443, 24493.387665308153, 24541.509169816505, 24546.26361209131, 24556.386090347525, 24559.140430084863, 9012.954935225413, 9122.498262595622, 9222.492762898106, 9230.538473076454, 9612.279079069098, 5039.546440820343, 5060.924086138711, 5098.872528186825, 5226.790928362899, 5275.340582427197, 3686.1337585667347, 3796.6013568575454, 3840.807959602009, 3843.442700808367, 3845.9038524036564, 679.864027194563, 683.9247682754992, 689.582802404538, 696.4115322552435, 721.3796135323618, 637.2830565613684, 699.622204009829, 724.583364565377, 746.4028776978396, 780.7813812132498, 9972.401655900669, 9998.650047248311, 10136.54385552652, 10437.791244175074, 10506.474676266156, 591.7544219149005, 608.4341562347059, 681.9556728812711, 720.6504845150098, 797.9840403191904, 5013.724245166478, 5119.360079990001, 5161.122609156378, 5167.0957582422925, 5193.818216362409, 81291.5935420331, 81629.0, 81727.591362044, 108501.0, 132200.0, 119555.0, 120203.39898300453, 122978.38510807388, 123180.0, 123761.0, 102019.0, 104028.47985760022, 104259.47870260601, 105546.47226763816, 108569.45715271532, 9743.366681165702, 9921.603135874484, 9989.850152247867, 10025.0, 10092.596296148073, 42819.0, 48611.75694121648, 49990.0, 51226.0, 51684.48315516797, 67175.66412168104, 78110.60944695468, 78118.2188178111, 78723.0, 82571.5871420639, 14621.780673289697, 14920.328585213474, 14932.253387330373, 14950.401983920521, 15110.924445377703, 14872.405103795727, 14915.85084149188, 15378.0, 15418.229088545751, 15702.057876527444, 11110.114598903836, 13408.865911340761, 14867.0, 15390.615234619225, 15628.4530041448, 14298.642533936734, 14928.10431374121, 15044.398224070914, 15175.544733658007, 15359.61600959985, 14574.708505830036, 15063.322150503034, 15236.0, 15674.764878526603, 15812.446564370191, 11470.353021821076, 11615.825762613398, 11636.243644163102, 12112.542677000663, 12140.93929530382, 12704.809427858774, 12712.872871271169, 12917.198591248423, 13285.402156903156, 13471.932640336736], "value": 8223.950444880811, "unit": "score"}], "name": "dromaeo_css", "value": 7672.621541050025, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 112.0, 109.0, 111.0, 111.0, 110.0, 111.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 116.0, 189.0, 116.0, 116.0, 146.0, 121.0, 155.0, 114.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 159.0, 160.0, 162.0, 160.0, 160.0, 161.0, 159.0, 158.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 111.0, 75.0, 76.0, 75.0, 74.0, 99.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 138.0, 83.0, 83.0, 82.0, 123.0, 82.0, 83.0, 129.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.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, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 50.0, 51.0, 52.0, 49.0, 50.0, 49.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 81.0, 73.0, 75.0, 72.0, 77.0, 71.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 115.0, 117.0, 116.0, 117.0, 116.0, 159.0, 119.0], "value": 121.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 157.0, 155.0, 155.0, 155.0, 160.0, 155.0, 156.0, 157.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1461.6999999999998, "alertThreshold": 2.0}]} 04:33:29 INFO - Return code: 0 04:33:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:29 INFO - # TBPL SUCCESS # 04:33:29 INFO - Running post-action listener: _resource_record_post_action 04:33:29 INFO - [mozharness: 2016-08-18 11:33:29.286941Z] Finished run-tests step (success) 04:33:29 INFO - Running post-run listener: _resource_record_post_run 04:33:29 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 495616; Write bytes: 328724480; Read time: 164; Write time: 190212 04:33:29 INFO - TinderboxPrint: CPU usage
12.9% 04:33:29 INFO - TinderboxPrint: I/O read bytes / time
495,616 / 164 04:33:29 INFO - TinderboxPrint: I/O write bytes / time
328,724,480 / 190,212 04:33:29 INFO - TinderboxPrint: CPU idle
8,267.4 (87.0%) 04:33:29 INFO - TinderboxPrint: CPU user
1,184.8 (12.5%) 04:33:29 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79167488; Read time: 0; Write time: 45144 04:33:29 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 495616; Write bytes: 249348096; Read time: 164; Write time: 145068 04:33:29 INFO - Running post-run listener: _upload_blobber_files 04:33:29 INFO - Blob upload gear active. 04:33:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:33:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:33:30 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:36 INFO - (blobuploader) - INFO - Done attempting. 04:33:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:37 INFO - (blobuploader) - INFO - Done attempting. 04:33:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:38 INFO - (blobuploader) - INFO - Done attempting. 04:33:38 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:38 INFO - Return code: 0 04:33:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f6e15dbb665549db827092622d893f0750199ec3e76ef7cb89c1fdd13f1907dfdae2b317aefb63ba237fe9bcdc5ac7c1b9d69a2fef2eab116c28856cf3a0078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab9d73d2b8f528f3ad9e232d21d7c21be4cc11787dcbe2d854509a533defe479ca3225641a6b7dae7b594155980e5f5d6e43dc3d223b222443cf345fb609e471", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0220f699ce172a0146bc4fcc7cce6f3bb50c5d4be47283508a95c398dbf651412b76df759e1b1386eb0121b8abc86726de7dae182cfacbbcaa684bbc55113c0"} 04:33:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:38 INFO - Contents: 04:33:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f6e15dbb665549db827092622d893f0750199ec3e76ef7cb89c1fdd13f1907dfdae2b317aefb63ba237fe9bcdc5ac7c1b9d69a2fef2eab116c28856cf3a0078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab9d73d2b8f528f3ad9e232d21d7c21be4cc11787dcbe2d854509a533defe479ca3225641a6b7dae7b594155980e5f5d6e43dc3d223b222443cf345fb609e471", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0220f699ce172a0146bc4fcc7cce6f3bb50c5d4be47283508a95c398dbf651412b76df759e1b1386eb0121b8abc86726de7dae182cfacbbcaa684bbc55113c0"} 04:33:38 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:38 INFO - Copying logs to upload dir... 04:33:38 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1686.772647 ========= master_lag: 1.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-08-18 04:33:39.915628) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:39.922484) ========= 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=523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302 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/5f6e15dbb665549db827092622d893f0750199ec3e76ef7cb89c1fdd13f1907dfdae2b317aefb63ba237fe9bcdc5ac7c1b9d69a2fef2eab116c28856cf3a0078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab9d73d2b8f528f3ad9e232d21d7c21be4cc11787dcbe2d854509a533defe479ca3225641a6b7dae7b594155980e5f5d6e43dc3d223b222443cf345fb609e471", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0220f699ce172a0146bc4fcc7cce6f3bb50c5d4be47283508a95c398dbf651412b76df759e1b1386eb0121b8abc86726de7dae182cfacbbcaa684bbc55113c0"} build_url:https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010456 build_url: 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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/5f6e15dbb665549db827092622d893f0750199ec3e76ef7cb89c1fdd13f1907dfdae2b317aefb63ba237fe9bcdc5ac7c1b9d69a2fef2eab116c28856cf3a0078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab9d73d2b8f528f3ad9e232d21d7c21be4cc11787dcbe2d854509a533defe479ca3225641a6b7dae7b594155980e5f5d6e43dc3d223b222443cf345fb609e471", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0220f699ce172a0146bc4fcc7cce6f3bb50c5d4be47283508a95c398dbf651412b76df759e1b1386eb0121b8abc86726de7dae182cfacbbcaa684bbc55113c0"}' ========= master_lag: 0.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:40.581223) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-18 04:33:40.581547) ========= 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=523ca41ca1e0623de333ad52000001bf-1471517369.162545-131461302 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004440 ========= master_lag: 2.22 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-18 04:33:42.810230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:42.810551) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:42.898214) ========= ========= Total master_lag: 4.24 =========