builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1470752908.19 results: success (0) buildid: 20160809045320 builduid: c834c341e2d243e890cec60379420dc1 revision: 43e9e32c32a8eb644e0ede4f111032cb4edbf489 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.194939) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.195434) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.196087) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.233951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.234266) ========= 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=52faae308a556c440401f8e5000001e8-1470746646.593634-470011628 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 07:28:29-- 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% 69.7M=0s 2016-08-09 07:28:29 (69.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120851 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.370818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.371135) ========= 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=52faae308a556c440401f8e5000001e8-1470746646.593634-470011628 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017540 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:28.420263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 07:28:28.420662) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 43e9e32c32a8eb644e0ede4f111032cb4edbf489 --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 43e9e32c32a8eb644e0ede4f111032cb4edbf489 --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=52faae308a556c440401f8e5000001e8-1470746646.593634-470011628 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 07:28:30,034 truncating revision to first 12 chars 2016-08-09 07:28:30,034 Setting DEBUG logging. 2016-08-09 07:28:30,034 attempt 1/10 2016-08-09 07:28:30,034 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/43e9e32c32a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 07:28:30,997 unpacking tar archive at: mozilla-aurora-43e9e32c32a8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.296263 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 07:28:29.731273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:29.731636) ========= script_repo_revision: 43e9e32c32a8eb644e0ede4f111032cb4edbf489 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:29.732151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:29.732529) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 07:28:29.743157) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-09 07:28:29.743490) ========= /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=52faae308a556c440401f8e5000001e8-1470746646.593634-470011628 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:28:31 INFO - MultiFileLogger online at 20160809 07:28:31 in /builds/slave/test 07:28:31 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 07:28:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:28:31 INFO - {'append_to_log': False, 07:28:31 INFO - 'base_work_dir': '/builds/slave/test', 07:28:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:28:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:28:31 INFO - 'branch': 'Mozilla-Aurora', 07:28:31 INFO - 'buildbot_json_path': 'buildprops.json', 07:28:31 INFO - 'config_files': ('talos/linux_config.py',), 07:28:31 INFO - 'default_actions': ('clobber', 07:28:31 INFO - 'read-buildbot-config', 07:28:31 INFO - 'download-and-extract', 07:28:31 INFO - 'populate-webroot', 07:28:31 INFO - 'create-virtualenv', 07:28:31 INFO - 'install', 07:28:31 INFO - 'run-tests'), 07:28:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:28:31 INFO - 'download_minidump_stackwalk': True, 07:28:31 INFO - 'download_symbols': 'ondemand', 07:28:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:28:31 INFO - 'tooltool.py': '/tools/tooltool.py', 07:28:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:28:31 INFO - '/tools/misc-python/virtualenv.py')}, 07:28:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:28:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:28:31 INFO - 'installer_path': 'installer.exe', 07:28:31 INFO - 'log_level': 'info', 07:28:31 INFO - 'log_name': 'talos', 07:28:31 INFO - 'log_to_console': True, 07:28:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:28:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:28:31 INFO - 'opt_config_files': (), 07:28:31 INFO - 'pip_index': False, 07:28:31 INFO - 'sps_profile': False, 07:28:31 INFO - 'sps_profile_interval': 0, 07:28:31 INFO - 'suite': 'dromaeojs', 07:28:31 INFO - 'system_bits': '32', 07:28:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:28:31 INFO - 'title': 'talos-linux64-ix-056', 07:28:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:28:31 INFO - 'use_talos_json': True, 07:28:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:28:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:28:31 INFO - 'work_dir': 'build'} 07:28:31 INFO - [mozharness: 2016-08-09 14:28:31.461532Z] Running clobber step. 07:28:31 INFO - Running pre-action listener: _resource_record_pre_action 07:28:31 INFO - Running main action method: clobber 07:28:31 INFO - rmtree: /builds/slave/test/build 07:28:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:28:31 INFO - Running post-action listener: _resource_record_post_action 07:28:31 INFO - [mozharness: 2016-08-09 14:28:31.927030Z] Finished clobber step (success) 07:28:31 INFO - [mozharness: 2016-08-09 14:28:31.927131Z] Running read-buildbot-config step. 07:28:31 INFO - Running pre-action listener: _resource_record_pre_action 07:28:31 INFO - Running main action method: read_buildbot_config 07:28:31 INFO - Using buildbot properties: 07:28:31 INFO - { 07:28:31 INFO - "project": "", 07:28:31 INFO - "product": "firefox", 07:28:31 INFO - "script_repo_revision": "production", 07:28:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:28:31 INFO - "repository": "", 07:28:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:28:31 INFO - "buildid": "20160809045320", 07:28:31 INFO - "pgo_build": "True", 07:28:31 INFO - "basedir": "/builds/slave/test", 07:28:31 INFO - "buildnumber": 667, 07:28:31 INFO - "slavename": "talos-linux64-ix-056", 07:28:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:28:31 INFO - "platform": "ubuntu64_hw", 07:28:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:28:31 INFO - "branch": "mozilla-aurora", 07:28:31 INFO - "repo_path": "releases/mozilla-aurora", 07:28:31 INFO - "revision": "43e9e32c32a8eb644e0ede4f111032cb4edbf489", 07:28:31 INFO - "stage_platform": "linux64-pgo", 07:28:31 INFO - "builduid": "c834c341e2d243e890cec60379420dc1", 07:28:31 INFO - "slavebuilddir": "test" 07:28:31 INFO - } 07:28:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 07:28:31 INFO - Running post-action listener: _resource_record_post_action 07:28:31 INFO - [mozharness: 2016-08-09 14:28:31.929601Z] Finished read-buildbot-config step (success) 07:28:31 INFO - [mozharness: 2016-08-09 14:28:31.929689Z] Running download-and-extract step. 07:28:31 INFO - Running pre-action listener: _resource_record_pre_action 07:28:31 INFO - Running main action method: download_and_extract 07:28:31 INFO - mkdir: /builds/slave/test/build/tests 07:28: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')]} 07:28:31 INFO - https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:28:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:28:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:28:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:28:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:28:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:28:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:28:31 INFO - retry: Failed, sleeping 30 seconds before retrying 07:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:29:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:29:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:29:01 INFO - retry: Failed, sleeping 60 seconds before retrying 07:30:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:30:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:30:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:30:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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! 07:30:02 INFO - Caught exception: HTTP Error 404: Not Found 07:30:02 INFO - Caught exception: HTTP Error 404: Not Found 07:30:02 INFO - Caught exception: HTTP Error 404: Not Found 07:30:02 INFO - trying https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:30:02 INFO - Downloading https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:30:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:30:02 INFO - Downloaded 1361 bytes. 07:30:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:30:02 INFO - Using the following test package requirements: 07:30:02 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:30:02 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:30:02 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:30:02 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'jsshell-linux-x86_64.zip'], 07:30:02 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:30:02 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:30:02 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:30:02 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:30:02 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:30:02 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:30:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:30:02 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:30:02 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')]} 07:30:02 INFO - https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:30:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:30:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:30:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:30:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:30:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:30:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:30:02 INFO - retry: Failed, sleeping 30 seconds before retrying 07:30:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:30:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:30:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:30:32 INFO - retry: Failed, sleeping 60 seconds before retrying 07:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:31:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:31:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:31:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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! 07:31:32 INFO - Caught exception: HTTP Error 404: Not Found 07:31:32 INFO - Caught exception: HTTP Error 404: Not Found 07:31:32 INFO - Caught exception: HTTP Error 404: Not Found 07:31:32 INFO - trying https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:31:32 INFO - Downloading https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:31:33 INFO - Downloaded 21767095 bytes. 07:31:33 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'] 07:31:33 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 07:31:34 INFO - Return code: 0 07:31:34 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 07:31: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')]} 07:31:34 INFO - https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:31:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:31:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:31:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:31:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:31:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:31:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:31:34 INFO - retry: Failed, sleeping 30 seconds before retrying 07:32:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:32:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:32:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:32:04 INFO - retry: Failed, sleeping 60 seconds before retrying 07:33:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:33:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:33:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:33:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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! 07:33:04 INFO - Caught exception: HTTP Error 404: Not Found 07:33:04 INFO - Caught exception: HTTP Error 404: Not Found 07:33:04 INFO - Caught exception: HTTP Error 404: Not Found 07:33:04 INFO - trying https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:33:04 INFO - Downloading https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:33:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:33:05 INFO - Downloaded 21767095 bytes. 07:33:05 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'] 07:33:05 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 07:33:07 INFO - Return code: 0 07:33:07 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')]} 07:33:07 INFO - https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:33:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:33:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:33:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:33:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:33:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:33:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:33:07 INFO - retry: Failed, sleeping 30 seconds before retrying 07:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:33:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:33:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:33:37 INFO - retry: Failed, sleeping 60 seconds before retrying 07:34:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:34:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:34:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:34:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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! 07:34:37 INFO - Caught exception: HTTP Error 404: Not Found 07:34:37 INFO - Caught exception: HTTP Error 404: Not Found 07:34:37 INFO - Caught exception: HTTP Error 404: Not Found 07:34:37 INFO - trying https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:34:37 INFO - Downloading https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:34:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:34:37 INFO - Downloaded 14413723 bytes. 07:34:37 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'] 07:34:37 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 07:34:38 INFO - Return code: 0 07:34: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')]} 07:34:38 INFO - https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:34:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:34:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:34:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:34:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:34:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:34:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:34:38 INFO - retry: Failed, sleeping 30 seconds before retrying 07:35:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:35:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:35:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:35:08 INFO - retry: Failed, sleeping 60 seconds before retrying 07:36:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:36:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:36:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:36:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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! 07:36:08 INFO - Caught exception: HTTP Error 404: Not Found 07:36:08 INFO - Caught exception: HTTP Error 404: Not Found 07:36:08 INFO - Caught exception: HTTP Error 404: Not Found 07:36:08 INFO - trying https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:36:08 INFO - Downloading https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:36:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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 07:36:09 INFO - Downloaded 63772068 bytes. 07:36:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:36:09 INFO - mkdir: /builds/slave/test/properties 07:36:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:36:09 INFO - Writing to file /builds/slave/test/properties/build_url 07:36:09 INFO - Contents: 07:36:09 INFO - build_url:https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:36:10 INFO - Running post-action listener: _resource_record_post_action 07:36:10 INFO - Running post-action listener: set_extra_try_arguments 07:36:10 INFO - [mozharness: 2016-08-09 14:36:10.072384Z] Finished download-and-extract step (success) 07:36:10 INFO - [mozharness: 2016-08-09 14:36:10.072616Z] Running populate-webroot step. 07:36:10 INFO - Running pre-action listener: _resource_record_pre_action 07:36:10 INFO - Running main action method: populate_webroot 07:36:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:36:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:36:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:36:10 INFO - '--tppagecycles', 07:36:10 INFO - '7'], 07:36:10 INFO - 'tests': ['tsvgm']}}, 07:36:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:36:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:36:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:36:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:36:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['damp', 'tps']}, 07:36:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'tests': ['damp', 'tps']}, 07:36:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['dromaeo_dom']}, 07:36:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:36:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['basic_compositor_video']}, 07:36:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:36:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['a11yr', 07:36:10 INFO - 'ts_paint', 07:36:10 INFO - 'tpaint', 07:36:10 INFO - 'sessionrestore', 07:36:10 INFO - 'sessionrestore_no_auto_restore', 07:36:10 INFO - 'tabpaint']}, 07:36:10 INFO - 'other-e10s': {'tests': ['a11yr', 07:36:10 INFO - 'ts_paint', 07:36:10 INFO - 'tpaint', 07:36:10 INFO - 'sessionrestore', 07:36:10 INFO - 'sessionrestore_no_auto_restore', 07:36:10 INFO - 'tabpaint']}, 07:36:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:36:10 INFO - 'ts_paint', 07:36:10 INFO - 'tpaint', 07:36:10 INFO - 'sessionrestore', 07:36:10 INFO - 'sessionrestore_no_auto_restore', 07:36:10 INFO - 'tabpaint']}, 07:36:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:36:10 INFO - 'ts_paint', 07:36:10 INFO - 'tpaint', 07:36:10 INFO - 'sessionrestore', 07:36:10 INFO - 'sessionrestore_no_auto_restore', 07:36:10 INFO - 'tabpaint']}, 07:36:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['a11yr', 07:36:10 INFO - 'ts_paint', 07:36:10 INFO - 'tpaint', 07:36:10 INFO - 'sessionrestore', 07:36:10 INFO - 'sessionrestore_no_auto_restore', 07:36:10 INFO - 'tabpaint']}, 07:36:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['a11yr', 07:36:10 INFO - 'ts_paint', 07:36:10 INFO - 'tpaint', 07:36:10 INFO - 'sessionrestore', 07:36:10 INFO - 'sessionrestore_no_auto_restore', 07:36:10 INFO - 'tabpaint']}, 07:36:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['tsvgx', 07:36:10 INFO - 'tsvgr_opacity', 07:36:10 INFO - 'tart', 07:36:10 INFO - 'tscrollx', 07:36:10 INFO - 'cart']}, 07:36:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:36:10 INFO - 'tsvgr_opacity', 07:36:10 INFO - 'tart', 07:36:10 INFO - 'tscrollx', 07:36:10 INFO - 'cart']}, 07:36:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'talos_options': ['--disable-e10s'], 07:36:10 INFO - 'tests': ['tp5o']}, 07:36:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'tests': ['tp5o']}, 07:36:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'talos_options': ['--disable-e10s', 07:36:10 INFO - '--xperf_path', 07:36:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:10 INFO - 'tests': ['tp5n']}, 07:36:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:10 INFO - 'talos_options': ['--xperf_path', 07:36:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:10 INFO - 'tests': ['tp5n']}}, 07:36:10 INFO - 'talos.zip': {'path': '', 07:36:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:36:10 INFO - Running post-action listener: _resource_record_post_action 07:36:10 INFO - [mozharness: 2016-08-09 14:36:10.088875Z] Finished populate-webroot step (success) 07:36:10 INFO - [mozharness: 2016-08-09 14:36:10.088963Z] Running create-virtualenv step. 07:36:10 INFO - Running pre-action listener: _resource_record_pre_action 07:36:10 INFO - Running main action method: create_virtualenv 07:36:10 INFO - Creating virtualenv /builds/slave/test/build/venv 07:36:10 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 07:36:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:36:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:36:10 INFO - Using real prefix '/usr' 07:36:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:36:10 INFO - Installing distribute.............................................................................................................................................................................................done. 07:36:12 INFO - Installing pip.................done. 07:36:12 INFO - Return code: 0 07:36:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:36:12 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')]} 07:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:12 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')]} 07:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:12 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:12 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 07:36:12 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 07:36:12 INFO - Using env: {'DISPLAY': ':0', 07:36:12 INFO - 'HOME': '/home/cltbld', 07:36:12 INFO - 'LANG': 'en_US.UTF-8', 07:36:12 INFO - 'LANGUAGE': 'en_US:en', 07:36:12 INFO - 'LOGNAME': 'cltbld', 07:36:12 INFO - 'MAIL': '/var/mail/cltbld', 07:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:12 INFO - 'MOZ_NO_REMOTE': '1', 07:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:12 INFO - 'NO_EM_RESTART': '1', 07:36:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:12 INFO - 'PWD': '/builds/slave/test', 07:36:12 INFO - 'SHELL': '/bin/bash', 07:36:12 INFO - 'SHLVL': '1', 07:36:12 INFO - 'TERM': 'linux', 07:36:12 INFO - 'TMOUT': '86400', 07:36:12 INFO - 'USER': 'cltbld', 07:36:12 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:12 INFO - '_': '/tools/buildbot/bin/python'} 07:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:12 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 07:36:12 INFO - Cleaning up... 07:36:12 INFO - Return code: 0 07:36:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:36:12 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')]} 07:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:12 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')]} 07:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:12 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:12 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 07:36:12 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 07:36:12 INFO - Using env: {'DISPLAY': ':0', 07:36:12 INFO - 'HOME': '/home/cltbld', 07:36:12 INFO - 'LANG': 'en_US.UTF-8', 07:36:12 INFO - 'LANGUAGE': 'en_US:en', 07:36:12 INFO - 'LOGNAME': 'cltbld', 07:36:12 INFO - 'MAIL': '/var/mail/cltbld', 07:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:12 INFO - 'MOZ_NO_REMOTE': '1', 07:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:12 INFO - 'NO_EM_RESTART': '1', 07:36:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:12 INFO - 'PWD': '/builds/slave/test', 07:36:12 INFO - 'SHELL': '/bin/bash', 07:36:12 INFO - 'SHLVL': '1', 07:36:12 INFO - 'TERM': 'linux', 07:36:12 INFO - 'TMOUT': '86400', 07:36:12 INFO - 'USER': 'cltbld', 07:36:12 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:12 INFO - '_': '/tools/buildbot/bin/python'} 07:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:13 INFO - Downloading/unpacking psutil>=3.1.1 07:36:13 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 07:36:13 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 07:36:13 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 07:36:13 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 07:36:13 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 07:36:13 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 07:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:36:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:15 INFO - Installing collected packages: psutil 07:36:15 INFO - Running setup.py install for psutil 07:36:15 INFO - building 'psutil._psutil_linux' extension 07:36:15 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 07:36:15 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 07:36:15 INFO - building 'psutil._psutil_posix' extension 07:36:15 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 07:36:15 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 07:36:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:15 INFO - Successfully installed psutil 07:36:15 INFO - Cleaning up... 07:36:15 INFO - Return code: 0 07:36:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:15 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:15 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 07:36:15 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 07:36:15 INFO - Using env: {'DISPLAY': ':0', 07:36:15 INFO - 'HOME': '/home/cltbld', 07:36:15 INFO - 'LANG': 'en_US.UTF-8', 07:36:15 INFO - 'LANGUAGE': 'en_US:en', 07:36:15 INFO - 'LOGNAME': 'cltbld', 07:36:15 INFO - 'MAIL': '/var/mail/cltbld', 07:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:15 INFO - 'MOZ_NO_REMOTE': '1', 07:36:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:15 INFO - 'NO_EM_RESTART': '1', 07:36:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:15 INFO - 'PWD': '/builds/slave/test', 07:36:15 INFO - 'SHELL': '/bin/bash', 07:36:15 INFO - 'SHLVL': '1', 07:36:15 INFO - 'TERM': 'linux', 07:36:15 INFO - 'TMOUT': '86400', 07:36:15 INFO - 'USER': 'cltbld', 07:36:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:15 INFO - '_': '/tools/buildbot/bin/python'} 07:36:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:36:15 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 07:36:15 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 07:36:15 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 07:36:15 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 07:36:15 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 07:36:15 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 07:36:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:36:17 INFO - Installing collected packages: mozsystemmonitor 07:36:17 INFO - Running setup.py install for mozsystemmonitor 07:36:17 INFO - Successfully installed mozsystemmonitor 07:36:17 INFO - Cleaning up... 07:36:17 INFO - Return code: 0 07:36:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:36:17 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')]} 07:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:17 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')]} 07:36:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:17 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:17 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 07:36:17 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 07:36:17 INFO - Using env: {'DISPLAY': ':0', 07:36:17 INFO - 'HOME': '/home/cltbld', 07:36:17 INFO - 'LANG': 'en_US.UTF-8', 07:36:17 INFO - 'LANGUAGE': 'en_US:en', 07:36:17 INFO - 'LOGNAME': 'cltbld', 07:36:17 INFO - 'MAIL': '/var/mail/cltbld', 07:36:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:17 INFO - 'MOZ_NO_REMOTE': '1', 07:36:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:17 INFO - 'NO_EM_RESTART': '1', 07:36:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:17 INFO - 'PWD': '/builds/slave/test', 07:36:17 INFO - 'SHELL': '/bin/bash', 07:36:17 INFO - 'SHLVL': '1', 07:36:17 INFO - 'TERM': 'linux', 07:36:17 INFO - 'TMOUT': '86400', 07:36:17 INFO - 'USER': 'cltbld', 07:36:17 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:17 INFO - '_': '/tools/buildbot/bin/python'} 07:36:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:18 INFO - Downloading/unpacking jsonschema==2.5.1 07:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:36:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 INFO - Downloading functools32-3.2.3-2.tar.gz 07:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:36:20 INFO - warning: no files found matching '*.txt' 07:36:20 INFO - no previously-included directories found matching 'build' 07:36:20 INFO - no previously-included directories found matching 'dist' 07:36:20 INFO - no previously-included directories found matching '.git*' 07:36:20 INFO - Installing collected packages: jsonschema, functools32 07:36:20 INFO - Running setup.py install for functools32 07:36:20 INFO - warning: no files found matching '*.txt' 07:36:20 INFO - no previously-included directories found matching 'build' 07:36:20 INFO - no previously-included directories found matching 'dist' 07:36:20 INFO - no previously-included directories found matching '.git*' 07:36:20 INFO - Successfully installed jsonschema functools32 07:36:20 INFO - Cleaning up... 07:36:20 INFO - Return code: 0 07:36:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:36:20 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')]} 07:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:20 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')]} 07:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:20 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:20 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 07:36:20 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 07:36:20 INFO - Using env: {'DISPLAY': ':0', 07:36:20 INFO - 'HOME': '/home/cltbld', 07:36:20 INFO - 'LANG': 'en_US.UTF-8', 07:36:20 INFO - 'LANGUAGE': 'en_US:en', 07:36:20 INFO - 'LOGNAME': 'cltbld', 07:36:20 INFO - 'MAIL': '/var/mail/cltbld', 07:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:20 INFO - 'MOZ_NO_REMOTE': '1', 07:36:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:20 INFO - 'NO_EM_RESTART': '1', 07:36:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:20 INFO - 'PWD': '/builds/slave/test', 07:36:20 INFO - 'SHELL': '/bin/bash', 07:36:20 INFO - 'SHLVL': '1', 07:36:20 INFO - 'TERM': 'linux', 07:36:20 INFO - 'TMOUT': '86400', 07:36:20 INFO - 'USER': 'cltbld', 07:36:20 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:20 INFO - '_': '/tools/buildbot/bin/python'} 07:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:36:20 INFO - Cleaning up... 07:36:20 INFO - Return code: 0 07:36:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:36:20 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')]} 07:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:20 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')]} 07:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:20 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:20 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 07:36:20 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 07:36:20 INFO - Using env: {'DISPLAY': ':0', 07:36:20 INFO - 'HOME': '/home/cltbld', 07:36:20 INFO - 'LANG': 'en_US.UTF-8', 07:36:20 INFO - 'LANGUAGE': 'en_US:en', 07:36:20 INFO - 'LOGNAME': 'cltbld', 07:36:20 INFO - 'MAIL': '/var/mail/cltbld', 07:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:20 INFO - 'MOZ_NO_REMOTE': '1', 07:36:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:20 INFO - 'NO_EM_RESTART': '1', 07:36:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:20 INFO - 'PWD': '/builds/slave/test', 07:36:20 INFO - 'SHELL': '/bin/bash', 07:36:20 INFO - 'SHLVL': '1', 07:36:20 INFO - 'TERM': 'linux', 07:36:20 INFO - 'TMOUT': '86400', 07:36:20 INFO - 'USER': 'cltbld', 07:36:20 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:20 INFO - '_': '/tools/buildbot/bin/python'} 07:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:20 INFO - Downloading/unpacking blobuploader==1.2.4 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 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 07:36:20 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 07:36:22 INFO - Downloading blobuploader-1.2.4.tar.gz 07:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:36:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:36:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 INFO - Downloading docopt-0.6.1.tar.gz 07:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:36:23 INFO - Installing collected packages: blobuploader, requests, docopt 07:36:23 INFO - Running setup.py install for blobuploader 07:36:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:36:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:36:23 INFO - Running setup.py install for requests 07:36:24 INFO - Running setup.py install for docopt 07:36:24 INFO - Successfully installed blobuploader requests docopt 07:36:24 INFO - Cleaning up... 07:36:24 INFO - Return code: 0 07:36:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:24 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:24 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 07:36:24 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 07:36:24 INFO - Using env: {'DISPLAY': ':0', 07:36:24 INFO - 'HOME': '/home/cltbld', 07:36:24 INFO - 'LANG': 'en_US.UTF-8', 07:36:24 INFO - 'LANGUAGE': 'en_US:en', 07:36:24 INFO - 'LOGNAME': 'cltbld', 07:36:24 INFO - 'MAIL': '/var/mail/cltbld', 07:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:24 INFO - 'MOZ_NO_REMOTE': '1', 07:36:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:24 INFO - 'NO_EM_RESTART': '1', 07:36:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:24 INFO - 'PWD': '/builds/slave/test', 07:36:24 INFO - 'SHELL': '/bin/bash', 07:36:24 INFO - 'SHLVL': '1', 07:36:24 INFO - 'TERM': 'linux', 07:36:24 INFO - 'TMOUT': '86400', 07:36:24 INFO - 'USER': 'cltbld', 07:36:24 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:24 INFO - '_': '/tools/buildbot/bin/python'} 07:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:24 INFO - Running setup.py (path:/tmp/pip-uJfctq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:24 INFO - Running setup.py (path:/tmp/pip-wmIC59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:24 INFO - Running setup.py (path:/tmp/pip-oZF6ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:24 INFO - Running setup.py (path:/tmp/pip-tgRaWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:24 INFO - Running setup.py (path:/tmp/pip-EHMB7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:24 INFO - Running setup.py (path:/tmp/pip-F2mDNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:24 INFO - Running setup.py (path:/tmp/pip-CXf8xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:24 INFO - Running setup.py (path:/tmp/pip-aGOkEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:24 INFO - Running setup.py (path:/tmp/pip-iotLhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:25 INFO - Running setup.py (path:/tmp/pip-eEzrDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:25 INFO - Running setup.py (path:/tmp/pip-bjaigf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:25 INFO - Running setup.py (path:/tmp/pip-QHpZdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:25 INFO - Running setup.py (path:/tmp/pip-ZkQA7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:25 INFO - Running setup.py (path:/tmp/pip-jboLrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:25 INFO - Running setup.py (path:/tmp/pip-1a5cNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:25 INFO - Running setup.py (path:/tmp/pip-_hpeGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:25 INFO - Running setup.py (path:/tmp/pip-nOw_WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:36:25 INFO - Running setup.py install for manifestparser 07:36:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:36:25 INFO - Running setup.py install for mozcrash 07:36:25 INFO - Running setup.py install for mozdebug 07:36:25 INFO - Running setup.py install for mozdevice 07:36:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for mozfile 07:36:26 INFO - Running setup.py install for mozhttpd 07:36:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for mozinfo 07:36:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for mozInstall 07:36:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for mozleak 07:36:26 INFO - Running setup.py install for mozlog 07:36:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for moznetwork 07:36:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for mozprocess 07:36:26 INFO - Running setup.py install for mozprofile 07:36:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:36:26 INFO - Running setup.py install for mozrunner 07:36:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:36:27 INFO - Running setup.py install for mozscreenshot 07:36:27 INFO - Running setup.py install for moztest 07:36:27 INFO - Running setup.py install for mozversion 07:36:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:36:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:36:27 INFO - Cleaning up... 07:36:27 INFO - Return code: 0 07:36:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:27 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:27 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 07:36:27 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 07:36:27 INFO - Using env: {'DISPLAY': ':0', 07:36:27 INFO - 'HOME': '/home/cltbld', 07:36:27 INFO - 'LANG': 'en_US.UTF-8', 07:36:27 INFO - 'LANGUAGE': 'en_US:en', 07:36:27 INFO - 'LOGNAME': 'cltbld', 07:36:27 INFO - 'MAIL': '/var/mail/cltbld', 07:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:27 INFO - 'MOZ_NO_REMOTE': '1', 07:36:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:27 INFO - 'NO_EM_RESTART': '1', 07:36:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:27 INFO - 'PWD': '/builds/slave/test', 07:36:27 INFO - 'SHELL': '/bin/bash', 07:36:27 INFO - 'SHLVL': '1', 07:36:27 INFO - 'TERM': 'linux', 07:36:27 INFO - 'TMOUT': '86400', 07:36:27 INFO - 'USER': 'cltbld', 07:36:27 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:27 INFO - '_': '/tools/buildbot/bin/python'} 07:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:27 INFO - Running setup.py (path:/tmp/pip-VYoLep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:27 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)) 07:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:27 INFO - Running setup.py (path:/tmp/pip-raOnSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:27 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)) 07:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:27 INFO - Running setup.py (path:/tmp/pip-IiQANU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:27 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)) 07:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:27 INFO - Running setup.py (path:/tmp/pip-15_6Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:27 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)) 07:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:27 INFO - Running setup.py (path:/tmp/pip-cNdWWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:28 INFO - Running setup.py (path:/tmp/pip-PEhf2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:28 INFO - Running setup.py (path:/tmp/pip-NA_TtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:28 INFO - Running setup.py (path:/tmp/pip-Fce1H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:28 INFO - Running setup.py (path:/tmp/pip-0jqRcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:28 INFO - Running setup.py (path:/tmp/pip-et_imA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:28 INFO - Running setup.py (path:/tmp/pip-tpO6FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:28 INFO - Running setup.py (path:/tmp/pip-qdjOVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:28 INFO - Running setup.py (path:/tmp/pip-U2uYn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:28 INFO - Running setup.py (path:/tmp/pip-7imP9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:28 INFO - Running setup.py (path:/tmp/pip-7e1H9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:28 INFO - Running setup.py (path:/tmp/pip-aEc9AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:28 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)) 07:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:28 INFO - Running setup.py (path:/tmp/pip-zWhCC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:28 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)) 07:36:28 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)) 07:36:28 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)) 07:36:28 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)) 07:36:28 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)) 07:36:28 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)) 07:36:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36:30 INFO - Downloading blessings-1.6.tar.gz 07:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:36:30 INFO - Installing collected packages: blessings 07:36:30 INFO - Running setup.py install for blessings 07:36:30 INFO - Successfully installed blessings 07:36:30 INFO - Cleaning up... 07:36:31 INFO - Return code: 0 07:36:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:36:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:31 INFO - Reading from file tmpfile_stdout 07:36:31 INFO - Current package versions: 07:36:31 INFO - argparse == 1.2.1 07:36:31 INFO - blessings == 1.6 07:36:31 INFO - blobuploader == 1.2.4 07:36:31 INFO - docopt == 0.6.1 07:36:31 INFO - functools32 == 3.2.3-2 07:36:31 INFO - jsonschema == 2.5.1 07:36:31 INFO - manifestparser == 1.1 07:36:31 INFO - mozInstall == 1.12 07:36:31 INFO - mozcrash == 0.17 07:36:31 INFO - mozdebug == 0.1 07:36:31 INFO - mozdevice == 0.48 07:36:31 INFO - mozfile == 1.2 07:36:31 INFO - mozhttpd == 0.7 07:36:31 INFO - mozinfo == 0.9 07:36:31 INFO - mozleak == 0.1 07:36:31 INFO - mozlog == 3.3 07:36:31 INFO - moznetwork == 0.27 07:36:31 INFO - mozprocess == 0.23 07:36:31 INFO - mozprofile == 0.28 07:36:31 INFO - mozrunner == 6.12 07:36:31 INFO - mozscreenshot == 0.1 07:36:31 INFO - mozsystemmonitor == 0.3 07:36:31 INFO - moztest == 0.7 07:36:31 INFO - mozversion == 1.4 07:36:31 INFO - psutil == 3.1.1 07:36:31 INFO - requests == 1.2.3 07:36:31 INFO - wsgiref == 0.1.2 07:36:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:31 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 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:31 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 07:36:31 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 07:36:31 INFO - Using env: {'DISPLAY': ':0', 07:36:31 INFO - 'HOME': '/home/cltbld', 07:36:31 INFO - 'LANG': 'en_US.UTF-8', 07:36:31 INFO - 'LANGUAGE': 'en_US:en', 07:36:31 INFO - 'LOGNAME': 'cltbld', 07:36:31 INFO - 'MAIL': '/var/mail/cltbld', 07:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:31 INFO - 'MOZ_NO_REMOTE': '1', 07:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:31 INFO - 'NO_EM_RESTART': '1', 07:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:31 INFO - 'PWD': '/builds/slave/test', 07:36:31 INFO - 'SHELL': '/bin/bash', 07:36:31 INFO - 'SHLVL': '1', 07:36:31 INFO - 'TERM': 'linux', 07:36:31 INFO - 'TMOUT': '86400', 07:36:31 INFO - 'USER': 'cltbld', 07:36:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:31 INFO - '_': '/tools/buildbot/bin/python'} 07:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 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)) 07:36:31 INFO - Cleaning up... 07:36:31 INFO - Return code: 0 07:36:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2581030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, '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': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', '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 07:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:36:31 INFO - Using env: {'DISPLAY': ':0', 07:36:31 INFO - 'HOME': '/home/cltbld', 07:36:31 INFO - 'LANG': 'en_US.UTF-8', 07:36:31 INFO - 'LANGUAGE': 'en_US:en', 07:36:31 INFO - 'LOGNAME': 'cltbld', 07:36:31 INFO - 'MAIL': '/var/mail/cltbld', 07:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:31 INFO - 'MOZ_NO_REMOTE': '1', 07:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:31 INFO - 'NO_EM_RESTART': '1', 07:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:31 INFO - 'PWD': '/builds/slave/test', 07:36:31 INFO - 'SHELL': '/bin/bash', 07:36:31 INFO - 'SHLVL': '1', 07:36:31 INFO - 'TERM': 'linux', 07:36:31 INFO - 'TMOUT': '86400', 07:36:31 INFO - 'USER': 'cltbld', 07:36:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:31 INFO - '_': '/tools/buildbot/bin/python'} 07:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:36:31 INFO - Cleaning up... 07:36:31 INFO - Return code: 0 07:36:31 INFO - Running post-action listener: _resource_record_post_action 07:36:31 INFO - Running post-action listener: _start_resource_monitoring 07:36:31 INFO - Starting resource monitoring. 07:36:31 INFO - [mozharness: 2016-08-09 14:36:31.715472Z] Finished create-virtualenv step (success) 07:36:31 INFO - [mozharness: 2016-08-09 14:36:31.716031Z] Running install step. 07:36:31 INFO - Running pre-action listener: _resource_record_pre_action 07:36:31 INFO - Running main action method: install 07:36:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:31 INFO - Reading from file tmpfile_stdout 07:36:31 INFO - Detecting whether we're running mozinstall >=1.0... 07:36:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:36:31 INFO - Reading from file tmpfile_stdout 07:36:31 INFO - Output received: 07:36:31 INFO - Usage: mozinstall [options] installer 07:36:31 INFO - Options: 07:36:31 INFO - -h, --help show this help message and exit 07:36:32 INFO - -d DEST, --destination=DEST 07:36:32 INFO - Directory to install application into. [default: 07:36:32 INFO - "/builds/slave/test"] 07:36:32 INFO - --app=APP Application being installed. [default: firefox] 07:36:32 INFO - mkdir: /builds/slave/test/build/application 07:36:32 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'] 07:36:32 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 07:36:48 INFO - Reading from file tmpfile_stdout 07:36:48 INFO - Output received: 07:36:48 INFO - /builds/slave/test/build/application/firefox/firefox 07:36:48 INFO - Running post-action listener: _resource_record_post_action 07:36:48 INFO - [mozharness: 2016-08-09 14:36:48.016524Z] Finished install step (success) 07:36:48 INFO - [mozharness: 2016-08-09 14:36:48.016806Z] Running run-tests step. 07:36:48 INFO - Running pre-action listener: _resource_record_pre_action 07:36:48 INFO - Running main action method: run_tests 07:36:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:36:48 INFO - Python 2.7.3 07:36:48 INFO - Return code: 0 07:36:48 INFO - grabbing minidump binary from tooltool 07:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:48 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 0x28403b0>, '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 0x28412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2841740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:36:48 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 07:36:48 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 07:36:48 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 07:36:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:36:48 INFO - Return code: 0 07:36:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:36:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:36:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:36:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:36:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:36:48 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/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 07:36:48 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/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 07:36:48 INFO - Using env: {'DISPLAY': ':0', 07:36:48 INFO - 'HOME': '/home/cltbld', 07:36:48 INFO - 'LANG': 'en_US.UTF-8', 07:36:48 INFO - 'LANGUAGE': 'en_US:en', 07:36:48 INFO - 'LOGNAME': 'cltbld', 07:36:48 INFO - 'MAIL': '/var/mail/cltbld', 07:36:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:36:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:48 INFO - 'MOZ_NO_REMOTE': '1', 07:36:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:36:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:48 INFO - 'NO_EM_RESTART': '1', 07:36:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:48 INFO - 'PWD': '/builds/slave/test', 07:36:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:36:48 INFO - 'SHELL': '/bin/bash', 07:36:48 INFO - 'SHLVL': '1', 07:36:48 INFO - 'TERM': 'linux', 07:36:48 INFO - 'TMOUT': '86400', 07:36:48 INFO - 'USER': 'cltbld', 07:36:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470746646.593634-470011628', 07:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:48 INFO - '_': '/tools/buildbot/bin/python'} 07:36:48 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/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 07:36:48 INFO - mozversion application_buildid: 20160809045320 07:36:48 INFO - mozversion application_changeset: 43e9e32c32a8eb644e0ede4f111032cb4edbf489 07:36:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:36:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:36:48 INFO - mozversion application_name: Firefox 07:36:48 INFO - mozversion application_remotingname: firefox-dev 07:36:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:36:48 INFO - mozversion application_vendor: Mozilla 07:36:48 INFO - mozversion application_version: 50.0a2 07:36:48 INFO - mozversion platform_buildid: 20160809045320 07:36:48 INFO - mozversion platform_changeset: 43e9e32c32a8eb644e0ede4f111032cb4edbf489 07:36:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:36:48 INFO - mozversion platform_version: 50.0a2 07:36:48 INFO - using testdate: 1470753408 07:36:48 INFO - actual date: 1470753408 07:36:48 INFO - starting webserver on 'localhost:59338' 07:36:48 INFO - SUITE-START | Running 2 tests 07:36:48 INFO - TEST-START | dromaeo_css 07:36:48 INFO - operating with platform_type : linux_ 07:36:48 INFO - Initialising browser for dromaeo_css test... 07:36:48 INFO - TEST-INFO | started process 21085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9hziuE/profile http://localhost:59338/getInfo.html) 07:36:52 INFO - PROCESS | 21085 | __metrics Screen width/height:1600/1200 07:36:52 INFO - PROCESS | 21085 | colorDepth:24 07:36:52 INFO - PROCESS | 21085 | Browser inner width/height: 1024/697 07:36:52 INFO - PROCESS | 21085 | __metrics 07:36:53 INFO - PROCESS | 21085 | 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" 07:36:53 INFO - PROCESS | 21085 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:36:54 INFO - TEST-INFO | 21085: exit 0 07:36:54 INFO - Browser initialized. 07:36:54 INFO - Running cycle 1/1 for dromaeo_css test... 07:36:54 INFO - TEST-INFO | started process 21174 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9hziuE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:37:05 INFO - PROCESS | 21174 | RSS: Main: 145195008 07:37:05 INFO - PROCESS | 21174 | 07:37:56 INFO - PROCESS | 21174 | 1470753476718 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] 07:40:05 INFO - PROCESS | 21174 | Cycle 1(1): loaded http://localhost:59338/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59338/tests/dromaeo/cssquery-ext.html) 07:40:07 INFO - PROCESS | 21174 | RSS: Main: 270856192 07:40:07 INFO - PROCESS | 21174 | 07:43:09 INFO - PROCESS | 21174 | Cycle 1(1): loaded http://localhost:59338/tests/dromaeo/cssquery-ext.html (next: http://localhost:59338/tests/dromaeo/cssquery-jquery.html) 07:43:10 INFO - PROCESS | 21174 | RSS: Main: 231550976 07:43:10 INFO - PROCESS | 21174 | 07:46:12 INFO - PROCESS | 21174 | Cycle 1(1): loaded http://localhost:59338/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59338/tests/dromaeo/cssquery-mootools.html) 07:46:12 INFO - PROCESS | 21174 | RSS: Main: 175575040 07:46:12 INFO - PROCESS | 21174 | 07:49:14 INFO - PROCESS | 21174 | Cycle 1(1): loaded http://localhost:59338/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59338/tests/dromaeo/cssquery-prototype.html) 07:49:15 INFO - PROCESS | 21174 | RSS: Main: 198713344 07:49:15 INFO - PROCESS | 21174 | 07:52:17 INFO - PROCESS | 21174 | Cycle 1(1): loaded http://localhost:59338/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59338/tests/dromaeo/cssquery-yui.html) 07:52:18 INFO - PROCESS | 21174 | RSS: Main: 175394816 07:52:18 INFO - PROCESS | 21174 | 07:55:20 INFO - PROCESS | 21174 | Cycle 1(1): loaded http://localhost:59338/tests/dromaeo/cssquery-yui.html (next: http://localhost:59338/tests/dromaeo/cssquery-dojo.html) 07:55:21 INFO - PROCESS | 21174 | RSS: Main: 254849024 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | __start_tp_report 07:55:21 INFO - PROCESS | 21174 | _x_x_mozilla_page_load 07:55:21 INFO - PROCESS | 21174 | _x_x_mozilla_page_load_details 07:55:21 INFO - PROCESS | 21174 | |i|pagename|runs| 07:55:21 INFO - PROCESS | 21174 | |0;dojo.html;3229.208843833261;3266.7223286020685;3267.0198940317914;3295.2915123248495;3309.7848639838408;5581.720913954301;5662.745176467059;5665.348484924235;5672.659640421578;5684.147377893318;5646;5703.942960570393;5718.113692377678;5727.971360143204;5752.539796816255;5065.822696205633;5135.768890399922;5151.304573882525;5188.04021256067;5251.054810134175;5205.791768329263;5258.316418865542;5258.526732594066;5261.158214685651;5264.763085661154;5119;5184.870378240536;5185.040767458014;5199.818006369778;5219.739013049351;5282.867928301785;5290.444503327153;5300.257963885059;5303.575713942875;5332.973335133338;5004.724740139291;5063.316452278932;5126.077306084903;5152.742362881841;5160.56135228506;5569.582281328917;5634.154876768517;5644;5647.8588035299035;5676.233708449347;6111.908321375183;6137.557673946619;6138.754449822001;6138.815835524942;6145.324014358417;5866.354700982888;5922.082077278029;5941.405859414067;6001.939980600182;6168.537359698041;5593.888122237532;5625.38120806711;5647.745851436695;5664.40523745005;5674.546036317085;6103.209309035924;6112.144299798032;6112.72492737819;6113.144159817629;6119.479844213202;5768.451997060272;5872.383399743051;5936.347001829795;5956.791512297049;6042.0332746760305;6090.116933044772;6093.872633562805;6098.969505152446;6103.211375619095;6143.109249158847;6042.758289668452;6054.788082417094;6058.27300723916;6064.514838812885;6068.089786532056;5895;5901.380355062743;5928;5933.169356290036;5982.641041537521;4589.977050114728;4624.583787459078;4630.282306242538;4647.117047760915;4656;4115.135821477464;4116.259073366823;4123.237201117803;4144.41978123063;4144.564820693844;5611.7474713637985;5642.943570564324;5706.28671416073;5722.341930678025;5806.071028635398;6172.506199504029;6175.660338681326;6193.194884665055;6196.349383314777;6203.069539569101;5779.104238842986;5788.913166302509;5832.591718579659;5838.532917366685;5879.3826648202175;5096.668716533413;5190.792368305301;5245.475452454801;5246.449122842047;5298.735063246822;6140.969295153585;6232.345603711545;6251.812445626639;6340.651264180515;6346.7143978522;6057.7879774207695;6119.44924956756;6126.203593532812;6127.264728232641;6137.171481849893;6076.848078797889;6152.569320147547;6282.968585157045;6327.019312006646;6338.841528961812;5865.032269675627;5951.196588460502;5961.374055724086;5988.221531200916;5998.880022399615;6019.51843852482;6040.184575082308;6040.36576159497;6054.606450580698;6064.272287325549;5794.594378393472;5794.884102317846;5881.264841894763;5894.821035792773;5897.764089436546;5795.3625101237985;5882.647041177543;5883.264591926009;5922.082077278029;5937.881242375215;5799.101139323411;5862.179294898632;5866.970665146647;5872.236609240771;5882.323532793784;5838.912416313844;5866.208061911588;5878.441548052928;5884.411208973639;5951.226340575698;5765.164051212634;5785.681787501728;5792.82621521355;5811.593188476935;5835.533157347487 07:55:21 INFO - PROCESS | 21174 | |1;ext.html;604.1753007145245;634.984125396865;651.2380514797687;671.885779417499;684.9075374824397;33312.33375332492;43135.137297254034;43663.78168109155;43837.56162438382;43868.56131438685;45886.541134588646;45922;46064;46127.53872461274;46352.76823615886;8461.915380846174;9057.411268267575;9220.769480762967;9248.815023699522;9319.860202096974;10876.836847447295;11162.162837787198;11167.664970050912;11456.599019034335;11514.251573647727;11170.441477926111;11200.159988000893;11294.887051129466;11296.830547541793;11300.717482062973;10954.890451095467;10980.286281391684;11073.612423565217;11073.889261107406;11077.058450031756;10894.891051089546;11010.889891101147;11017.944910275477;11019.889801102046;11029.889701102966;44244.11511769747;45198.32202516965;45259.32111018337;45262.54737452616;45267.32099018517;4014.598540145991;4153.460050193486;4487.12501062293;4604.493505714368;4639.37368455261;33307.33385332304;33394.666053339395;33437.66562334394;34067.31865362704;35849.82075089634;4579.656525760582;4582.495925448198;4673.556012178838;4675.251959686468;4809.398825146856;361867;362240;364005;364894;366302;62236.37763622306;62563.68718156471;62633.373666263666;62687.37312626906;62804;38794;39643.405348919216;39716.602833971294;41219.381709274385;41299.38050929239;17312.913435432743;17534.210960506807;17543.912280438515;17546.736798948026;17559.824401756076;12482.687932801753;13280.335983200801;13541;13588.320583970763;13696.794548081787;20781;20841.478963025744;20960.790392096187;20983.79016209849;21115;7646.35006024483;7705.691772329044;7735.458517903693;7745.148033716287;7819.530828150329;990.5889056041806;1039.9220058495644;1075.521392980132;1130.5647325779635;1159.2059439284117;33633.49549756756;34914;35560.82219588885;35581.46627800585;36050.63949360525;1146.1747541769912;1183.23681225609;1223.9571614993433;1265.5760320292804;1270.688681273094;17686.204120814593;18285.54286142857;18347.357842475718;18498.35255766042;18636.627267454576;592201;596976;597503;598527;598936;63291;63828.36171638317;63930.36069639244;64043.6797816008;64061;1313.4155300891011;1427.8143841300564;1460.4888289098942;1475.4393330534542;1478.4381934864898;34052.489212661334;36150.457743133346;36364.81817590895;36764.26471470609;37144.44283335698;54157;54622;54820.45179548153;54866.725666371414;54937.450625493235;434883;439171;439492;439706;441800;30465;30703.539446908762;30979.225519361295;31090.533641994934;32640.183995400304;51884.48115518797;52569;52919.47080529146;52924;53185.4681453196;43552.78223608862;43887.49402199808;44306.55693443024;45420;45621.08757824892;43939;44427.33358999682;45121.77439112784;45814;45948.310775337726;44259;44555.77722111368;45424.31863521984;47750.52249477461;49616.75191624019;36987.896996995194;39901.20197595974;40278;40445.797771010955;40936.181276374904;29928.850355748084;30208;30320.303073935916;30691.846540767154;30753.846230768704 07:55:21 INFO - PROCESS | 21174 | |2;jquery.html;389.4761545721004;403.6447925825273;412.7626614696549;417.65543426672997;436.679040904935;16786.83213167868;19732.506687332825;20137.697934530974;20242.696359554582;20650;19415.32046378377;19524.609507809837;19561.608767824637;19613.803861961376;19666.508337291576;5628.774849006056;5806.477417032477;5953.3748956359605;6010.789622363218;6021.578489505737;5637.492625663689;5946.851328716795;5947.434993675594;5953.464188223059;5962.07587823888;5694.259746232984;5862.091375836752;5873.324567674729;5876.941230587682;5894.911576326359;5737.684427356494;5830.475257226827;5877.67672777997;5928.703564821742;5937.940620593782;5341.946580534183;5346.224797404355;5347.7058761768085;5349.839504814861;5350.839474815762;23647.05411783544;23665.645015324782;23874.88062559676;23956.880215598983;24028.159014434586;2363.5036642305136;2603.336149281921;2646.775024122952;2690.3274181454635;3061.0969763919697;10363;10639.680809575726;10707.464626768631;10744.73138171544;10980.670579882617;2018.9798102018938;2027.9493012674654;2047.8566500344982;2073.533454972634;2151.5589304192604;61260;65903.3409665902;73399.26600733977;79147.20852791514;86017.56991215066;11207.327560346404;11369.488373023234;11552.422378881023;11641.53433862653;11783.587574434854;16422.1788910554;16470.341186352412;16486;16502.917485412498;16507;8444.873326900102;8517.531535765593;8540.914590854136;8592.52741099233;8674.479531228146;8065.9596702016115;8117.310028647513;8307.958460207781;8325.167483251626;8325.8334833303;4792.292354819586;4857.125717370836;4935.8025678972435;4987.850364489072;5092.974535127375;1976.1107501624326;2011.4770159758275;2022.2214447437843;2035.2266138867433;2050.4361300642145;944.8252073366311;1006.758377989292;1032.700516850105;1041.4116024446164;1046.529061922138;20996.580068398547;21634.13463461444;21725.23961661366;21763;21861.12555497794;1015.6597539824241;1042.864427624404;1050.0573938214395;1078.6440474643348;1081.9729506762237;11600.535978560933;11961.34212618297;12035.879641203652;12080.335581543099;12218.450169742382;475356;479224;480391;503170;533751;92392;92538;94556.52721736347;94671;94895.52552237194;1026.1893104447552;1048.8112139814907;1059.4967390489455;1070.5878236878857;1080.6325849211305;18078.096095194924;18319.267229310677;18820.341288054853;18858.339958101402;19188;43873.561264386946;44776.328355074045;45322.77338613286;46149;46636.76681616706;57837.71081144567;60078.69960650169;60889.69555152373;61318;61539.38460615336;8249.463784853966;8313.376496762889;8387.161283871808;8437.535935523605;8573.699920502753;12403.62789116328;12490.375481225721;12754.42605082792;12912.806307905577;12957.157784743962;11084.61203857861;11255.774884502427;11404.885951140383;11473.254238474472;11568.595099171825;5939.613925094855;9133.858267716536;9996.950015249877;11259.437028148724;11526.769464610828;11312.717182070513;11398.544058237578;11430.485628146587;11481.483333250191;11726.237794543327;8231.218034286732;8299.543525106177;8359.874601880854;8400.537970411686;8458.238758511763;9783.902160978583;10073.496325183858;10269.486525673836;10335.638252661121;10485.947570262406 07:55:21 INFO - PROCESS | 21174 | |3;mootools.html;1434.3115304653772;1520.1942970225778;1581.0513691784931;1732.913354332283;1872.3072463188614;8323.62543685534;9305.4881981491;9614.13472787449;9619.759506012353;9869.506524673774;9686.66096686616;9783.070608292219;9788.168005719523;9789.608415663364;9804.55879485423;2385.952280954384;2409.180878501309;2410.9035638574437;2413.9517209655883;2415.710114786228;2448.400141965221;2452.0559584559983;2501.0746023971155;2507.924762257135;2509.134348649722;2454.226918520669;2462.9014839406404;2469.234537293436;2498.487809998946;2498.487809998955;2365.290412876139;2372.8694921779297;2380.047980807682;2411.9397015074587;2455.1529722245973;2354.152505098163;2367.91712290069;2370.502194539149;2372.905083796646;2373.2642880706862;9352.67265645699;9667.323287369887;9920;9927.255455840768;9927.751806204831;5665.575081868877;5861.150133230658;5896.380880007623;5896.439838215363;5899.675517846516;3644.325799727059;3651.178484840916;3668.52309199805;3710;3714.53568303962;1128.9548418063268;1161.309021132425;1204.1931905623253;1214.2471667566165;1242.6210005948183;13990.16059036461;14066.296685165804;14124.293785310796;14130.71738565223;14169.78745318821;4533.184026875128;4702.600278976257;4802.615790736732;4823.2282834746275;4877.244027175793;10833.349999000086;10875.673729788119;10883.18376121794;10884.91150884921;10916.07213386871;5508.862278442991;5514.779408823602;5522.751476183581;5525.419830917695;5526.530244929226;4600.240960241589;4839.95940872707;5094.592432605456;5144.202648589475;5193.428722840485;4130.463039804806;4219.746815191098;4233.449651545341;4299.806508707115;4341.934870976938;1195.6891208285908;1209.6431552691977;1217.6042786094379;1219.7743417467798;1252.4426630149496;885.9778505537413;986.1075726467558;1021.2187676427538;1037.7976294622626;1057.8994995475418;6236.345183755731;6405.967970160119;6419.711112999927;6590.505712071614;6629.370209830058;458.3377020205837;480.24121887417795;481.2228251374076;481.86266913930064;483.4657703237871;4284.91430171401;4297.8495752648505;4382.342648602671;4395.912081758411;4405.074934263776;12022.75954480923;12462.563810266778;12566.497340106476;12571.748565028649;12572.937135314265;11453.770924581628;11896.22674526153;12131;12316.445759940823;12467.251964882136;8957.462552246887;9145.725628231297;9163.541822908828;9189.954050229706;9197.816043679222;6240.594361366497;6668.399844014062;6885;6963.512554121163;6964.025036494996;11432.199746017699;11448.255863368855;11582.6525204244;11685.707857303636;11702.941485292518;9414.67048653294;11211.439428028729;11316.15128865322;11834;11940.164188506722;8471.49171049739;8512.191341822765;8652.134786521297;8678.392512524062;8684.565771711515;9504.097110774464;9726.319157658896;9793.51032448389;9795.902040979785;9798.65704700332;8346.582670866312;8498.192671696179;8552.144785521397;8754.25588824957;8763.036066032604;8024.0772311184965;8250.834983300181;8331.583420828812;8435.24082832548;8741.431806932529;7710.267524585155;7795.41534384912;7854.72508462201;7893.17121702213;7927.207279272026;5426.918596221139;5480.123180291134;5500.257465242141;5517.586181036519;5729.799457018984;5714.20001199824;5729.742161602821;5758.280214973129;5797.072468405035;5846.152307915243 07:55:21 INFO - PROCESS | 21174 | |4;prototype.html;351.88387832015434;355.4313099041534;360.85746130278557;362.77978769928046;369.20436459429936;5553.500184983351;5593.272874526306;5617.803376881805;5620.353659329168;5634.40838711935;5188.247704082905;5260.605454590902;5400.270963419937;5447.346318441782;5533.557315414767;4320.395144679747;4326.84856030039;4337.587929146726;4348.5216626171095;4366.432363792703;4337.240982828008;4354.80403381847;4355.956440435587;4395.230834603932;4398.296272596385;4154.875353739392;4253.8723838284905;4266.338717498777;4289.892752681192;4338.566143385652;4285.635720963722;4292.785360731982;4302.225599392108;4316.11519638475;4331.245845432621;3795.3737633290475;3802.657760801541;3809.961900381016;3814.618538146191;3816.122291872857;5227.3465816772905;5299.920501192485;5314.309139811839;5351.143816989302;5359.14253719403;4894.461609222983;4901.426750955777;4908.533689299511;4909.165441874889;4917.336159618441;5625.662460252398;5682.323714504231;5702;5704.914426283609;5715.371309155989;3898.7660740355386;4085.997861048095;4132.710710250284;4178.331466965271;4179.226843034048;5973.282156618728;5974.402559744034;5974.701264936779;5981.132735753292;5984.311804142536;4187.874363769091;4189.706720529594;4211.684123690736;4212.831486740496;4249.91500170001;4161.771102589387;4226.8798768325805;4230.857668429507;4255.0426154115075;4283;4828.903421931542;4842.564169224786;4844.273358996179;4861.246506791453;4890.462049174588;5793.8551536211935;5845.503132233808;5852.36643510422;5861.856937897154;5877.206577112035;1464.5899148238511;1486.2865824404346;1493.57433131557;1506.412499125342;1508.7887695722607;935.2237642756496;947.6920000999703;953.4422362917633;977.2524019125375;982.9901700982898;4921.753912304442;4936.790486330872;4958.628102892298;4972.428170760337;4990.525900039491;5107.846764597068;5234.7959969207295;5240.868978275574;5292.814751483679;5332.6000549958135;4695;4975.9847261859;5018.247262910592;5040;5065.594752419797;2755.2836262571473;2768.543190373606;2777.777777777773;2783.6937936826935;2785.206216228365;5372.597055220874;5375.924815037009;5378.005069062236;5409;5412.539934105566;5005.874853128701;5276.577873770166;5313.654612450178;5328.653637513549;5380.498068398214;5350.51845333914;5393.514583687408;5403.918941215885;5443.911217756386;5451.427600102012;5254.579633629377;5266.577374407815;5268.2361057647195;5272.314599102246;5283.811142492908;4957.851264462072;5029.968856384506;5166.225066240094;5169.7673604687125;5193.896122077613;5362.158056230204;5365.96093484489;5371.650842695212;5401.648892821954;5406.24312596229;3944.566097729305;3959.2081583683953;3977.2244412339314;3977.7016723745255;4097.877063687974;5542.75057622416;5804.303483581997;5874;5883.676397798162;5887.4406931341455;5696.629719068247;5715.342735585295;5717.799877004285;5726.141078838207;5750.194972703741;5495.422980586981;5763.5965482417505;5768.451997060272;5775.566832487664;5801.419858014165;5744.678723893444;5778.219940308005;5791.7683292667825;5801.071828507418;5818.2436283282905;5825.766969321349;5863.560232982458;5866.149408335682;5867.471927526372;5867.765289388377;5423.322084739408;5665.065264231355;5684;5706.201131841629;5743.770249189985 07:55:21 INFO - PROCESS | 21174 | |5;yui.html;820.228984754331;850.506706110456;860.3246451535542;900.9189172974433;904.0642934562729;16703.66592668146;17908;17929.372471963452;17932.103394830254;17937;16155;16358.7546186807;16669.24988375523;17242.482725518254;17384.478465646018;729.5440349781387;875.492214515581;901.6438506789814;903.7153296711548;905.1446383167876;852.842224188524;883.514067263006;885.7696998780332;887.0375642427973;890.8084761776216;938.7934654376027;939.318993729544;939.7650587353162;939.8261321655516;939.8825146856643;761.3528500774353;931.487681775021;931.7903471718843;933.4212788071371;934.6681927915594;661.5501459007872;682.0451368084672;684.8630273945231;686.6429456682547;692.5256199503357;15867.365305387772;15952;15990;16003;16005.91997040019;14546.199959002251;15013.849861501354;15361.769573456408;15387.53837384869;15543.766843497357;14487.899254608128;14544.545545445371;14554.927225363912;14584.1978691173;14925.402983880631;4792.9760351198365;4827.203511420611;4905.435874874363;4906.852794416173;4937.80248790048;83457;83892.58053709692;84648.99999999939;85202;87963.56018219932;11998.400079995965;12149.271043737228;12153.149279550347;12176;12252.571160009356;22534.21130260433;23114.1910033148;23129.306120816404;23170.7682923172;23176.65235021476;9053.68312109073;9218.400803947721;9380.8592871107;9467.337286390024;9559.378640388353;5209.114450543341;5423.942331245448;5453.727313634382;5545.750441230154;5564.749586268546;3784.526934133233;3935.842566297316;3945.151792364655;3948.4077388391397;3950.20995800841;724.3336130759714;746.5893758432949;747.491705640159;756.3306473770753;765.4527013185599;721.1346384338815;775.83707421441;810.9107998120202;813.943023988327;843.9620217090246;10662.840057399146;10767.300125491818;11359.663241302464;11460.484278207501;11629.941850290694;700.9964950175319;721.7906807026009;740.6111791309605;782.338923609549;811.7402431222071;4901.877453063702;5236.214567814858;5243.397009343898;5281.524662780368;5305.336832895888;72252;75283.62358188174;121782.391088044;130358;139947.30026349804;113132;117951;118666.4066679661;119638.4018079904;119786.29805066546;101587.49206253921;102783.48608256911;103982.48008760059;104190.47904760428;104603;9636.807263854544;10107.191424686154;10133.949330253301;10149.188064954638;10248.948755256177;60833.391666082774;60839;61215.38784612097;61822.381776183465;65755.34244657493;87658.56170719105;92267.53866230627;92441.53779231061;92480.53759731159;93433.53283233813;12854.935725321313;13205.603831885059;13235.801462978256;13238.867611323763;13409.664758380728;15092.471763488225;15107.169105698853;15213.847861521243;15319.693606128038;15527.37890484368;14007.579772606838;14154.504592339219;14363.64090897694;14415.711685766435;15080.245987700351;13953.302334883418;13984.300784960915;14267.357968891218;14295.285235737963;14427.134371937716;13902.860971390157;14085.295735212994;14247.928760356546;14851.257437127884;15037;11400.258983166068;11914.642560723192;12067.818982715442;12162.452689629165;12572.434240459384;11825.290482571074;11835.408229588658;12042;12135.453904574491;12169.452374642986 07:55:21 INFO - PROCESS | 21174 | __end_tp_report 07:55:21 INFO - PROCESS | 21174 | __start_cc_report 07:55:21 INFO - PROCESS | 21174 | _x_x_mozilla_cycle_collect,1093 07:55:21 INFO - PROCESS | 21174 | __end_cc_report 07:55:21 INFO - PROCESS | 21174 | __startTimestamp1470754521164__endTimestamp 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | ------- Summary: start ------- 07:55:21 INFO - PROCESS | 21174 | Number of tests: 6 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | [#0] dojo.html Cycles:165 Average:5627.47 Median:5835.53 stddev:631.78 (10.8%) stddev-sans-first:605.05 07:55:21 INFO - PROCESS | 21174 | Values: 3229.2 3266.7 3267.0 3295.3 3309.8 5581.7 5662.7 5665.3 5672.7 5684.1 5646.0 5703.9 5718.1 5728.0 5752.5 5065.8 5135.8 5151.3 5188.0 5251.1 5205.8 5258.3 5258.5 5261.2 5264.8 5119.0 5184.9 5185.0 5199.8 5219.7 5282.9 5290.4 5300.3 5303.6 5333.0 5004.7 5063.3 5126.1 5152.7 5160.6 5569.6 5634.2 5644.0 5647.9 5676.2 6111.9 6137.6 6138.8 6138.8 6145.3 5866.4 5922.1 5941.4 6001.9 6168.5 5593.9 5625.4 5647.7 5664.4 5674.5 6103.2 6112.1 6112.7 6113.1 6119.5 5768.5 5872.4 5936.3 5956.8 6042.0 6090.1 6093.9 6099.0 6103.2 6143.1 6042.8 6054.8 6058.3 6064.5 6068.1 5895.0 5901.4 5928.0 5933.2 5982.6 4590.0 4624.6 4630.3 4647.1 4656.0 4115.1 4116.3 4123.2 4144.4 4144.6 5611.7 5642.9 5706.3 5722.3 5806.1 6172.5 6175.7 6193.2 6196.3 6203.1 5779.1 5788.9 5832.6 5838.5 5879.4 5096.7 5190.8 5245.5 5246.4 5298.7 6141.0 6232.3 6251.8 6340.7 6346.7 6057.8 6119.4 6126.2 6127.3 6137.2 6076.8 6152.6 6283.0 6327.0 6338.8 5865.0 5951.2 5961.4 5988.2 5998.9 6019.5 6040.2 6040.4 6054.6 6064.3 5794.6 5794.9 5881.3 5894.8 5897.8 5795.4 5882.6 5883.3 5922.1 5937.9 5799.1 5862.2 5867.0 5872.2 5882.3 5838.9 5866.2 5878.4 5884.4 5951.2 5765.2 5785.7 5792.8 5811.6 5835.5 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | [#1] ext.html Cycles:180 Average:63709.21 Median:22146.30 stddev:126377.31 (570.6%) stddev-sans-first:126643.02 07:55:21 INFO - PROCESS | 21174 | Values: 604.2 635.0 651.2 671.9 684.9 33312.3 43135.1 43663.8 43837.6 43868.6 45886.5 45922.0 46064.0 46127.5 46352.8 8461.9 9057.4 9220.8 9248.8 9319.9 10876.8 11162.2 11167.7 11456.6 11514.3 11170.4 11200.2 11294.9 11296.8 11300.7 10954.9 10980.3 11073.6 11073.9 11077.1 10894.9 11010.9 11017.9 11019.9 11029.9 44244.1 45198.3 45259.3 45262.5 45267.3 4014.6 4153.5 4487.1 4604.5 4639.4 33307.3 33394.7 33437.7 34067.3 35849.8 4579.7 4582.5 4673.6 4675.3 4809.4 361867.0 362240.0 364005.0 364894.0 366302.0 62236.4 62563.7 62633.4 62687.4 62804.0 38794.0 39643.4 39716.6 41219.4 41299.4 17312.9 17534.2 17543.9 17546.7 17559.8 12482.7 13280.3 13541.0 13588.3 13696.8 20781.0 20841.5 20960.8 20983.8 21115.0 7646.4 7705.7 7735.5 7745.1 7819.5 990.6 1039.9 1075.5 1130.6 1159.2 33633.5 34914.0 35560.8 35581.5 36050.6 1146.2 1183.2 1224.0 1265.6 1270.7 17686.2 18285.5 18347.4 18498.4 18636.6 592201.0 596976.0 597503.0 598527.0 598936.0 63291.0 63828.4 63930.4 64043.7 64061.0 1313.4 1427.8 1460.5 1475.4 1478.4 34052.5 36150.5 36364.8 36764.3 37144.4 54157.0 54622.0 54820.5 54866.7 54937.5 434883.0 439171.0 439492.0 439706.0 441800.0 30465.0 30703.5 30979.2 31090.5 32640.2 51884.5 52569.0 52919.5 52924.0 53185.5 43552.8 43887.5 44306.6 45420.0 45621.1 43939.0 44427.3 45121.8 45814.0 45948.3 44259.0 44555.8 45424.3 47750.5 49616.8 36987.9 39901.2 40278.0 40445.8 40936.2 29928.9 30208.0 30320.3 30691.8 30753.8 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | [#2] jquery.html Cycles:180 Average:29269.19 Median:13315.75 stddev:81519.23 (612.2%) stddev-sans-first:81719.08 07:55:21 INFO - PROCESS | 21174 | Values: 389.5 403.6 412.8 417.7 436.7 16786.8 19732.5 20137.7 20242.7 20650.0 19415.3 19524.6 19561.6 19613.8 19666.5 5628.8 5806.5 5953.4 6010.8 6021.6 5637.5 5946.9 5947.4 5953.5 5962.1 5694.3 5862.1 5873.3 5876.9 5894.9 5737.7 5830.5 5877.7 5928.7 5937.9 5341.9 5346.2 5347.7 5349.8 5350.8 23647.1 23665.6 23874.9 23956.9 24028.2 2363.5 2603.3 2646.8 2690.3 3061.1 10363.0 10639.7 10707.5 10744.7 10980.7 2019.0 2027.9 2047.9 2073.5 2151.6 61260.0 65903.3 73399.3 79147.2 86017.6 11207.3 11369.5 11552.4 11641.5 11783.6 16422.2 16470.3 16486.0 16502.9 16507.0 8444.9 8517.5 8540.9 8592.5 8674.5 8066.0 8117.3 8308.0 8325.2 8325.8 4792.3 4857.1 4935.8 4987.9 5093.0 1976.1 2011.5 2022.2 2035.2 2050.4 944.8 1006.8 1032.7 1041.4 1046.5 20996.6 21634.1 21725.2 21763.0 21861.1 1015.7 1042.9 1050.1 1078.6 1082.0 11600.5 11961.3 12035.9 12080.3 12218.5 475356.0 479224.0 480391.0 503170.0 533751.0 92392.0 92538.0 94556.5 94671.0 94895.5 1026.2 1048.8 1059.5 1070.6 1080.6 18078.1 18319.3 18820.3 18858.3 19188.0 43873.6 44776.3 45322.8 46149.0 46636.8 57837.7 60078.7 60889.7 61318.0 61539.4 8249.5 8313.4 8387.2 8437.5 8573.7 12403.6 12490.4 12754.4 12912.8 12957.2 11084.6 11255.8 11404.9 11473.3 11568.6 5939.6 9133.9 9997.0 11259.4 11526.8 11312.7 11398.5 11430.5 11481.5 11726.2 8231.2 8299.5 8359.9 8400.5 8458.2 9783.9 10073.5 10269.5 10335.6 10485.9 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | [#3] mootools.html Cycles:180 Average:6355.38 Median:2591.42 stddev:3764.15 (145.3%) stddev-sans-first:3756.54 07:55:21 INFO - PROCESS | 21174 | Values: 1434.3 1520.2 1581.1 1732.9 1872.3 8323.6 9305.5 9614.1 9619.8 9869.5 9686.7 9783.1 9788.2 9789.6 9804.6 2386.0 2409.2 2410.9 2414.0 2415.7 2448.4 2452.1 2501.1 2507.9 2509.1 2454.2 2462.9 2469.2 2498.5 2498.5 2365.3 2372.9 2380.0 2411.9 2455.2 2354.2 2367.9 2370.5 2372.9 2373.3 9352.7 9667.3 9920.0 9927.3 9927.8 5665.6 5861.2 5896.4 5896.4 5899.7 3644.3 3651.2 3668.5 3710.0 3714.5 1129.0 1161.3 1204.2 1214.2 1242.6 13990.2 14066.3 14124.3 14130.7 14169.8 4533.2 4702.6 4802.6 4823.2 4877.2 10833.3 10875.7 10883.2 10884.9 10916.1 5508.9 5514.8 5522.8 5525.4 5526.5 4600.2 4840.0 5094.6 5144.2 5193.4 4130.5 4219.7 4233.4 4299.8 4341.9 1195.7 1209.6 1217.6 1219.8 1252.4 886.0 986.1 1021.2 1037.8 1057.9 6236.3 6406.0 6419.7 6590.5 6629.4 458.3 480.2 481.2 481.9 483.5 4284.9 4297.8 4382.3 4395.9 4405.1 12022.8 12462.6 12566.5 12571.7 12572.9 11453.8 11896.2 12131.0 12316.4 12467.3 8957.5 9145.7 9163.5 9190.0 9197.8 6240.6 6668.4 6885.0 6963.5 6964.0 11432.2 11448.3 11582.7 11685.7 11702.9 9414.7 11211.4 11316.2 11834.0 11940.2 8471.5 8512.2 8652.1 8678.4 8684.6 9504.1 9726.3 9793.5 9795.9 9798.7 8346.6 8498.2 8552.1 8754.3 8763.0 8024.1 8250.8 8331.6 8435.2 8741.4 7710.3 7795.4 7854.7 7893.2 7927.2 5426.9 5480.1 5500.3 5517.6 5729.8 5714.2 5729.7 5758.3 5797.1 5846.2 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | [#4] prototype.html Cycles:180 Average:4673.50 Median:5247.72 stddev:1343.81 (25.6%) stddev-sans-first:1307.84 07:55:21 INFO - PROCESS | 21174 | Values: 351.9 355.4 360.9 362.8 369.2 5553.5 5593.3 5617.8 5620.4 5634.4 5188.2 5260.6 5400.3 5447.3 5533.6 4320.4 4326.8 4337.6 4348.5 4366.4 4337.2 4354.8 4356.0 4395.2 4398.3 4154.9 4253.9 4266.3 4289.9 4338.6 4285.6 4292.8 4302.2 4316.1 4331.2 3795.4 3802.7 3810.0 3814.6 3816.1 5227.3 5299.9 5314.3 5351.1 5359.1 4894.5 4901.4 4908.5 4909.2 4917.3 5625.7 5682.3 5702.0 5704.9 5715.4 3898.8 4086.0 4132.7 4178.3 4179.2 5973.3 5974.4 5974.7 5981.1 5984.3 4187.9 4189.7 4211.7 4212.8 4249.9 4161.8 4226.9 4230.9 4255.0 4283.0 4828.9 4842.6 4844.3 4861.2 4890.5 5793.9 5845.5 5852.4 5861.9 5877.2 1464.6 1486.3 1493.6 1506.4 1508.8 935.2 947.7 953.4 977.3 983.0 4921.8 4936.8 4958.6 4972.4 4990.5 5107.8 5234.8 5240.9 5292.8 5332.6 4695.0 4976.0 5018.2 5040.0 5065.6 2755.3 2768.5 2777.8 2783.7 2785.2 5372.6 5375.9 5378.0 5409.0 5412.5 5005.9 5276.6 5313.7 5328.7 5380.5 5350.5 5393.5 5403.9 5443.9 5451.4 5254.6 5266.6 5268.2 5272.3 5283.8 4957.9 5030.0 5166.2 5169.8 5193.9 5362.2 5366.0 5371.7 5401.6 5406.2 3944.6 3959.2 3977.2 3977.7 4097.9 5542.8 5804.3 5874.0 5883.7 5887.4 5696.6 5715.3 5717.8 5726.1 5750.2 5495.4 5763.6 5768.5 5775.6 5801.4 5744.7 5778.2 5791.8 5801.1 5818.2 5825.8 5863.6 5866.1 5867.5 5867.8 5423.3 5665.1 5684.0 5706.2 5743.8 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - PROCESS | 21174 | [#5] yui.html Cycles:180 Average:23228.62 Median:23173.71 stddev:33767.43 (145.7%) stddev-sans-first:33820.24 07:55:21 INFO - PROCESS | 21174 | Values: 820.2 850.5 860.3 900.9 904.1 16703.7 17908.0 17929.4 17932.1 17937.0 16155.0 16358.8 16669.2 17242.5 17384.5 729.5 875.5 901.6 903.7 905.1 852.8 883.5 885.8 887.0 890.8 938.8 939.3 939.8 939.8 939.9 761.4 931.5 931.8 933.4 934.7 661.6 682.0 684.9 686.6 692.5 15867.4 15952.0 15990.0 16003.0 16005.9 14546.2 15013.8 15361.8 15387.5 15543.8 14487.9 14544.5 14554.9 14584.2 14925.4 4793.0 4827.2 4905.4 4906.9 4937.8 83457.0 83892.6 84649.0 85202.0 87963.6 11998.4 12149.3 12153.1 12176.0 12252.6 22534.2 23114.2 23129.3 23170.8 23176.7 9053.7 9218.4 9380.9 9467.3 9559.4 5209.1 5423.9 5453.7 5545.8 5564.7 3784.5 3935.8 3945.2 3948.4 3950.2 724.3 746.6 747.5 756.3 765.5 721.1 775.8 810.9 813.9 844.0 10662.8 10767.3 11359.7 11460.5 11629.9 701.0 721.8 740.6 782.3 811.7 4901.9 5236.2 5243.4 5281.5 5305.3 72252.0 75283.6 121782.4 130358.0 139947.3 113132.0 117951.0 118666.4 119638.4 119786.3 101587.5 102783.5 103982.5 104190.5 104603.0 9636.8 10107.2 10133.9 10149.2 10248.9 60833.4 60839.0 61215.4 61822.4 65755.3 87658.6 92267.5 92441.5 92480.5 93433.5 12854.9 13205.6 13235.8 13238.9 13409.7 15092.5 15107.2 15213.8 15319.7 15527.4 14007.6 14154.5 14363.6 14415.7 15080.2 13953.3 13984.3 14267.4 14295.3 14427.1 13902.9 14085.3 14247.9 14851.3 15037.0 11400.3 11914.6 12067.8 12162.5 12572.4 11825.3 11835.4 12042.0 12135.5 12169.5 07:55:21 INFO - PROCESS | 21174 | -------- Summary: end -------- 07:55:21 INFO - PROCESS | 21174 | 07:55:21 INFO - TEST-INFO | 21174: exit 0 07:55:21 INFO - TEST-OK | dromaeo_css | took 1113655ms 07:55:21 INFO - TEST-START | kraken 07:55:21 INFO - operating with platform_type : linux_ 07:55:21 INFO - Initialising browser for kraken test... 07:55:21 INFO - TEST-INFO | started process 21376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPUYuiu/profile http://localhost:59338/getInfo.html) 07:55:25 INFO - PROCESS | 21376 | __metrics Screen width/height:1600/1200 07:55:25 INFO - PROCESS | 21376 | colorDepth:24 07:55:25 INFO - PROCESS | 21376 | Browser inner width/height: 1024/697 07:55:25 INFO - PROCESS | 21376 | __metrics 07:55:26 INFO - PROCESS | 21376 | 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" 07:55:26 INFO - PROCESS | 21376 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:55:27 INFO - TEST-INFO | 21376: exit 0 07:55:27 INFO - Browser initialized. 07:55:27 INFO - Running cycle 1/1 for kraken test... 07:55:27 INFO - TEST-INFO | started process 21466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPUYuiu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:39 INFO - PROCESS | 21466 | RSS: Main: 145932288 07:55:39 INFO - PROCESS | 21466 | 07:55:41 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59338/tests/kraken/driver.html?testName=audio-beat-detection) 07:55:42 INFO - PROCESS | 21466 | RSS: Main: 191492096 07:55:42 INFO - PROCESS | 21466 | 07:55:45 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59338/tests/kraken/driver.html?testName=audio-dft) 07:55:45 INFO - PROCESS | 21466 | RSS: Main: 180781056 07:55:45 INFO - PROCESS | 21466 | 07:55:48 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59338/tests/kraken/driver.html?testName=audio-fft) 07:55:49 INFO - PROCESS | 21466 | RSS: Main: 269066240 07:55:49 INFO - PROCESS | 21466 | 07:55:51 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59338/tests/kraken/driver.html?testName=audio-oscillator) 07:55:52 INFO - PROCESS | 21466 | RSS: Main: 175620096 07:55:52 INFO - PROCESS | 21466 | 07:55:54 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59338/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:55:54 INFO - PROCESS | 21466 | RSS: Main: 177176576 07:55:54 INFO - PROCESS | 21466 | 07:55:57 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59338/tests/kraken/driver.html?testName=imaging-darkroom) 07:55:58 INFO - PROCESS | 21466 | RSS: Main: 213159936 07:55:58 INFO - PROCESS | 21466 | 07:56:01 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59338/tests/kraken/driver.html?testName=imaging-desaturate) 07:56:02 INFO - PROCESS | 21466 | RSS: Main: 211849216 07:56:02 INFO - PROCESS | 21466 | 07:56:05 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59338/tests/kraken/driver.html?testName=json-parse-financial) 07:56:05 INFO - PROCESS | 21466 | RSS: Main: 212492288 07:56:05 INFO - PROCESS | 21466 | 07:56:07 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59338/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:56:07 INFO - PROCESS | 21466 | RSS: Main: 176594944 07:56:07 INFO - PROCESS | 21466 | 07:56:09 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:56:10 INFO - PROCESS | 21466 | RSS: Main: 180760576 07:56:10 INFO - PROCESS | 21466 | 07:56:12 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:56:12 INFO - PROCESS | 21466 | RSS: Main: 185999360 07:56:12 INFO - PROCESS | 21466 | 07:56:15 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:56:16 INFO - PROCESS | 21466 | RSS: Main: 181829632 07:56:16 INFO - PROCESS | 21466 | 07:56:18 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:56:19 INFO - PROCESS | 21466 | RSS: Main: 184750080 07:56:19 INFO - PROCESS | 21466 | 07:56:20 INFO - PROCESS | 21466 | Cycle 1(1): loaded http://localhost:59338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59338/tests/kraken/driver.html?testName=ai-astar) 07:56:21 INFO - PROCESS | 21466 | RSS: Main: 235704320 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | __start_tp_report 07:56:21 INFO - PROCESS | 21466 | _x_x_mozilla_page_load 07:56:21 INFO - PROCESS | 21466 | _x_x_mozilla_page_load_details 07:56:21 INFO - PROCESS | 21466 | |i|pagename|runs| 07:56:21 INFO - PROCESS | 21466 | |0;ai-astar;102;111;111;110;111;110;110;109;110;109 07:56:21 INFO - PROCESS | 21466 | |1;audio-beat-detection;151;118;118;169;116;119;158;119;158;124 07:56:21 INFO - PROCESS | 21466 | |2;audio-dft;161;162;160;161;160;162;161;161;162;162 07:56:21 INFO - PROCESS | 21466 | |3;audio-fft;75;76;75;123;77;76;76;75;96;74 07:56:21 INFO - PROCESS | 21466 | |4;audio-oscillator;82;83;113;84;83;83;124;83;84;122 07:56:21 INFO - PROCESS | 21466 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;117;117 07:56:21 INFO - PROCESS | 21466 | |6;imaging-darkroom;146;147;147;147;147;147;146;146;146;147 07:56:21 INFO - PROCESS | 21466 | |7;imaging-desaturate;95;96;95;95;95;95;95;96;95;95 07:56:21 INFO - PROCESS | 21466 | |8;json-parse-financial;49;51;52;51;54;51;50;48;49;50 07:56:21 INFO - PROCESS | 21466 | |9;json-stringify-tinderbox;60;60;60;61;61;61;61;61;60;60 07:56:21 INFO - PROCESS | 21466 | |10;stanford-crypto-aes;74;72;72;73;76;78;73;82;77;73 07:56:21 INFO - PROCESS | 21466 | |11;stanford-crypto-ccm;119;117;131;129;116;120;127;119;159;127 07:56:21 INFO - PROCESS | 21466 | |12;stanford-crypto-pbkdf2;152;155;159;154;154;157;154;153;155;154 07:56:21 INFO - PROCESS | 21466 | |13;stanford-crypto-sha256-iterative;63;64;62;61;63;67;62;64;61;61 07:56:21 INFO - PROCESS | 21466 | __end_tp_report 07:56:21 INFO - PROCESS | 21466 | __start_cc_report 07:56:21 INFO - PROCESS | 21466 | _x_x_mozilla_cycle_collect,1659 07:56:21 INFO - PROCESS | 21466 | __end_cc_report 07:56:21 INFO - PROCESS | 21466 | __startTimestamp1470754581204__endTimestamp 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | ------- Summary: start ------- 07:56:21 INFO - PROCESS | 21466 | Number of tests: 14 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:2.67 (2.4%) stddev-sans-first:0.78 07:56:21 INFO - PROCESS | 21466 | Values: 102.0 111.0 111.0 110.0 111.0 110.0 110.0 109.0 110.0 109.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:137.50 stddev:21.19 (15.4%) stddev-sans-first:21.67 07:56:21 INFO - PROCESS | 21466 | Values: 151.0 118.0 118.0 169.0 116.0 119.0 158.0 119.0 158.0 124.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 07:56:21 INFO - PROCESS | 21466 | Values: 161.0 162.0 160.0 161.0 160.0 162.0 161.0 161.0 162.0 162.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#3] audio-fft Cycles:10 Average:82.30 Median:76.00 stddev:15.71 (20.7%) stddev-sans-first:16.44 07:56:21 INFO - PROCESS | 21466 | Values: 75.0 76.0 75.0 123.0 77.0 76.0 76.0 75.0 96.0 74.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.00 stddev:17.87 (21.5%) stddev-sans-first:18.41 07:56:21 INFO - PROCESS | 21466 | Values: 82.0 83.0 113.0 84.0 83.0 83.0 124.0 83.0 84.0 122.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 07:56:21 INFO - PROCESS | 21466 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 07:56:21 INFO - PROCESS | 21466 | Values: 146.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 146.0 147.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:56:21 INFO - PROCESS | 21466 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:1.72 (3.4%) stddev-sans-first:1.73 07:56:21 INFO - PROCESS | 21466 | Values: 49.0 51.0 52.0 51.0 54.0 51.0 50.0 48.0 49.0 50.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 07:56:21 INFO - PROCESS | 21466 | Values: 60.0 60.0 60.0 61.0 61.0 61.0 61.0 61.0 60.0 60.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.00 stddev:3.23 (4.3%) stddev-sans-first:3.41 07:56:21 INFO - PROCESS | 21466 | Values: 74.0 72.0 72.0 73.0 76.0 78.0 73.0 82.0 77.0 73.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:127.00 stddev:12.64 (10.0%) stddev-sans-first:13.12 07:56:21 INFO - PROCESS | 21466 | Values: 119.0 117.0 131.0 129.0 116.0 120.0 127.0 119.0 159.0 127.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:154.50 stddev:2.00 (1.3%) stddev-sans-first:1.87 07:56:21 INFO - PROCESS | 21466 | Values: 152.0 155.0 159.0 154.0 154.0 157.0 154.0 153.0 155.0 154.0 07:56:21 INFO - PROCESS | 21466 | 07:56:21 INFO - PROCESS | 21466 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.87 (3.0%) stddev-sans-first:1.99 07:56:21 INFO - PROCESS | 21466 | Values: 63.0 64.0 62.0 61.0 63.0 67.0 62.0 64.0 61.0 61.0 07:56:21 INFO - PROCESS | 21466 | -------- Summary: end -------- 07:56:21 INFO - PROCESS | 21466 | 07:56:22 INFO - TEST-INFO | 21466: exit 0 07:56:22 INFO - TEST-OK | kraken | took 60275ms 07:56:22 INFO - SUITE-END | took 1173s 07:56:22 INFO - Completed test suite (00:19:34) 07:56:22 INFO - javascript benchmark 07:56:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3229.208843833261, 3266.7223286020685, 3267.0198940317914, 3295.2915123248495, 3309.7848639838408, 5581.720913954301, 5662.745176467059, 5665.348484924235, 5672.659640421578, 5684.147377893318, 5646.0, 5703.942960570393, 5718.113692377678, 5727.971360143204, 5752.539796816255, 5065.822696205633, 5135.768890399922, 5151.304573882525, 5188.04021256067, 5251.054810134175, 5205.791768329263, 5258.316418865542, 5258.526732594066, 5261.158214685651, 5264.763085661154, 5119.0, 5184.870378240536, 5185.040767458014, 5199.818006369778, 5219.739013049351, 5282.867928301785, 5290.444503327153, 5300.257963885059, 5303.575713942875, 5332.973335133338, 5004.724740139291, 5063.316452278932, 5126.077306084903, 5152.742362881841, 5160.56135228506, 5569.582281328917, 5634.154876768517, 5644.0, 5647.8588035299035, 5676.233708449347, 6111.908321375183, 6137.557673946619, 6138.754449822001, 6138.815835524942, 6145.324014358417, 5866.354700982888, 5922.082077278029, 5941.405859414067, 6001.939980600182, 6168.537359698041, 5593.888122237532, 5625.38120806711, 5647.745851436695, 5664.40523745005, 5674.546036317085, 6103.209309035924, 6112.144299798032, 6112.72492737819, 6113.144159817629, 6119.479844213202, 5768.451997060272, 5872.383399743051, 5936.347001829795, 5956.791512297049, 6042.0332746760305, 6090.116933044772, 6093.872633562805, 6098.969505152446, 6103.211375619095, 6143.109249158847, 6042.758289668452, 6054.788082417094, 6058.27300723916, 6064.514838812885, 6068.089786532056, 5895.0, 5901.380355062743, 5928.0, 5933.169356290036, 5982.641041537521, 4589.977050114728, 4624.583787459078, 4630.282306242538, 4647.117047760915, 4656.0, 4115.135821477464, 4116.259073366823, 4123.237201117803, 4144.41978123063, 4144.564820693844, 5611.7474713637985, 5642.943570564324, 5706.28671416073, 5722.341930678025, 5806.071028635398, 6172.506199504029, 6175.660338681326, 6193.194884665055, 6196.349383314777, 6203.069539569101, 5779.104238842986, 5788.913166302509, 5832.591718579659, 5838.532917366685, 5879.3826648202175, 5096.668716533413, 5190.792368305301, 5245.475452454801, 5246.449122842047, 5298.735063246822, 6140.969295153585, 6232.345603711545, 6251.812445626639, 6340.651264180515, 6346.7143978522, 6057.7879774207695, 6119.44924956756, 6126.203593532812, 6127.264728232641, 6137.171481849893, 6076.848078797889, 6152.569320147547, 6282.968585157045, 6327.019312006646, 6338.841528961812, 5865.032269675627, 5951.196588460502, 5961.374055724086, 5988.221531200916, 5998.880022399615, 6019.51843852482, 6040.184575082308, 6040.36576159497, 6054.606450580698, 6064.272287325549, 5794.594378393472, 5794.884102317846, 5881.264841894763, 5894.821035792773, 5897.764089436546, 5795.3625101237985, 5882.647041177543, 5883.264591926009, 5922.082077278029, 5937.881242375215, 5799.101139323411, 5862.179294898632, 5866.970665146647, 5872.236609240771, 5882.323532793784, 5838.912416313844, 5866.208061911588, 5878.441548052928, 5884.411208973639, 5951.226340575698, 5765.164051212634, 5785.681787501728, 5792.82621521355, 5811.593188476935, 5835.533157347487], "value": 5585.012793274943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.1753007145245, 634.984125396865, 651.2380514797687, 671.885779417499, 684.9075374824397, 33312.33375332492, 43135.137297254034, 43663.78168109155, 43837.56162438382, 43868.56131438685, 45886.541134588646, 45922.0, 46064.0, 46127.53872461274, 46352.76823615886, 8461.915380846174, 9057.411268267575, 9220.769480762967, 9248.815023699522, 9319.860202096974, 10876.836847447295, 11162.162837787198, 11167.664970050912, 11456.599019034335, 11514.251573647727, 11170.441477926111, 11200.159988000893, 11294.887051129466, 11296.830547541793, 11300.717482062973, 10954.890451095467, 10980.286281391684, 11073.612423565217, 11073.889261107406, 11077.058450031756, 10894.891051089546, 11010.889891101147, 11017.944910275477, 11019.889801102046, 11029.889701102966, 44244.11511769747, 45198.32202516965, 45259.32111018337, 45262.54737452616, 45267.32099018517, 4014.598540145991, 4153.460050193486, 4487.12501062293, 4604.493505714368, 4639.37368455261, 33307.33385332304, 33394.666053339395, 33437.66562334394, 34067.31865362704, 35849.82075089634, 4579.656525760582, 4582.495925448198, 4673.556012178838, 4675.251959686468, 4809.398825146856, 361867.0, 362240.0, 364005.0, 364894.0, 366302.0, 62236.37763622306, 62563.68718156471, 62633.373666263666, 62687.37312626906, 62804.0, 38794.0, 39643.405348919216, 39716.602833971294, 41219.381709274385, 41299.38050929239, 17312.913435432743, 17534.210960506807, 17543.912280438515, 17546.736798948026, 17559.824401756076, 12482.687932801753, 13280.335983200801, 13541.0, 13588.320583970763, 13696.794548081787, 20781.0, 20841.478963025744, 20960.790392096187, 20983.79016209849, 21115.0, 7646.35006024483, 7705.691772329044, 7735.458517903693, 7745.148033716287, 7819.530828150329, 990.5889056041806, 1039.9220058495644, 1075.521392980132, 1130.5647325779635, 1159.2059439284117, 33633.49549756756, 34914.0, 35560.82219588885, 35581.46627800585, 36050.63949360525, 1146.1747541769912, 1183.23681225609, 1223.9571614993433, 1265.5760320292804, 1270.688681273094, 17686.204120814593, 18285.54286142857, 18347.357842475718, 18498.35255766042, 18636.627267454576, 592201.0, 596976.0, 597503.0, 598527.0, 598936.0, 63291.0, 63828.36171638317, 63930.36069639244, 64043.6797816008, 64061.0, 1313.4155300891011, 1427.8143841300564, 1460.4888289098942, 1475.4393330534542, 1478.4381934864898, 34052.489212661334, 36150.457743133346, 36364.81817590895, 36764.26471470609, 37144.44283335698, 54157.0, 54622.0, 54820.45179548153, 54866.725666371414, 54937.450625493235, 434883.0, 439171.0, 439492.0, 439706.0, 441800.0, 30465.0, 30703.539446908762, 30979.225519361295, 31090.533641994934, 32640.183995400304, 51884.48115518797, 52569.0, 52919.47080529146, 52924.0, 53185.4681453196, 43552.78223608862, 43887.49402199808, 44306.55693443024, 45420.0, 45621.08757824892, 43939.0, 44427.33358999682, 45121.77439112784, 45814.0, 45948.310775337726, 44259.0, 44555.77722111368, 45424.31863521984, 47750.52249477461, 49616.75191624019, 36987.896996995194, 39901.20197595974, 40278.0, 40445.797771010955, 40936.181276374904, 29928.850355748084, 30208.0, 30320.303073935916, 30691.846540767154, 30753.846230768704], "value": 21736.674983316785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.4761545721004, 403.6447925825273, 412.7626614696549, 417.65543426672997, 436.679040904935, 16786.83213167868, 19732.506687332825, 20137.697934530974, 20242.696359554582, 20650.0, 19415.32046378377, 19524.609507809837, 19561.608767824637, 19613.803861961376, 19666.508337291576, 5628.774849006056, 5806.477417032477, 5953.3748956359605, 6010.789622363218, 6021.578489505737, 5637.492625663689, 5946.851328716795, 5947.434993675594, 5953.464188223059, 5962.07587823888, 5694.259746232984, 5862.091375836752, 5873.324567674729, 5876.941230587682, 5894.911576326359, 5737.684427356494, 5830.475257226827, 5877.67672777997, 5928.703564821742, 5937.940620593782, 5341.946580534183, 5346.224797404355, 5347.7058761768085, 5349.839504814861, 5350.839474815762, 23647.05411783544, 23665.645015324782, 23874.88062559676, 23956.880215598983, 24028.159014434586, 2363.5036642305136, 2603.336149281921, 2646.775024122952, 2690.3274181454635, 3061.0969763919697, 10363.0, 10639.680809575726, 10707.464626768631, 10744.73138171544, 10980.670579882617, 2018.9798102018938, 2027.9493012674654, 2047.8566500344982, 2073.533454972634, 2151.5589304192604, 61260.0, 65903.3409665902, 73399.26600733977, 79147.20852791514, 86017.56991215066, 11207.327560346404, 11369.488373023234, 11552.422378881023, 11641.53433862653, 11783.587574434854, 16422.1788910554, 16470.341186352412, 16486.0, 16502.917485412498, 16507.0, 8444.873326900102, 8517.531535765593, 8540.914590854136, 8592.52741099233, 8674.479531228146, 8065.9596702016115, 8117.310028647513, 8307.958460207781, 8325.167483251626, 8325.8334833303, 4792.292354819586, 4857.125717370836, 4935.8025678972435, 4987.850364489072, 5092.974535127375, 1976.1107501624326, 2011.4770159758275, 2022.2214447437843, 2035.2266138867433, 2050.4361300642145, 944.8252073366311, 1006.758377989292, 1032.700516850105, 1041.4116024446164, 1046.529061922138, 20996.580068398547, 21634.13463461444, 21725.23961661366, 21763.0, 21861.12555497794, 1015.6597539824241, 1042.864427624404, 1050.0573938214395, 1078.6440474643348, 1081.9729506762237, 11600.535978560933, 11961.34212618297, 12035.879641203652, 12080.335581543099, 12218.450169742382, 475356.0, 479224.0, 480391.0, 503170.0, 533751.0, 92392.0, 92538.0, 94556.52721736347, 94671.0, 94895.52552237194, 1026.1893104447552, 1048.8112139814907, 1059.4967390489455, 1070.5878236878857, 1080.6325849211305, 18078.096095194924, 18319.267229310677, 18820.341288054853, 18858.339958101402, 19188.0, 43873.561264386946, 44776.328355074045, 45322.77338613286, 46149.0, 46636.76681616706, 57837.71081144567, 60078.69960650169, 60889.69555152373, 61318.0, 61539.38460615336, 8249.463784853966, 8313.376496762889, 8387.161283871808, 8437.535935523605, 8573.699920502753, 12403.62789116328, 12490.375481225721, 12754.42605082792, 12912.806307905577, 12957.157784743962, 11084.61203857861, 11255.774884502427, 11404.885951140383, 11473.254238474472, 11568.595099171825, 5939.613925094855, 9133.858267716536, 9996.950015249877, 11259.437028148724, 11526.769464610828, 11312.717182070513, 11398.544058237578, 11430.485628146587, 11481.483333250191, 11726.237794543327, 8231.218034286732, 8299.543525106177, 8359.874601880854, 8400.537970411686, 8458.238758511763, 9783.902160978583, 10073.496325183858, 10269.486525673836, 10335.638252661121, 10485.947570262406], "value": 9398.809721194717, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.3115304653772, 1520.1942970225778, 1581.0513691784931, 1732.913354332283, 1872.3072463188614, 8323.62543685534, 9305.4881981491, 9614.13472787449, 9619.759506012353, 9869.506524673774, 9686.66096686616, 9783.070608292219, 9788.168005719523, 9789.608415663364, 9804.55879485423, 2385.952280954384, 2409.180878501309, 2410.9035638574437, 2413.9517209655883, 2415.710114786228, 2448.400141965221, 2452.0559584559983, 2501.0746023971155, 2507.924762257135, 2509.134348649722, 2454.226918520669, 2462.9014839406404, 2469.234537293436, 2498.487809998946, 2498.487809998955, 2365.290412876139, 2372.8694921779297, 2380.047980807682, 2411.9397015074587, 2455.1529722245973, 2354.152505098163, 2367.91712290069, 2370.502194539149, 2372.905083796646, 2373.2642880706862, 9352.67265645699, 9667.323287369887, 9920.0, 9927.255455840768, 9927.751806204831, 5665.575081868877, 5861.150133230658, 5896.380880007623, 5896.439838215363, 5899.675517846516, 3644.325799727059, 3651.178484840916, 3668.52309199805, 3710.0, 3714.53568303962, 1128.9548418063268, 1161.309021132425, 1204.1931905623253, 1214.2471667566165, 1242.6210005948183, 13990.16059036461, 14066.296685165804, 14124.293785310796, 14130.71738565223, 14169.78745318821, 4533.184026875128, 4702.600278976257, 4802.615790736732, 4823.2282834746275, 4877.244027175793, 10833.349999000086, 10875.673729788119, 10883.18376121794, 10884.91150884921, 10916.07213386871, 5508.862278442991, 5514.779408823602, 5522.751476183581, 5525.419830917695, 5526.530244929226, 4600.240960241589, 4839.95940872707, 5094.592432605456, 5144.202648589475, 5193.428722840485, 4130.463039804806, 4219.746815191098, 4233.449651545341, 4299.806508707115, 4341.934870976938, 1195.6891208285908, 1209.6431552691977, 1217.6042786094379, 1219.7743417467798, 1252.4426630149496, 885.9778505537413, 986.1075726467558, 1021.2187676427538, 1037.7976294622626, 1057.8994995475418, 6236.345183755731, 6405.967970160119, 6419.711112999927, 6590.505712071614, 6629.370209830058, 458.3377020205837, 480.24121887417795, 481.2228251374076, 481.86266913930064, 483.4657703237871, 4284.91430171401, 4297.8495752648505, 4382.342648602671, 4395.912081758411, 4405.074934263776, 12022.75954480923, 12462.563810266778, 12566.497340106476, 12571.748565028649, 12572.937135314265, 11453.770924581628, 11896.22674526153, 12131.0, 12316.445759940823, 12467.251964882136, 8957.462552246887, 9145.725628231297, 9163.541822908828, 9189.954050229706, 9197.816043679222, 6240.594361366497, 6668.399844014062, 6885.0, 6963.512554121163, 6964.025036494996, 11432.199746017699, 11448.255863368855, 11582.6525204244, 11685.707857303636, 11702.941485292518, 9414.67048653294, 11211.439428028729, 11316.15128865322, 11834.0, 11940.164188506722, 8471.49171049739, 8512.191341822765, 8652.134786521297, 8678.392512524062, 8684.565771711515, 9504.097110774464, 9726.319157658896, 9793.51032448389, 9795.902040979785, 9798.65704700332, 8346.582670866312, 8498.192671696179, 8552.144785521397, 8754.25588824957, 8763.036066032604, 8024.0772311184965, 8250.834983300181, 8331.583420828812, 8435.24082832548, 8741.431806932529, 7710.267524585155, 7795.41534384912, 7854.72508462201, 7893.17121702213, 7927.207279272026, 5426.918596221139, 5480.123180291134, 5500.257465242141, 5517.586181036519, 5729.799457018984, 5714.20001199824, 5729.742161602821, 5758.280214973129, 5797.072468405035, 5846.152307915243], "value": 4913.008261995917, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.88387832015434, 355.4313099041534, 360.85746130278557, 362.77978769928046, 369.20436459429936, 5553.500184983351, 5593.272874526306, 5617.803376881805, 5620.353659329168, 5634.40838711935, 5188.247704082905, 5260.605454590902, 5400.270963419937, 5447.346318441782, 5533.557315414767, 4320.395144679747, 4326.84856030039, 4337.587929146726, 4348.5216626171095, 4366.432363792703, 4337.240982828008, 4354.80403381847, 4355.956440435587, 4395.230834603932, 4398.296272596385, 4154.875353739392, 4253.8723838284905, 4266.338717498777, 4289.892752681192, 4338.566143385652, 4285.635720963722, 4292.785360731982, 4302.225599392108, 4316.11519638475, 4331.245845432621, 3795.3737633290475, 3802.657760801541, 3809.961900381016, 3814.618538146191, 3816.122291872857, 5227.3465816772905, 5299.920501192485, 5314.309139811839, 5351.143816989302, 5359.14253719403, 4894.461609222983, 4901.426750955777, 4908.533689299511, 4909.165441874889, 4917.336159618441, 5625.662460252398, 5682.323714504231, 5702.0, 5704.914426283609, 5715.371309155989, 3898.7660740355386, 4085.997861048095, 4132.710710250284, 4178.331466965271, 4179.226843034048, 5973.282156618728, 5974.402559744034, 5974.701264936779, 5981.132735753292, 5984.311804142536, 4187.874363769091, 4189.706720529594, 4211.684123690736, 4212.831486740496, 4249.91500170001, 4161.771102589387, 4226.8798768325805, 4230.857668429507, 4255.0426154115075, 4283.0, 4828.903421931542, 4842.564169224786, 4844.273358996179, 4861.246506791453, 4890.462049174588, 5793.8551536211935, 5845.503132233808, 5852.36643510422, 5861.856937897154, 5877.206577112035, 1464.5899148238511, 1486.2865824404346, 1493.57433131557, 1506.412499125342, 1508.7887695722607, 935.2237642756496, 947.6920000999703, 953.4422362917633, 977.2524019125375, 982.9901700982898, 4921.753912304442, 4936.790486330872, 4958.628102892298, 4972.428170760337, 4990.525900039491, 5107.846764597068, 5234.7959969207295, 5240.868978275574, 5292.814751483679, 5332.6000549958135, 4695.0, 4975.9847261859, 5018.247262910592, 5040.0, 5065.594752419797, 2755.2836262571473, 2768.543190373606, 2777.777777777773, 2783.6937936826935, 2785.206216228365, 5372.597055220874, 5375.924815037009, 5378.005069062236, 5409.0, 5412.539934105566, 5005.874853128701, 5276.577873770166, 5313.654612450178, 5328.653637513549, 5380.498068398214, 5350.51845333914, 5393.514583687408, 5403.918941215885, 5443.911217756386, 5451.427600102012, 5254.579633629377, 5266.577374407815, 5268.2361057647195, 5272.314599102246, 5283.811142492908, 4957.851264462072, 5029.968856384506, 5166.225066240094, 5169.7673604687125, 5193.896122077613, 5362.158056230204, 5365.96093484489, 5371.650842695212, 5401.648892821954, 5406.24312596229, 3944.566097729305, 3959.2081583683953, 3977.2244412339314, 3977.7016723745255, 4097.877063687974, 5542.75057622416, 5804.303483581997, 5874.0, 5883.676397798162, 5887.4406931341455, 5696.629719068247, 5715.342735585295, 5717.799877004285, 5726.141078838207, 5750.194972703741, 5495.422980586981, 5763.5965482417505, 5768.451997060272, 5775.566832487664, 5801.419858014165, 5744.678723893444, 5778.219940308005, 5791.7683292667825, 5801.071828507418, 5818.2436283282905, 5825.766969321349, 5863.560232982458, 5866.149408335682, 5867.471927526372, 5867.765289388377, 5423.322084739408, 5665.065264231355, 5684.0, 5706.201131841629, 5743.770249189985], "value": 4254.119266324456, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.228984754331, 850.506706110456, 860.3246451535542, 900.9189172974433, 904.0642934562729, 16703.66592668146, 17908.0, 17929.372471963452, 17932.103394830254, 17937.0, 16155.0, 16358.7546186807, 16669.24988375523, 17242.482725518254, 17384.478465646018, 729.5440349781387, 875.492214515581, 901.6438506789814, 903.7153296711548, 905.1446383167876, 852.842224188524, 883.514067263006, 885.7696998780332, 887.0375642427973, 890.8084761776216, 938.7934654376027, 939.318993729544, 939.7650587353162, 939.8261321655516, 939.8825146856643, 761.3528500774353, 931.487681775021, 931.7903471718843, 933.4212788071371, 934.6681927915594, 661.5501459007872, 682.0451368084672, 684.8630273945231, 686.6429456682547, 692.5256199503357, 15867.365305387772, 15952.0, 15990.0, 16003.0, 16005.91997040019, 14546.199959002251, 15013.849861501354, 15361.769573456408, 15387.53837384869, 15543.766843497357, 14487.899254608128, 14544.545545445371, 14554.927225363912, 14584.1978691173, 14925.402983880631, 4792.9760351198365, 4827.203511420611, 4905.435874874363, 4906.852794416173, 4937.80248790048, 83457.0, 83892.58053709692, 84648.99999999939, 85202.0, 87963.56018219932, 11998.400079995965, 12149.271043737228, 12153.149279550347, 12176.0, 12252.571160009356, 22534.21130260433, 23114.1910033148, 23129.306120816404, 23170.7682923172, 23176.65235021476, 9053.68312109073, 9218.400803947721, 9380.8592871107, 9467.337286390024, 9559.378640388353, 5209.114450543341, 5423.942331245448, 5453.727313634382, 5545.750441230154, 5564.749586268546, 3784.526934133233, 3935.842566297316, 3945.151792364655, 3948.4077388391397, 3950.20995800841, 724.3336130759714, 746.5893758432949, 747.491705640159, 756.3306473770753, 765.4527013185599, 721.1346384338815, 775.83707421441, 810.9107998120202, 813.943023988327, 843.9620217090246, 10662.840057399146, 10767.300125491818, 11359.663241302464, 11460.484278207501, 11629.941850290694, 700.9964950175319, 721.7906807026009, 740.6111791309605, 782.338923609549, 811.7402431222071, 4901.877453063702, 5236.214567814858, 5243.397009343898, 5281.524662780368, 5305.336832895888, 72252.0, 75283.62358188174, 121782.391088044, 130358.0, 139947.30026349804, 113132.0, 117951.0, 118666.4066679661, 119638.4018079904, 119786.29805066546, 101587.49206253921, 102783.48608256911, 103982.48008760059, 104190.47904760428, 104603.0, 9636.807263854544, 10107.191424686154, 10133.949330253301, 10149.188064954638, 10248.948755256177, 60833.391666082774, 60839.0, 61215.38784612097, 61822.381776183465, 65755.34244657493, 87658.56170719105, 92267.53866230627, 92441.53779231061, 92480.53759731159, 93433.53283233813, 12854.935725321313, 13205.603831885059, 13235.801462978256, 13238.867611323763, 13409.664758380728, 15092.471763488225, 15107.169105698853, 15213.847861521243, 15319.693606128038, 15527.37890484368, 14007.579772606838, 14154.504592339219, 14363.64090897694, 14415.711685766435, 15080.245987700351, 13953.302334883418, 13984.300784960915, 14267.357968891218, 14295.285235737963, 14427.134371937716, 13902.860971390157, 14085.295735212994, 14247.928760356546, 14851.257437127884, 15037.0, 11400.258983166068, 11914.642560723192, 12067.818982715442, 12162.452689629165, 12572.434240459384, 11825.290482571074, 11835.408229588658, 12042.0, 12135.453904574491, 12169.452374642986], "value": 8353.648106807253, "unit": "score"}], "name": "dromaeo_css", "value": 7642.371645507804, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 110.0, 111.0, 110.0, 110.0, 109.0, 110.0, 109.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 118.0, 169.0, 116.0, 119.0, 158.0, 119.0, 158.0, 124.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 162.0, 161.0, 161.0, 162.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 123.0, 77.0, 76.0, 76.0, 75.0, 96.0, 74.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 113.0, 84.0, 83.0, 83.0, 124.0, 83.0, 84.0, 122.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 52.0, 51.0, 54.0, 51.0, 50.0, 48.0, 49.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 72.0, 73.0, 76.0, 78.0, 73.0, 82.0, 77.0, 73.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 131.0, 129.0, 116.0, 120.0, 127.0, 119.0, 159.0, 127.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 159.0, 154.0, 154.0, 157.0, 154.0, 153.0, 155.0, 154.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 61.0, 63.0, 67.0, 62.0, 64.0, 61.0, 61.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1470.7000000000003, "alertThreshold": 2.0}]} 07:56:22 INFO - Return code: 0 07:56:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:56:22 INFO - # TBPL SUCCESS # 07:56:22 INFO - Running post-action listener: _resource_record_post_action 07:56:22 INFO - [mozharness: 2016-08-09 14:56:22.509146Z] Finished run-tests step (success) 07:56:22 INFO - Running post-run listener: _resource_record_post_run 07:56:22 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327725056; Read time: 12; Write time: 242060 07:56:22 INFO - TinderboxPrint: CPU usage
13.0% 07:56:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:56:22 INFO - TinderboxPrint: I/O write bytes / time
327,725,056 / 242,060 07:56:22 INFO - TinderboxPrint: CPU idle
8,257.6 (87.0%) 07:56:22 INFO - TinderboxPrint: CPU user
1,191.2 (12.5%) 07:56:22 INFO - TinderboxPrint: Swap in / out
0 / 0 07:56:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79384576; Read time: 0; Write time: 68724 07:56:22 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248340480; Read time: 0; Write time: 173336 07:56:23 INFO - Running post-run listener: _upload_blobber_files 07:56:23 INFO - Blob upload gear active. 07:56:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:56:23 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 07:56:23 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'] 07:56:23 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 07:56:23 INFO - (blobuploader) - INFO - Open directory for files ... 07:56:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:56:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:56:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:26 INFO - (blobuploader) - INFO - Done attempting. 07:56:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:56:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:56:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:27 INFO - (blobuploader) - INFO - Done attempting. 07:56:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:56:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:56:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:28 INFO - (blobuploader) - INFO - Done attempting. 07:56:28 INFO - (blobuploader) - INFO - Iteration through files over. 07:56:28 INFO - Return code: 0 07:56:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:56:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:56:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b45415e6d7486daded0b0ed56e9cb3f3bb4996536131d73e45ae822cb260486e6abddf7cf74d096619e7aea236a55bc846c9c9f90e7b1ffc529b2873914ecda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f040da8df9144e52ce18624a8a57e43d3112c0daa09b5234213d50a6e06447cd5fb7759781f8377fcf4b2285af8f65aafff7f3eeb5705447018e0fc7dfce773c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0edfc9e579eed43f5f910948f7c1394a2a153d75ce22c0ce810f5ac1b67baa6c687f900b49bf4a590adcd3aa132cc87cbb34b4ca7b217ff72a436e8469fa7494"} 07:56:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:56:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:56:28 INFO - Contents: 07:56:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b45415e6d7486daded0b0ed56e9cb3f3bb4996536131d73e45ae822cb260486e6abddf7cf74d096619e7aea236a55bc846c9c9f90e7b1ffc529b2873914ecda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f040da8df9144e52ce18624a8a57e43d3112c0daa09b5234213d50a6e06447cd5fb7759781f8377fcf4b2285af8f65aafff7f3eeb5705447018e0fc7dfce773c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0edfc9e579eed43f5f910948f7c1394a2a153d75ce22c0ce810f5ac1b67baa6c687f900b49bf4a590adcd3aa132cc87cbb34b4ca7b217ff72a436e8469fa7494"} 07:56:28 INFO - Running post-run listener: copy_logs_to_upload_dir 07:56:28 INFO - Copying logs to upload dir... 07:56:28 INFO - mkdir: /builds/slave/test/build/upload/logs 07:56:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.430591 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-09 07:56:27.163370) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 07:56:27.166785) ========= 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=52faae308a556c440401f8e5000001e8-1470746646.593634-470011628 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/1b45415e6d7486daded0b0ed56e9cb3f3bb4996536131d73e45ae822cb260486e6abddf7cf74d096619e7aea236a55bc846c9c9f90e7b1ffc529b2873914ecda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f040da8df9144e52ce18624a8a57e43d3112c0daa09b5234213d50a6e06447cd5fb7759781f8377fcf4b2285af8f65aafff7f3eeb5705447018e0fc7dfce773c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0edfc9e579eed43f5f910948f7c1394a2a153d75ce22c0ce810f5ac1b67baa6c687f900b49bf4a590adcd3aa132cc87cbb34b4ca7b217ff72a436e8469fa7494"} build_url:https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011289 build_url: 'https://queue.taskcluster.net/v1/task/eAz_XVvRQLy73OHdH6Hsuw/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/1b45415e6d7486daded0b0ed56e9cb3f3bb4996536131d73e45ae822cb260486e6abddf7cf74d096619e7aea236a55bc846c9c9f90e7b1ffc529b2873914ecda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f040da8df9144e52ce18624a8a57e43d3112c0daa09b5234213d50a6e06447cd5fb7759781f8377fcf4b2285af8f65aafff7f3eeb5705447018e0fc7dfce773c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0edfc9e579eed43f5f910948f7c1394a2a153d75ce22c0ce810f5ac1b67baa6c687f900b49bf4a590adcd3aa132cc87cbb34b4ca7b217ff72a436e8469fa7494"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 07:56:27.192769) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 07:56:27.193134) ========= 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=52faae308a556c440401f8e5000001e8-1470746646.593634-470011628 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 07:56:27.275585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 07:56:27.275937) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 07:56:27.276260) ========= ========= Total master_lag: 0.14 =========