builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1478388010.28 results: success (0) buildid: 20161103181821 builduid: d984d1bb7c314f73889b19df79c575c9 revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.283629) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.284049) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.284341) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.298799) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.299110) ========= 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=0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-05 16:20:10-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.6M=0s 2016-11-05 16:20:11 (50.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117254 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.429825) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.430154) ========= 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=0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019929 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.480588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 16:20:10.481176) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24b8f08f77565f859898b45f62d2132ccc64c6d8 --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-beta --rev 24b8f08f77565f859898b45f62d2132ccc64c6d8 --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=0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-05 16:20:11,171 truncating revision to first 12 chars 2016-11-05 16:20:11,171 Setting DEBUG logging. 2016-11-05 16:20:11,172 attempt 1/10 2016-11-05 16:20:11,172 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/24b8f08f7756?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 16:20:12,547 unpacking tar archive at: mozilla-beta-24b8f08f7756/testing/mozharness/ program finished with exit code 0 elapsedTime=1.700770 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 16:20:12.195556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:12.195880) ========= script_repo_revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:12.196285) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:12.196627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:12.205593) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-11-05 16:20:12.205889) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:20:12 INFO - MultiFileLogger online at 20161105 16:20:12 in /builds/slave/test 16:20:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:20:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:12 INFO - {'append_to_log': False, 16:20:12 INFO - 'base_work_dir': '/builds/slave/test', 16:20:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:20:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:12 INFO - 'branch': 'Mozilla-Beta', 16:20:12 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:12 INFO - 'config_files': ('talos/linux_config.py',), 16:20:12 INFO - 'default_actions': ('clobber', 16:20:12 INFO - 'read-buildbot-config', 16:20:12 INFO - 'download-and-extract', 16:20:12 INFO - 'populate-webroot', 16:20:12 INFO - 'create-virtualenv', 16:20:12 INFO - 'install', 16:20:12 INFO - 'run-tests'), 16:20:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:12 INFO - 'download_minidump_stackwalk': True, 16:20:12 INFO - 'download_symbols': 'ondemand', 16:20:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:20:12 INFO - 'tooltool.py': '/tools/tooltool.py', 16:20:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:20:12 INFO - '/tools/misc-python/virtualenv.py')}, 16:20:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:12 INFO - 'installer_path': 'installer.exe', 16:20:12 INFO - 'log_level': 'info', 16:20:12 INFO - 'log_name': 'talos', 16:20:12 INFO - 'log_to_console': True, 16:20:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:20:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:20:12 INFO - 'opt_config_files': (), 16:20:12 INFO - 'pip_index': False, 16:20:12 INFO - 'sps_profile': False, 16:20:12 INFO - 'sps_profile_interval': 0, 16:20:12 INFO - 'suite': 'dromaeojs', 16:20:12 INFO - 'system_bits': '32', 16:20:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:12 INFO - 'title': 'talos-linux64-ix-017', 16:20:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:12 INFO - 'use_talos_json': True, 16:20:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:12 INFO - 'work_dir': 'build'} 16:20:12 INFO - [mozharness: 2016-11-05 23:20:12.988532Z] Running clobber step. 16:20:12 INFO - Running pre-action listener: _resource_record_pre_action 16:20:12 INFO - Running main action method: clobber 16:20:12 INFO - rmtree: /builds/slave/test/build 16:20:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:13 INFO - Running post-action listener: _resource_record_post_action 16:20:13 INFO - [mozharness: 2016-11-05 23:20:13.448430Z] Finished clobber step (success) 16:20:13 INFO - [mozharness: 2016-11-05 23:20:13.448531Z] Running read-buildbot-config step. 16:20:13 INFO - Running pre-action listener: _resource_record_pre_action 16:20:13 INFO - Running main action method: read_buildbot_config 16:20:13 INFO - Using buildbot properties: 16:20:13 INFO - { 16:20:13 INFO - "project": "", 16:20:13 INFO - "product": "firefox", 16:20:13 INFO - "script_repo_revision": "production", 16:20:13 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:20:13 INFO - "repository": "", 16:20:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:20:13 INFO - "buildid": "20161103181821", 16:20:13 INFO - "pgo_build": "True", 16:20:13 INFO - "basedir": "/builds/slave/test", 16:20:13 INFO - "buildnumber": 5, 16:20:13 INFO - "slavename": "talos-linux64-ix-017", 16:20:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:20:13 INFO - "platform": "ubuntu64_hw", 16:20:13 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:20:13 INFO - "branch": "mozilla-beta", 16:20:13 INFO - "repo_path": "releases/mozilla-beta", 16:20:13 INFO - "revision": "24b8f08f77565f859898b45f62d2132ccc64c6d8", 16:20:13 INFO - "stage_platform": "linux64-pgo", 16:20:13 INFO - "builduid": "d984d1bb7c314f73889b19df79c575c9", 16:20:13 INFO - "slavebuilddir": "test" 16:20:13 INFO - } 16:20:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:20:13 INFO - Running post-action listener: _resource_record_post_action 16:20:13 INFO - [mozharness: 2016-11-05 23:20:13.450974Z] Finished read-buildbot-config step (success) 16:20:13 INFO - [mozharness: 2016-11-05 23:20:13.451062Z] Running download-and-extract step. 16:20:13 INFO - Running pre-action listener: _resource_record_pre_action 16:20:13 INFO - Running main action method: download_and_extract 16:20:13 INFO - mkdir: /builds/slave/test/build/tests 16:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:13 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:20:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:20:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:20:13 INFO - retry: Failed, sleeping 30 seconds before retrying 16:20:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 16:20:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:20:43 INFO - retry: Failed, sleeping 60 seconds before retrying 16:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 16:21:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:21:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 16:21:43 INFO - Caught exception: HTTP Error 404: Not Found 16:21:43 INFO - Caught exception: HTTP Error 404: Not Found 16:21:43 INFO - Caught exception: HTTP Error 404: Not Found 16:21:43 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:43 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:21:44 INFO - Downloaded 1327 bytes. 16:21:44 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:44 INFO - Using the following test package requirements: 16:21:44 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:44 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:21:44 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:21:44 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'jsshell-linux-x86_64.zip'], 16:21:44 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:21:44 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:44 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:21:44 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:21:44 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:21:44 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:44 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:21:44 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:44 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:21:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:21:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:21:44 INFO - retry: Failed, sleeping 30 seconds before retrying 16:22:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:22:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:22:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:22:14 INFO - retry: Failed, sleeping 60 seconds before retrying 16:23:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 16:23:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:23:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 16:23:14 INFO - Caught exception: HTTP Error 404: Not Found 16:23:14 INFO - Caught exception: HTTP Error 404: Not Found 16:23:14 INFO - Caught exception: HTTP Error 404: Not Found 16:23:14 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:14 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:15 INFO - Downloaded 21787606 bytes. 16:23:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:23:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:23:16 INFO - Return code: 0 16:23:16 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:16 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:23:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:23:16 INFO - retry: Failed, sleeping 30 seconds before retrying 16:23:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:23:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:23:46 INFO - retry: Failed, sleeping 60 seconds before retrying 16:24:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 16:24:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:24:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 16:24:46 INFO - Caught exception: HTTP Error 404: Not Found 16:24:46 INFO - Caught exception: HTTP Error 404: Not Found 16:24:46 INFO - Caught exception: HTTP Error 404: Not Found 16:24:46 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:46 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:24:47 INFO - Downloaded 21787606 bytes. 16:24:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:24:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:24:48 INFO - Return code: 0 16:24: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')]} 16:24:48 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:24:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:24:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:24:48 INFO - retry: Failed, sleeping 30 seconds before retrying 16:25:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 16:25:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:25:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:25:18 INFO - retry: Failed, sleeping 60 seconds before retrying 16:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 16:26:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:26:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 16:26:18 INFO - Caught exception: HTTP Error 404: Not Found 16:26:18 INFO - Caught exception: HTTP Error 404: Not Found 16:26:18 INFO - Caught exception: HTTP Error 404: Not Found 16:26:18 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:18 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:26:19 INFO - Downloaded 14413770 bytes. 16:26:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:26:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:26:19 INFO - Return code: 0 16:26:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:19 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:26:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:26:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:26:19 INFO - retry: Failed, sleeping 30 seconds before retrying 16:26:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 16:26:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:26:49 INFO - retry: Failed, sleeping 60 seconds before retrying 16:27:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 16:27:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:27:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 16:27:49 INFO - Caught exception: HTTP Error 404: Not Found 16:27:49 INFO - Caught exception: HTTP Error 404: Not Found 16:27:49 INFO - Caught exception: HTTP Error 404: Not Found 16:27:49 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:49 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:27:50 INFO - Downloaded 56799929 bytes. 16:27:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:50 INFO - mkdir: /builds/slave/test/properties 16:27:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:50 INFO - Contents: 16:27:50 INFO - build_url:https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:51 INFO - Running post-action listener: _resource_record_post_action 16:27:51 INFO - Running post-action listener: set_extra_try_arguments 16:27:51 INFO - [mozharness: 2016-11-05 23:27:51.655099Z] Finished download-and-extract step (success) 16:27:51 INFO - [mozharness: 2016-11-05 23:27:51.655332Z] Running populate-webroot step. 16:27:51 INFO - Running pre-action listener: _resource_record_pre_action 16:27:51 INFO - Running main action method: populate_webroot 16:27:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:51 INFO - '--tppagecycles', 16:27:51 INFO - '7'], 16:27:51 INFO - 'tests': ['tsvgm']}}, 16:27:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['damp', 'tps']}, 16:27:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'tests': ['damp', 'tps']}, 16:27:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['dromaeo_dom']}, 16:27:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['basic_compositor_video']}, 16:27:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['a11yr', 16:27:51 INFO - 'ts_paint', 16:27:51 INFO - 'tpaint', 16:27:51 INFO - 'sessionrestore', 16:27:51 INFO - 'sessionrestore_no_auto_restore', 16:27:51 INFO - 'tabpaint']}, 16:27:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:51 INFO - 'ts_paint', 16:27:51 INFO - 'tpaint', 16:27:51 INFO - 'sessionrestore', 16:27:51 INFO - 'sessionrestore_no_auto_restore', 16:27:51 INFO - 'tabpaint']}, 16:27:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:51 INFO - 'ts_paint', 16:27:51 INFO - 'tpaint', 16:27:51 INFO - 'sessionrestore', 16:27:51 INFO - 'sessionrestore_no_auto_restore', 16:27:51 INFO - 'tabpaint']}, 16:27:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:51 INFO - 'ts_paint', 16:27:51 INFO - 'tpaint', 16:27:51 INFO - 'sessionrestore', 16:27:51 INFO - 'sessionrestore_no_auto_restore', 16:27:51 INFO - 'tabpaint']}, 16:27:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['a11yr', 16:27:51 INFO - 'ts_paint', 16:27:51 INFO - 'tpaint', 16:27:51 INFO - 'sessionrestore', 16:27:51 INFO - 'sessionrestore_no_auto_restore', 16:27:51 INFO - 'tabpaint']}, 16:27:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['a11yr', 16:27:51 INFO - 'ts_paint', 16:27:51 INFO - 'tpaint', 16:27:51 INFO - 'sessionrestore', 16:27:51 INFO - 'sessionrestore_no_auto_restore', 16:27:51 INFO - 'tabpaint']}, 16:27:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['tsvgx', 16:27:51 INFO - 'tsvgr_opacity', 16:27:51 INFO - 'tart', 16:27:51 INFO - 'tscrollx', 16:27:51 INFO - 'cart']}, 16:27:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:51 INFO - 'tsvgr_opacity', 16:27:51 INFO - 'tart', 16:27:51 INFO - 'tscrollx', 16:27:51 INFO - 'cart']}, 16:27:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'talos_options': ['--disable-e10s'], 16:27:51 INFO - 'tests': ['tp5o']}, 16:27:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'tests': ['tp5o']}, 16:27:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'talos_options': ['--disable-e10s', 16:27:51 INFO - '--xperf_path', 16:27:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:51 INFO - 'tests': ['tp5n']}, 16:27:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:51 INFO - 'talos_options': ['--xperf_path', 16:27:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:51 INFO - 'tests': ['tp5n']}}, 16:27:51 INFO - 'talos.zip': {'path': '', 16:27:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:51 INFO - Running post-action listener: _resource_record_post_action 16:27:51 INFO - [mozharness: 2016-11-05 23:27:51.671771Z] Finished populate-webroot step (success) 16:27:51 INFO - [mozharness: 2016-11-05 23:27:51.671861Z] Running create-virtualenv step. 16:27:51 INFO - Running pre-action listener: _resource_record_pre_action 16:27:51 INFO - Running main action method: create_virtualenv 16:27:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:51 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 16:27:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:51 INFO - Using real prefix '/usr' 16:27:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:54 INFO - Installing pip.................done. 16:27:54 INFO - Return code: 0 16:27:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:54 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:27:54 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 16:27:54 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 16:27:54 INFO - Using env: {'DISPLAY': ':0', 16:27:54 INFO - 'HOME': '/home/cltbld', 16:27:54 INFO - 'LANG': 'en_US.UTF-8', 16:27:54 INFO - 'LANGUAGE': 'en_US:en', 16:27:54 INFO - 'LOGNAME': 'cltbld', 16:27:54 INFO - 'MAIL': '/var/mail/cltbld', 16:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:54 INFO - 'MOZ_NO_REMOTE': '1', 16:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:54 INFO - 'NO_EM_RESTART': '1', 16:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:54 INFO - 'PWD': '/builds/slave/test', 16:27:54 INFO - 'SHELL': '/bin/bash', 16:27:54 INFO - 'SHLVL': '1', 16:27:54 INFO - 'TERM': 'linux', 16:27:54 INFO - 'TMOUT': '86400', 16:27:54 INFO - 'USER': 'cltbld', 16:27:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:54 INFO - '_': '/tools/buildbot/bin/python'} 16:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:54 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 16:27:54 INFO - Cleaning up... 16:27:54 INFO - Return code: 0 16:27:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:54 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:27:54 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 16:27:54 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 16:27:54 INFO - Using env: {'DISPLAY': ':0', 16:27:54 INFO - 'HOME': '/home/cltbld', 16:27:54 INFO - 'LANG': 'en_US.UTF-8', 16:27:54 INFO - 'LANGUAGE': 'en_US:en', 16:27:54 INFO - 'LOGNAME': 'cltbld', 16:27:54 INFO - 'MAIL': '/var/mail/cltbld', 16:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:54 INFO - 'MOZ_NO_REMOTE': '1', 16:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:54 INFO - 'NO_EM_RESTART': '1', 16:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:54 INFO - 'PWD': '/builds/slave/test', 16:27:54 INFO - 'SHELL': '/bin/bash', 16:27:54 INFO - 'SHLVL': '1', 16:27:54 INFO - 'TERM': 'linux', 16:27:54 INFO - 'TMOUT': '86400', 16:27:54 INFO - 'USER': 'cltbld', 16:27:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:54 INFO - '_': '/tools/buildbot/bin/python'} 16:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:54 INFO - Downloading/unpacking psutil>=3.1.1 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:56 INFO - Installing collected packages: psutil 16:27:56 INFO - Running setup.py install for psutil 16:27:56 INFO - building 'psutil._psutil_linux' extension 16:27:56 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 16:27:56 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 16:27:56 INFO - building 'psutil._psutil_posix' extension 16:27:56 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 16:27:57 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 16:27:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:57 INFO - Successfully installed psutil 16:27:57 INFO - Cleaning up... 16:27:57 INFO - Return code: 0 16:27:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:57 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')]} 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:57 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')]} 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:57 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:27:57 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 16:27:57 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 16:27:57 INFO - Using env: {'DISPLAY': ':0', 16:27:57 INFO - 'HOME': '/home/cltbld', 16:27:57 INFO - 'LANG': 'en_US.UTF-8', 16:27:57 INFO - 'LANGUAGE': 'en_US:en', 16:27:57 INFO - 'LOGNAME': 'cltbld', 16:27:57 INFO - 'MAIL': '/var/mail/cltbld', 16:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:57 INFO - 'MOZ_NO_REMOTE': '1', 16:27:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:57 INFO - 'NO_EM_RESTART': '1', 16:27:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:57 INFO - 'PWD': '/builds/slave/test', 16:27:57 INFO - 'SHELL': '/bin/bash', 16:27:57 INFO - 'SHLVL': '1', 16:27:57 INFO - 'TERM': 'linux', 16:27:57 INFO - 'TMOUT': '86400', 16:27:57 INFO - 'USER': 'cltbld', 16:27:57 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:57 INFO - '_': '/tools/buildbot/bin/python'} 16:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:57 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 16:27:57 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 16:27:57 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 16:27:57 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 16:27:57 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 16:27:57 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 16:27:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:59 INFO - Installing collected packages: mozsystemmonitor 16:27:59 INFO - Running setup.py install for mozsystemmonitor 16:27:59 INFO - Successfully installed mozsystemmonitor 16:27:59 INFO - Cleaning up... 16:27:59 INFO - Return code: 0 16:27:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:27:59 INFO - Using env: {'DISPLAY': ':0', 16:27:59 INFO - 'HOME': '/home/cltbld', 16:27:59 INFO - 'LANG': 'en_US.UTF-8', 16:27:59 INFO - 'LANGUAGE': 'en_US:en', 16:27:59 INFO - 'LOGNAME': 'cltbld', 16:27:59 INFO - 'MAIL': '/var/mail/cltbld', 16:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:59 INFO - 'MOZ_NO_REMOTE': '1', 16:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:59 INFO - 'NO_EM_RESTART': '1', 16:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:59 INFO - 'PWD': '/builds/slave/test', 16:27:59 INFO - 'SHELL': '/bin/bash', 16:27:59 INFO - 'SHLVL': '1', 16:27:59 INFO - 'TERM': 'linux', 16:27:59 INFO - 'TMOUT': '86400', 16:27:59 INFO - 'USER': 'cltbld', 16:27:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:59 INFO - '_': '/tools/buildbot/bin/python'} 16:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:59 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:59 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 16:27:59 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 16:27:59 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 16:27:59 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 16:27:59 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 16:27:59 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 16:28:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:28:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:28:01 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 16:28:01 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 16:28:01 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 16:28:01 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 16:28:01 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 16:28:01 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 16:28:01 INFO - Downloading functools32-3.2.3-2.tar.gz 16:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:28:01 INFO - warning: no files found matching '*.txt' 16:28:01 INFO - no previously-included directories found matching 'build' 16:28:01 INFO - no previously-included directories found matching 'dist' 16:28:01 INFO - no previously-included directories found matching '.git*' 16:28:02 INFO - Installing collected packages: jsonschema, functools32 16:28:02 INFO - Running setup.py install for functools32 16:28:02 INFO - warning: no files found matching '*.txt' 16:28:02 INFO - no previously-included directories found matching 'build' 16:28:02 INFO - no previously-included directories found matching 'dist' 16:28:02 INFO - no previously-included directories found matching '.git*' 16:28:02 INFO - Successfully installed jsonschema functools32 16:28:02 INFO - Cleaning up... 16:28:02 INFO - Return code: 0 16:28:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:02 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:28:02 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 16:28:02 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 16:28:02 INFO - Using env: {'DISPLAY': ':0', 16:28:02 INFO - 'HOME': '/home/cltbld', 16:28:02 INFO - 'LANG': 'en_US.UTF-8', 16:28:02 INFO - 'LANGUAGE': 'en_US:en', 16:28:02 INFO - 'LOGNAME': 'cltbld', 16:28:02 INFO - 'MAIL': '/var/mail/cltbld', 16:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:02 INFO - 'MOZ_NO_REMOTE': '1', 16:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:02 INFO - 'NO_EM_RESTART': '1', 16:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:02 INFO - 'PWD': '/builds/slave/test', 16:28:02 INFO - 'SHELL': '/bin/bash', 16:28:02 INFO - 'SHLVL': '1', 16:28:02 INFO - 'TERM': 'linux', 16:28:02 INFO - 'TMOUT': '86400', 16:28:02 INFO - 'USER': 'cltbld', 16:28:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:02 INFO - '_': '/tools/buildbot/bin/python'} 16:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:28:02 INFO - Cleaning up... 16:28:02 INFO - Return code: 0 16:28:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:02 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:28:02 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 16:28:02 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 16:28:02 INFO - Using env: {'DISPLAY': ':0', 16:28:02 INFO - 'HOME': '/home/cltbld', 16:28:02 INFO - 'LANG': 'en_US.UTF-8', 16:28:02 INFO - 'LANGUAGE': 'en_US:en', 16:28:02 INFO - 'LOGNAME': 'cltbld', 16:28:02 INFO - 'MAIL': '/var/mail/cltbld', 16:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:02 INFO - 'MOZ_NO_REMOTE': '1', 16:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:02 INFO - 'NO_EM_RESTART': '1', 16:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:02 INFO - 'PWD': '/builds/slave/test', 16:28:02 INFO - 'SHELL': '/bin/bash', 16:28:02 INFO - 'SHLVL': '1', 16:28:02 INFO - 'TERM': 'linux', 16:28:02 INFO - 'TMOUT': '86400', 16:28:02 INFO - 'USER': 'cltbld', 16:28:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:02 INFO - '_': '/tools/buildbot/bin/python'} 16:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:02 INFO - Downloading/unpacking blobuploader==1.2.4 16:28:02 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 16:28:02 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 16:28:02 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 16:28:02 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 16:28:02 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 16:28:02 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 16:28:04 INFO - Downloading blobuploader-1.2.4.tar.gz 16:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:28:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:28:04 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 16:28:04 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 16:28:04 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 16:28:04 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 16:28:04 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 16:28:04 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 16:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:05 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 16:28:05 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 16:28:05 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 16:28:05 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 16:28:05 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 16:28:05 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 16:28:05 INFO - Downloading docopt-0.6.1.tar.gz 16:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:05 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:05 INFO - Running setup.py install for blobuploader 16:28:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:05 INFO - Running setup.py install for requests 16:28:05 INFO - Running setup.py install for docopt 16:28:05 INFO - Successfully installed blobuploader requests docopt 16:28:05 INFO - Cleaning up... 16:28:05 INFO - Return code: 0 16:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:05 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:28:05 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 16:28:05 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 16:28:05 INFO - Using env: {'DISPLAY': ':0', 16:28:05 INFO - 'HOME': '/home/cltbld', 16:28:05 INFO - 'LANG': 'en_US.UTF-8', 16:28:05 INFO - 'LANGUAGE': 'en_US:en', 16:28:05 INFO - 'LOGNAME': 'cltbld', 16:28:05 INFO - 'MAIL': '/var/mail/cltbld', 16:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:05 INFO - 'MOZ_NO_REMOTE': '1', 16:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:05 INFO - 'NO_EM_RESTART': '1', 16:28:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:05 INFO - 'PWD': '/builds/slave/test', 16:28:05 INFO - 'SHELL': '/bin/bash', 16:28:05 INFO - 'SHLVL': '1', 16:28:05 INFO - 'TERM': 'linux', 16:28:05 INFO - 'TMOUT': '86400', 16:28:05 INFO - 'USER': 'cltbld', 16:28:05 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:05 INFO - '_': '/tools/buildbot/bin/python'} 16:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:06 INFO - Running setup.py (path:/tmp/pip-SXA5e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:06 INFO - Running setup.py (path:/tmp/pip-OVOKZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:06 INFO - Running setup.py (path:/tmp/pip-xApwTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:06 INFO - Running setup.py (path:/tmp/pip-de6ZQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:06 INFO - Running setup.py (path:/tmp/pip-ck9TU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:06 INFO - Running setup.py (path:/tmp/pip-K2uKVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:06 INFO - Running setup.py (path:/tmp/pip-X5Toid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:06 INFO - Running setup.py (path:/tmp/pip-w16cdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:06 INFO - Running setup.py (path:/tmp/pip-RL7glC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:06 INFO - Running setup.py (path:/tmp/pip-iIwKfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:06 INFO - Running setup.py (path:/tmp/pip-mThHhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:06 INFO - Running setup.py (path:/tmp/pip-li2uKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:07 INFO - Running setup.py (path:/tmp/pip-R_cLMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:07 INFO - Running setup.py (path:/tmp/pip-oilild-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:07 INFO - Running setup.py (path:/tmp/pip-sSdMCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:07 INFO - Running setup.py (path:/tmp/pip-TRrYjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:07 INFO - Running setup.py (path:/tmp/pip-FyBVY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:07 INFO - Running setup.py install for manifestparser 16:28:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:07 INFO - Running setup.py install for mozcrash 16:28:07 INFO - Running setup.py install for mozdebug 16:28:07 INFO - Running setup.py install for mozdevice 16:28:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:28:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:07 INFO - Running setup.py install for mozfile 16:28:07 INFO - Running setup.py install for mozhttpd 16:28:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for mozinfo 16:28:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for mozInstall 16:28:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for mozleak 16:28:08 INFO - Running setup.py install for mozlog 16:28:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for moznetwork 16:28:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for mozprocess 16:28:08 INFO - Running setup.py install for mozprofile 16:28:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for mozrunner 16:28:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:08 INFO - Running setup.py install for mozscreenshot 16:28:08 INFO - Running setup.py install for moztest 16:28:09 INFO - Running setup.py install for mozversion 16:28:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:09 INFO - Cleaning up... 16:28:09 INFO - Return code: 0 16:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:09 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')]} 16:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:09 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')]} 16:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:09 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:28:09 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 16:28:09 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 16:28:09 INFO - Using env: {'DISPLAY': ':0', 16:28:09 INFO - 'HOME': '/home/cltbld', 16:28:09 INFO - 'LANG': 'en_US.UTF-8', 16:28:09 INFO - 'LANGUAGE': 'en_US:en', 16:28:09 INFO - 'LOGNAME': 'cltbld', 16:28:09 INFO - 'MAIL': '/var/mail/cltbld', 16:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:09 INFO - 'MOZ_NO_REMOTE': '1', 16:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:09 INFO - 'NO_EM_RESTART': '1', 16:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:09 INFO - 'PWD': '/builds/slave/test', 16:28:09 INFO - 'SHELL': '/bin/bash', 16:28:09 INFO - 'SHLVL': '1', 16:28:09 INFO - 'TERM': 'linux', 16:28:09 INFO - 'TMOUT': '86400', 16:28:09 INFO - 'USER': 'cltbld', 16:28:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:09 INFO - '_': '/tools/buildbot/bin/python'} 16:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:09 INFO - Running setup.py (path:/tmp/pip-oXw4rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:09 INFO - Running setup.py (path:/tmp/pip-4fRa3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:09 INFO - Running setup.py (path:/tmp/pip-7ctgS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:09 INFO - Running setup.py (path:/tmp/pip-FmRSpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:09 INFO - Running setup.py (path:/tmp/pip-CUaXoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:09 INFO - Running setup.py (path:/tmp/pip-XqnoNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:09 INFO - Running setup.py (path:/tmp/pip-9U8dvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:09 INFO - Running setup.py (path:/tmp/pip-zw207T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:09 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)) 16:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:09 INFO - Running setup.py (path:/tmp/pip-WZqIlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:10 INFO - Running setup.py (path:/tmp/pip-5bbSIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:10 INFO - Running setup.py (path:/tmp/pip-5pxg07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:10 INFO - Running setup.py (path:/tmp/pip-ixZ62E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:10 INFO - Running setup.py (path:/tmp/pip-te1CUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:10 INFO - Running setup.py (path:/tmp/pip-JPNf5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:10 INFO - Running setup.py (path:/tmp/pip-BUto_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:10 INFO - Running setup.py (path:/tmp/pip-vZ6UQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:10 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)) 16:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:10 INFO - Running setup.py (path:/tmp/pip-mHVMiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:10 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)) 16:28:10 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)) 16:28:10 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)) 16:28:10 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)) 16:28:10 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)) 16:28:10 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)) 16:28:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:10 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 16:28:10 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 16:28:10 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 16:28:10 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 16:28:10 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 16:28:10 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 16:28:12 INFO - Downloading blessings-1.6.tar.gz 16:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:12 INFO - Installing collected packages: blessings 16:28:12 INFO - Running setup.py install for blessings 16:28:12 INFO - Successfully installed blessings 16:28:12 INFO - Cleaning up... 16:28:12 INFO - Return code: 0 16:28:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:13 INFO - Reading from file tmpfile_stdout 16:28:13 INFO - Current package versions: 16:28:13 INFO - argparse == 1.2.1 16:28:13 INFO - blessings == 1.6 16:28:13 INFO - blobuploader == 1.2.4 16:28:13 INFO - docopt == 0.6.1 16:28:13 INFO - functools32 == 3.2.3-2 16:28:13 INFO - jsonschema == 2.5.1 16:28:13 INFO - manifestparser == 1.1 16:28:13 INFO - mozInstall == 1.12 16:28:13 INFO - mozcrash == 0.17 16:28:13 INFO - mozdebug == 0.1 16:28:13 INFO - mozdevice == 0.48 16:28:13 INFO - mozfile == 1.2 16:28:13 INFO - mozhttpd == 0.7 16:28:13 INFO - mozinfo == 0.9 16:28:13 INFO - mozleak == 0.1 16:28:13 INFO - mozlog == 3.3 16:28:13 INFO - moznetwork == 0.27 16:28:13 INFO - mozprocess == 0.23 16:28:13 INFO - mozprofile == 0.28 16:28:13 INFO - mozrunner == 6.12 16:28:13 INFO - mozscreenshot == 0.1 16:28:13 INFO - mozsystemmonitor == 0.3 16:28:13 INFO - moztest == 0.7 16:28:13 INFO - mozversion == 1.4 16:28:13 INFO - psutil == 3.1.1 16:28:13 INFO - requests == 1.2.3 16:28:13 INFO - wsgiref == 0.1.2 16:28:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:13 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 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:28:13 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 16:28:13 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 16:28:13 INFO - Using env: {'DISPLAY': ':0', 16:28:13 INFO - 'HOME': '/home/cltbld', 16:28:13 INFO - 'LANG': 'en_US.UTF-8', 16:28:13 INFO - 'LANGUAGE': 'en_US:en', 16:28:13 INFO - 'LOGNAME': 'cltbld', 16:28:13 INFO - 'MAIL': '/var/mail/cltbld', 16:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:13 INFO - 'MOZ_NO_REMOTE': '1', 16:28:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:13 INFO - 'NO_EM_RESTART': '1', 16:28:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:13 INFO - 'PWD': '/builds/slave/test', 16:28:13 INFO - 'SHELL': '/bin/bash', 16:28:13 INFO - 'SHLVL': '1', 16:28:13 INFO - 'TERM': 'linux', 16:28:13 INFO - 'TMOUT': '86400', 16:28:13 INFO - 'USER': 'cltbld', 16:28:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:13 INFO - '_': '/tools/buildbot/bin/python'} 16:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 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)) 16:28:13 INFO - Cleaning up... 16:28:13 INFO - Return code: 0 16:28:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, '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': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', '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 16:28:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:28:13 INFO - Using env: {'DISPLAY': ':0', 16:28:13 INFO - 'HOME': '/home/cltbld', 16:28:13 INFO - 'LANG': 'en_US.UTF-8', 16:28:13 INFO - 'LANGUAGE': 'en_US:en', 16:28:13 INFO - 'LOGNAME': 'cltbld', 16:28:13 INFO - 'MAIL': '/var/mail/cltbld', 16:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:13 INFO - 'MOZ_NO_REMOTE': '1', 16:28:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:13 INFO - 'NO_EM_RESTART': '1', 16:28:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:13 INFO - 'PWD': '/builds/slave/test', 16:28:13 INFO - 'SHELL': '/bin/bash', 16:28:13 INFO - 'SHLVL': '1', 16:28:13 INFO - 'TERM': 'linux', 16:28:13 INFO - 'TMOUT': '86400', 16:28:13 INFO - 'USER': 'cltbld', 16:28:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:13 INFO - '_': '/tools/buildbot/bin/python'} 16:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:13 INFO - Cleaning up... 16:28:13 INFO - Return code: 0 16:28:13 INFO - Running post-action listener: _resource_record_post_action 16:28:13 INFO - Running post-action listener: _start_resource_monitoring 16:28:13 INFO - Starting resource monitoring. 16:28:13 INFO - [mozharness: 2016-11-05 23:28:13.525958Z] Finished create-virtualenv step (success) 16:28:13 INFO - [mozharness: 2016-11-05 23:28:13.526377Z] Running install step. 16:28:13 INFO - Running pre-action listener: _resource_record_pre_action 16:28:13 INFO - Running main action method: install 16:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:13 INFO - Reading from file tmpfile_stdout 16:28:13 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:13 INFO - Reading from file tmpfile_stdout 16:28:13 INFO - Output received: 16:28:13 INFO - Usage: mozinstall [options] installer 16:28:13 INFO - Options: 16:28:13 INFO - -h, --help show this help message and exit 16:28:13 INFO - -d DEST, --destination=DEST 16:28:13 INFO - Directory to install application into. [default: 16:28:13 INFO - "/builds/slave/test"] 16:28:13 INFO - --app=APP Application being installed. [default: firefox] 16:28:13 INFO - mkdir: /builds/slave/test/build/application 16:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:28:27 INFO - Reading from file tmpfile_stdout 16:28:27 INFO - Output received: 16:28:27 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:27 INFO - Running post-action listener: _resource_record_post_action 16:28:27 INFO - [mozharness: 2016-11-05 23:28:27.829535Z] Finished install step (success) 16:28:27 INFO - [mozharness: 2016-11-05 23:28:27.829812Z] Running run-tests step. 16:28:27 INFO - Running pre-action listener: _resource_record_pre_action 16:28:27 INFO - Running main action method: run_tests 16:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:27 INFO - Python 2.7.3 16:28:27 INFO - Return code: 0 16:28:27 INFO - grabbing minidump binary from tooltool 16:28: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')]} 16:28:27 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 0x269a3b0>, '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 0x269b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:27 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 16:28:27 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 16:28:27 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 16:28:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:27 INFO - Return code: 0 16:28:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 16:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 16:28:27 INFO - Using env: {'DISPLAY': ':0', 16:28:27 INFO - 'HOME': '/home/cltbld', 16:28:27 INFO - 'LANG': 'en_US.UTF-8', 16:28:27 INFO - 'LANGUAGE': 'en_US:en', 16:28:27 INFO - 'LOGNAME': 'cltbld', 16:28:27 INFO - 'MAIL': '/var/mail/cltbld', 16:28:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:27 INFO - 'MOZ_NO_REMOTE': '1', 16:28:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:27 INFO - 'NO_EM_RESTART': '1', 16:28:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:27 INFO - 'PWD': '/builds/slave/test', 16:28:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:27 INFO - 'SHELL': '/bin/bash', 16:28:27 INFO - 'SHLVL': '1', 16:28:27 INFO - 'TERM': 'linux', 16:28:27 INFO - 'TMOUT': '86400', 16:28:27 INFO - 'USER': 'cltbld', 16:28:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280', 16:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:27 INFO - '_': '/tools/buildbot/bin/python'} 16:28:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 16:28:28 INFO - mozversion application_buildid: 20161103181821 16:28:28 INFO - mozversion application_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:28 INFO - mozversion application_display_name: Firefox 16:28:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:28 INFO - mozversion application_name: Firefox 16:28:28 INFO - mozversion application_remotingname: firefox 16:28:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:28 INFO - mozversion application_vendor: Mozilla 16:28:28 INFO - mozversion application_version: 50.0 16:28:28 INFO - mozversion platform_buildid: 20161103181821 16:28:28 INFO - mozversion platform_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:28 INFO - mozversion platform_version: 50.0 16:28:28 INFO - using testdate: 1478388508 16:28:28 INFO - actual date: 1478388508 16:28:28 INFO - starting webserver on 'localhost:56874' 16:28:28 INFO - SUITE-START | Running 2 tests 16:28:28 INFO - TEST-START | dromaeo_css 16:28:28 INFO - operating with platform_type : linux_ 16:28:28 INFO - Initialising browser for dromaeo_css test... 16:28:28 INFO - TEST-INFO | started process 26318 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbK6x3U/profile http://localhost:56874/getInfo.html) 16:28:31 INFO - PROCESS | 26318 | __metrics Screen width/height:1600/1200 16:28:31 INFO - PROCESS | 26318 | colorDepth:24 16:28:31 INFO - PROCESS | 26318 | Browser inner width/height: 1024/697 16:28:31 INFO - PROCESS | 26318 | __metrics 16:28:32 INFO - PROCESS | 26318 | 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" 16:28:32 INFO - PROCESS | 26318 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:33 INFO - TEST-INFO | 26318: exit 0 16:28:33 INFO - Browser initialized. 16:28:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:33 INFO - TEST-INFO | started process 26403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbK6x3U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:45 INFO - PROCESS | 26403 | RSS: Main: 144760832 16:28:45 INFO - PROCESS | 26403 | 16:29:35 INFO - PROCESS | 26403 | 1478388575925 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] 16:31:44 INFO - PROCESS | 26403 | Cycle 1(1): loaded http://localhost:56874/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56874/tests/dromaeo/cssquery-ext.html) 16:31:45 INFO - PROCESS | 26403 | RSS: Main: 240529408 16:31:45 INFO - PROCESS | 26403 | 16:34:48 INFO - PROCESS | 26403 | Cycle 1(1): loaded http://localhost:56874/tests/dromaeo/cssquery-ext.html (next: http://localhost:56874/tests/dromaeo/cssquery-jquery.html) 16:34:48 INFO - PROCESS | 26403 | RSS: Main: 232214528 16:34:48 INFO - PROCESS | 26403 | 16:37:50 INFO - PROCESS | 26403 | Cycle 1(1): loaded http://localhost:56874/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56874/tests/dromaeo/cssquery-mootools.html) 16:37:51 INFO - PROCESS | 26403 | RSS: Main: 179064832 16:37:51 INFO - PROCESS | 26403 | 16:40:53 INFO - PROCESS | 26403 | Cycle 1(1): loaded http://localhost:56874/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56874/tests/dromaeo/cssquery-prototype.html) 16:40:53 INFO - PROCESS | 26403 | RSS: Main: 220921856 16:40:53 INFO - PROCESS | 26403 | 16:43:56 INFO - PROCESS | 26403 | Cycle 1(1): loaded http://localhost:56874/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56874/tests/dromaeo/cssquery-yui.html) 16:43:56 INFO - PROCESS | 26403 | RSS: Main: 176906240 16:43:56 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | Cycle 1(1): loaded http://localhost:56874/tests/dromaeo/cssquery-yui.html (next: http://localhost:56874/tests/dromaeo/cssquery-dojo.html) 16:46:59 INFO - PROCESS | 26403 | RSS: Main: 266928128 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | __start_tp_report 16:46:59 INFO - PROCESS | 26403 | _x_x_mozilla_page_load 16:46:59 INFO - PROCESS | 26403 | _x_x_mozilla_page_load_details 16:46:59 INFO - PROCESS | 26403 | |i|pagename|runs| 16:46:59 INFO - PROCESS | 26403 | |0;dojo.html;3194.504851747979;3198.9200269993235;3214.3892660394517;3229.8546565404554;3253.2192273854253;5540.196671482632;5624.690642014688;5636.0982242841155;5637.436256374371;5641.04102302608;5581.8604534886645;5663.292088488939;5663.9433605663935;5681.034224181889;5715.171300161474;5093.541581257686;5105.18317069269;5167.044096842077;5178.430372659004;5190.558802501773;4995.200767877122;5043.067032598981;5195.506426889439;5223.582113430935;5246.134387826023;5050.823221187241;5063.139266324715;5092.261622064799;5118.257852611369;5121.590272778169;5074.771635276422;5124.026434977342;5224.39919409269;5228.764705588258;5248.65883717561;4879.487653796371;5089.618278629117;5102.6428150029515;5157.226416037587;5170.612204084671;5430.429804870471;5430.647007944471;5450.100733378988;5456.61803673743;5539.944600554023;5874.030784920483;5916.142159386908;5970.97014514929;5971.402859713994;5972.313183983854;5859.355470898197;5962.046072628402;5997.790077347272;6009.75960961561;6014.127951447016;5605.691686957216;5630.887382252414;5662.065759149694;5693.857653558653;5697.743601537941;6159.0413292293015;6193.628382297076;6204.193454850841;6206.72069756862;6207.193064901534;5774.711264436845;5862.208601838783;5905.202797622352;5915.70421478933;5940.61386009908;6069.2446087983335;6069.666168360783;6074.149619053336;6082.665453400017;6103.35914728956;6018.668973206516;6020;6030.4572588467245;6035.36628653985;6048.243969503812;5787.797427090031;5911.497522710618;5917.822465326047;6016.278046634432;6016.819495415057;4616.60758835496;4620;4621.399218101626;4623.16782979067;4634.142683603544;4079.7756123413496;4107.445494858216;4119.691023173274;4134.751914885116;4147.875563733093;5743.8564035899435;5750.367459579443;5750.654960702372;5751.166080918243;5777.653340799566;6075.392460753889;6082.75668973245;6085.482733967662;6087.634741915499;6087.726052327567;5543.944560554423;5612.719364031782;5647.209390685307;5657.094864821609;5736.942630573724;5199.168133098761;5199.55803756685;5203.661761985459;5220.869478262998;5231.529162375405;6177.135201071854;6201.224846894138;6233.252009758858;6241.375862413723;6242.406971337716;5909.143174239625;5948.553858460719;5954.374790647093;5981.730822112929;6008.849778755566;6190;6191.535634827496;6210.472109870531;6211.254649442096;6219.222597175353;5885.117232415172;5895.145203945318;5900.409959004065;5901.262342207224;5933.940660593339;5796.333421656564;5951.375105614022;6006.429389208019;6007.4893634039845;6025.276966764016;5899.115132730125;5944.286685597756;5946;5954.553408494294;5969.582129250911;5896.439838215363;5949.196858424058;5951.375105613848;5955.910661339996;5959.791407300723;5651.632643878299;5805.767769289181;5806.332271788799;5855.912161317498;5896.970515147397;5964.940350596438;5976.103584462366;5976.402359763989;5978.4021597839865;6011.87976240464;5808.186853840551;5837.066069428871;5845.3570107289015;5847.707614619167;5875.588708790344 16:46:59 INFO - PROCESS | 26403 | |1;ext.html;597.9850503737406;629.3297638015513;657.2901266632036;660.597035808157;673.4612310151878;34541;37905;46108.76945615275;46643.30035049477;46679.53320466794;47686.76156619221;48149.27776083362;48207.27689084667;48238.27642585364;48248.758756206254;8449.028361738418;9097.499637519933;9234.86147707785;9249.768755781091;9253.491057991807;10926.672199833978;11174.385408802513;11176.552937882476;11491.770164596745;11492.425378731072;11247.100231981463;11328.41402203688;11337.603183888565;11339.43302834859;11350.60272890449;11200;11258.605948791836;11272.492737826817;11285.322880627147;11287.717807054807;11119.388433636146;11183.217174797768;11183.944080279627;11188.552457901675;11189.608363707313;44733;44784;44877.32684009743;45162.09675806498;45164;3845.5577608574813;3914.4911161549103;3974.841006359742;4008.2584721826556;4390.780460976938;33906.660933390594;33948.66051339504;34024.31951360959;34424.65575344239;34504;4289.806958686867;4387.319965405367;4421.093675796454;4506.842260520899;4523.208438523278;389517;393769.03115484526;395103;396615;397197;68715.65642171857;69439;69445.30554694489;69543.65228173826;69623.65188174027;39073.41389879154;39873.80063099724;40020.59979400227;42172;42175.7891210542;19199.424017279503;19405.2237910482;19408.932508712158;19410.12654430554;19412.708809367872;12151.331676757687;14148.434062637585;14154.85845141556;14161.291935403395;14669.119852808866;20295.391138265877;21082.683759743617;21128.471788205417;21189;21406.143754249966;7439.962800186073;7486.176520582731;7487.7004919803685;7650.73222437212;7805.843883122419;1087.1574529739514;1099.7470581766238;1131.4229742831214;1172.191188080222;1179.3926128044063;33027.83486082554;34831.30337393238;35263;35379.469307960404;35468.822655887074;1180.3744015671707;1194.7491026884452;1201.4293210724836;1217.403472298585;1230.932298723561;18598.81401185998;18692.252309907723;18754;18767.624647507248;18987.145578449003;621562;626013;627369;627697;628453;64521.67739161274;64631.67684161549;64758;64796.35203647998;64873.35126648673;1357.3688234970787;1443.9205843678487;1454.178389210093;1476.1364601707905;1486.1009089500913;32630.51054234141;36358.454623181206;36377.45357824927;36495.63504364922;36647.45028824516;55347.72326138504;59814;61084.38915610787;61157.69421152866;67743;569683;576122;576226;577570;577753;40530.59469405386;45395.773021134686;45567.088658227316;46096;48870.75564622154;52178.73910630422;52472.47527524829;52641.21038184507;52646.210306846195;52670.73664631806;44281.778591106835;44720.32919506145;45226.652812053726;45715;45962;43343.56656433521;44380.334294984954;44819.86260961701;45611.77194114008;45906.770466148795;45114.548854511035;45787.77106114448;46051.07897842091;46294.76852615715;47833.52166478291;38136.237275253785;41167.35330586743;41508;42055.57944420639;42153.36769948392;30158.095257142322;30372.696273036985;30433.847830760704;30455.543166852072;30577.08268751941 16:46:59 INFO - PROCESS | 26403 | |2;jquery.html;407.34824281150145;410.41925675169637;413.74761395548717;428.90135268888156;440.4428398076434;19236.13437395317;20850.165993360246;20856.165753369867;21025.789742102574;21381.57236855262;15744.527664170064;19700.11349489273;19943.501412464695;20034.799652003512;20811.68782468264;5214.0614689356;5408.756605952742;5411.323584551931;5413.350397952251;5413.64811287267;5320.840374788762;5372.382176049765;5373.328333958255;5377.838664840061;5592.916106258409;5666.348369937448;5666.348369937448;5672;5674.2339784129235;5677.205191273225;5502.99999999998;5729.39841316662;5733.082706766939;5803.187553742479;5807.680577568232;5594.748236329375;5654.943450565524;5666.886662266773;5671.489565939044;5688.089905615082;15456.459023934109;15591;15598.454054108166;18031.098445077692;24483.143089991943;2430.829841911067;2440.2313271319654;2486.8010559155223;2584.1343150044568;2913.46100971321;10074.69775906724;10098.596056157745;10211.029952154533;10238.027387398186;10400.79198416035;1921.2699174313811;2032.5325175209637;2039.9388018359325;2051.712760213571;2082.822960048398;86380.5680971591;86853.13146868577;87953.63955349254;90106;90478;10754.677359679223;10938.835917461085;11186.38474883873;11642.185047046625;11695.473703683185;16872.91563542199;17042.233099510304;17049.573760656083;17051.829481705023;17088;6700.798976030727;6704.530682852251;6706.222850944504;7747.690092396354;8920.687775927941;8043.356531477468;8626.611802468778;8690.304775617935;8808.779780505538;8846.424982376126;4542.227821270392;4579.198640237945;4952.356193694796;5012.473690262543;5169.431362550116;1894.535838719495;1910.6942889137672;1917.8561607879465;1965.6068786242809;1994.7107669387858;1050.3172937590627;1065.6217042949797;1074.908632766208;1121.9439028048728;1150.4362862197463;20558.177672892918;20781.896090519753;21154.78845211559;21498.78501214999;21600.459988500414;1045.1220974381556;1045.8274384726587;1070.8714954205545;1078.9946050269855;1080.438172150493;11004.39475828821;11212.383318917375;11436.599719009795;11437.885621143849;11486.712832179095;553296;555020;563865;578840;582373;93541.5322923381;96672;96674.51662741641;97726.51136744412;109175;1066.7759770448138;1068.0387651113904;1085.918556108295;1113.4544073403974;1126.5212284778936;17800.46598602044;18182.45452636423;18761.812381876007;18800.52998675044;19117.713234301777;31241.843790780902;41397.79301103475;44959.77520112378;46591.534084660074;46606.76696616631;58967.41032589619;63107;67539;69603.65198173976;71729.6413517929;9041.95479022627;9067.274618030673;9161.816763664823;9591.664291749756;9803.607855685692;11548.711282218012;14182.432702691776;14502;14622.341994610055;14735.705285894437;12231.571894983634;12247.632571022883;12393.876061239273;12718.300493472578;12904.225746455242;11903.345316007702;12123.515059397525;12551.93724031374;12636.620901372973;12846.357682116044;12319.69200769988;12479.563215287784;12497.687557810763;12604.432800523815;12749.107562470537;9367;9383.155516003593;9495.43027418357;9541.379810312308;9741.318107732392;11136.220464567403;11440.370779606868;11738.882611173778;11951.163418560618;12505 16:46:59 INFO - PROCESS | 26403 | |3;mootools.html;1420.5312246958504;1533.923303834808;1549.5041586692257;1715.322447633185;1805.3230038735473;8249.917500824997;8649.956750216248;8678.783030424243;8947.284217262604;8961.820763584725;8781.385303028774;8892.510911899843;8895.288376929846;8912.197902188802;8913.777155571113;2418.226167626356;2461.1509029384833;2466.3217615155886;2466.7409921958206;2468.753124687526;2384.253947985356;2428.757124287566;2430.513897220554;2432.3189506937997;2441.963370549443;2389.569877422063;2408.2534414331526;2411.6623672685837;2414.420539070628;2423.103451722865;2355.846869953456;2362.0669835415;2366.6095094309417;2380.178838300792;2405.2543711449507;2352.1414683640346;2352.694149760537;2354.540864531417;2357.116081469449;2358.2099996501192;8648.697295594624;8723.25852302555;8872.955635221848;8876.733697989072;8878.378513504058;5294.894102117974;5387.865303367447;5418.810341638064;5424.403315635277;5440.537554307889;3540.203454222805;3545.840437180333;3579.176789338441;3599.6400359964055;3606.549181352331;1175.341808587194;1194.152151972101;1227.0122551346162;1257.3650306595202;1260.4769020856425;12236;12259.877401225962;12294.20087694306;12352.567660131852;12365.69085772854;4387.605115539617;4469.351943968107;4573.63410927125;4630.74530900797;4675.906481870412;9718.95140524307;9743.025697430201;9748.171405430618;9754.561044753003;9758.170555502862;5356.7857285708915;5365;5370.4361042090795;5371.086915224421;5379.058664733656;4144.33690609501;4406.272964960745;4525.230710779109;4897.069556784199;5001.9499805002215;3780.7920564369224;3835.194609132058;3871.7289789715014;3932.8820135395986;3941.428492868575;1162;1179.1215544419463;1181.5332943487274;1183.3136780667191;1192.5706745571588;1000.6497725795913;1004.7237009822358;1005.7385079879137;1016.517154351677;1024.3853687787268;5645.661260324394;5774.37782127596;5803.622764520293;5959.612625179349;6018.638881667114;434.4569288389513;440.3153096934298;455.0534887434129;465.54144167994497;471.89382388962895;4220.282551966173;4260.531341552427;4264.317709166518;4270.380794784739;4288.142371525707;10774.78450430987;11085.113190944705;11140.777184456265;11191;11192.216544841946;10115;10615.52230149645;10791.78416431667;10875.184361172944;10880.29278096937;8092.190780921861;8303.875441868377;8309.086000540057;8309.25216730485;8322.16778322175;5965.194698715792;6009.36901625323;6207.565470417027;6209.96895015522;6296.6851657417865;10010.199184065403;10052.949735251277;10058.8491172631;10090.747731306776;10154.39073655583;9741.220702343653;9914.603415863285;9991.800163996533;10096.141827944706;10389.324693894872;7878.251566101211;7968;7997.520148791091;8020.518768873653;8083.959580202061;8672.78318042054;8748.475091494276;8754.343424243334;8755.693550725695;8767.430117042371;7797.142314345304;7810.921890781019;7838.333741631797;7876.72431464896;8219.506829590004;7635.618219089134;7638.694452222071;7699.422543309386;7744.070711514429;7893.092294386219;6989.7553585624255;7069.787906362817;7072.646367681698;7077.858442831011;7083.185433675193;5095.337606111182;5130.461301563282;5224.190250511177;5235.528802407802;5457.61796674229;5337.653052551572;5383.380911195262;5441.129419292894;5444.26502422168;5449.840764331273 16:46:59 INFO - PROCESS | 26403 | |4;prototype.html;342.24022669673315;352.89060391278707;353.067900742041;358.625236627724;361.37121408748766;5551.2228288039705;5555.916661250074;5566.471185237406;5577;5577.832665020051;5233.528982391584;5345.49217824307;5496.4503549645015;5522.723863806814;5554.278173766135;4310.245707001278;4318.395424640553;4330.372096046071;4345.869623911288;4353.238183317907;4323.762193079379;4331.133773245348;4332.176886391591;4336.720667403108;4347.673924455663;4060.20825938942;4234.364845273203;4267.423897773808;4307.320145143381;4313.482382114151;4235.68232382571;4238.131183107455;4239.682023848224;4247.362895565674;4251.851185208534;3722.9255414891823;3723.757955732896;3723.94414083789;3742.401215805458;3750.606186350421;5166.928476268121;5371.543418809406;5383.327084114486;5383.703896285702;5387.595930305203;4892.119418504668;4931.778069986859;4935.605151587864;4940.975295123538;4948.826791062295;5739.483446489836;5742.799002034951;5749.568782341341;5757.186486256857;5759.308882934033;3977.1846771411792;4145.481814773153;4185.142045880587;4185.414042034117;4190.832366705328;5948.698472347845;5987.311459182206;5999.610025348338;6018.187544681456;6027.608205466675;4266.658667306608;4275.315949448073;4280.957190428118;4299.269124248885;4299.419578356882;4174.540800511942;4370.366296886934;4411.073674528321;4423.248047831877;4424.11517696462;4946.159152944008;4953.331300274489;4963.553280204726;4969.577585905166;4975.850724478271;5833.7083145842535;5865.472107510259;5872.794452194153;5874.383189765013;5890.673184092259;1402.0886423824595;1452.992735036318;1501.6396064944336;1502.6694127291978;1516.9014014089048;985.9992108010375;1015.4465816130227;1016.339379403379;1017.7455636090978;1022.1873610479687;5589.189567512688;5596.972015139898;5661.631993920382;5662.2255095003975;5698.430156984268;5621.662700237999;5893.145493903446;5898;5929.140274660236;5955.166276721263;5565.220869078333;5589.217509548666;5597.552195824405;5598;5650.74571644277;3133.373325334942;3142.6543080261154;3150.322680623677;3151.054683594912;3160.5101209312593;5886.793962211302;6010.879782404328;6025.728842202111;6038.6678732670125;6075.513958883367;5922.911156332659;5938.851528711731;5944.702764861826;5951.851203719855;5966.224390829251;5749.655020698771;5974.731137098841;6047.485963693048;6052.425019623217;6070.2412198475195;5772.942270577409;5818.679972601548;5820.079373806549;5842.532597392284;5845.18167456565;5749.252597162509;5775.133729940542;5784.2769653793275;5795.015566983091;5812.3025236971835;5866.090755932837;6008.639481631116;6135.631862088289;6180.536459765445;6183.536234782499;4268.210381079511;4287.464066991626;4301.870943871689;4334.9349759752995;4337.913241735211;5865.442782935615;6100.176476175659;6107.4197951194565;6135.631862088289;6143.293521245114;5830.300363956352;5855.3266374367495;5864.507584276751;5869.976961864729;5900.262467191601;5816.47651711348;5856.502197313276;5870.53035757147;5872.500837428866;5874.706264686834;5342.973285133549;5510.559155267649;5715.428457154418;5781.971090144522;5792.218050563289;5833.737481813413;5877.588568800143;5888.411158884077;5888.705564721832;5891.232630210834;5548.611597188158;5679.545636349165;5680.801171958961;5727.627704199213;5764.942350576441 16:46:59 INFO - PROCESS | 26403 | |5;yui.html;768.4582369429552;809.5547448903102;823.7322870067226;862.6635612111281;880.3221519430037;14262.643433914145;15084.698306033873;15134;15139.621509462255;15190.088594684326;13406.396712147953;13443;13460.730785384287;13467.663308417294;13493.122947008438;705.6048612776862;840.5797101449275;841.154639587214;879.8240351929608;884.9911500885005;843.28320927212;843.7089204224533;844.1221130024767;845.4969293270525;846.4582667093065;904.1139683110556;904.3262769236927;904.6471875968377;904.6969265296134;906.4108329585756;901.9278457723366;902.4314681750456;902.8826252587187;903.1375036839835;906.5467266366817;658.4666420199653;659.4493597845805;660.191265699519;660.9985871404825;662.7614058938778;13923.30383480822;13986.580402587939;13991.58025259244;14005.509807156803;14039.85960140406;14521.12873227599;14840.480583179646;14879.776803347959;14890.627734306729;15073.547793566211;11890.940545297219;11935.820962685566;11988.520459181622;12081.57714479989;12475.376231188495;4031.23406552754;4100.528439229497;4107.507099148122;4189.329707246857;4224.345226489899;67933;70117;70368.6481567594;70535;78786.99999999943;12400;12603.764177988283;13242.139260948008;13508.527201547899;13724.245166515739;24373.75626243715;24809.751902481104;25040.373990650016;25056.624150637756;25285.115020974546;9580.520973951274;9658.323917325719;9752.951235243778;10029.849552256723;10059.446730429896;4895;5058;5075.873103172376;5179.326687530597;5212.4266330703595;3781.640744129303;3873.1672690371706;3882.301185786529;3894.084890050836;3906.609339066071;666.5500786968777;671.2414971082645;680.2483256002146;691.1740470138137;693.2409012131708;756.4175584799783;761.4289283037722;765.284459030808;768.6041688530412;785.1441928298183;9602.759931001641;10262.846057309147;10273.537690803932;10586.735331616617;10627.36235825853;726.8727972604879;738.9298016688406;747.9773322141735;769.8806684963839;789.2935822439;4555.863324100216;4875.414950205927;4905;4919.606431485473;4956.628252880976;132129.33935330453;134353;136334;137956.31021844826;140441.5955840449;82863.5856820712;114528;116585;116885.41557292159;117951;100835.49582252042;101897.49051254697;102819.48590257001;103057.48471257745;104795;9027.36808423404;9228.40015398997;9251.537423128952;9417.529123543658;9475.57359918819;59875.401245986985;62576;62579;62645.37354626395;63119.36880631135;82086.58956705178;84397;89182.10817891738;90528;92233.07766922245;12870.67823304425;13286;13632.591022269347;13651.608009102862;13735.656608584864;13359.86640133625;13522.391492382669;13653.726925461237;13822.930885345912;14354.138751674933;12330.63008109758;12563.74872502563;12861.935690321488;13051.738965220453;13874.167549947035;11722.531098755955;11835.585754498552;11999.580014699444;12083.154179207722;12300.753984920431;12663.493460261854;12816.935915320364;13067.869321306665;13120.278384688552;13557.051006429456;10134.23993200498;10196.59213631476;10356.326838755456;10514.842277365693;10746.677599672023;10430.895691042992;10643.308184967953;10998.725031874266;11135.888641113484;11216.943915280372 16:46:59 INFO - PROCESS | 26403 | __end_tp_report 16:46:59 INFO - PROCESS | 26403 | __start_cc_report 16:46:59 INFO - PROCESS | 26403 | _x_x_mozilla_cycle_collect,895 16:46:59 INFO - PROCESS | 26403 | __end_cc_report 16:46:59 INFO - PROCESS | 26403 | __startTimestamp1478389619258__endTimestamp 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | ------- Summary: start ------- 16:46:59 INFO - PROCESS | 26403 | Number of tests: 6 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | [#0] dojo.html Cycles:165 Average:5589.79 Median:5837.07 stddev:635.41 (10.9%) stddev-sans-first:608.94 16:46:59 INFO - PROCESS | 26403 | Values: 3194.5 3198.9 3214.4 3229.9 3253.2 5540.2 5624.7 5636.1 5637.4 5641.0 5581.9 5663.3 5663.9 5681.0 5715.2 5093.5 5105.2 5167.0 5178.4 5190.6 4995.2 5043.1 5195.5 5223.6 5246.1 5050.8 5063.1 5092.3 5118.3 5121.6 5074.8 5124.0 5224.4 5228.8 5248.7 4879.5 5089.6 5102.6 5157.2 5170.6 5430.4 5430.6 5450.1 5456.6 5539.9 5874.0 5916.1 5971.0 5971.4 5972.3 5859.4 5962.0 5997.8 6009.8 6014.1 5605.7 5630.9 5662.1 5693.9 5697.7 6159.0 6193.6 6204.2 6206.7 6207.2 5774.7 5862.2 5905.2 5915.7 5940.6 6069.2 6069.7 6074.1 6082.7 6103.4 6018.7 6020.0 6030.5 6035.4 6048.2 5787.8 5911.5 5917.8 6016.3 6016.8 4616.6 4620.0 4621.4 4623.2 4634.1 4079.8 4107.4 4119.7 4134.8 4147.9 5743.9 5750.4 5750.7 5751.2 5777.7 6075.4 6082.8 6085.5 6087.6 6087.7 5543.9 5612.7 5647.2 5657.1 5736.9 5199.2 5199.6 5203.7 5220.9 5231.5 6177.1 6201.2 6233.3 6241.4 6242.4 5909.1 5948.6 5954.4 5981.7 6008.8 6190.0 6191.5 6210.5 6211.3 6219.2 5885.1 5895.1 5900.4 5901.3 5933.9 5796.3 5951.4 6006.4 6007.5 6025.3 5899.1 5944.3 5946.0 5954.6 5969.6 5896.4 5949.2 5951.4 5955.9 5959.8 5651.6 5805.8 5806.3 5855.9 5897.0 5964.9 5976.1 5976.4 5978.4 6011.9 5808.2 5837.1 5845.4 5847.7 5875.6 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | [#1] ext.html Cycles:180 Average:70136.77 Median:40848.97 stddev:143936.95 (352.4%) stddev-sans-first:144246.04 16:46:59 INFO - PROCESS | 26403 | Values: 598.0 629.3 657.3 660.6 673.5 34541.0 37905.0 46108.8 46643.3 46679.5 47686.8 48149.3 48207.3 48238.3 48248.8 8449.0 9097.5 9234.9 9249.8 9253.5 10926.7 11174.4 11176.6 11491.8 11492.4 11247.1 11328.4 11337.6 11339.4 11350.6 11200.0 11258.6 11272.5 11285.3 11287.7 11119.4 11183.2 11183.9 11188.6 11189.6 44733.0 44784.0 44877.3 45162.1 45164.0 3845.6 3914.5 3974.8 4008.3 4390.8 33906.7 33948.7 34024.3 34424.7 34504.0 4289.8 4387.3 4421.1 4506.8 4523.2 389517.0 393769.0 395103.0 396615.0 397197.0 68715.7 69439.0 69445.3 69543.7 69623.7 39073.4 39873.8 40020.6 42172.0 42175.8 19199.4 19405.2 19408.9 19410.1 19412.7 12151.3 14148.4 14154.9 14161.3 14669.1 20295.4 21082.7 21128.5 21189.0 21406.1 7440.0 7486.2 7487.7 7650.7 7805.8 1087.2 1099.7 1131.4 1172.2 1179.4 33027.8 34831.3 35263.0 35379.5 35468.8 1180.4 1194.7 1201.4 1217.4 1230.9 18598.8 18692.3 18754.0 18767.6 18987.1 621562.0 626013.0 627369.0 627697.0 628453.0 64521.7 64631.7 64758.0 64796.4 64873.4 1357.4 1443.9 1454.2 1476.1 1486.1 32630.5 36358.5 36377.5 36495.6 36647.5 55347.7 59814.0 61084.4 61157.7 67743.0 569683.0 576122.0 576226.0 577570.0 577753.0 40530.6 45395.8 45567.1 46096.0 48870.8 52178.7 52472.5 52641.2 52646.2 52670.7 44281.8 44720.3 45226.7 45715.0 45962.0 43343.6 44380.3 44819.9 45611.8 45906.8 45114.5 45787.8 46051.1 46294.8 47833.5 38136.2 41167.4 41508.0 42055.6 42153.4 30158.1 30372.7 30433.8 30455.5 30577.1 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | [#2] jquery.html Cycles:180 Average:31947.56 Median:21090.29 stddev:93385.07 (442.8%) stddev-sans-first:93617.01 16:46:59 INFO - PROCESS | 26403 | Values: 407.3 410.4 413.7 428.9 440.4 19236.1 20850.2 20856.2 21025.8 21381.6 15744.5 19700.1 19943.5 20034.8 20811.7 5214.1 5408.8 5411.3 5413.4 5413.6 5320.8 5372.4 5373.3 5377.8 5592.9 5666.3 5666.3 5672.0 5674.2 5677.2 5503.0 5729.4 5733.1 5803.2 5807.7 5594.7 5654.9 5666.9 5671.5 5688.1 15456.5 15591.0 15598.5 18031.1 24483.1 2430.8 2440.2 2486.8 2584.1 2913.5 10074.7 10098.6 10211.0 10238.0 10400.8 1921.3 2032.5 2039.9 2051.7 2082.8 86380.6 86853.1 87953.6 90106.0 90478.0 10754.7 10938.8 11186.4 11642.2 11695.5 16872.9 17042.2 17049.6 17051.8 17088.0 6700.8 6704.5 6706.2 7747.7 8920.7 8043.4 8626.6 8690.3 8808.8 8846.4 4542.2 4579.2 4952.4 5012.5 5169.4 1894.5 1910.7 1917.9 1965.6 1994.7 1050.3 1065.6 1074.9 1121.9 1150.4 20558.2 20781.9 21154.8 21498.8 21600.5 1045.1 1045.8 1070.9 1079.0 1080.4 11004.4 11212.4 11436.6 11437.9 11486.7 553296.0 555020.0 563865.0 578840.0 582373.0 93541.5 96672.0 96674.5 97726.5 109175.0 1066.8 1068.0 1085.9 1113.5 1126.5 17800.5 18182.5 18761.8 18800.5 19117.7 31241.8 41397.8 44959.8 46591.5 46606.8 58967.4 63107.0 67539.0 69603.7 71729.6 9042.0 9067.3 9161.8 9591.7 9803.6 11548.7 14182.4 14502.0 14622.3 14735.7 12231.6 12247.6 12393.9 12718.3 12904.2 11903.3 12123.5 12551.9 12636.6 12846.4 12319.7 12479.6 12497.7 12604.4 12749.1 9367.0 9383.2 9495.4 9541.4 9741.3 11136.2 11440.4 11738.9 11951.2 12505.0 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | [#3] mootools.html Cycles:180 Average:5823.90 Median:4602.19 stddev:3283.92 (71.4%) stddev-sans-first:3276.45 16:46:59 INFO - PROCESS | 26403 | Values: 1420.5 1533.9 1549.5 1715.3 1805.3 8249.9 8650.0 8678.8 8947.3 8961.8 8781.4 8892.5 8895.3 8912.2 8913.8 2418.2 2461.2 2466.3 2466.7 2468.8 2384.3 2428.8 2430.5 2432.3 2442.0 2389.6 2408.3 2411.7 2414.4 2423.1 2355.8 2362.1 2366.6 2380.2 2405.3 2352.1 2352.7 2354.5 2357.1 2358.2 8648.7 8723.3 8873.0 8876.7 8878.4 5294.9 5387.9 5418.8 5424.4 5440.5 3540.2 3545.8 3579.2 3599.6 3606.5 1175.3 1194.2 1227.0 1257.4 1260.5 12236.0 12259.9 12294.2 12352.6 12365.7 4387.6 4469.4 4573.6 4630.7 4675.9 9719.0 9743.0 9748.2 9754.6 9758.2 5356.8 5365.0 5370.4 5371.1 5379.1 4144.3 4406.3 4525.2 4897.1 5001.9 3780.8 3835.2 3871.7 3932.9 3941.4 1162.0 1179.1 1181.5 1183.3 1192.6 1000.6 1004.7 1005.7 1016.5 1024.4 5645.7 5774.4 5803.6 5959.6 6018.6 434.5 440.3 455.1 465.5 471.9 4220.3 4260.5 4264.3 4270.4 4288.1 10774.8 11085.1 11140.8 11191.0 11192.2 10115.0 10615.5 10791.8 10875.2 10880.3 8092.2 8303.9 8309.1 8309.3 8322.2 5965.2 6009.4 6207.6 6210.0 6296.7 10010.2 10052.9 10058.8 10090.7 10154.4 9741.2 9914.6 9991.8 10096.1 10389.3 7878.3 7968.0 7997.5 8020.5 8084.0 8672.8 8748.5 8754.3 8755.7 8767.4 7797.1 7810.9 7838.3 7876.7 8219.5 7635.6 7638.7 7699.4 7744.1 7893.1 6989.8 7069.8 7072.6 7077.9 7083.2 5095.3 5130.5 5224.2 5235.5 5457.6 5337.7 5383.4 5441.1 5444.3 5449.8 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | [#4] prototype.html Cycles:180 Average:4874.48 Median:5571.74 stddev:1425.44 (25.6%) stddev-sans-first:1388.26 16:46:59 INFO - PROCESS | 26403 | Values: 342.2 352.9 353.1 358.6 361.4 5551.2 5555.9 5566.5 5577.0 5577.8 5233.5 5345.5 5496.5 5522.7 5554.3 4310.2 4318.4 4330.4 4345.9 4353.2 4323.8 4331.1 4332.2 4336.7 4347.7 4060.2 4234.4 4267.4 4307.3 4313.5 4235.7 4238.1 4239.7 4247.4 4251.9 3722.9 3723.8 3723.9 3742.4 3750.6 5166.9 5371.5 5383.3 5383.7 5387.6 4892.1 4931.8 4935.6 4941.0 4948.8 5739.5 5742.8 5749.6 5757.2 5759.3 3977.2 4145.5 4185.1 4185.4 4190.8 5948.7 5987.3 5999.6 6018.2 6027.6 4266.7 4275.3 4281.0 4299.3 4299.4 4174.5 4370.4 4411.1 4423.2 4424.1 4946.2 4953.3 4963.6 4969.6 4975.9 5833.7 5865.5 5872.8 5874.4 5890.7 1402.1 1453.0 1501.6 1502.7 1516.9 986.0 1015.4 1016.3 1017.7 1022.2 5589.2 5597.0 5661.6 5662.2 5698.4 5621.7 5893.1 5898.0 5929.1 5955.2 5565.2 5589.2 5597.6 5598.0 5650.7 3133.4 3142.7 3150.3 3151.1 3160.5 5886.8 6010.9 6025.7 6038.7 6075.5 5922.9 5938.9 5944.7 5951.9 5966.2 5749.7 5974.7 6047.5 6052.4 6070.2 5772.9 5818.7 5820.1 5842.5 5845.2 5749.3 5775.1 5784.3 5795.0 5812.3 5866.1 6008.6 6135.6 6180.5 6183.5 4268.2 4287.5 4301.9 4334.9 4337.9 5865.4 6100.2 6107.4 6135.6 6143.3 5830.3 5855.3 5864.5 5870.0 5900.3 5816.5 5856.5 5870.5 5872.5 5874.7 5343.0 5510.6 5715.4 5782.0 5792.2 5833.7 5877.6 5888.4 5888.7 5891.2 5548.6 5679.5 5680.8 5727.6 5764.9 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - PROCESS | 26403 | [#5] yui.html Cycles:180 Average:22730.52 Median:3827.40 stddev:34519.48 (901.9%) stddev-sans-first:34576.93 16:46:59 INFO - PROCESS | 26403 | Values: 768.5 809.6 823.7 862.7 880.3 14262.6 15084.7 15134.0 15139.6 15190.1 13406.4 13443.0 13460.7 13467.7 13493.1 705.6 840.6 841.2 879.8 885.0 843.3 843.7 844.1 845.5 846.5 904.1 904.3 904.6 904.7 906.4 901.9 902.4 902.9 903.1 906.5 658.5 659.4 660.2 661.0 662.8 13923.3 13986.6 13991.6 14005.5 14039.9 14521.1 14840.5 14879.8 14890.6 15073.5 11890.9 11935.8 11988.5 12081.6 12475.4 4031.2 4100.5 4107.5 4189.3 4224.3 67933.0 70117.0 70368.6 70535.0 78787.0 12400.0 12603.8 13242.1 13508.5 13724.2 24373.8 24809.8 25040.4 25056.6 25285.1 9580.5 9658.3 9753.0 10029.8 10059.4 4895.0 5058.0 5075.9 5179.3 5212.4 3781.6 3873.2 3882.3 3894.1 3906.6 666.6 671.2 680.2 691.2 693.2 756.4 761.4 765.3 768.6 785.1 9602.8 10262.8 10273.5 10586.7 10627.4 726.9 738.9 748.0 769.9 789.3 4555.9 4875.4 4905.0 4919.6 4956.6 132129.3 134353.0 136334.0 137956.3 140441.6 82863.6 114528.0 116585.0 116885.4 117951.0 100835.5 101897.5 102819.5 103057.5 104795.0 9027.4 9228.4 9251.5 9417.5 9475.6 59875.4 62576.0 62579.0 62645.4 63119.4 82086.6 84397.0 89182.1 90528.0 92233.1 12870.7 13286.0 13632.6 13651.6 13735.7 13359.9 13522.4 13653.7 13822.9 14354.1 12330.6 12563.7 12861.9 13051.7 13874.2 11722.5 11835.6 11999.6 12083.2 12300.8 12663.5 12816.9 13067.9 13120.3 13557.1 10134.2 10196.6 10356.3 10514.8 10746.7 10430.9 10643.3 10998.7 11135.9 11216.9 16:46:59 INFO - PROCESS | 26403 | -------- Summary: end -------- 16:46:59 INFO - PROCESS | 26403 | 16:46:59 INFO - TEST-INFO | 26403: exit 0 16:47:00 INFO - TEST-OK | dromaeo_css | took 1111837ms 16:47:00 INFO - TEST-START | kraken 16:47:00 INFO - operating with platform_type : linux_ 16:47:00 INFO - Initialising browser for kraken test... 16:47:00 INFO - TEST-INFO | started process 26597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzAQsfz/profile http://localhost:56874/getInfo.html) 16:47:03 INFO - PROCESS | 26597 | __metrics Screen width/height:1600/1200 16:47:03 INFO - PROCESS | 26597 | colorDepth:24 16:47:03 INFO - PROCESS | 26597 | Browser inner width/height: 1024/697 16:47:03 INFO - PROCESS | 26597 | __metrics 16:47:04 INFO - PROCESS | 26597 | 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" 16:47:04 INFO - PROCESS | 26597 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:05 INFO - TEST-INFO | 26597: exit 0 16:47:05 INFO - Browser initialized. 16:47:05 INFO - Running cycle 1/1 for kraken test... 16:47:05 INFO - TEST-INFO | started process 26685 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzAQsfz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:16 INFO - PROCESS | 26685 | RSS: Main: 144875520 16:47:16 INFO - PROCESS | 26685 | 16:47:19 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56874/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:20 INFO - PROCESS | 26685 | RSS: Main: 199720960 16:47:20 INFO - PROCESS | 26685 | 16:47:22 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56874/tests/kraken/driver.html?testName=audio-dft) 16:47:23 INFO - PROCESS | 26685 | RSS: Main: 184193024 16:47:23 INFO - PROCESS | 26685 | 16:47:26 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56874/tests/kraken/driver.html?testName=audio-fft) 16:47:26 INFO - PROCESS | 26685 | RSS: Main: 274911232 16:47:26 INFO - PROCESS | 26685 | 16:47:28 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56874/tests/kraken/driver.html?testName=audio-oscillator) 16:47:29 INFO - PROCESS | 26685 | RSS: Main: 181325824 16:47:29 INFO - PROCESS | 26685 | 16:47:31 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56874/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:32 INFO - PROCESS | 26685 | RSS: Main: 179924992 16:47:32 INFO - PROCESS | 26685 | 16:47:35 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56874/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:35 INFO - PROCESS | 26685 | RSS: Main: 216039424 16:47:35 INFO - PROCESS | 26685 | 16:47:39 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56874/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:39 INFO - PROCESS | 26685 | RSS: Main: 214577152 16:47:39 INFO - PROCESS | 26685 | 16:47:42 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56874/tests/kraken/driver.html?testName=json-parse-financial) 16:47:43 INFO - PROCESS | 26685 | RSS: Main: 214716416 16:47:43 INFO - PROCESS | 26685 | 16:47:44 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56874/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:45 INFO - PROCESS | 26685 | RSS: Main: 176545792 16:47:45 INFO - PROCESS | 26685 | 16:47:46 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:47 INFO - PROCESS | 26685 | RSS: Main: 184463360 16:47:47 INFO - PROCESS | 26685 | 16:47:49 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:50 INFO - PROCESS | 26685 | RSS: Main: 190144512 16:47:50 INFO - PROCESS | 26685 | 16:47:52 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:53 INFO - PROCESS | 26685 | RSS: Main: 191033344 16:47:53 INFO - PROCESS | 26685 | 16:47:56 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:56 INFO - PROCESS | 26685 | RSS: Main: 193380352 16:47:56 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | Cycle 1(1): loaded http://localhost:56874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56874/tests/kraken/driver.html?testName=ai-astar) 16:47:58 INFO - PROCESS | 26685 | RSS: Main: 244363264 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | __start_tp_report 16:47:58 INFO - PROCESS | 26685 | _x_x_mozilla_page_load 16:47:58 INFO - PROCESS | 26685 | _x_x_mozilla_page_load_details 16:47:58 INFO - PROCESS | 26685 | |i|pagename|runs| 16:47:58 INFO - PROCESS | 26685 | |0;ai-astar;110;111;120;110;111;110;110;111;111;111 16:47:58 INFO - PROCESS | 26685 | |1;audio-beat-detection;153;119;146;191;116;145;149;123;189;120 16:47:58 INFO - PROCESS | 26685 | |2;audio-dft;159;162;162;161;161;163;161;160;161;160 16:47:58 INFO - PROCESS | 26685 | |3;audio-fft;76;76;75;181;76;75;73;75;162;76 16:47:58 INFO - PROCESS | 26685 | |4;audio-oscillator;83;83;160;83;84;83;144;84;83;153 16:47:58 INFO - PROCESS | 26685 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 16:47:58 INFO - PROCESS | 26685 | |6;imaging-darkroom;149;148;148;149;148;148;149;149;149;145 16:47:58 INFO - PROCESS | 26685 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;94;95 16:47:58 INFO - PROCESS | 26685 | |8;json-parse-financial;56;53;52;53;53;52;54;54;52;53 16:47:58 INFO - PROCESS | 26685 | |9;json-stringify-tinderbox;61;61;59;60;61;60;60;60;60;61 16:47:58 INFO - PROCESS | 26685 | |10;stanford-crypto-aes;73;73;73;82;77;73;73;72;72;75 16:47:58 INFO - PROCESS | 26685 | |11;stanford-crypto-ccm;131;123;119;122;126;121;128;127;154;118 16:47:58 INFO - PROCESS | 26685 | |12;stanford-crypto-pbkdf2;153;154;153;154;151;155;155;153;157;153 16:47:58 INFO - PROCESS | 26685 | |13;stanford-crypto-sha256-iterative;64;61;61;61;62;68;61;61;67;62 16:47:58 INFO - PROCESS | 26685 | __end_tp_report 16:47:58 INFO - PROCESS | 26685 | __start_cc_report 16:47:58 INFO - PROCESS | 26685 | _x_x_mozilla_cycle_collect,1659 16:47:58 INFO - PROCESS | 26685 | __end_cc_report 16:47:58 INFO - PROCESS | 26685 | __startTimestamp1478389678528__endTimestamp 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | ------- Summary: start ------- 16:47:58 INFO - PROCESS | 26685 | Number of tests: 14 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:3.03 (2.7%) stddev-sans-first:3.16 16:47:58 INFO - PROCESS | 26685 | Values: 110.0 111.0 120.0 110.0 111.0 110.0 110.0 111.0 111.0 111.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:147.50 stddev:27.40 (18.6%) stddev-sans-first:28.92 16:47:58 INFO - PROCESS | 26685 | Values: 153.0 119.0 146.0 191.0 116.0 145.0 149.0 123.0 189.0 120.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:0.97 16:47:58 INFO - PROCESS | 26685 | Values: 159.0 162.0 162.0 161.0 161.0 163.0 161.0 160.0 161.0 160.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.50 stddev:40.84 (54.1%) stddev-sans-first:42.76 16:47:58 INFO - PROCESS | 26685 | Values: 76.0 76.0 75.0 181.0 76.0 75.0 73.0 75.0 162.0 76.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:83.00 stddev:33.57 (40.4%) stddev-sans-first:34.73 16:47:58 INFO - PROCESS | 26685 | Values: 83.0 83.0 160.0 83.0 84.0 83.0 144.0 84.0 83.0 153.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:47:58 INFO - PROCESS | 26685 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:149.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 16:47:58 INFO - PROCESS | 26685 | Values: 149.0 148.0 148.0 149.0 148.0 148.0 149.0 149.0 149.0 145.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:47:58 INFO - PROCESS | 26685 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:1.23 (2.3%) stddev-sans-first:0.78 16:47:58 INFO - PROCESS | 26685 | Values: 56.0 53.0 52.0 53.0 53.0 52.0 54.0 54.0 52.0 53.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 16:47:58 INFO - PROCESS | 26685 | Values: 61.0 61.0 59.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.00 stddev:3.09 (4.2%) stddev-sans-first:3.24 16:47:58 INFO - PROCESS | 26685 | Values: 73.0 73.0 73.0 82.0 77.0 73.0 73.0 72.0 72.0 75.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:126.50 stddev:10.38 (8.2%) stddev-sans-first:10.90 16:47:58 INFO - PROCESS | 26685 | Values: 131.0 123.0 119.0 122.0 126.0 121.0 128.0 127.0 154.0 118.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.80 Median:154.00 stddev:1.62 (1.1%) stddev-sans-first:1.69 16:47:58 INFO - PROCESS | 26685 | Values: 153.0 154.0 153.0 154.0 151.0 155.0 155.0 153.0 157.0 153.0 16:47:58 INFO - PROCESS | 26685 | 16:47:58 INFO - PROCESS | 26685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:62.00 stddev:2.66 (4.3%) stddev-sans-first:2.78 16:47:58 INFO - PROCESS | 26685 | Values: 64.0 61.0 61.0 61.0 62.0 68.0 61.0 61.0 67.0 62.0 16:47:58 INFO - PROCESS | 26685 | -------- Summary: end -------- 16:47:58 INFO - PROCESS | 26685 | 16:47:59 INFO - TEST-INFO | 26685: exit 0 16:47:59 INFO - TEST-OK | kraken | took 59498ms 16:47:59 INFO - SUITE-END | took 1171s 16:47:59 INFO - Completed test suite (00:19:31) 16:47:59 INFO - javascript benchmark 16:47:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3194.504851747979, 3198.9200269993235, 3214.3892660394517, 3229.8546565404554, 3253.2192273854253, 5540.196671482632, 5624.690642014688, 5636.0982242841155, 5637.436256374371, 5641.04102302608, 5581.8604534886645, 5663.292088488939, 5663.9433605663935, 5681.034224181889, 5715.171300161474, 5093.541581257686, 5105.18317069269, 5167.044096842077, 5178.430372659004, 5190.558802501773, 4995.200767877122, 5043.067032598981, 5195.506426889439, 5223.582113430935, 5246.134387826023, 5050.823221187241, 5063.139266324715, 5092.261622064799, 5118.257852611369, 5121.590272778169, 5074.771635276422, 5124.026434977342, 5224.39919409269, 5228.764705588258, 5248.65883717561, 4879.487653796371, 5089.618278629117, 5102.6428150029515, 5157.226416037587, 5170.612204084671, 5430.429804870471, 5430.647007944471, 5450.100733378988, 5456.61803673743, 5539.944600554023, 5874.030784920483, 5916.142159386908, 5970.97014514929, 5971.402859713994, 5972.313183983854, 5859.355470898197, 5962.046072628402, 5997.790077347272, 6009.75960961561, 6014.127951447016, 5605.691686957216, 5630.887382252414, 5662.065759149694, 5693.857653558653, 5697.743601537941, 6159.0413292293015, 6193.628382297076, 6204.193454850841, 6206.72069756862, 6207.193064901534, 5774.711264436845, 5862.208601838783, 5905.202797622352, 5915.70421478933, 5940.61386009908, 6069.2446087983335, 6069.666168360783, 6074.149619053336, 6082.665453400017, 6103.35914728956, 6018.668973206516, 6020.0, 6030.4572588467245, 6035.36628653985, 6048.243969503812, 5787.797427090031, 5911.497522710618, 5917.822465326047, 6016.278046634432, 6016.819495415057, 4616.60758835496, 4620.0, 4621.399218101626, 4623.16782979067, 4634.142683603544, 4079.7756123413496, 4107.445494858216, 4119.691023173274, 4134.751914885116, 4147.875563733093, 5743.8564035899435, 5750.367459579443, 5750.654960702372, 5751.166080918243, 5777.653340799566, 6075.392460753889, 6082.75668973245, 6085.482733967662, 6087.634741915499, 6087.726052327567, 5543.944560554423, 5612.719364031782, 5647.209390685307, 5657.094864821609, 5736.942630573724, 5199.168133098761, 5199.55803756685, 5203.661761985459, 5220.869478262998, 5231.529162375405, 6177.135201071854, 6201.224846894138, 6233.252009758858, 6241.375862413723, 6242.406971337716, 5909.143174239625, 5948.553858460719, 5954.374790647093, 5981.730822112929, 6008.849778755566, 6190.0, 6191.535634827496, 6210.472109870531, 6211.254649442096, 6219.222597175353, 5885.117232415172, 5895.145203945318, 5900.409959004065, 5901.262342207224, 5933.940660593339, 5796.333421656564, 5951.375105614022, 6006.429389208019, 6007.4893634039845, 6025.276966764016, 5899.115132730125, 5944.286685597756, 5946.0, 5954.553408494294, 5969.582129250911, 5896.439838215363, 5949.196858424058, 5951.375105613848, 5955.910661339996, 5959.791407300723, 5651.632643878299, 5805.767769289181, 5806.332271788799, 5855.912161317498, 5896.970515147397, 5964.940350596438, 5976.103584462366, 5976.402359763989, 5978.4021597839865, 6011.87976240464, 5808.186853840551, 5837.066069428871, 5845.3570107289015, 5847.707614619167, 5875.588708790344], "value": 5546.498232010836, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9850503737406, 629.3297638015513, 657.2901266632036, 660.597035808157, 673.4612310151878, 34541.0, 37905.0, 46108.76945615275, 46643.30035049477, 46679.53320466794, 47686.76156619221, 48149.27776083362, 48207.27689084667, 48238.27642585364, 48248.758756206254, 8449.028361738418, 9097.499637519933, 9234.86147707785, 9249.768755781091, 9253.491057991807, 10926.672199833978, 11174.385408802513, 11176.552937882476, 11491.770164596745, 11492.425378731072, 11247.100231981463, 11328.41402203688, 11337.603183888565, 11339.43302834859, 11350.60272890449, 11200.0, 11258.605948791836, 11272.492737826817, 11285.322880627147, 11287.717807054807, 11119.388433636146, 11183.217174797768, 11183.944080279627, 11188.552457901675, 11189.608363707313, 44733.0, 44784.0, 44877.32684009743, 45162.09675806498, 45164.0, 3845.5577608574813, 3914.4911161549103, 3974.841006359742, 4008.2584721826556, 4390.780460976938, 33906.660933390594, 33948.66051339504, 34024.31951360959, 34424.65575344239, 34504.0, 4289.806958686867, 4387.319965405367, 4421.093675796454, 4506.842260520899, 4523.208438523278, 389517.0, 393769.03115484526, 395103.0, 396615.0, 397197.0, 68715.65642171857, 69439.0, 69445.30554694489, 69543.65228173826, 69623.65188174027, 39073.41389879154, 39873.80063099724, 40020.59979400227, 42172.0, 42175.7891210542, 19199.424017279503, 19405.2237910482, 19408.932508712158, 19410.12654430554, 19412.708809367872, 12151.331676757687, 14148.434062637585, 14154.85845141556, 14161.291935403395, 14669.119852808866, 20295.391138265877, 21082.683759743617, 21128.471788205417, 21189.0, 21406.143754249966, 7439.962800186073, 7486.176520582731, 7487.7004919803685, 7650.73222437212, 7805.843883122419, 1087.1574529739514, 1099.7470581766238, 1131.4229742831214, 1172.191188080222, 1179.3926128044063, 33027.83486082554, 34831.30337393238, 35263.0, 35379.469307960404, 35468.822655887074, 1180.3744015671707, 1194.7491026884452, 1201.4293210724836, 1217.403472298585, 1230.932298723561, 18598.81401185998, 18692.252309907723, 18754.0, 18767.624647507248, 18987.145578449003, 621562.0, 626013.0, 627369.0, 627697.0, 628453.0, 64521.67739161274, 64631.67684161549, 64758.0, 64796.35203647998, 64873.35126648673, 1357.3688234970787, 1443.9205843678487, 1454.178389210093, 1476.1364601707905, 1486.1009089500913, 32630.51054234141, 36358.454623181206, 36377.45357824927, 36495.63504364922, 36647.45028824516, 55347.72326138504, 59814.0, 61084.38915610787, 61157.69421152866, 67743.0, 569683.0, 576122.0, 576226.0, 577570.0, 577753.0, 40530.59469405386, 45395.773021134686, 45567.088658227316, 46096.0, 48870.75564622154, 52178.73910630422, 52472.47527524829, 52641.21038184507, 52646.210306846195, 52670.73664631806, 44281.778591106835, 44720.32919506145, 45226.652812053726, 45715.0, 45962.0, 43343.56656433521, 44380.334294984954, 44819.86260961701, 45611.77194114008, 45906.770466148795, 45114.548854511035, 45787.77106114448, 46051.07897842091, 46294.76852615715, 47833.52166478291, 38136.237275253785, 41167.35330586743, 41508.0, 42055.57944420639, 42153.36769948392, 30158.095257142322, 30372.696273036985, 30433.847830760704, 30455.543166852072, 30577.08268751941], "value": 22465.625801457776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.34824281150145, 410.41925675169637, 413.74761395548717, 428.90135268888156, 440.4428398076434, 19236.13437395317, 20850.165993360246, 20856.165753369867, 21025.789742102574, 21381.57236855262, 15744.527664170064, 19700.11349489273, 19943.501412464695, 20034.799652003512, 20811.68782468264, 5214.0614689356, 5408.756605952742, 5411.323584551931, 5413.350397952251, 5413.64811287267, 5320.840374788762, 5372.382176049765, 5373.328333958255, 5377.838664840061, 5592.916106258409, 5666.348369937448, 5666.348369937448, 5672.0, 5674.2339784129235, 5677.205191273225, 5502.99999999998, 5729.39841316662, 5733.082706766939, 5803.187553742479, 5807.680577568232, 5594.748236329375, 5654.943450565524, 5666.886662266773, 5671.489565939044, 5688.089905615082, 15456.459023934109, 15591.0, 15598.454054108166, 18031.098445077692, 24483.143089991943, 2430.829841911067, 2440.2313271319654, 2486.8010559155223, 2584.1343150044568, 2913.46100971321, 10074.69775906724, 10098.596056157745, 10211.029952154533, 10238.027387398186, 10400.79198416035, 1921.2699174313811, 2032.5325175209637, 2039.9388018359325, 2051.712760213571, 2082.822960048398, 86380.5680971591, 86853.13146868577, 87953.63955349254, 90106.0, 90478.0, 10754.677359679223, 10938.835917461085, 11186.38474883873, 11642.185047046625, 11695.473703683185, 16872.91563542199, 17042.233099510304, 17049.573760656083, 17051.829481705023, 17088.0, 6700.798976030727, 6704.530682852251, 6706.222850944504, 7747.690092396354, 8920.687775927941, 8043.356531477468, 8626.611802468778, 8690.304775617935, 8808.779780505538, 8846.424982376126, 4542.227821270392, 4579.198640237945, 4952.356193694796, 5012.473690262543, 5169.431362550116, 1894.535838719495, 1910.6942889137672, 1917.8561607879465, 1965.6068786242809, 1994.7107669387858, 1050.3172937590627, 1065.6217042949797, 1074.908632766208, 1121.9439028048728, 1150.4362862197463, 20558.177672892918, 20781.896090519753, 21154.78845211559, 21498.78501214999, 21600.459988500414, 1045.1220974381556, 1045.8274384726587, 1070.8714954205545, 1078.9946050269855, 1080.438172150493, 11004.39475828821, 11212.383318917375, 11436.599719009795, 11437.885621143849, 11486.712832179095, 553296.0, 555020.0, 563865.0, 578840.0, 582373.0, 93541.5322923381, 96672.0, 96674.51662741641, 97726.51136744412, 109175.0, 1066.7759770448138, 1068.0387651113904, 1085.918556108295, 1113.4544073403974, 1126.5212284778936, 17800.46598602044, 18182.45452636423, 18761.812381876007, 18800.52998675044, 19117.713234301777, 31241.843790780902, 41397.79301103475, 44959.77520112378, 46591.534084660074, 46606.76696616631, 58967.41032589619, 63107.0, 67539.0, 69603.65198173976, 71729.6413517929, 9041.95479022627, 9067.274618030673, 9161.816763664823, 9591.664291749756, 9803.607855685692, 11548.711282218012, 14182.432702691776, 14502.0, 14622.341994610055, 14735.705285894437, 12231.571894983634, 12247.632571022883, 12393.876061239273, 12718.300493472578, 12904.225746455242, 11903.345316007702, 12123.515059397525, 12551.93724031374, 12636.620901372973, 12846.357682116044, 12319.69200769988, 12479.563215287784, 12497.687557810763, 12604.432800523815, 12749.107562470537, 9367.0, 9383.155516003593, 9495.43027418357, 9541.379810312308, 9741.318107732392, 11136.220464567403, 11440.370779606868, 11738.882611173778, 11951.163418560618, 12505.0], "value": 9577.417635966469, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.5312246958504, 1533.923303834808, 1549.5041586692257, 1715.322447633185, 1805.3230038735473, 8249.917500824997, 8649.956750216248, 8678.783030424243, 8947.284217262604, 8961.820763584725, 8781.385303028774, 8892.510911899843, 8895.288376929846, 8912.197902188802, 8913.777155571113, 2418.226167626356, 2461.1509029384833, 2466.3217615155886, 2466.7409921958206, 2468.753124687526, 2384.253947985356, 2428.757124287566, 2430.513897220554, 2432.3189506937997, 2441.963370549443, 2389.569877422063, 2408.2534414331526, 2411.6623672685837, 2414.420539070628, 2423.103451722865, 2355.846869953456, 2362.0669835415, 2366.6095094309417, 2380.178838300792, 2405.2543711449507, 2352.1414683640346, 2352.694149760537, 2354.540864531417, 2357.116081469449, 2358.2099996501192, 8648.697295594624, 8723.25852302555, 8872.955635221848, 8876.733697989072, 8878.378513504058, 5294.894102117974, 5387.865303367447, 5418.810341638064, 5424.403315635277, 5440.537554307889, 3540.203454222805, 3545.840437180333, 3579.176789338441, 3599.6400359964055, 3606.549181352331, 1175.341808587194, 1194.152151972101, 1227.0122551346162, 1257.3650306595202, 1260.4769020856425, 12236.0, 12259.877401225962, 12294.20087694306, 12352.567660131852, 12365.69085772854, 4387.605115539617, 4469.351943968107, 4573.63410927125, 4630.74530900797, 4675.906481870412, 9718.95140524307, 9743.025697430201, 9748.171405430618, 9754.561044753003, 9758.170555502862, 5356.7857285708915, 5365.0, 5370.4361042090795, 5371.086915224421, 5379.058664733656, 4144.33690609501, 4406.272964960745, 4525.230710779109, 4897.069556784199, 5001.9499805002215, 3780.7920564369224, 3835.194609132058, 3871.7289789715014, 3932.8820135395986, 3941.428492868575, 1162.0, 1179.1215544419463, 1181.5332943487274, 1183.3136780667191, 1192.5706745571588, 1000.6497725795913, 1004.7237009822358, 1005.7385079879137, 1016.517154351677, 1024.3853687787268, 5645.661260324394, 5774.37782127596, 5803.622764520293, 5959.612625179349, 6018.638881667114, 434.4569288389513, 440.3153096934298, 455.0534887434129, 465.54144167994497, 471.89382388962895, 4220.282551966173, 4260.531341552427, 4264.317709166518, 4270.380794784739, 4288.142371525707, 10774.78450430987, 11085.113190944705, 11140.777184456265, 11191.0, 11192.216544841946, 10115.0, 10615.52230149645, 10791.78416431667, 10875.184361172944, 10880.29278096937, 8092.190780921861, 8303.875441868377, 8309.086000540057, 8309.25216730485, 8322.16778322175, 5965.194698715792, 6009.36901625323, 6207.565470417027, 6209.96895015522, 6296.6851657417865, 10010.199184065403, 10052.949735251277, 10058.8491172631, 10090.747731306776, 10154.39073655583, 9741.220702343653, 9914.603415863285, 9991.800163996533, 10096.141827944706, 10389.324693894872, 7878.251566101211, 7968.0, 7997.520148791091, 8020.518768873653, 8083.959580202061, 8672.78318042054, 8748.475091494276, 8754.343424243334, 8755.693550725695, 8767.430117042371, 7797.142314345304, 7810.921890781019, 7838.333741631797, 7876.72431464896, 8219.506829590004, 7635.618219089134, 7638.694452222071, 7699.422543309386, 7744.070711514429, 7893.092294386219, 6989.7553585624255, 7069.787906362817, 7072.646367681698, 7077.858442831011, 7083.185433675193, 5095.337606111182, 5130.461301563282, 5224.190250511177, 5235.528802407802, 5457.61796674229, 5337.653052551572, 5383.380911195262, 5441.129419292894, 5444.26502422168, 5449.840764331273], "value": 4604.073895743648, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.24022669673315, 352.89060391278707, 353.067900742041, 358.625236627724, 361.37121408748766, 5551.2228288039705, 5555.916661250074, 5566.471185237406, 5577.0, 5577.832665020051, 5233.528982391584, 5345.49217824307, 5496.4503549645015, 5522.723863806814, 5554.278173766135, 4310.245707001278, 4318.395424640553, 4330.372096046071, 4345.869623911288, 4353.238183317907, 4323.762193079379, 4331.133773245348, 4332.176886391591, 4336.720667403108, 4347.673924455663, 4060.20825938942, 4234.364845273203, 4267.423897773808, 4307.320145143381, 4313.482382114151, 4235.68232382571, 4238.131183107455, 4239.682023848224, 4247.362895565674, 4251.851185208534, 3722.9255414891823, 3723.757955732896, 3723.94414083789, 3742.401215805458, 3750.606186350421, 5166.928476268121, 5371.543418809406, 5383.327084114486, 5383.703896285702, 5387.595930305203, 4892.119418504668, 4931.778069986859, 4935.605151587864, 4940.975295123538, 4948.826791062295, 5739.483446489836, 5742.799002034951, 5749.568782341341, 5757.186486256857, 5759.308882934033, 3977.1846771411792, 4145.481814773153, 4185.142045880587, 4185.414042034117, 4190.832366705328, 5948.698472347845, 5987.311459182206, 5999.610025348338, 6018.187544681456, 6027.608205466675, 4266.658667306608, 4275.315949448073, 4280.957190428118, 4299.269124248885, 4299.419578356882, 4174.540800511942, 4370.366296886934, 4411.073674528321, 4423.248047831877, 4424.11517696462, 4946.159152944008, 4953.331300274489, 4963.553280204726, 4969.577585905166, 4975.850724478271, 5833.7083145842535, 5865.472107510259, 5872.794452194153, 5874.383189765013, 5890.673184092259, 1402.0886423824595, 1452.992735036318, 1501.6396064944336, 1502.6694127291978, 1516.9014014089048, 985.9992108010375, 1015.4465816130227, 1016.339379403379, 1017.7455636090978, 1022.1873610479687, 5589.189567512688, 5596.972015139898, 5661.631993920382, 5662.2255095003975, 5698.430156984268, 5621.662700237999, 5893.145493903446, 5898.0, 5929.140274660236, 5955.166276721263, 5565.220869078333, 5589.217509548666, 5597.552195824405, 5598.0, 5650.74571644277, 3133.373325334942, 3142.6543080261154, 3150.322680623677, 3151.054683594912, 3160.5101209312593, 5886.793962211302, 6010.879782404328, 6025.728842202111, 6038.6678732670125, 6075.513958883367, 5922.911156332659, 5938.851528711731, 5944.702764861826, 5951.851203719855, 5966.224390829251, 5749.655020698771, 5974.731137098841, 6047.485963693048, 6052.425019623217, 6070.2412198475195, 5772.942270577409, 5818.679972601548, 5820.079373806549, 5842.532597392284, 5845.18167456565, 5749.252597162509, 5775.133729940542, 5784.2769653793275, 5795.015566983091, 5812.3025236971835, 5866.090755932837, 6008.639481631116, 6135.631862088289, 6180.536459765445, 6183.536234782499, 4268.210381079511, 4287.464066991626, 4301.870943871689, 4334.9349759752995, 4337.913241735211, 5865.442782935615, 6100.176476175659, 6107.4197951194565, 6135.631862088289, 6143.293521245114, 5830.300363956352, 5855.3266374367495, 5864.507584276751, 5869.976961864729, 5900.262467191601, 5816.47651711348, 5856.502197313276, 5870.53035757147, 5872.500837428866, 5874.706264686834, 5342.973285133549, 5510.559155267649, 5715.428457154418, 5781.971090144522, 5792.218050563289, 5833.737481813413, 5877.588568800143, 5888.411158884077, 5888.705564721832, 5891.232630210834, 5548.611597188158, 5679.545636349165, 5680.801171958961, 5727.627704199213, 5764.942350576441], "value": 4423.291014119335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.4582369429552, 809.5547448903102, 823.7322870067226, 862.6635612111281, 880.3221519430037, 14262.643433914145, 15084.698306033873, 15134.0, 15139.621509462255, 15190.088594684326, 13406.396712147953, 13443.0, 13460.730785384287, 13467.663308417294, 13493.122947008438, 705.6048612776862, 840.5797101449275, 841.154639587214, 879.8240351929608, 884.9911500885005, 843.28320927212, 843.7089204224533, 844.1221130024767, 845.4969293270525, 846.4582667093065, 904.1139683110556, 904.3262769236927, 904.6471875968377, 904.6969265296134, 906.4108329585756, 901.9278457723366, 902.4314681750456, 902.8826252587187, 903.1375036839835, 906.5467266366817, 658.4666420199653, 659.4493597845805, 660.191265699519, 660.9985871404825, 662.7614058938778, 13923.30383480822, 13986.580402587939, 13991.58025259244, 14005.509807156803, 14039.85960140406, 14521.12873227599, 14840.480583179646, 14879.776803347959, 14890.627734306729, 15073.547793566211, 11890.940545297219, 11935.820962685566, 11988.520459181622, 12081.57714479989, 12475.376231188495, 4031.23406552754, 4100.528439229497, 4107.507099148122, 4189.329707246857, 4224.345226489899, 67933.0, 70117.0, 70368.6481567594, 70535.0, 78786.99999999943, 12400.0, 12603.764177988283, 13242.139260948008, 13508.527201547899, 13724.245166515739, 24373.75626243715, 24809.751902481104, 25040.373990650016, 25056.624150637756, 25285.115020974546, 9580.520973951274, 9658.323917325719, 9752.951235243778, 10029.849552256723, 10059.446730429896, 4895.0, 5058.0, 5075.873103172376, 5179.326687530597, 5212.4266330703595, 3781.640744129303, 3873.1672690371706, 3882.301185786529, 3894.084890050836, 3906.609339066071, 666.5500786968777, 671.2414971082645, 680.2483256002146, 691.1740470138137, 693.2409012131708, 756.4175584799783, 761.4289283037722, 765.284459030808, 768.6041688530412, 785.1441928298183, 9602.759931001641, 10262.846057309147, 10273.537690803932, 10586.735331616617, 10627.36235825853, 726.8727972604879, 738.9298016688406, 747.9773322141735, 769.8806684963839, 789.2935822439, 4555.863324100216, 4875.414950205927, 4905.0, 4919.606431485473, 4956.628252880976, 132129.33935330453, 134353.0, 136334.0, 137956.31021844826, 140441.5955840449, 82863.5856820712, 114528.0, 116585.0, 116885.41557292159, 117951.0, 100835.49582252042, 101897.49051254697, 102819.48590257001, 103057.48471257745, 104795.0, 9027.36808423404, 9228.40015398997, 9251.537423128952, 9417.529123543658, 9475.57359918819, 59875.401245986985, 62576.0, 62579.0, 62645.37354626395, 63119.36880631135, 82086.58956705178, 84397.0, 89182.10817891738, 90528.0, 92233.07766922245, 12870.67823304425, 13286.0, 13632.591022269347, 13651.608009102862, 13735.656608584864, 13359.86640133625, 13522.391492382669, 13653.726925461237, 13822.930885345912, 14354.138751674933, 12330.63008109758, 12563.74872502563, 12861.935690321488, 13051.738965220453, 13874.167549947035, 11722.531098755955, 11835.585754498552, 11999.580014699444, 12083.154179207722, 12300.753984920431, 12663.493460261854, 12816.935915320364, 13067.869321306665, 13120.278384688552, 13557.051006429456, 10134.23993200498, 10196.59213631476, 10356.326838755456, 10514.842277365693, 10746.677599672023, 10430.895691042992, 10643.308184967953, 10998.725031874266, 11135.888641113484, 11216.943915280372], "value": 7883.398879469844, "unit": "score"}], "name": "dromaeo_css", "value": 7592.866273412424, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 110.0, 111.0, 110.0, 110.0, 111.0, 111.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 146.0, 191.0, 116.0, 145.0, 149.0, 123.0, 189.0, 120.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 162.0, 161.0, 161.0, 163.0, 161.0, 160.0, 161.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 181.0, 76.0, 75.0, 73.0, 75.0, 162.0, 76.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 160.0, 83.0, 84.0, 83.0, 144.0, 84.0, 83.0, 153.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 149.0, 148.0, 148.0, 149.0, 149.0, 149.0, 145.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 53.0, 53.0, 52.0, 54.0, 54.0, 52.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 82.0, 77.0, 73.0, 73.0, 72.0, 72.0, 75.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 123.0, 119.0, 122.0, 126.0, 121.0, 128.0, 127.0, 154.0, 118.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 153.0, 154.0, 151.0, 155.0, 155.0, 153.0, 157.0, 153.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 61.0, 62.0, 68.0, 61.0, 61.0, 67.0, 62.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1507.3, "alertThreshold": 2.0}]} 16:47:59 INFO - Return code: 0 16:47:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:59 INFO - # TBPL SUCCESS # 16:47:59 INFO - Running post-action listener: _resource_record_post_action 16:47:59 INFO - [mozharness: 2016-11-05 23:47:59.815993Z] Finished run-tests step (success) 16:47:59 INFO - Running post-run listener: _resource_record_post_run 16:47:59 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 291700736; Read time: 64; Write time: 165536 16:47:59 INFO - TinderboxPrint: CPU usage
12.9% 16:47:59 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 64 16:47:59 INFO - TinderboxPrint: I/O write bytes / time
291,700,736 / 165,536 16:47:59 INFO - TinderboxPrint: CPU idle
8,246.6 (87.1%) 16:47:59 INFO - TinderboxPrint: CPU user
1,178.7 (12.4%) 16:47:59 INFO - TinderboxPrint: Swap in / out
0 / 0 16:47:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71311360; Read time: 0; Write time: 35816 16:47:59 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 220389376; Read time: 0; Write time: 129720 16:48:00 INFO - Running post-run listener: _upload_blobber_files 16:48:00 INFO - Blob upload gear active. 16:48:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:00 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 16:48:00 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:48:00 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:48:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:01 INFO - (blobuploader) - INFO - Done attempting. 16:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:02 INFO - (blobuploader) - INFO - Done attempting. 16:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:05 INFO - (blobuploader) - INFO - Done attempting. 16:48:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:05 INFO - Return code: 0 16:48:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dbcc836051c90e5be5ba5b8479b981d7c86ad8dff8aa2e9a816ffa8b3f6bc3ee779825ce1a3ea22e6c1f27aca45591f54469e35dc3582053ad497e562fd81a0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0fc6468ce17f2dad59b9ab773d0935d25b6ba0366961b353eab7bb384babc9598db2bec696d55dd640eaad353de356dff5f687febaa12e5982a0ed4797349104", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f1d010104c019e3884f83e05b3b0ced95baca74b799ecfad6128a47678a20fd8a268548c2e2699933fd118dce64d8bb07f43eece22b9687c84948baa5d0a552"} 16:48:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:05 INFO - Contents: 16:48:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dbcc836051c90e5be5ba5b8479b981d7c86ad8dff8aa2e9a816ffa8b3f6bc3ee779825ce1a3ea22e6c1f27aca45591f54469e35dc3582053ad497e562fd81a0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0fc6468ce17f2dad59b9ab773d0935d25b6ba0366961b353eab7bb384babc9598db2bec696d55dd640eaad353de356dff5f687febaa12e5982a0ed4797349104", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f1d010104c019e3884f83e05b3b0ced95baca74b799ecfad6128a47678a20fd8a268548c2e2699933fd118dce64d8bb07f43eece22b9687c84948baa5d0a552"} 16:48:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:05 INFO - Copying logs to upload dir... 16:48:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1672.803359 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-11-05 16:48:05.204825) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:05.213582) ========= 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=0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280 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-Beta/sha512/dbcc836051c90e5be5ba5b8479b981d7c86ad8dff8aa2e9a816ffa8b3f6bc3ee779825ce1a3ea22e6c1f27aca45591f54469e35dc3582053ad497e562fd81a0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0fc6468ce17f2dad59b9ab773d0935d25b6ba0366961b353eab7bb384babc9598db2bec696d55dd640eaad353de356dff5f687febaa12e5982a0ed4797349104", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f1d010104c019e3884f83e05b3b0ced95baca74b799ecfad6128a47678a20fd8a268548c2e2699933fd118dce64d8bb07f43eece22b9687c84948baa5d0a552"} build_url:https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011202 build_url: 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dbcc836051c90e5be5ba5b8479b981d7c86ad8dff8aa2e9a816ffa8b3f6bc3ee779825ce1a3ea22e6c1f27aca45591f54469e35dc3582053ad497e562fd81a0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0fc6468ce17f2dad59b9ab773d0935d25b6ba0366961b353eab7bb384babc9598db2bec696d55dd640eaad353de356dff5f687febaa12e5982a0ed4797349104", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f1d010104c019e3884f83e05b3b0ced95baca74b799ecfad6128a47678a20fd8a268548c2e2699933fd118dce64d8bb07f43eece22b9687c84948baa5d0a552"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:05.246941) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:05.247321) ========= 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=0333a256a5e4f052382dd180000001b1-1478388010.294545-1507219280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:05.300835) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:48:05.303200) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:48:05.303571) ========= ========= Total master_lag: 0.32 =========