builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1477423245.93 results: success (0) buildid: 20161025093138 builduid: f62c4c7d8aa54d90b04b8b17a4ae587c revision: 4d922532989dbbd59fdf07233d4f07bd5a153919 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:45.929134) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:45.929578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:45.929860) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:45.944832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:45.945111) ========= 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-1477417183.426719-2064641521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-25 12:20:46-- 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% 18.4M=0.001s 2016-10-25 12:20:46 (18.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112430 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.071793) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.072230) ========= 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-1477417183.426719-2064641521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018231 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.128775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.129090) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4d922532989dbbd59fdf07233d4f07bd5a153919 --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 4d922532989dbbd59fdf07233d4f07bd5a153919 --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-1477417183.426719-2064641521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-25 12:20:46,921 truncating revision to first 12 chars 2016-10-25 12:20:46,921 Setting DEBUG logging. 2016-10-25 12:20:46,921 attempt 1/10 2016-10-25 12:20:46,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4d922532989d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 12:20:47,215 unpacking tar archive at: mozilla-beta-4d922532989d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653290 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.796143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.796470) ========= script_repo_revision: 4d922532989dbbd59fdf07233d4f07bd5a153919 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.796874) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.797148) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 12:20:46.806939) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-25 12:20:46.807207) ========= /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-1477417183.426719-2064641521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:20:47 INFO - MultiFileLogger online at 20161025 12:20:47 in /builds/slave/test 12:20:47 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 12:20:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:20:47 INFO - {'append_to_log': False, 12:20:47 INFO - 'base_work_dir': '/builds/slave/test', 12:20:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:20:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:20:47 INFO - 'branch': 'Mozilla-Beta', 12:20:47 INFO - 'buildbot_json_path': 'buildprops.json', 12:20:47 INFO - 'config_files': ('talos/linux_config.py',), 12:20:47 INFO - 'default_actions': ('clobber', 12:20:47 INFO - 'read-buildbot-config', 12:20:47 INFO - 'download-and-extract', 12:20:47 INFO - 'populate-webroot', 12:20:47 INFO - 'create-virtualenv', 12:20:47 INFO - 'install', 12:20:47 INFO - 'run-tests'), 12:20:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:20:47 INFO - 'download_minidump_stackwalk': True, 12:20:47 INFO - 'download_symbols': 'ondemand', 12:20:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:20:47 INFO - 'tooltool.py': '/tools/tooltool.py', 12:20:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:20:47 INFO - '/tools/misc-python/virtualenv.py')}, 12:20:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:20:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:20:47 INFO - 'installer_path': 'installer.exe', 12:20:47 INFO - 'log_level': 'info', 12:20:47 INFO - 'log_name': 'talos', 12:20:47 INFO - 'log_to_console': True, 12:20:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:20:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:20:47 INFO - 'opt_config_files': (), 12:20:47 INFO - 'pip_index': False, 12:20:47 INFO - 'sps_profile': False, 12:20:47 INFO - 'sps_profile_interval': 0, 12:20:47 INFO - 'suite': 'dromaeojs', 12:20:47 INFO - 'system_bits': '32', 12:20:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:20:47 INFO - 'title': 'talos-linux64-ix-017', 12:20:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:20:47 INFO - 'use_talos_json': True, 12:20:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:20:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:20:47 INFO - 'work_dir': 'build'} 12:20:47 INFO - [mozharness: 2016-10-25 19:20:47.690318Z] Running clobber step. 12:20:47 INFO - Running pre-action listener: _resource_record_pre_action 12:20:47 INFO - Running main action method: clobber 12:20:47 INFO - rmtree: /builds/slave/test/build 12:20:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:20:48 INFO - Running post-action listener: _resource_record_post_action 12:20:48 INFO - [mozharness: 2016-10-25 19:20:48.151827Z] Finished clobber step (success) 12:20:48 INFO - [mozharness: 2016-10-25 19:20:48.151928Z] Running read-buildbot-config step. 12:20:48 INFO - Running pre-action listener: _resource_record_pre_action 12:20:48 INFO - Running main action method: read_buildbot_config 12:20:48 INFO - Using buildbot properties: 12:20:48 INFO - { 12:20:48 INFO - "project": "", 12:20:48 INFO - "product": "firefox", 12:20:48 INFO - "script_repo_revision": "production", 12:20:48 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:20:48 INFO - "repository": "", 12:20:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:20:48 INFO - "buildid": "20161025093138", 12:20:48 INFO - "pgo_build": "True", 12:20:48 INFO - "basedir": "/builds/slave/test", 12:20:48 INFO - "buildnumber": 444, 12:20:48 INFO - "slavename": "talos-linux64-ix-017", 12:20:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:20:48 INFO - "platform": "ubuntu64_hw", 12:20:48 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:20:48 INFO - "branch": "mozilla-beta", 12:20:48 INFO - "repo_path": "releases/mozilla-beta", 12:20:48 INFO - "revision": "4d922532989dbbd59fdf07233d4f07bd5a153919", 12:20:48 INFO - "stage_platform": "linux64-pgo", 12:20:48 INFO - "builduid": "f62c4c7d8aa54d90b04b8b17a4ae587c", 12:20:48 INFO - "slavebuilddir": "test" 12:20:48 INFO - } 12:20:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 12:20:48 INFO - Running post-action listener: _resource_record_post_action 12:20:48 INFO - [mozharness: 2016-10-25 19:20:48.154371Z] Finished read-buildbot-config step (success) 12:20:48 INFO - [mozharness: 2016-10-25 19:20:48.154459Z] Running download-and-extract step. 12:20:48 INFO - Running pre-action listener: _resource_record_pre_action 12:20:48 INFO - Running main action method: download_and_extract 12:20:48 INFO - mkdir: /builds/slave/test/build/tests 12:20: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')]} 12:20:48 INFO - https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:20:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:20:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:20:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:20:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:20:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:20:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:20:48 INFO - retry: Failed, sleeping 30 seconds before retrying 12:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:21:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:21:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:21:18 INFO - retry: Failed, sleeping 60 seconds before retrying 12:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:22:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:22:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:22:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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! 12:22:18 INFO - Caught exception: HTTP Error 404: Not Found 12:22:18 INFO - Caught exception: HTTP Error 404: Not Found 12:22:18 INFO - Caught exception: HTTP Error 404: Not Found 12:22:18 INFO - trying https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:22:18 INFO - Downloading https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:22:18 INFO - Downloaded 1327 bytes. 12:22:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:22:18 INFO - Using the following test package requirements: 12:22:18 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:22:18 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:22:18 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 12:22:18 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'jsshell-linux-x86_64.zip'], 12:22:18 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:22:18 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:22:18 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 12:22:18 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 12:22:18 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:22:18 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:22:18 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:22:18 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:18 INFO - https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:22:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:22:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:22:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:22:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:22:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:22:18 INFO - retry: Failed, sleeping 30 seconds before retrying 12:22:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:22:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:22:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:22:48 INFO - retry: Failed, sleeping 60 seconds before retrying 12:23:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:23:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:23:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:23:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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! 12:23:48 INFO - Caught exception: HTTP Error 404: Not Found 12:23:48 INFO - Caught exception: HTTP Error 404: Not Found 12:23:48 INFO - Caught exception: HTTP Error 404: Not Found 12:23:48 INFO - trying https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:23:48 INFO - Downloading https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:23:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:23:50 INFO - Downloaded 21774800 bytes. 12:23:50 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'] 12:23:50 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 12:23:51 INFO - Return code: 0 12:23:51 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 12:23:51 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')]} 12:23:51 INFO - https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:23:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:23:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:23:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:23:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:23:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:23:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:23:51 INFO - retry: Failed, sleeping 30 seconds before retrying 12:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:24:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:24:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:24:21 INFO - retry: Failed, sleeping 60 seconds before retrying 12:25:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:25:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:25:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:25:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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! 12:25:21 INFO - Caught exception: HTTP Error 404: Not Found 12:25:21 INFO - Caught exception: HTTP Error 404: Not Found 12:25:21 INFO - Caught exception: HTTP Error 404: Not Found 12:25:21 INFO - trying https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:25:21 INFO - Downloading https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:25:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:25:22 INFO - Downloaded 21774800 bytes. 12:25:22 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'] 12:25:22 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 12:25:24 INFO - Return code: 0 12:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:25:24 INFO - https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:25:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:25:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:25:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:25:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:25:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:25:24 INFO - retry: Failed, sleeping 30 seconds before retrying 12:25:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:25:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:25:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:25:54 INFO - retry: Failed, sleeping 60 seconds before retrying 12:26:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:26:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:26:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:26:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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! 12:26:54 INFO - Caught exception: HTTP Error 404: Not Found 12:26:54 INFO - Caught exception: HTTP Error 404: Not Found 12:26:54 INFO - Caught exception: HTTP Error 404: Not Found 12:26:54 INFO - trying https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:26:54 INFO - Downloading https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:26:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:26:55 INFO - Downloaded 14413762 bytes. 12:26:55 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'] 12:26:55 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 12:26:56 INFO - Return code: 0 12:26:56 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')]} 12:26:56 INFO - https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:26:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:26:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:26:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:26:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:26:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:26:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:26:56 INFO - retry: Failed, sleeping 30 seconds before retrying 12:27:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:27:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:27:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:27:26 INFO - retry: Failed, sleeping 60 seconds before retrying 12:28:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:28:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:28:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:28:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JsXQOb2aQxurIphyLTm2_Q/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! 12:28:26 INFO - Caught exception: HTTP Error 404: Not Found 12:28:26 INFO - Caught exception: HTTP Error 404: Not Found 12:28:26 INFO - Caught exception: HTTP Error 404: Not Found 12:28:26 INFO - trying https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:28:26 INFO - Downloading https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:28:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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 12:28:27 INFO - Downloaded 56791185 bytes. 12:28:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:28:27 INFO - mkdir: /builds/slave/test/properties 12:28:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:28:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:28:27 INFO - Contents: 12:28:27 INFO - build_url:https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:28:28 INFO - Running post-action listener: _resource_record_post_action 12:28:28 INFO - Running post-action listener: set_extra_try_arguments 12:28:28 INFO - [mozharness: 2016-10-25 19:28:28.593173Z] Finished download-and-extract step (success) 12:28:28 INFO - [mozharness: 2016-10-25 19:28:28.593452Z] Running populate-webroot step. 12:28:28 INFO - Running pre-action listener: _resource_record_pre_action 12:28:28 INFO - Running main action method: populate_webroot 12:28:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:28:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:28:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:28:28 INFO - '--tppagecycles', 12:28:28 INFO - '7'], 12:28:28 INFO - 'tests': ['tsvgm']}}, 12:28:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:28:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:28:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:28:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:28:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['damp', 'tps']}, 12:28:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'tests': ['damp', 'tps']}, 12:28:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['dromaeo_dom']}, 12:28:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:28:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['basic_compositor_video']}, 12:28:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:28:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['a11yr', 12:28:28 INFO - 'ts_paint', 12:28:28 INFO - 'tpaint', 12:28:28 INFO - 'sessionrestore', 12:28:28 INFO - 'sessionrestore_no_auto_restore', 12:28:28 INFO - 'tabpaint']}, 12:28:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:28:28 INFO - 'ts_paint', 12:28:28 INFO - 'tpaint', 12:28:28 INFO - 'sessionrestore', 12:28:28 INFO - 'sessionrestore_no_auto_restore', 12:28:28 INFO - 'tabpaint']}, 12:28:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:28:28 INFO - 'ts_paint', 12:28:28 INFO - 'tpaint', 12:28:28 INFO - 'sessionrestore', 12:28:28 INFO - 'sessionrestore_no_auto_restore', 12:28:28 INFO - 'tabpaint']}, 12:28:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:28:28 INFO - 'ts_paint', 12:28:28 INFO - 'tpaint', 12:28:28 INFO - 'sessionrestore', 12:28:28 INFO - 'sessionrestore_no_auto_restore', 12:28:28 INFO - 'tabpaint']}, 12:28:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['a11yr', 12:28:28 INFO - 'ts_paint', 12:28:28 INFO - 'tpaint', 12:28:28 INFO - 'sessionrestore', 12:28:28 INFO - 'sessionrestore_no_auto_restore', 12:28:28 INFO - 'tabpaint']}, 12:28:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['a11yr', 12:28:28 INFO - 'ts_paint', 12:28:28 INFO - 'tpaint', 12:28:28 INFO - 'sessionrestore', 12:28:28 INFO - 'sessionrestore_no_auto_restore', 12:28:28 INFO - 'tabpaint']}, 12:28:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['tsvgx', 12:28:28 INFO - 'tsvgr_opacity', 12:28:28 INFO - 'tart', 12:28:28 INFO - 'tscrollx', 12:28:28 INFO - 'cart']}, 12:28:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:28:28 INFO - 'tsvgr_opacity', 12:28:28 INFO - 'tart', 12:28:28 INFO - 'tscrollx', 12:28:28 INFO - 'cart']}, 12:28:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'talos_options': ['--disable-e10s'], 12:28:28 INFO - 'tests': ['tp5o']}, 12:28:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'tests': ['tp5o']}, 12:28:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'talos_options': ['--disable-e10s', 12:28:28 INFO - '--xperf_path', 12:28:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:28 INFO - 'tests': ['tp5n']}, 12:28:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:28 INFO - 'talos_options': ['--xperf_path', 12:28:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:28 INFO - 'tests': ['tp5n']}}, 12:28:28 INFO - 'talos.zip': {'path': '', 12:28:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:28:28 INFO - Running post-action listener: _resource_record_post_action 12:28:28 INFO - [mozharness: 2016-10-25 19:28:28.609260Z] Finished populate-webroot step (success) 12:28:28 INFO - [mozharness: 2016-10-25 19:28:28.609366Z] Running create-virtualenv step. 12:28:28 INFO - Running pre-action listener: _resource_record_pre_action 12:28:28 INFO - Running main action method: create_virtualenv 12:28:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:28:28 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 12:28:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:28:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:28:28 INFO - Using real prefix '/usr' 12:28:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:28:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:28:31 INFO - Installing pip.................done. 12:28:31 INFO - Return code: 0 12:28:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:28:31 INFO - Using env: {'DISPLAY': ':0', 12:28:31 INFO - 'HOME': '/home/cltbld', 12:28:31 INFO - 'LANG': 'en_US.UTF-8', 12:28:31 INFO - 'LANGUAGE': 'en_US:en', 12:28:31 INFO - 'LOGNAME': 'cltbld', 12:28:31 INFO - 'MAIL': '/var/mail/cltbld', 12:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:31 INFO - 'MOZ_NO_REMOTE': '1', 12:28:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:31 INFO - 'NO_EM_RESTART': '1', 12:28:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:31 INFO - 'PWD': '/builds/slave/test', 12:28:31 INFO - 'SHELL': '/bin/bash', 12:28:31 INFO - 'SHLVL': '1', 12:28:31 INFO - 'TERM': 'linux', 12:28:31 INFO - 'TMOUT': '86400', 12:28:31 INFO - 'USER': 'cltbld', 12:28:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:31 INFO - '_': '/tools/buildbot/bin/python'} 12:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:31 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 12:28:31 INFO - Cleaning up... 12:28:31 INFO - Return code: 0 12:28:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:28:31 INFO - Using env: {'DISPLAY': ':0', 12:28:31 INFO - 'HOME': '/home/cltbld', 12:28:31 INFO - 'LANG': 'en_US.UTF-8', 12:28:31 INFO - 'LANGUAGE': 'en_US:en', 12:28:31 INFO - 'LOGNAME': 'cltbld', 12:28:31 INFO - 'MAIL': '/var/mail/cltbld', 12:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:31 INFO - 'MOZ_NO_REMOTE': '1', 12:28:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:31 INFO - 'NO_EM_RESTART': '1', 12:28:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:31 INFO - 'PWD': '/builds/slave/test', 12:28:31 INFO - 'SHELL': '/bin/bash', 12:28:31 INFO - 'SHLVL': '1', 12:28:31 INFO - 'TERM': 'linux', 12:28:31 INFO - 'TMOUT': '86400', 12:28:31 INFO - 'USER': 'cltbld', 12:28:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:31 INFO - '_': '/tools/buildbot/bin/python'} 12:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:31 INFO - Downloading/unpacking psutil>=3.1.1 12:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:28:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:28:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:28:33 INFO - Installing collected packages: psutil 12:28:33 INFO - Running setup.py install for psutil 12:28:33 INFO - building 'psutil._psutil_linux' extension 12:28:33 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 12:28:33 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 12:28:33 INFO - building 'psutil._psutil_posix' extension 12:28:33 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 12:28:34 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 12:28:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:28:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:28:34 INFO - Successfully installed psutil 12:28:34 INFO - Cleaning up... 12:28:34 INFO - Return code: 0 12:28:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:34 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:34 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 12:28:34 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 12:28:34 INFO - Using env: {'DISPLAY': ':0', 12:28:34 INFO - 'HOME': '/home/cltbld', 12:28:34 INFO - 'LANG': 'en_US.UTF-8', 12:28:34 INFO - 'LANGUAGE': 'en_US:en', 12:28:34 INFO - 'LOGNAME': 'cltbld', 12:28:34 INFO - 'MAIL': '/var/mail/cltbld', 12:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:34 INFO - 'MOZ_NO_REMOTE': '1', 12:28:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:34 INFO - 'NO_EM_RESTART': '1', 12:28:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:34 INFO - 'PWD': '/builds/slave/test', 12:28:34 INFO - 'SHELL': '/bin/bash', 12:28:34 INFO - 'SHLVL': '1', 12:28:34 INFO - 'TERM': 'linux', 12:28:34 INFO - 'TMOUT': '86400', 12:28:34 INFO - 'USER': 'cltbld', 12:28:34 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:34 INFO - '_': '/tools/buildbot/bin/python'} 12:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:28:36 INFO - Installing collected packages: mozsystemmonitor 12:28:36 INFO - Running setup.py install for mozsystemmonitor 12:28:36 INFO - Successfully installed mozsystemmonitor 12:28:36 INFO - Cleaning up... 12:28:36 INFO - Return code: 0 12:28:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:36 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:36 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 12:28:36 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 12:28:36 INFO - Using env: {'DISPLAY': ':0', 12:28:36 INFO - 'HOME': '/home/cltbld', 12:28:36 INFO - 'LANG': 'en_US.UTF-8', 12:28:36 INFO - 'LANGUAGE': 'en_US:en', 12:28:36 INFO - 'LOGNAME': 'cltbld', 12:28:36 INFO - 'MAIL': '/var/mail/cltbld', 12:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:36 INFO - 'MOZ_NO_REMOTE': '1', 12:28:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:36 INFO - 'NO_EM_RESTART': '1', 12:28:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:36 INFO - 'PWD': '/builds/slave/test', 12:28:36 INFO - 'SHELL': '/bin/bash', 12:28:36 INFO - 'SHLVL': '1', 12:28:36 INFO - 'TERM': 'linux', 12:28:36 INFO - 'TMOUT': '86400', 12:28:36 INFO - 'USER': 'cltbld', 12:28:36 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:36 INFO - '_': '/tools/buildbot/bin/python'} 12:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:36 INFO - Downloading/unpacking jsonschema==2.5.1 12:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:28:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:28:38 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 12:28:38 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 12:28:38 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 12:28:38 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 12:28:38 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 12:28:38 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 12:28:38 INFO - Downloading functools32-3.2.3-2.tar.gz 12:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:28:38 INFO - warning: no files found matching '*.txt' 12:28:38 INFO - no previously-included directories found matching 'build' 12:28:38 INFO - no previously-included directories found matching 'dist' 12:28:38 INFO - no previously-included directories found matching '.git*' 12:28:39 INFO - Installing collected packages: jsonschema, functools32 12:28:39 INFO - Running setup.py install for functools32 12:28:39 INFO - warning: no files found matching '*.txt' 12:28:39 INFO - no previously-included directories found matching 'build' 12:28:39 INFO - no previously-included directories found matching 'dist' 12:28:39 INFO - no previously-included directories found matching '.git*' 12:28:39 INFO - Successfully installed jsonschema functools32 12:28:39 INFO - Cleaning up... 12:28:39 INFO - Return code: 0 12:28:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:39 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:39 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 12:28:39 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 12:28:39 INFO - Using env: {'DISPLAY': ':0', 12:28:39 INFO - 'HOME': '/home/cltbld', 12:28:39 INFO - 'LANG': 'en_US.UTF-8', 12:28:39 INFO - 'LANGUAGE': 'en_US:en', 12:28:39 INFO - 'LOGNAME': 'cltbld', 12:28:39 INFO - 'MAIL': '/var/mail/cltbld', 12:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:39 INFO - 'MOZ_NO_REMOTE': '1', 12:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:39 INFO - 'NO_EM_RESTART': '1', 12:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:39 INFO - 'PWD': '/builds/slave/test', 12:28:39 INFO - 'SHELL': '/bin/bash', 12:28:39 INFO - 'SHLVL': '1', 12:28:39 INFO - 'TERM': 'linux', 12:28:39 INFO - 'TMOUT': '86400', 12:28:39 INFO - 'USER': 'cltbld', 12:28:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:39 INFO - '_': '/tools/buildbot/bin/python'} 12:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:28:39 INFO - Cleaning up... 12:28:39 INFO - Return code: 0 12:28:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:39 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:39 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 12:28:39 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 12:28:39 INFO - Using env: {'DISPLAY': ':0', 12:28:39 INFO - 'HOME': '/home/cltbld', 12:28:39 INFO - 'LANG': 'en_US.UTF-8', 12:28:39 INFO - 'LANGUAGE': 'en_US:en', 12:28:39 INFO - 'LOGNAME': 'cltbld', 12:28:39 INFO - 'MAIL': '/var/mail/cltbld', 12:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:39 INFO - 'MOZ_NO_REMOTE': '1', 12:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:39 INFO - 'NO_EM_RESTART': '1', 12:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:39 INFO - 'PWD': '/builds/slave/test', 12:28:39 INFO - 'SHELL': '/bin/bash', 12:28:39 INFO - 'SHLVL': '1', 12:28:39 INFO - 'TERM': 'linux', 12:28:39 INFO - 'TMOUT': '86400', 12:28:39 INFO - 'USER': 'cltbld', 12:28:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:39 INFO - '_': '/tools/buildbot/bin/python'} 12:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:39 INFO - Downloading/unpacking blobuploader==1.2.4 12:28:39 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 12:28:39 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 12:28:39 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 12:28:39 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 12:28:39 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 12:28:39 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 12:28:41 INFO - Downloading blobuploader-1.2.4.tar.gz 12:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:28:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:28:41 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 12:28:41 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 12:28:41 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 12:28:41 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 12:28:41 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 12:28:41 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 12:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:28:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:28:42 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 12:28:42 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 12:28:42 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 12:28:42 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 12:28:42 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 12:28:42 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 12:28:42 INFO - Downloading docopt-0.6.1.tar.gz 12:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:28:42 INFO - Installing collected packages: blobuploader, requests, docopt 12:28:42 INFO - Running setup.py install for blobuploader 12:28:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:28:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:28:42 INFO - Running setup.py install for requests 12:28:42 INFO - Running setup.py install for docopt 12:28:42 INFO - Successfully installed blobuploader requests docopt 12:28:42 INFO - Cleaning up... 12:28:42 INFO - Return code: 0 12:28:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:28:42 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')]} 12:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:42 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')]} 12:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:42 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:42 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 12:28:42 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 12:28:42 INFO - Using env: {'DISPLAY': ':0', 12:28:42 INFO - 'HOME': '/home/cltbld', 12:28:42 INFO - 'LANG': 'en_US.UTF-8', 12:28:42 INFO - 'LANGUAGE': 'en_US:en', 12:28:42 INFO - 'LOGNAME': 'cltbld', 12:28:42 INFO - 'MAIL': '/var/mail/cltbld', 12:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:42 INFO - 'MOZ_NO_REMOTE': '1', 12:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:42 INFO - 'NO_EM_RESTART': '1', 12:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:42 INFO - 'PWD': '/builds/slave/test', 12:28:42 INFO - 'SHELL': '/bin/bash', 12:28:42 INFO - 'SHLVL': '1', 12:28:42 INFO - 'TERM': 'linux', 12:28:42 INFO - 'TMOUT': '86400', 12:28:42 INFO - 'USER': 'cltbld', 12:28:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:42 INFO - '_': '/tools/buildbot/bin/python'} 12:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:28:43 INFO - Running setup.py (path:/tmp/pip-DXSaxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:28:43 INFO - Running setup.py (path:/tmp/pip-XhjxmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:28:43 INFO - Running setup.py (path:/tmp/pip-gMH0vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:28:43 INFO - Running setup.py (path:/tmp/pip-lJ_s1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:28:43 INFO - Running setup.py (path:/tmp/pip-IelisL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:28:43 INFO - Running setup.py (path:/tmp/pip-GjRLXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:28:43 INFO - Running setup.py (path:/tmp/pip-GHFI78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:28:43 INFO - Running setup.py (path:/tmp/pip-taOzbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:28:43 INFO - Running setup.py (path:/tmp/pip-W9KrBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:28:43 INFO - Running setup.py (path:/tmp/pip-D0Pr1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:28:43 INFO - Running setup.py (path:/tmp/pip-iC8FV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:28:43 INFO - Running setup.py (path:/tmp/pip-RCsHzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:28:44 INFO - Running setup.py (path:/tmp/pip-Oagh7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:28:44 INFO - Running setup.py (path:/tmp/pip-IZDfmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:28:44 INFO - Running setup.py (path:/tmp/pip-t3QsZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:28:44 INFO - Running setup.py (path:/tmp/pip-IKV93e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:28:44 INFO - Running setup.py (path:/tmp/pip-_Ahk5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:28:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:28:44 INFO - Running setup.py install for manifestparser 12:28:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:28:44 INFO - Running setup.py install for mozcrash 12:28:44 INFO - Running setup.py install for mozdebug 12:28:44 INFO - Running setup.py install for mozdevice 12:28:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:28:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:28:44 INFO - Running setup.py install for mozfile 12:28:44 INFO - Running setup.py install for mozhttpd 12:28:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for mozinfo 12:28:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for mozInstall 12:28:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for mozleak 12:28:45 INFO - Running setup.py install for mozlog 12:28:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for moznetwork 12:28:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for mozprocess 12:28:45 INFO - Running setup.py install for mozprofile 12:28:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for mozrunner 12:28:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:28:45 INFO - Running setup.py install for mozscreenshot 12:28:45 INFO - Running setup.py install for moztest 12:28:46 INFO - Running setup.py install for mozversion 12:28:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:28:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:28:46 INFO - Cleaning up... 12:28:46 INFO - Return code: 0 12:28:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:46 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:46 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 12:28:46 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 12:28:46 INFO - Using env: {'DISPLAY': ':0', 12:28:46 INFO - 'HOME': '/home/cltbld', 12:28:46 INFO - 'LANG': 'en_US.UTF-8', 12:28:46 INFO - 'LANGUAGE': 'en_US:en', 12:28:46 INFO - 'LOGNAME': 'cltbld', 12:28:46 INFO - 'MAIL': '/var/mail/cltbld', 12:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:46 INFO - 'MOZ_NO_REMOTE': '1', 12:28:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:46 INFO - 'NO_EM_RESTART': '1', 12:28:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:46 INFO - 'PWD': '/builds/slave/test', 12:28:46 INFO - 'SHELL': '/bin/bash', 12:28:46 INFO - 'SHLVL': '1', 12:28:46 INFO - 'TERM': 'linux', 12:28:46 INFO - 'TMOUT': '86400', 12:28:46 INFO - 'USER': 'cltbld', 12:28:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:46 INFO - '_': '/tools/buildbot/bin/python'} 12:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:28:46 INFO - Running setup.py (path:/tmp/pip-u2I7M3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:28:46 INFO - Running setup.py (path:/tmp/pip-zW6hix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:28:46 INFO - Running setup.py (path:/tmp/pip-2z9Lm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:28:46 INFO - Running setup.py (path:/tmp/pip-adPq8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:28:46 INFO - Running setup.py (path:/tmp/pip-zbTA5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:28:46 INFO - Running setup.py (path:/tmp/pip-zq2pzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:28:46 INFO - Running setup.py (path:/tmp/pip-dZnHat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:28:46 INFO - Running setup.py (path:/tmp/pip-exPJK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:28:46 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)) 12:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:28:46 INFO - Running setup.py (path:/tmp/pip-CAA58Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:28:47 INFO - Running setup.py (path:/tmp/pip-DwaCgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:28:47 INFO - Running setup.py (path:/tmp/pip-3coWHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:28:47 INFO - Running setup.py (path:/tmp/pip-lJYA5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:28:47 INFO - Running setup.py (path:/tmp/pip-IctjYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:28:47 INFO - Running setup.py (path:/tmp/pip-MWs3Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:28:47 INFO - Running setup.py (path:/tmp/pip-zMkfuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:28:47 INFO - Running setup.py (path:/tmp/pip-flNhgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:28:47 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)) 12:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:28:47 INFO - Running setup.py (path:/tmp/pip-MtTBdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:28:47 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)) 12:28:47 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)) 12:28:47 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)) 12:28:47 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)) 12:28:47 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)) 12:28:47 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)) 12:28:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:28:47 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 12:28:47 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 12:28:47 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 12:28:47 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 12:28:47 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 12:28:47 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 12:28:49 INFO - Downloading blessings-1.6.tar.gz 12:28:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:28:49 INFO - Installing collected packages: blessings 12:28:49 INFO - Running setup.py install for blessings 12:28:49 INFO - Successfully installed blessings 12:28:49 INFO - Cleaning up... 12:28:49 INFO - Return code: 0 12:28:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:28:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:28:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:28:50 INFO - Reading from file tmpfile_stdout 12:28:50 INFO - Current package versions: 12:28:50 INFO - argparse == 1.2.1 12:28:50 INFO - blessings == 1.6 12:28:50 INFO - blobuploader == 1.2.4 12:28:50 INFO - docopt == 0.6.1 12:28:50 INFO - functools32 == 3.2.3-2 12:28:50 INFO - jsonschema == 2.5.1 12:28:50 INFO - manifestparser == 1.1 12:28:50 INFO - mozInstall == 1.12 12:28:50 INFO - mozcrash == 0.17 12:28:50 INFO - mozdebug == 0.1 12:28:50 INFO - mozdevice == 0.48 12:28:50 INFO - mozfile == 1.2 12:28:50 INFO - mozhttpd == 0.7 12:28:50 INFO - mozinfo == 0.9 12:28:50 INFO - mozleak == 0.1 12:28:50 INFO - mozlog == 3.3 12:28:50 INFO - moznetwork == 0.27 12:28:50 INFO - mozprocess == 0.23 12:28:50 INFO - mozprofile == 0.28 12:28:50 INFO - mozrunner == 6.12 12:28:50 INFO - mozscreenshot == 0.1 12:28:50 INFO - mozsystemmonitor == 0.3 12:28:50 INFO - moztest == 0.7 12:28:50 INFO - mozversion == 1.4 12:28:50 INFO - psutil == 3.1.1 12:28:50 INFO - requests == 1.2.3 12:28:50 INFO - wsgiref == 0.1.2 12:28:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:50 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:50 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 12:28:50 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 12:28:50 INFO - Using env: {'DISPLAY': ':0', 12:28:50 INFO - 'HOME': '/home/cltbld', 12:28:50 INFO - 'LANG': 'en_US.UTF-8', 12:28:50 INFO - 'LANGUAGE': 'en_US:en', 12:28:50 INFO - 'LOGNAME': 'cltbld', 12:28:50 INFO - 'MAIL': '/var/mail/cltbld', 12:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:50 INFO - 'MOZ_NO_REMOTE': '1', 12:28:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:50 INFO - 'NO_EM_RESTART': '1', 12:28:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:50 INFO - 'PWD': '/builds/slave/test', 12:28:50 INFO - 'SHELL': '/bin/bash', 12:28:50 INFO - 'SHLVL': '1', 12:28:50 INFO - 'TERM': 'linux', 12:28:50 INFO - 'TMOUT': '86400', 12:28:50 INFO - 'USER': 'cltbld', 12:28:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:50 INFO - '_': '/tools/buildbot/bin/python'} 12:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 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)) 12:28:50 INFO - Cleaning up... 12:28:50 INFO - Return code: 0 12:28:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:28:50 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 0x1f7b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x223c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, '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-1477417183.426719-2064641521', '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 12:28:50 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 12:28:50 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 12:28:50 INFO - Using env: {'DISPLAY': ':0', 12:28:50 INFO - 'HOME': '/home/cltbld', 12:28:50 INFO - 'LANG': 'en_US.UTF-8', 12:28:50 INFO - 'LANGUAGE': 'en_US:en', 12:28:50 INFO - 'LOGNAME': 'cltbld', 12:28:50 INFO - 'MAIL': '/var/mail/cltbld', 12:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:50 INFO - 'MOZ_NO_REMOTE': '1', 12:28:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:50 INFO - 'NO_EM_RESTART': '1', 12:28:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:50 INFO - 'PWD': '/builds/slave/test', 12:28:50 INFO - 'SHELL': '/bin/bash', 12:28:50 INFO - 'SHLVL': '1', 12:28:50 INFO - 'TERM': 'linux', 12:28:50 INFO - 'TMOUT': '86400', 12:28:50 INFO - 'USER': 'cltbld', 12:28:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:50 INFO - '_': '/tools/buildbot/bin/python'} 12:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:28:50 INFO - Cleaning up... 12:28:50 INFO - Return code: 0 12:28:50 INFO - Running post-action listener: _resource_record_post_action 12:28:50 INFO - Running post-action listener: _start_resource_monitoring 12:28:50 INFO - Starting resource monitoring. 12:28:50 INFO - [mozharness: 2016-10-25 19:28:50.487016Z] Finished create-virtualenv step (success) 12:28:50 INFO - [mozharness: 2016-10-25 19:28:50.487444Z] Running install step. 12:28:50 INFO - Running pre-action listener: _resource_record_pre_action 12:28:50 INFO - Running main action method: install 12:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:28:50 INFO - Reading from file tmpfile_stdout 12:28:50 INFO - Detecting whether we're running mozinstall >=1.0... 12:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:28:50 INFO - Reading from file tmpfile_stdout 12:28:50 INFO - Output received: 12:28:50 INFO - Usage: mozinstall [options] installer 12:28:50 INFO - Options: 12:28:50 INFO - -h, --help show this help message and exit 12:28:50 INFO - -d DEST, --destination=DEST 12:28:50 INFO - Directory to install application into. [default: 12:28:50 INFO - "/builds/slave/test"] 12:28:50 INFO - --app=APP Application being installed. [default: firefox] 12:28:50 INFO - mkdir: /builds/slave/test/build/application 12:28:50 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'] 12:28:50 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 12:29:04 INFO - Reading from file tmpfile_stdout 12:29:04 INFO - Output received: 12:29:04 INFO - /builds/slave/test/build/application/firefox/firefox 12:29:04 INFO - Running post-action listener: _resource_record_post_action 12:29:04 INFO - [mozharness: 2016-10-25 19:29:04.286996Z] Finished install step (success) 12:29:04 INFO - [mozharness: 2016-10-25 19:29:04.287267Z] Running run-tests step. 12:29:04 INFO - Running pre-action listener: _resource_record_pre_action 12:29:04 INFO - Running main action method: run_tests 12:29:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:29:04 INFO - Python 2.7.3 12:29:04 INFO - Return code: 0 12:29:04 INFO - grabbing minidump binary from tooltool 12:29:04 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')]} 12:29:04 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 0x223a3b0>, '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 0x223b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:29:04 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 12:29:04 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 12:29:04 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 12:29:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:29:04 INFO - Return code: 0 12:29:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:29:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:29:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:29:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:29:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:29:04 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/JsXQOb2aQxurIphyLTm2_Q/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 12:29:04 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/JsXQOb2aQxurIphyLTm2_Q/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 12:29:04 INFO - Using env: {'DISPLAY': ':0', 12:29:04 INFO - 'HOME': '/home/cltbld', 12:29:04 INFO - 'LANG': 'en_US.UTF-8', 12:29:04 INFO - 'LANGUAGE': 'en_US:en', 12:29:04 INFO - 'LOGNAME': 'cltbld', 12:29:04 INFO - 'MAIL': '/var/mail/cltbld', 12:29:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:29:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:04 INFO - 'MOZ_NO_REMOTE': '1', 12:29:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:04 INFO - 'NO_EM_RESTART': '1', 12:29:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:04 INFO - 'PWD': '/builds/slave/test', 12:29:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:29:04 INFO - 'SHELL': '/bin/bash', 12:29:04 INFO - 'SHLVL': '1', 12:29:04 INFO - 'TERM': 'linux', 12:29:04 INFO - 'TMOUT': '86400', 12:29:04 INFO - 'USER': 'cltbld', 12:29:04 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477417183.426719-2064641521', 12:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:04 INFO - '_': '/tools/buildbot/bin/python'} 12:29:04 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/JsXQOb2aQxurIphyLTm2_Q/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 12:29:04 INFO - mozversion application_buildid: 20161025093138 12:29:04 INFO - mozversion application_changeset: 4d922532989dbbd59fdf07233d4f07bd5a153919 12:29:04 INFO - mozversion application_display_name: Firefox 12:29:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:29:04 INFO - mozversion application_name: Firefox 12:29:04 INFO - mozversion application_remotingname: firefox 12:29:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:29:04 INFO - mozversion application_vendor: Mozilla 12:29:04 INFO - mozversion application_version: 50.0 12:29:04 INFO - mozversion platform_buildid: 20161025093138 12:29:04 INFO - mozversion platform_changeset: 4d922532989dbbd59fdf07233d4f07bd5a153919 12:29:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:29:04 INFO - mozversion platform_version: 50.0 12:29:04 INFO - using testdate: 1477423744 12:29:04 INFO - actual date: 1477423744 12:29:04 INFO - starting webserver on 'localhost:44463' 12:29:04 INFO - SUITE-START | Running 2 tests 12:29:04 INFO - TEST-START | dromaeo_css 12:29:04 INFO - operating with platform_type : linux_ 12:29:04 INFO - Initialising browser for dromaeo_css test... 12:29:04 INFO - TEST-INFO | started process 32319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd0rb0k/profile http://localhost:44463/getInfo.html) 12:29:08 INFO - PROCESS | 32319 | __metrics Screen width/height:1600/1200 12:29:08 INFO - PROCESS | 32319 | colorDepth:24 12:29:08 INFO - PROCESS | 32319 | Browser inner width/height: 1024/697 12:29:08 INFO - PROCESS | 32319 | __metrics 12:29:08 INFO - PROCESS | 32319 | 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" 12:29:08 INFO - PROCESS | 32319 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:29:09 INFO - TEST-INFO | 32319: exit 0 12:29:09 INFO - Browser initialized. 12:29:09 INFO - Running cycle 1/1 for dromaeo_css test... 12:29:09 INFO - TEST-INFO | started process 32407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd0rb0k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:21 INFO - PROCESS | 32407 | RSS: Main: 143777792 12:29:21 INFO - PROCESS | 32407 | 12:30:13 INFO - PROCESS | 32407 | 1477423813020 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] 12:32:20 INFO - PROCESS | 32407 | Cycle 1(1): loaded http://localhost:44463/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44463/tests/dromaeo/cssquery-ext.html) 12:32:21 INFO - PROCESS | 32407 | RSS: Main: 236683264 12:32:21 INFO - PROCESS | 32407 | 12:35:23 INFO - PROCESS | 32407 | Cycle 1(1): loaded http://localhost:44463/tests/dromaeo/cssquery-ext.html (next: http://localhost:44463/tests/dromaeo/cssquery-jquery.html) 12:35:24 INFO - PROCESS | 32407 | RSS: Main: 238370816 12:35:24 INFO - PROCESS | 32407 | 12:38:26 INFO - PROCESS | 32407 | Cycle 1(1): loaded http://localhost:44463/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44463/tests/dromaeo/cssquery-mootools.html) 12:38:27 INFO - PROCESS | 32407 | RSS: Main: 204640256 12:38:27 INFO - PROCESS | 32407 | 12:41:28 INFO - PROCESS | 32407 | Cycle 1(1): loaded http://localhost:44463/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44463/tests/dromaeo/cssquery-prototype.html) 12:41:29 INFO - PROCESS | 32407 | RSS: Main: 174080000 12:41:29 INFO - PROCESS | 32407 | 12:44:32 INFO - PROCESS | 32407 | Cycle 1(1): loaded http://localhost:44463/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44463/tests/dromaeo/cssquery-yui.html) 12:44:32 INFO - PROCESS | 32407 | RSS: Main: 176820224 12:44:32 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | Cycle 1(1): loaded http://localhost:44463/tests/dromaeo/cssquery-yui.html (next: http://localhost:44463/tests/dromaeo/cssquery-dojo.html) 12:47:35 INFO - PROCESS | 32407 | RSS: Main: 258478080 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | __start_tp_report 12:47:35 INFO - PROCESS | 32407 | _x_x_mozilla_page_load 12:47:35 INFO - PROCESS | 32407 | _x_x_mozilla_page_load_details 12:47:35 INFO - PROCESS | 32407 | |i|pagename|runs| 12:47:35 INFO - PROCESS | 32407 | |0;dojo.html;3215.212272993116;3226.548283240347;3246.155999440145;3279.147421670365;3285.2115492281846;5648.039833228351;5731.168980497825;5753.108268218428;5776.191333213354;5779.711014449282;5560.582956278275;5675.177099320596;5677.40387259337;5682.403347648489;5685.630434021786;5118.590512758989;5118.769655365499;5119.718415487147;5167.018266529366;5187.273781670569;5020.422651395082;5073.036123136592;5216.60875434344;5216.7391630418515;5253.921191182136;5018.799248030093;5041.1178043842365;5062.139436295838;5146.897062058775;5187.922181167267;5198.558122559568;5249;5331.120365139747;5336.653117547386;5362.624616276862;4950.653454258203;5154.896902061975;5241.449647786965;5298.97350513245;5312.150055991023;5443.319585051869;5513.807016754435;5564.471375219388;5578.163275508706;5585.581081418869;6076.392360763933;6094.756209751649;6121.234845644294;6123.9387606124255;6134.8773024539705;5793.8841223175305;5949.9107513387335;6174.598651087665;6189.412005859436;6193.194884665009;5592.245046918654;5664.093745000862;5681.971590142023;5702.800401985911;5704.857378565527;6183.505319574423;6190.226221722285;6193.256809182927;6197.504199664016;6200.689965501797;5644.8024319148635;5738.856528586819;5795.971020144872;5913.290405151323;5922.111683247547;5953.76184952597;5960.731767070492;5961.552883533753;5982.341942386334;5992.820215393545;5917.733701983421;5923.970380148072;5925.318588362307;5926.377730338339;5931.851703707355;5667;5753.741081651336;5765.596408251382;5930.347661757203;5943.286805583271;4533.591976722111;4535.251683472256;4535.455745310518;4536.591706746409;4537.977310113428;3997.2205419942834;4047.251258517184;4069.6337329640473;4082.1223436961195;4144.233316836395;5690.857728556819;5694.743736531866;5701.486866182064;5702.45826646468;5725.427457254241;5982;5990.341062483123;5991.101334799815;5991.580589358703;5999.820005399845;5560.110382338807;5686.545076393879;5689.146628005749;5737.827865164052;5750.166225897222;5269.604779641542;5270.49930256625;5270.92093618596;5272.815451459181;5295.549878260314;6199.814005579839;6289.937100628935;6310.2743184534365;6310.7475700971445;6319.526035547445;5938.020226662552;5973.701314934149;6010.549208809444;6063.727132279147;6109.816705498843;6253.343398943045;6262.968685156545;6268.843278918063;6282.748690052529;6284.340144284784;5946.762129514771;5956.344802071682;5973.611715238496;5981.222441082806;6005.879882402415;5892;6034.849128771641;6077.331493535619;6092.634441933498;6110.2973158085615;5985.790497332572;6022.48808851235;6023.427774361428;6040.486558642388;6067.211262535842;5962.284525856925;6020.849478763066;6023.397660233942;6025.276966764016;6028.939710602838;5740.167675687086;5815.04051831443;5873.207117039145;6005.519558435228;6006.249218847644;5986.341502434641;6001.909971350346;6009.218801555945;6009.759609615567;6011.128386383863;5827.854303642443;5833.79581714638;5838.678872661874;5838.678872662044;5869.618474799296 12:47:35 INFO - PROCESS | 32407 | |1;ext.html;576.4638885836173;618.1716499890147;639.191422850095;639.8144538083956;653.9346065393459;33222.50166247505;45649.77175114124;47087.29369059467;47131.52868471323;47255.76372118143;47348.76325618367;47442;47797;47901.68375073094;47958;8411.663533458654;9209.58556864941;9348.1119293667;9366.484843333614;9372;10790.622328218515;11035.72410689735;11042.944785276062;11398.373089480076;11400.201985860951;11203.60787372442;11313.321200727942;11319.603813866517;11321;11326.263792853439;11111.055560277386;11173.273737207055;11180.888191118065;11182.105431565455;11192.44037798111;10974.506147223394;11003.61487347947;11005;11007.559697612085;11011.119110471223;44462;44889.877753056106;44896.3265551017;44902.101957960986;44936.77531612321;3785.1483416231404;3808.333541630231;3882.786446745428;4196.727212731163;4531.3429552715;34830.30339393198;34836.477452838226;34873.302533949434;35221.82389088038;35757.82121089378;4563.429571303587;4574.839880604196;4645.140648979949;4656.092062047902;4727.621790256771;382030;383337;383557;383910;384183;60924.69537652372;61392;61424;61452.38547614556;61473.69263153745;39319.80340098281;39358.21283574313;39461;41908.58091419108;64024.67987660125;16971.830281697024;17029.48911532656;17112.144392780312;17164.656706865793;17191.82808171927;12807.871921280854;12988.610341689764;13167.144135631153;13296.335183240994;13303;19345.4196374109;20356.38930832048;20470.59058818815;20502.282420115225;20633.174673012913;7419.406447484296;7554.131274903347;7612.429067819936;7644.503107298119;7779.572123533147;956.9043095690514;958.5399008475971;1086.760912599227;1159.2233203753506;1208.7642909632714;32212;33343.166420839676;34059.31881362359;34092.65907340944;34104.317913641586;1184.248002518407;1184.9111316651113;1226.1049433913292;1231.4950870143198;1235.425527129889;16865.747013794804;17726.29094836218;17905.820941790676;17948.102594870204;18051.909740451214;617151;621212;622890.8554572344;623598;624357;61718.69140654358;62338.68830655818;62456;62514.811981580424;62612.68693656594;1314.6581888709004;1379.5033787836371;1381.4405165907824;1406.3038795795942;1416.8653977872086;33530.66469335275;35220;35731.821340893126;35767.821160894026;36171.27657446784;53226.2016069767;53934;53939.651508711024;53954.46045539495;53967;462066;470558;470970;473612;474132;27826.721732783222;29769.404611907208;30079.398412032075;30118;31954.520682189323;51096.74451627867;51334.48665513447;51846.48153518417;51873.740631296605;52658;41815.7909210452;42422.36366454444;42820.28718851211;42859.357109642755;44104.55895441133;42670;44004.33993490164;44222.557774421846;44646.3303050448;44914;41832.163356733305;42561.574384255764;42822;43148.56851431446;43259;35887.64112358843;38591.289155122926;39202.215955680156;39637.80181099076;39863.40204896987;29444.172257606373;29445.26386840346;29843.10470685883;29980.250493737833;30030.3993920116 12:47:35 INFO - PROCESS | 32407 | |2;jquery.html;408.99182016359657;419.8946463432591;433.66391046938617;434.25091716788546;441.28732097662265;19886.20455181793;20256.189752409886;20817;20964.58070838586;20979.16083356664;19829.50426239341;20341.389758307276;20457.693134602956;20629.174833006662;20635.17459301626;5715.742791574389;5976.312724036748;5984.940150598503;5986.28164620244;5993.730282137314;5897.056470964647;6015.398460153971;6238.625682459045;6261.937380626181;6358.777442789503;6149.508039356863;6353.968230158843;6358.332375100617;6358.809235722936;6361.491080713555;6228.252609686844;6285.685715714196;6329.2404911410695;6366.267879193906;6373.968130159319;5998.970005149947;6132.816015519542;6190.41191086846;6192.195014648112;6201.410865967726;15263.542093737206;15354.539363819104;15457.458988935334;21282.680759788615;24138.275851724477;2384.67806846077;2431.8779668461966;2463.5442443148077;2482.95034099317;2494.638277449778;10200.79598408035;10458.267921245515;10625.149988000941;11189.720256993558;11254.43727813606;2005.7091721700417;2006.7190593316948;2069.906854191565;2100.1179504608103;2106.1259577275414;84592;86528.56735716344;87345;87652.68520972255;91738;11583.478743456564;11836.822447663291;11902.285862848257;12033.819492707617;12158.148929575022;16590.419335323448;16605.0867202305;16614.6677066458;16615.6676866462;16617.833821661872;8061.516309021477;8202.630881610341;8263.876041859377;8443.240108390275;8505.362097842562;7918;8016.23845734666;8164.795880102926;8175.223353781381;8225.95887020561;4911;5042.142835717937;5063.367079115111;5089.440161582223;5224.92162617561;2096.8322534197227;2101.915923363079;2111.4088055344528;2132.317658349344;2167.583144793544;945.4091193004373;972.5283237629744;975.6000039983714;1024.5491983527372;1032.6024480574883;20734.43172298255;21421.571568568543;21497.892510537346;21605.459863503223;21809;1020.7601213714771;1028.8250997330472;1058.9099926506333;1089.3572792052728;1099.0328510910508;11263.380514071641;11722.88277117235;11796.174267801343;12019.278843269432;12061;469648;488837;526365;543482;548416;78094.21905780869;83475;84841;99150;100638.49680751549;995.0994350113159;1001.6844693921537;1022.9386236825815;1028.3726926574784;1039.5789705169418;18576.81423185751;18781.248750050392;18903.84673042911;18910.716339255196;19219.32732354361;43504.34743478787;44444;44648.55351446444;45113;45231.32153017773;57412.42587574071;57466.425335746106;57716.4228357711;64795.67602161959;66478.66760666166;6230.345813689497;8577.614007369808;8682.913170868464;8768.473891566526;9038.909610903807;12440.253584784941;13033.543825966046;13118.934405328295;13186.472541098248;13260.270685112411;10971.561137554409;11021.173411994292;11106.555737770399;11437.485313161094;11589.570984124819;10631.20265980039;11106.666799996014;11257.268277561932;11369.2609980351;11566.884331156582;10899.673009809718;10926.289791163548;10935;10995.230333876552;11144.442777861237;8546.316294696533;8595.44129631572;8674.132586741276;8690.30477561781;9001.819963600823;9150.725478235398;10935.45322733876;11093.833592496281;11113.22207445471;11272.210945233755 12:47:35 INFO - PROCESS | 32407 | |3;mootools.html;1520.9847901520984;1579.6761663858906;1739.2868923741262;1840.7699037620298;1859.3864024871793;9043.954780226099;9726.124648781608;9732.951335243331;9744.805103897917;9749.463779492125;7988.800279993003;9188.632454701821;9446.05539446055;9447.622095116205;9654.758631034229;2419.9153029643967;2451.472933319336;2462.827602067856;2463.334899577122;2474.134053081425;2411.1078900806724;2465.3220364399754;2503.111395454615;2507.185164821431;2507.323022783847;2474.814388920829;2479.8884050217785;2486.589712697412;2496.712878019033;2517.5971844504884;2415.444447777021;2416.250962201714;2423.3456966619;2483.2177863972793;2497.775200231988;2480.4790993891306;2495.8128140389545;2501.7873480753974;2503.161440917295;2526.9115580954576;9606.519674016272;9702.369345992487;9753.414795112316;9758.707238782848;9803.90196098037;5494.5054945054735;5615.606907516475;5654.37801841797;5662.745176467069;5665.093585026417;3381.8332675227007;3493.598236202844;3552.0231936217488;3553;3581.4448760442174;1189.7858385490608;1212.3332167308033;1234.4383305595932;1237.2947419970621;1251.8184863194786;13767;13885.722285554333;13905.582832515041;13926.582202533838;13926.791098133435;4988.326575912278;5028.874278143076;5164.845054648366;5175.120229560908;5303.469653034743;11366.374849383197;11384.715382115513;11405.372704501166;11437.142214333959;11479.426028698532;5764.654120752768;5767.423257674283;5767.740451679684;5772.653640781483;5774.364819869811;4545.636349092131;5184.274201611777;5247.475252474798;5282.841514754564;5292.682439053669;4320.567943205654;4363.52001279859;4394.472663280363;4410;4439.889002774892;1239.0892693869998;1241.2490443281686;1293.1723696834033;1293.2628401810878;1312.0290984671274;1051.836965270369;1055.303499690216;1061.7717190804015;1062.436908438529;1066.4561073852392;6298.842528936813;6385.6487893165395;6775.559588626822;6777.353841262256;6836.316368363224;500.16722157607177;503.55183886341183;504.85863958091795;505.40867185393176;506.78714939725404;4454.22051141049;4468.419105516327;4508.819647214074;4527.207738645724;4622.375979242759;11525.88474115248;11941.701457463632;12042.33767142798;12389.876101239053;12484.063695222894;12257.14200005991;12274.693132671755;12277.57028504016;12291.385430728427;12294.508219671114;8808.603612837438;9003.504807235533;9008.63965441375;9022.323325750594;9024.187823095954;6605.504587155887;6776.6950487227205;6863.313668633296;6925.342092501204;6991.615461149686;11008.174386921173;11186.72033199144;11367.943160284145;11386.146039046938;11402.20184587071;11242.494087766234;11544.307341559534;11638.418079095842;11688.123390745557;11846.052315814886;7482.812929676802;7725.381969442544;7737.497062690907;8459.407841451039;8628.39601227933;8308.086110527716;9136.26909847224;9292.95353523228;9318.114779095975;9576.377535460173;8331.625076871434;8528.147185281423;8551.8717219243;8581.528015958933;8633.30933525329;8324.542150181798;8325.95837020811;8347.12355202695;8434.072252052147;8567.700130495403;7525.8118547036765;7578.242175782378;7655.808604784702;7659.234076592296;7676.270754278335;5738.081906894877;5791.9131213031;5874.647521148745;5888.852778680396;5934.376890426443;5488.560915126701;5608.719564021864;5735.225744524436;5764.538836893123;5891.263592050994 12:47:35 INFO - PROCESS | 32407 | |4;prototype.html;343.80574975139047;346.9531613232213;351.15897425665514;354.65066909094537;360.44671429355935;5603.215549823023;5648.830535083944;5666.603337766358;5674.489295963363;5713.485786279234;5540.9168862466995;5583.134614134816;5585.3297604287445;5607.271054762875;5644.181593668915;3895.331697787025;4219.28272193727;4291.420658211148;4373.2565463871215;4378.496472905609;4371.344298355256;4375.103103863717;4375.387445757597;4377.737335759864;4402.8679139625865;4340.717853339539;4346.5436129206455;4352.325389564607;4397.736135831844;4416.359627853959;4276.3157894737005;4310.676699247569;4363.410939523155;4379.715318504287;4405.801738921757;3836.7889766062854;3836.9424458633143;3851.4030325299623;3853.730238883279;3854.2676891390547;5404.891902161974;5412.350517937873;5422.620416570841;5481.013417584833;5483.890322193573;4797.328374027639;4970.950290497085;4995.226694523428;5003.924941125886;5037.748112594356;5753.971230143864;5763.308402991626;5790.536757059425;5791.7837254176675;5830.658948441882;4236.046889449849;4239.788010599457;4246.363045543162;4261.808218630138;4272.978635106804;5878.941210587882;5988.7604495820115;6049.1833602463985;6054.002179280827;6066.756314955468;4288.099499105223;4306.978465107717;4332.263515202423;4342.08816148606;4348.869533913988;4385.122975404932;4388.956110438919;4391.275439552502;4395.846145384896;4410.4927933287445;4807.519248075165;4941.604671626261;4978.626603004789;4992.425871024806;5005.34930459038;5812.88374232513;5814.389489103583;5822.573469499915;5854.677992710442;5930.169776231331;1497.4010395841751;1500.1824005916583;1520.6198450387403;1522.1856306875768;1528.533797191845;914.9679761208458;938.8591711243369;940.7789169545151;946.4652471353663;969.1651918139414;5056.890012642199;5059.468755780664;5076.340075790124;5081.551757749097;5082.593392528589;5284.973575132177;5329.467053294639;5342.759575819097;5346.4386239445075;5373.731313434313;4933.851984440473;5114.820981265712;5124.514610413947;5137.434882162959;5142.765736223354;2780.249332680165;2806.775457963358;2832.603435519029;2838.7728981681416;2876.2234196767176;5410.48600382963;5413.864653383618;5415.296011518556;5460.34475862899;5488.56091512678;5335.839924802262;5639.520640745501;5820.767169313265;6083.422074902877;6139.723712432951;6105.48103411215;6161.84595385119;6202.255729312421;6212;6233.189685340877;6017;6020.488258498077;6030.90953635687;6032.155498230163;6041.670832416861;5694.430556944438;5906.379830117948;5919.881602367843;5929.940700592939;5960.731767070579;6241.095041219089;6242.438180563771;6249.281332646804;6263.906041409291;6267.373262673696;4071.91856162881;4290.099079193405;4320.200762858881;4323.870283891489;4374.081442897027;5924.31870334917;5950.791722289872;5953.196318496947;5953.821385358273;5988.850278743066;5774.451427114417;5795.8551036224435;5800.59395842287;5802.332731735735;5818.359980402237;5632.746526406403;5791.610013596623;5800.651960882361;5805.10020946754;5818.214541036906;5796.1305804129715;5800.6809625471005;5802.448767367094;5804.912926306193;5813.56398270123;5639.54883609302;5807.477327040587;5830.271216097988;5833.7666493341485;5843.503302219359;5660.179274005329;5667.971660141673;5677.621815020057;5685.346185188756;5702.287214098238 12:47:35 INFO - PROCESS | 32407 | |5;yui.html;811.8132829449227;828.2793969246757;868.7524055644143;884.3323290915358;907.4101833808028;16266.91866540667;17915.28338866447;17925.999999999985;17935.551611209725;17940.910295448506;16136.354545818183;16237.106959117244;16260.268287927041;16263.10552919589;16279.430219942304;736.0799001248427;860.9238451935081;883.2404132446123;887.3611000079965;889.6085722282207;838.5681374092347;838.714836955435;839.0728245288959;839.6053854688287;840.4075127035426;891.095538028898;893.4951752259952;893.9821203575924;894.4990805149105;897.712731925784;883.0771843423638;883.6509578716435;884.2263019857593;885.0087901550305;886.8536691445904;653.4674240493982;653.6633633678659;653.950953678476;654.2508827392609;660.3726459863101;15464.226788660522;15510.146941918303;15515.301811418512;15548.533543993699;15564.377424903103;14433.206173660445;14703;14714.264286785725;15261;16295.918520407322;14629.41482340705;14760.630984225372;14816.629584260372;14877.404903803836;15118.168500732456;4851.781669824866;4868.196747536652;4892.437369702494;4929.457759646436;4947.752612369367;85173;86091.13908860894;86480.99999999937;92040.53979730126;92894;12421.00631949442;12440.626781196579;12526.185797923106;12586.307753073668;12728.10903236783;21640.675389869164;23337.18319858797;23343.649845252334;23346.64980025301;23398;9425.811483770287;9450.243980481546;9547.427154370622;9573.952130239444;9654.613815447445;5142.5628821549835;5175.275461435402;5191.143461328914;5307.548858347007;5379.596530260246;3966.841326346914;4037.454943582579;4101.425800387948;4112.897177570525;4113.609207125318;711.850511392603;712.8075419636773;725.2855936902147;726.0525014855558;727.6543641770116;774.7636970723984;796.900168057162;807.0557447785988;813.0771574263135;817.513579420244;10010.749731256778;10903.008363500187;10904.945475272572;10908.290961087503;10965.232433729721;663.227340148731;688.1295161620554;751.0874287740312;760.2435576601317;780.808701868046;5009.924851127236;5144.7942082316285;5153.725068244812;5299;5387.353517577916;73005.26994730091;73145;73184.63407683033;73479;114257;100211.49894250483;110392;111348.44325778482;112489;114888;57348.71325643345;89912.5504372474;94830.15509534586;95335.52332238432;97213.51393242988;9672.85490717654;10031.29780915329;10047.246456515644;10146.391216527032;10186.439745814052;55030;55349;55474.94887781211;56025.71987140038;56226;81717;82515.58742206251;82628.21602487768;84327;84568.57715711382;12038.398080096134;12526.12317137828;12595.181313214613;12624.55814046504;12763.106582539132;14116.929415352857;14139.151650900978;14476.203808790618;14547.490837820626;14561.708765824413;13023.544175953795;13197.604071877859;13291.933540332237;13307.334633268492;13688.589342319747;13400.798988015382;13425.395857186646;13503.12229705066;13616.455341786217;13640.863591364356;12505.499780008699;12661.177023493443;13174.209547427185;13176.802347964596;13459.192448453125;10031.247656425943;10153.898461015295;10538.156947444339;10753.085987691135;11139.387333696724;9901.55443005052;11027.466937944939;11675.416229188337;11898.76202475963;12174.756504869676 12:47:35 INFO - PROCESS | 32407 | __end_tp_report 12:47:35 INFO - PROCESS | 32407 | __start_cc_report 12:47:35 INFO - PROCESS | 32407 | _x_x_mozilla_cycle_collect,838 12:47:35 INFO - PROCESS | 32407 | __end_cc_report 12:47:35 INFO - PROCESS | 32407 | __startTimestamp1477424855286__endTimestamp 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | ------- Summary: start ------- 12:47:35 INFO - PROCESS | 32407 | Number of tests: 6 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | [#0] dojo.html Cycles:165 Average:5612.82 Median:5795.97 stddev:643.73 (11.1%) stddev-sans-first:617.61 12:47:35 INFO - PROCESS | 32407 | Values: 3215.2 3226.5 3246.2 3279.1 3285.2 5648.0 5731.2 5753.1 5776.2 5779.7 5560.6 5675.2 5677.4 5682.4 5685.6 5118.6 5118.8 5119.7 5167.0 5187.3 5020.4 5073.0 5216.6 5216.7 5253.9 5018.8 5041.1 5062.1 5146.9 5187.9 5198.6 5249.0 5331.1 5336.7 5362.6 4950.7 5154.9 5241.4 5299.0 5312.2 5443.3 5513.8 5564.5 5578.2 5585.6 6076.4 6094.8 6121.2 6123.9 6134.9 5793.9 5949.9 6174.6 6189.4 6193.2 5592.2 5664.1 5682.0 5702.8 5704.9 6183.5 6190.2 6193.3 6197.5 6200.7 5644.8 5738.9 5796.0 5913.3 5922.1 5953.8 5960.7 5961.6 5982.3 5992.8 5917.7 5924.0 5925.3 5926.4 5931.9 5667.0 5753.7 5765.6 5930.3 5943.3 4533.6 4535.3 4535.5 4536.6 4538.0 3997.2 4047.3 4069.6 4082.1 4144.2 5690.9 5694.7 5701.5 5702.5 5725.4 5982.0 5990.3 5991.1 5991.6 5999.8 5560.1 5686.5 5689.1 5737.8 5750.2 5269.6 5270.5 5270.9 5272.8 5295.5 6199.8 6289.9 6310.3 6310.7 6319.5 5938.0 5973.7 6010.5 6063.7 6109.8 6253.3 6263.0 6268.8 6282.7 6284.3 5946.8 5956.3 5973.6 5981.2 6005.9 5892.0 6034.8 6077.3 6092.6 6110.3 5985.8 6022.5 6023.4 6040.5 6067.2 5962.3 6020.8 6023.4 6025.3 6028.9 5740.2 5815.0 5873.2 6005.5 6006.2 5986.3 6001.9 6009.2 6009.8 6011.1 5827.9 5833.8 5838.7 5838.7 5869.6 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | [#1] ext.html Cycles:180 Average:65569.81 Median:39339.01 stddev:133288.78 (338.8%) stddev-sans-first:133573.37 12:47:35 INFO - PROCESS | 32407 | Values: 576.5 618.2 639.2 639.8 653.9 33222.5 45649.8 47087.3 47131.5 47255.8 47348.8 47442.0 47797.0 47901.7 47958.0 8411.7 9209.6 9348.1 9366.5 9372.0 10790.6 11035.7 11042.9 11398.4 11400.2 11203.6 11313.3 11319.6 11321.0 11326.3 11111.1 11173.3 11180.9 11182.1 11192.4 10974.5 11003.6 11005.0 11007.6 11011.1 44462.0 44889.9 44896.3 44902.1 44936.8 3785.1 3808.3 3882.8 4196.7 4531.3 34830.3 34836.5 34873.3 35221.8 35757.8 4563.4 4574.8 4645.1 4656.1 4727.6 382030.0 383337.0 383557.0 383910.0 384183.0 60924.7 61392.0 61424.0 61452.4 61473.7 39319.8 39358.2 39461.0 41908.6 64024.7 16971.8 17029.5 17112.1 17164.7 17191.8 12807.9 12988.6 13167.1 13296.3 13303.0 19345.4 20356.4 20470.6 20502.3 20633.2 7419.4 7554.1 7612.4 7644.5 7779.6 956.9 958.5 1086.8 1159.2 1208.8 32212.0 33343.2 34059.3 34092.7 34104.3 1184.2 1184.9 1226.1 1231.5 1235.4 16865.7 17726.3 17905.8 17948.1 18051.9 617151.0 621212.0 622890.9 623598.0 624357.0 61718.7 62338.7 62456.0 62514.8 62612.7 1314.7 1379.5 1381.4 1406.3 1416.9 33530.7 35220.0 35731.8 35767.8 36171.3 53226.2 53934.0 53939.7 53954.5 53967.0 462066.0 470558.0 470970.0 473612.0 474132.0 27826.7 29769.4 30079.4 30118.0 31954.5 51096.7 51334.5 51846.5 51873.7 52658.0 41815.8 42422.4 42820.3 42859.4 44104.6 42670.0 44004.3 44222.6 44646.3 44914.0 41832.2 42561.6 42822.0 43148.6 43259.0 35887.6 38591.3 39202.2 39637.8 39863.4 29444.2 29445.3 29843.1 29980.3 30030.4 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | [#2] jquery.html Cycles:180 Average:30128.52 Median:13261.48 stddev:85077.80 (641.5%) stddev-sans-first:85287.20 12:47:35 INFO - PROCESS | 32407 | Values: 409.0 419.9 433.7 434.3 441.3 19886.2 20256.2 20817.0 20964.6 20979.2 19829.5 20341.4 20457.7 20629.2 20635.2 5715.7 5976.3 5984.9 5986.3 5993.7 5897.1 6015.4 6238.6 6261.9 6358.8 6149.5 6354.0 6358.3 6358.8 6361.5 6228.3 6285.7 6329.2 6366.3 6374.0 5999.0 6132.8 6190.4 6192.2 6201.4 15263.5 15354.5 15457.5 21282.7 24138.3 2384.7 2431.9 2463.5 2483.0 2494.6 10200.8 10458.3 10625.1 11189.7 11254.4 2005.7 2006.7 2069.9 2100.1 2106.1 84592.0 86528.6 87345.0 87652.7 91738.0 11583.5 11836.8 11902.3 12033.8 12158.1 16590.4 16605.1 16614.7 16615.7 16617.8 8061.5 8202.6 8263.9 8443.2 8505.4 7918.0 8016.2 8164.8 8175.2 8226.0 4911.0 5042.1 5063.4 5089.4 5224.9 2096.8 2101.9 2111.4 2132.3 2167.6 945.4 972.5 975.6 1024.5 1032.6 20734.4 21421.6 21497.9 21605.5 21809.0 1020.8 1028.8 1058.9 1089.4 1099.0 11263.4 11722.9 11796.2 12019.3 12061.0 469648.0 488837.0 526365.0 543482.0 548416.0 78094.2 83475.0 84841.0 99150.0 100638.5 995.1 1001.7 1022.9 1028.4 1039.6 18576.8 18781.2 18903.8 18910.7 19219.3 43504.3 44444.0 44648.6 45113.0 45231.3 57412.4 57466.4 57716.4 64795.7 66478.7 6230.3 8577.6 8682.9 8768.5 9038.9 12440.3 13033.5 13118.9 13186.5 13260.3 10971.6 11021.2 11106.6 11437.5 11589.6 10631.2 11106.7 11257.3 11369.3 11566.9 10899.7 10926.3 10935.0 10995.2 11144.4 8546.3 8595.4 8674.1 8690.3 9001.8 9150.7 10935.5 11093.8 11113.2 11272.2 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | [#3] mootools.html Cycles:180 Average:6356.01 Median:4805.35 stddev:3706.77 (77.1%) stddev-sans-first:3699.36 12:47:35 INFO - PROCESS | 32407 | Values: 1521.0 1579.7 1739.3 1840.8 1859.4 9044.0 9726.1 9733.0 9744.8 9749.5 7988.8 9188.6 9446.1 9447.6 9654.8 2419.9 2451.5 2462.8 2463.3 2474.1 2411.1 2465.3 2503.1 2507.2 2507.3 2474.8 2479.9 2486.6 2496.7 2517.6 2415.4 2416.3 2423.3 2483.2 2497.8 2480.5 2495.8 2501.8 2503.2 2526.9 9606.5 9702.4 9753.4 9758.7 9803.9 5494.5 5615.6 5654.4 5662.7 5665.1 3381.8 3493.6 3552.0 3553.0 3581.4 1189.8 1212.3 1234.4 1237.3 1251.8 13767.0 13885.7 13905.6 13926.6 13926.8 4988.3 5028.9 5164.8 5175.1 5303.5 11366.4 11384.7 11405.4 11437.1 11479.4 5764.7 5767.4 5767.7 5772.7 5774.4 4545.6 5184.3 5247.5 5282.8 5292.7 4320.6 4363.5 4394.5 4410.0 4439.9 1239.1 1241.2 1293.2 1293.3 1312.0 1051.8 1055.3 1061.8 1062.4 1066.5 6298.8 6385.6 6775.6 6777.4 6836.3 500.2 503.6 504.9 505.4 506.8 4454.2 4468.4 4508.8 4527.2 4622.4 11525.9 11941.7 12042.3 12389.9 12484.1 12257.1 12274.7 12277.6 12291.4 12294.5 8808.6 9003.5 9008.6 9022.3 9024.2 6605.5 6776.7 6863.3 6925.3 6991.6 11008.2 11186.7 11367.9 11386.1 11402.2 11242.5 11544.3 11638.4 11688.1 11846.1 7482.8 7725.4 7737.5 8459.4 8628.4 8308.1 9136.3 9293.0 9318.1 9576.4 8331.6 8528.1 8551.9 8581.5 8633.3 8324.5 8326.0 8347.1 8434.1 8567.7 7525.8 7578.2 7655.8 7659.2 7676.3 5738.1 5791.9 5874.6 5888.9 5934.4 5488.6 5608.7 5735.2 5764.5 5891.3 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | [#4] prototype.html Cycles:180 Average:4838.28 Median:5418.96 stddev:1420.27 (26.2%) stddev-sans-first:1383.61 12:47:35 INFO - PROCESS | 32407 | Values: 343.8 347.0 351.2 354.7 360.4 5603.2 5648.8 5666.6 5674.5 5713.5 5540.9 5583.1 5585.3 5607.3 5644.2 3895.3 4219.3 4291.4 4373.3 4378.5 4371.3 4375.1 4375.4 4377.7 4402.9 4340.7 4346.5 4352.3 4397.7 4416.4 4276.3 4310.7 4363.4 4379.7 4405.8 3836.8 3836.9 3851.4 3853.7 3854.3 5404.9 5412.4 5422.6 5481.0 5483.9 4797.3 4971.0 4995.2 5003.9 5037.7 5754.0 5763.3 5790.5 5791.8 5830.7 4236.0 4239.8 4246.4 4261.8 4273.0 5878.9 5988.8 6049.2 6054.0 6066.8 4288.1 4307.0 4332.3 4342.1 4348.9 4385.1 4389.0 4391.3 4395.8 4410.5 4807.5 4941.6 4978.6 4992.4 5005.3 5812.9 5814.4 5822.6 5854.7 5930.2 1497.4 1500.2 1520.6 1522.2 1528.5 915.0 938.9 940.8 946.5 969.2 5056.9 5059.5 5076.3 5081.6 5082.6 5285.0 5329.5 5342.8 5346.4 5373.7 4933.9 5114.8 5124.5 5137.4 5142.8 2780.2 2806.8 2832.6 2838.8 2876.2 5410.5 5413.9 5415.3 5460.3 5488.6 5335.8 5639.5 5820.8 6083.4 6139.7 6105.5 6161.8 6202.3 6212.0 6233.2 6017.0 6020.5 6030.9 6032.2 6041.7 5694.4 5906.4 5919.9 5929.9 5960.7 6241.1 6242.4 6249.3 6263.9 6267.4 4071.9 4290.1 4320.2 4323.9 4374.1 5924.3 5950.8 5953.2 5953.8 5988.9 5774.5 5795.9 5800.6 5802.3 5818.4 5632.7 5791.6 5800.7 5805.1 5818.2 5796.1 5800.7 5802.4 5804.9 5813.6 5639.5 5807.5 5830.3 5833.8 5843.5 5660.2 5668.0 5677.6 5685.3 5702.3 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - PROCESS | 32407 | [#5] yui.html Cycles:180 Average:21272.78 Median:4880.32 stddev:29818.74 (611.0%) stddev-sans-first:29862.81 12:47:35 INFO - PROCESS | 32407 | Values: 811.8 828.3 868.8 884.3 907.4 16266.9 17915.3 17926.0 17935.6 17940.9 16136.4 16237.1 16260.3 16263.1 16279.4 736.1 860.9 883.2 887.4 889.6 838.6 838.7 839.1 839.6 840.4 891.1 893.5 894.0 894.5 897.7 883.1 883.7 884.2 885.0 886.9 653.5 653.7 654.0 654.3 660.4 15464.2 15510.1 15515.3 15548.5 15564.4 14433.2 14703.0 14714.3 15261.0 16295.9 14629.4 14760.6 14816.6 14877.4 15118.2 4851.8 4868.2 4892.4 4929.5 4947.8 85173.0 86091.1 86481.0 92040.5 92894.0 12421.0 12440.6 12526.2 12586.3 12728.1 21640.7 23337.2 23343.6 23346.6 23398.0 9425.8 9450.2 9547.4 9574.0 9654.6 5142.6 5175.3 5191.1 5307.5 5379.6 3966.8 4037.5 4101.4 4112.9 4113.6 711.9 712.8 725.3 726.1 727.7 774.8 796.9 807.1 813.1 817.5 10010.7 10903.0 10904.9 10908.3 10965.2 663.2 688.1 751.1 760.2 780.8 5009.9 5144.8 5153.7 5299.0 5387.4 73005.3 73145.0 73184.6 73479.0 114257.0 100211.5 110392.0 111348.4 112489.0 114888.0 57348.7 89912.6 94830.2 95335.5 97213.5 9672.9 10031.3 10047.2 10146.4 10186.4 55030.0 55349.0 55474.9 56025.7 56226.0 81717.0 82515.6 82628.2 84327.0 84568.6 12038.4 12526.1 12595.2 12624.6 12763.1 14116.9 14139.2 14476.2 14547.5 14561.7 13023.5 13197.6 13291.9 13307.3 13688.6 13400.8 13425.4 13503.1 13616.5 13640.9 12505.5 12661.2 13174.2 13176.8 13459.2 10031.2 10153.9 10538.2 10753.1 11139.4 9901.6 11027.5 11675.4 11898.8 12174.8 12:47:35 INFO - PROCESS | 32407 | -------- Summary: end -------- 12:47:35 INFO - PROCESS | 32407 | 12:47:35 INFO - TEST-INFO | 32407: exit 0 12:47:35 INFO - TEST-OK | dromaeo_css | took 1111407ms 12:47:35 INFO - TEST-START | kraken 12:47:35 INFO - operating with platform_type : linux_ 12:47:35 INFO - Initialising browser for kraken test... 12:47:35 INFO - TEST-INFO | started process 32595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjSVVE_/profile http://localhost:44463/getInfo.html) 12:47:39 INFO - PROCESS | 32595 | __metrics Screen width/height:1600/1200 12:47:39 INFO - PROCESS | 32595 | colorDepth:24 12:47:39 INFO - PROCESS | 32595 | Browser inner width/height: 1024/697 12:47:39 INFO - PROCESS | 32595 | __metrics 12:47:40 INFO - PROCESS | 32595 | 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" 12:47:40 INFO - PROCESS | 32595 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:47:41 INFO - TEST-INFO | 32595: exit 0 12:47:41 INFO - Browser initialized. 12:47:41 INFO - Running cycle 1/1 for kraken test... 12:47:41 INFO - TEST-INFO | started process 32682 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjSVVE_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:47:53 INFO - PROCESS | 32682 | RSS: Main: 144887808 12:47:53 INFO - PROCESS | 32682 | 12:47:55 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44463/tests/kraken/driver.html?testName=audio-beat-detection) 12:47:56 INFO - PROCESS | 32682 | RSS: Main: 199618560 12:47:56 INFO - PROCESS | 32682 | 12:47:58 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44463/tests/kraken/driver.html?testName=audio-dft) 12:47:59 INFO - PROCESS | 32682 | RSS: Main: 183717888 12:47:59 INFO - PROCESS | 32682 | 12:48:02 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44463/tests/kraken/driver.html?testName=audio-fft) 12:48:02 INFO - PROCESS | 32682 | RSS: Main: 274554880 12:48:02 INFO - PROCESS | 32682 | 12:48:04 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44463/tests/kraken/driver.html?testName=audio-oscillator) 12:48:05 INFO - PROCESS | 32682 | RSS: Main: 181018624 12:48:05 INFO - PROCESS | 32682 | 12:48:07 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44463/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:48:08 INFO - PROCESS | 32682 | RSS: Main: 179773440 12:48:08 INFO - PROCESS | 32682 | 12:48:11 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44463/tests/kraken/driver.html?testName=imaging-darkroom) 12:48:11 INFO - PROCESS | 32682 | RSS: Main: 215764992 12:48:11 INFO - PROCESS | 32682 | 12:48:15 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44463/tests/kraken/driver.html?testName=imaging-desaturate) 12:48:15 INFO - PROCESS | 32682 | RSS: Main: 214949888 12:48:15 INFO - PROCESS | 32682 | 12:48:18 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44463/tests/kraken/driver.html?testName=json-parse-financial) 12:48:19 INFO - PROCESS | 32682 | RSS: Main: 213954560 12:48:19 INFO - PROCESS | 32682 | 12:48:20 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44463/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:48:21 INFO - PROCESS | 32682 | RSS: Main: 176586752 12:48:21 INFO - PROCESS | 32682 | 12:48:22 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:48:23 INFO - PROCESS | 32682 | RSS: Main: 183459840 12:48:23 INFO - PROCESS | 32682 | 12:48:25 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:48:26 INFO - PROCESS | 32682 | RSS: Main: 190128128 12:48:26 INFO - PROCESS | 32682 | 12:48:28 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:48:29 INFO - PROCESS | 32682 | RSS: Main: 198946816 12:48:29 INFO - PROCESS | 32682 | 12:48:31 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:48:32 INFO - PROCESS | 32682 | RSS: Main: 195039232 12:48:32 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | Cycle 1(1): loaded http://localhost:44463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44463/tests/kraken/driver.html?testName=ai-astar) 12:48:34 INFO - PROCESS | 32682 | RSS: Main: 245030912 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | __start_tp_report 12:48:34 INFO - PROCESS | 32682 | _x_x_mozilla_page_load 12:48:34 INFO - PROCESS | 32682 | _x_x_mozilla_page_load_details 12:48:34 INFO - PROCESS | 32682 | |i|pagename|runs| 12:48:34 INFO - PROCESS | 32682 | |0;ai-astar;110;110;118;110;111;113;111;109;109;110 12:48:34 INFO - PROCESS | 32682 | |1;audio-beat-detection;149;115;138;169;117;138;148;121;169;120 12:48:34 INFO - PROCESS | 32682 | |2;audio-dft;160;160;160;163;160;161;161;160;160;164 12:48:34 INFO - PROCESS | 32682 | |3;audio-fft;75;76;75;169;75;75;76;76;176;75 12:48:34 INFO - PROCESS | 32682 | |4;audio-oscillator;84;87;157;84;84;83;150;83;83;153 12:48:34 INFO - PROCESS | 32682 | |5;imaging-gaussian-blur;116;116;116;117;117;117;117;117;117;116 12:48:34 INFO - PROCESS | 32682 | |6;imaging-darkroom;146;145;145;146;145;145;145;146;147;147 12:48:34 INFO - PROCESS | 32682 | |7;imaging-desaturate;94;95;94;95;94;95;94;94;94;94 12:48:34 INFO - PROCESS | 32682 | |8;json-parse-financial;53;50;54;50;50;50;51;51;50;50 12:48:34 INFO - PROCESS | 32682 | |9;json-stringify-tinderbox;59;61;59;61;61;59;60;61;60;62 12:48:34 INFO - PROCESS | 32682 | |10;stanford-crypto-aes;73;73;72;80;77;73;82;75;74;81 12:48:34 INFO - PROCESS | 32682 | |11;stanford-crypto-ccm;119;119;119;130;118;121;130;131;151;130 12:48:34 INFO - PROCESS | 32682 | |12;stanford-crypto-pbkdf2;153;156;154;150;154;150;149;151;153;151 12:48:34 INFO - PROCESS | 32682 | |13;stanford-crypto-sha256-iterative;61;60;62;61;62;62;60;60;63;61 12:48:34 INFO - PROCESS | 32682 | __end_tp_report 12:48:34 INFO - PROCESS | 32682 | __start_cc_report 12:48:34 INFO - PROCESS | 32682 | _x_x_mozilla_cycle_collect,1656 12:48:34 INFO - PROCESS | 32682 | __end_cc_report 12:48:34 INFO - PROCESS | 32682 | __startTimestamp1477424914275__endTimestamp 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | ------- Summary: start ------- 12:48:34 INFO - PROCESS | 32682 | Number of tests: 14 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#0] ai-astar Cycles:10 Average:111.10 Median:110.50 stddev:2.69 (2.4%) stddev-sans-first:2.82 12:48:34 INFO - PROCESS | 32682 | Values: 110.0 110.0 118.0 110.0 111.0 113.0 111.0 109.0 109.0 110.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:143.00 stddev:20.34 (14.2%) stddev-sans-first:21.21 12:48:34 INFO - PROCESS | 32682 | Values: 149.0 115.0 138.0 169.0 117.0 138.0 148.0 121.0 169.0 120.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#2] audio-dft Cycles:10 Average:160.90 Median:160.50 stddev:1.45 (0.9%) stddev-sans-first:1.50 12:48:34 INFO - PROCESS | 32682 | Values: 160.0 160.0 160.0 163.0 160.0 161.0 161.0 160.0 160.0 164.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#3] audio-fft Cycles:10 Average:94.80 Median:75.00 stddev:40.99 (54.6%) stddev-sans-first:42.84 12:48:34 INFO - PROCESS | 32682 | Values: 75.0 76.0 75.0 169.0 75.0 75.0 76.0 76.0 176.0 75.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:83.50 stddev:33.55 (40.2%) stddev-sans-first:34.73 12:48:34 INFO - PROCESS | 32682 | Values: 84.0 87.0 157.0 84.0 84.0 83.0 150.0 83.0 83.0 153.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 12:48:34 INFO - PROCESS | 32682 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 12:48:34 INFO - PROCESS | 32682 | Values: 146.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 147.0 147.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 12:48:34 INFO - PROCESS | 32682 | Values: 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:50.50 stddev:1.45 (2.9%) stddev-sans-first:1.32 12:48:34 INFO - PROCESS | 32682 | Values: 53.0 50.0 54.0 50.0 50.0 50.0 51.0 51.0 50.0 50.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:61.00 stddev:1.06 (1.7%) stddev-sans-first:1.01 12:48:34 INFO - PROCESS | 32682 | Values: 59.0 61.0 59.0 61.0 61.0 59.0 60.0 61.0 60.0 62.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.00 stddev:3.74 (4.9%) stddev-sans-first:3.81 12:48:34 INFO - PROCESS | 32682 | Values: 73.0 73.0 72.0 80.0 77.0 73.0 82.0 75.0 74.0 81.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:130.00 stddev:10.15 (7.8%) stddev-sans-first:10.37 12:48:34 INFO - PROCESS | 32682 | Values: 119.0 119.0 119.0 130.0 118.0 121.0 130.0 131.0 151.0 130.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:153.00 stddev:2.23 (1.5%) stddev-sans-first:2.35 12:48:34 INFO - PROCESS | 32682 | Values: 153.0 156.0 154.0 150.0 154.0 150.0 149.0 151.0 153.0 151.0 12:48:34 INFO - PROCESS | 32682 | 12:48:34 INFO - PROCESS | 32682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:1.03 (1.7%) stddev-sans-first:1.09 12:48:34 INFO - PROCESS | 32682 | Values: 61.0 60.0 62.0 61.0 62.0 62.0 60.0 60.0 63.0 61.0 12:48:34 INFO - PROCESS | 32682 | -------- Summary: end -------- 12:48:34 INFO - PROCESS | 32682 | 12:48:35 INFO - TEST-INFO | 32682: exit 0 12:48:35 INFO - TEST-OK | kraken | took 59255ms 12:48:35 INFO - SUITE-END | took 1170s 12:48:35 INFO - Completed test suite (00:19:30) 12:48:35 INFO - javascript benchmark 12:48:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3215.212272993116, 3226.548283240347, 3246.155999440145, 3279.147421670365, 3285.2115492281846, 5648.039833228351, 5731.168980497825, 5753.108268218428, 5776.191333213354, 5779.711014449282, 5560.582956278275, 5675.177099320596, 5677.40387259337, 5682.403347648489, 5685.630434021786, 5118.590512758989, 5118.769655365499, 5119.718415487147, 5167.018266529366, 5187.273781670569, 5020.422651395082, 5073.036123136592, 5216.60875434344, 5216.7391630418515, 5253.921191182136, 5018.799248030093, 5041.1178043842365, 5062.139436295838, 5146.897062058775, 5187.922181167267, 5198.558122559568, 5249.0, 5331.120365139747, 5336.653117547386, 5362.624616276862, 4950.653454258203, 5154.896902061975, 5241.449647786965, 5298.97350513245, 5312.150055991023, 5443.319585051869, 5513.807016754435, 5564.471375219388, 5578.163275508706, 5585.581081418869, 6076.392360763933, 6094.756209751649, 6121.234845644294, 6123.9387606124255, 6134.8773024539705, 5793.8841223175305, 5949.9107513387335, 6174.598651087665, 6189.412005859436, 6193.194884665009, 5592.245046918654, 5664.093745000862, 5681.971590142023, 5702.800401985911, 5704.857378565527, 6183.505319574423, 6190.226221722285, 6193.256809182927, 6197.504199664016, 6200.689965501797, 5644.8024319148635, 5738.856528586819, 5795.971020144872, 5913.290405151323, 5922.111683247547, 5953.76184952597, 5960.731767070492, 5961.552883533753, 5982.341942386334, 5992.820215393545, 5917.733701983421, 5923.970380148072, 5925.318588362307, 5926.377730338339, 5931.851703707355, 5667.0, 5753.741081651336, 5765.596408251382, 5930.347661757203, 5943.286805583271, 4533.591976722111, 4535.251683472256, 4535.455745310518, 4536.591706746409, 4537.977310113428, 3997.2205419942834, 4047.251258517184, 4069.6337329640473, 4082.1223436961195, 4144.233316836395, 5690.857728556819, 5694.743736531866, 5701.486866182064, 5702.45826646468, 5725.427457254241, 5982.0, 5990.341062483123, 5991.101334799815, 5991.580589358703, 5999.820005399845, 5560.110382338807, 5686.545076393879, 5689.146628005749, 5737.827865164052, 5750.166225897222, 5269.604779641542, 5270.49930256625, 5270.92093618596, 5272.815451459181, 5295.549878260314, 6199.814005579839, 6289.937100628935, 6310.2743184534365, 6310.7475700971445, 6319.526035547445, 5938.020226662552, 5973.701314934149, 6010.549208809444, 6063.727132279147, 6109.816705498843, 6253.343398943045, 6262.968685156545, 6268.843278918063, 6282.748690052529, 6284.340144284784, 5946.762129514771, 5956.344802071682, 5973.611715238496, 5981.222441082806, 6005.879882402415, 5892.0, 6034.849128771641, 6077.331493535619, 6092.634441933498, 6110.2973158085615, 5985.790497332572, 6022.48808851235, 6023.427774361428, 6040.486558642388, 6067.211262535842, 5962.284525856925, 6020.849478763066, 6023.397660233942, 6025.276966764016, 6028.939710602838, 5740.167675687086, 5815.04051831443, 5873.207117039145, 6005.519558435228, 6006.249218847644, 5986.341502434641, 6001.909971350346, 6009.218801555945, 6009.759609615567, 6011.128386383863, 5827.854303642443, 5833.79581714638, 5838.678872661874, 5838.678872662044, 5869.618474799296], "value": 5568.738888241433, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.4638885836173, 618.1716499890147, 639.191422850095, 639.8144538083956, 653.9346065393459, 33222.50166247505, 45649.77175114124, 47087.29369059467, 47131.52868471323, 47255.76372118143, 47348.76325618367, 47442.0, 47797.0, 47901.68375073094, 47958.0, 8411.663533458654, 9209.58556864941, 9348.1119293667, 9366.484843333614, 9372.0, 10790.622328218515, 11035.72410689735, 11042.944785276062, 11398.373089480076, 11400.201985860951, 11203.60787372442, 11313.321200727942, 11319.603813866517, 11321.0, 11326.263792853439, 11111.055560277386, 11173.273737207055, 11180.888191118065, 11182.105431565455, 11192.44037798111, 10974.506147223394, 11003.61487347947, 11005.0, 11007.559697612085, 11011.119110471223, 44462.0, 44889.877753056106, 44896.3265551017, 44902.101957960986, 44936.77531612321, 3785.1483416231404, 3808.333541630231, 3882.786446745428, 4196.727212731163, 4531.3429552715, 34830.30339393198, 34836.477452838226, 34873.302533949434, 35221.82389088038, 35757.82121089378, 4563.429571303587, 4574.839880604196, 4645.140648979949, 4656.092062047902, 4727.621790256771, 382030.0, 383337.0, 383557.0, 383910.0, 384183.0, 60924.69537652372, 61392.0, 61424.0, 61452.38547614556, 61473.69263153745, 39319.80340098281, 39358.21283574313, 39461.0, 41908.58091419108, 64024.67987660125, 16971.830281697024, 17029.48911532656, 17112.144392780312, 17164.656706865793, 17191.82808171927, 12807.871921280854, 12988.610341689764, 13167.144135631153, 13296.335183240994, 13303.0, 19345.4196374109, 20356.38930832048, 20470.59058818815, 20502.282420115225, 20633.174673012913, 7419.406447484296, 7554.131274903347, 7612.429067819936, 7644.503107298119, 7779.572123533147, 956.9043095690514, 958.5399008475971, 1086.760912599227, 1159.2233203753506, 1208.7642909632714, 32212.0, 33343.166420839676, 34059.31881362359, 34092.65907340944, 34104.317913641586, 1184.248002518407, 1184.9111316651113, 1226.1049433913292, 1231.4950870143198, 1235.425527129889, 16865.747013794804, 17726.29094836218, 17905.820941790676, 17948.102594870204, 18051.909740451214, 617151.0, 621212.0, 622890.8554572344, 623598.0, 624357.0, 61718.69140654358, 62338.68830655818, 62456.0, 62514.811981580424, 62612.68693656594, 1314.6581888709004, 1379.5033787836371, 1381.4405165907824, 1406.3038795795942, 1416.8653977872086, 33530.66469335275, 35220.0, 35731.821340893126, 35767.821160894026, 36171.27657446784, 53226.2016069767, 53934.0, 53939.651508711024, 53954.46045539495, 53967.0, 462066.0, 470558.0, 470970.0, 473612.0, 474132.0, 27826.721732783222, 29769.404611907208, 30079.398412032075, 30118.0, 31954.520682189323, 51096.74451627867, 51334.48665513447, 51846.48153518417, 51873.740631296605, 52658.0, 41815.7909210452, 42422.36366454444, 42820.28718851211, 42859.357109642755, 44104.55895441133, 42670.0, 44004.33993490164, 44222.557774421846, 44646.3303050448, 44914.0, 41832.163356733305, 42561.574384255764, 42822.0, 43148.56851431446, 43259.0, 35887.64112358843, 38591.289155122926, 39202.215955680156, 39637.80181099076, 39863.40204896987, 29444.172257606373, 29445.26386840346, 29843.10470685883, 29980.250493737833, 30030.3993920116], "value": 21579.450439933793, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.99182016359657, 419.8946463432591, 433.66391046938617, 434.25091716788546, 441.28732097662265, 19886.20455181793, 20256.189752409886, 20817.0, 20964.58070838586, 20979.16083356664, 19829.50426239341, 20341.389758307276, 20457.693134602956, 20629.174833006662, 20635.17459301626, 5715.742791574389, 5976.312724036748, 5984.940150598503, 5986.28164620244, 5993.730282137314, 5897.056470964647, 6015.398460153971, 6238.625682459045, 6261.937380626181, 6358.777442789503, 6149.508039356863, 6353.968230158843, 6358.332375100617, 6358.809235722936, 6361.491080713555, 6228.252609686844, 6285.685715714196, 6329.2404911410695, 6366.267879193906, 6373.968130159319, 5998.970005149947, 6132.816015519542, 6190.41191086846, 6192.195014648112, 6201.410865967726, 15263.542093737206, 15354.539363819104, 15457.458988935334, 21282.680759788615, 24138.275851724477, 2384.67806846077, 2431.8779668461966, 2463.5442443148077, 2482.95034099317, 2494.638277449778, 10200.79598408035, 10458.267921245515, 10625.149988000941, 11189.720256993558, 11254.43727813606, 2005.7091721700417, 2006.7190593316948, 2069.906854191565, 2100.1179504608103, 2106.1259577275414, 84592.0, 86528.56735716344, 87345.0, 87652.68520972255, 91738.0, 11583.478743456564, 11836.822447663291, 11902.285862848257, 12033.819492707617, 12158.148929575022, 16590.419335323448, 16605.0867202305, 16614.6677066458, 16615.6676866462, 16617.833821661872, 8061.516309021477, 8202.630881610341, 8263.876041859377, 8443.240108390275, 8505.362097842562, 7918.0, 8016.23845734666, 8164.795880102926, 8175.223353781381, 8225.95887020561, 4911.0, 5042.142835717937, 5063.367079115111, 5089.440161582223, 5224.92162617561, 2096.8322534197227, 2101.915923363079, 2111.4088055344528, 2132.317658349344, 2167.583144793544, 945.4091193004373, 972.5283237629744, 975.6000039983714, 1024.5491983527372, 1032.6024480574883, 20734.43172298255, 21421.571568568543, 21497.892510537346, 21605.459863503223, 21809.0, 1020.7601213714771, 1028.8250997330472, 1058.9099926506333, 1089.3572792052728, 1099.0328510910508, 11263.380514071641, 11722.88277117235, 11796.174267801343, 12019.278843269432, 12061.0, 469648.0, 488837.0, 526365.0, 543482.0, 548416.0, 78094.21905780869, 83475.0, 84841.0, 99150.0, 100638.49680751549, 995.0994350113159, 1001.6844693921537, 1022.9386236825815, 1028.3726926574784, 1039.5789705169418, 18576.81423185751, 18781.248750050392, 18903.84673042911, 18910.716339255196, 19219.32732354361, 43504.34743478787, 44444.0, 44648.55351446444, 45113.0, 45231.32153017773, 57412.42587574071, 57466.425335746106, 57716.4228357711, 64795.67602161959, 66478.66760666166, 6230.345813689497, 8577.614007369808, 8682.913170868464, 8768.473891566526, 9038.909610903807, 12440.253584784941, 13033.543825966046, 13118.934405328295, 13186.472541098248, 13260.270685112411, 10971.561137554409, 11021.173411994292, 11106.555737770399, 11437.485313161094, 11589.570984124819, 10631.20265980039, 11106.666799996014, 11257.268277561932, 11369.2609980351, 11566.884331156582, 10899.673009809718, 10926.289791163548, 10935.0, 10995.230333876552, 11144.442777861237, 8546.316294696533, 8595.44129631572, 8674.132586741276, 8690.30477561781, 9001.819963600823, 9150.725478235398, 10935.45322733876, 11093.833592496281, 11113.22207445471, 11272.210945233755], "value": 9527.302947801532, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.9847901520984, 1579.6761663858906, 1739.2868923741262, 1840.7699037620298, 1859.3864024871793, 9043.954780226099, 9726.124648781608, 9732.951335243331, 9744.805103897917, 9749.463779492125, 7988.800279993003, 9188.632454701821, 9446.05539446055, 9447.622095116205, 9654.758631034229, 2419.9153029643967, 2451.472933319336, 2462.827602067856, 2463.334899577122, 2474.134053081425, 2411.1078900806724, 2465.3220364399754, 2503.111395454615, 2507.185164821431, 2507.323022783847, 2474.814388920829, 2479.8884050217785, 2486.589712697412, 2496.712878019033, 2517.5971844504884, 2415.444447777021, 2416.250962201714, 2423.3456966619, 2483.2177863972793, 2497.775200231988, 2480.4790993891306, 2495.8128140389545, 2501.7873480753974, 2503.161440917295, 2526.9115580954576, 9606.519674016272, 9702.369345992487, 9753.414795112316, 9758.707238782848, 9803.90196098037, 5494.5054945054735, 5615.606907516475, 5654.37801841797, 5662.745176467069, 5665.093585026417, 3381.8332675227007, 3493.598236202844, 3552.0231936217488, 3553.0, 3581.4448760442174, 1189.7858385490608, 1212.3332167308033, 1234.4383305595932, 1237.2947419970621, 1251.8184863194786, 13767.0, 13885.722285554333, 13905.582832515041, 13926.582202533838, 13926.791098133435, 4988.326575912278, 5028.874278143076, 5164.845054648366, 5175.120229560908, 5303.469653034743, 11366.374849383197, 11384.715382115513, 11405.372704501166, 11437.142214333959, 11479.426028698532, 5764.654120752768, 5767.423257674283, 5767.740451679684, 5772.653640781483, 5774.364819869811, 4545.636349092131, 5184.274201611777, 5247.475252474798, 5282.841514754564, 5292.682439053669, 4320.567943205654, 4363.52001279859, 4394.472663280363, 4410.0, 4439.889002774892, 1239.0892693869998, 1241.2490443281686, 1293.1723696834033, 1293.2628401810878, 1312.0290984671274, 1051.836965270369, 1055.303499690216, 1061.7717190804015, 1062.436908438529, 1066.4561073852392, 6298.842528936813, 6385.6487893165395, 6775.559588626822, 6777.353841262256, 6836.316368363224, 500.16722157607177, 503.55183886341183, 504.85863958091795, 505.40867185393176, 506.78714939725404, 4454.22051141049, 4468.419105516327, 4508.819647214074, 4527.207738645724, 4622.375979242759, 11525.88474115248, 11941.701457463632, 12042.33767142798, 12389.876101239053, 12484.063695222894, 12257.14200005991, 12274.693132671755, 12277.57028504016, 12291.385430728427, 12294.508219671114, 8808.603612837438, 9003.504807235533, 9008.63965441375, 9022.323325750594, 9024.187823095954, 6605.504587155887, 6776.6950487227205, 6863.313668633296, 6925.342092501204, 6991.615461149686, 11008.174386921173, 11186.72033199144, 11367.943160284145, 11386.146039046938, 11402.20184587071, 11242.494087766234, 11544.307341559534, 11638.418079095842, 11688.123390745557, 11846.052315814886, 7482.812929676802, 7725.381969442544, 7737.497062690907, 8459.407841451039, 8628.39601227933, 8308.086110527716, 9136.26909847224, 9292.95353523228, 9318.114779095975, 9576.377535460173, 8331.625076871434, 8528.147185281423, 8551.8717219243, 8581.528015958933, 8633.30933525329, 8324.542150181798, 8325.95837020811, 8347.12355202695, 8434.072252052147, 8567.700130495403, 7525.8118547036765, 7578.242175782378, 7655.808604784702, 7659.234076592296, 7676.270754278335, 5738.081906894877, 5791.9131213031, 5874.647521148745, 5888.852778680396, 5934.376890426443, 5488.560915126701, 5608.719564021864, 5735.225744524436, 5764.538836893123, 5891.263592050994], "value": 4962.343301556656, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.80574975139047, 346.9531613232213, 351.15897425665514, 354.65066909094537, 360.44671429355935, 5603.215549823023, 5648.830535083944, 5666.603337766358, 5674.489295963363, 5713.485786279234, 5540.9168862466995, 5583.134614134816, 5585.3297604287445, 5607.271054762875, 5644.181593668915, 3895.331697787025, 4219.28272193727, 4291.420658211148, 4373.2565463871215, 4378.496472905609, 4371.344298355256, 4375.103103863717, 4375.387445757597, 4377.737335759864, 4402.8679139625865, 4340.717853339539, 4346.5436129206455, 4352.325389564607, 4397.736135831844, 4416.359627853959, 4276.3157894737005, 4310.676699247569, 4363.410939523155, 4379.715318504287, 4405.801738921757, 3836.7889766062854, 3836.9424458633143, 3851.4030325299623, 3853.730238883279, 3854.2676891390547, 5404.891902161974, 5412.350517937873, 5422.620416570841, 5481.013417584833, 5483.890322193573, 4797.328374027639, 4970.950290497085, 4995.226694523428, 5003.924941125886, 5037.748112594356, 5753.971230143864, 5763.308402991626, 5790.536757059425, 5791.7837254176675, 5830.658948441882, 4236.046889449849, 4239.788010599457, 4246.363045543162, 4261.808218630138, 4272.978635106804, 5878.941210587882, 5988.7604495820115, 6049.1833602463985, 6054.002179280827, 6066.756314955468, 4288.099499105223, 4306.978465107717, 4332.263515202423, 4342.08816148606, 4348.869533913988, 4385.122975404932, 4388.956110438919, 4391.275439552502, 4395.846145384896, 4410.4927933287445, 4807.519248075165, 4941.604671626261, 4978.626603004789, 4992.425871024806, 5005.34930459038, 5812.88374232513, 5814.389489103583, 5822.573469499915, 5854.677992710442, 5930.169776231331, 1497.4010395841751, 1500.1824005916583, 1520.6198450387403, 1522.1856306875768, 1528.533797191845, 914.9679761208458, 938.8591711243369, 940.7789169545151, 946.4652471353663, 969.1651918139414, 5056.890012642199, 5059.468755780664, 5076.340075790124, 5081.551757749097, 5082.593392528589, 5284.973575132177, 5329.467053294639, 5342.759575819097, 5346.4386239445075, 5373.731313434313, 4933.851984440473, 5114.820981265712, 5124.514610413947, 5137.434882162959, 5142.765736223354, 2780.249332680165, 2806.775457963358, 2832.603435519029, 2838.7728981681416, 2876.2234196767176, 5410.48600382963, 5413.864653383618, 5415.296011518556, 5460.34475862899, 5488.56091512678, 5335.839924802262, 5639.520640745501, 5820.767169313265, 6083.422074902877, 6139.723712432951, 6105.48103411215, 6161.84595385119, 6202.255729312421, 6212.0, 6233.189685340877, 6017.0, 6020.488258498077, 6030.90953635687, 6032.155498230163, 6041.670832416861, 5694.430556944438, 5906.379830117948, 5919.881602367843, 5929.940700592939, 5960.731767070579, 6241.095041219089, 6242.438180563771, 6249.281332646804, 6263.906041409291, 6267.373262673696, 4071.91856162881, 4290.099079193405, 4320.200762858881, 4323.870283891489, 4374.081442897027, 5924.31870334917, 5950.791722289872, 5953.196318496947, 5953.821385358273, 5988.850278743066, 5774.451427114417, 5795.8551036224435, 5800.59395842287, 5802.332731735735, 5818.359980402237, 5632.746526406403, 5791.610013596623, 5800.651960882361, 5805.10020946754, 5818.214541036906, 5796.1305804129715, 5800.6809625471005, 5802.448767367094, 5804.912926306193, 5813.56398270123, 5639.54883609302, 5807.477327040587, 5830.271216097988, 5833.7666493341485, 5843.503302219359, 5660.179274005329, 5667.971660141673, 5677.621815020057, 5685.346185188756, 5702.287214098238], "value": 4385.256663030992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.8132829449227, 828.2793969246757, 868.7524055644143, 884.3323290915358, 907.4101833808028, 16266.91866540667, 17915.28338866447, 17925.999999999985, 17935.551611209725, 17940.910295448506, 16136.354545818183, 16237.106959117244, 16260.268287927041, 16263.10552919589, 16279.430219942304, 736.0799001248427, 860.9238451935081, 883.2404132446123, 887.3611000079965, 889.6085722282207, 838.5681374092347, 838.714836955435, 839.0728245288959, 839.6053854688287, 840.4075127035426, 891.095538028898, 893.4951752259952, 893.9821203575924, 894.4990805149105, 897.712731925784, 883.0771843423638, 883.6509578716435, 884.2263019857593, 885.0087901550305, 886.8536691445904, 653.4674240493982, 653.6633633678659, 653.950953678476, 654.2508827392609, 660.3726459863101, 15464.226788660522, 15510.146941918303, 15515.301811418512, 15548.533543993699, 15564.377424903103, 14433.206173660445, 14703.0, 14714.264286785725, 15261.0, 16295.918520407322, 14629.41482340705, 14760.630984225372, 14816.629584260372, 14877.404903803836, 15118.168500732456, 4851.781669824866, 4868.196747536652, 4892.437369702494, 4929.457759646436, 4947.752612369367, 85173.0, 86091.13908860894, 86480.99999999937, 92040.53979730126, 92894.0, 12421.00631949442, 12440.626781196579, 12526.185797923106, 12586.307753073668, 12728.10903236783, 21640.675389869164, 23337.18319858797, 23343.649845252334, 23346.64980025301, 23398.0, 9425.811483770287, 9450.243980481546, 9547.427154370622, 9573.952130239444, 9654.613815447445, 5142.5628821549835, 5175.275461435402, 5191.143461328914, 5307.548858347007, 5379.596530260246, 3966.841326346914, 4037.454943582579, 4101.425800387948, 4112.897177570525, 4113.609207125318, 711.850511392603, 712.8075419636773, 725.2855936902147, 726.0525014855558, 727.6543641770116, 774.7636970723984, 796.900168057162, 807.0557447785988, 813.0771574263135, 817.513579420244, 10010.749731256778, 10903.008363500187, 10904.945475272572, 10908.290961087503, 10965.232433729721, 663.227340148731, 688.1295161620554, 751.0874287740312, 760.2435576601317, 780.808701868046, 5009.924851127236, 5144.7942082316285, 5153.725068244812, 5299.0, 5387.353517577916, 73005.26994730091, 73145.0, 73184.63407683033, 73479.0, 114257.0, 100211.49894250483, 110392.0, 111348.44325778482, 112489.0, 114888.0, 57348.71325643345, 89912.5504372474, 94830.15509534586, 95335.52332238432, 97213.51393242988, 9672.85490717654, 10031.29780915329, 10047.246456515644, 10146.391216527032, 10186.439745814052, 55030.0, 55349.0, 55474.94887781211, 56025.71987140038, 56226.0, 81717.0, 82515.58742206251, 82628.21602487768, 84327.0, 84568.57715711382, 12038.398080096134, 12526.12317137828, 12595.181313214613, 12624.55814046504, 12763.106582539132, 14116.929415352857, 14139.151650900978, 14476.203808790618, 14547.490837820626, 14561.708765824413, 13023.544175953795, 13197.604071877859, 13291.933540332237, 13307.334633268492, 13688.589342319747, 13400.798988015382, 13425.395857186646, 13503.12229705066, 13616.455341786217, 13640.863591364356, 12505.499780008699, 12661.177023493443, 13174.209547427185, 13176.802347964596, 13459.192448453125, 10031.247656425943, 10153.898461015295, 10538.156947444339, 10753.085987691135, 11139.387333696724, 9901.55443005052, 11027.466937944939, 11675.416229188337, 11898.76202475963, 12174.756504869676], "value": 8012.8765806844385, "unit": "score"}], "name": "dromaeo_css", "value": 7645.047364179303, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 118.0, 110.0, 111.0, 113.0, 111.0, 109.0, 109.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 138.0, 169.0, 117.0, 138.0, 148.0, 121.0, 169.0, 120.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 163.0, 160.0, 161.0, 161.0, 160.0, 160.0, 164.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 169.0, 75.0, 75.0, 76.0, 76.0, 176.0, 75.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 157.0, 84.0, 84.0, 83.0, 150.0, 83.0, 83.0, 153.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 147.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 54.0, 50.0, 50.0, 50.0, 51.0, 51.0, 50.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 61.0, 61.0, 59.0, 60.0, 61.0, 60.0, 62.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 80.0, 77.0, 73.0, 82.0, 75.0, 74.0, 81.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 119.0, 130.0, 118.0, 121.0, 130.0, 131.0, 151.0, 130.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 154.0, 150.0, 154.0, 150.0, 149.0, 151.0, 153.0, 151.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 62.0, 61.0, 62.0, 62.0, 60.0, 60.0, 63.0, 61.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1493.8999999999999, "alertThreshold": 2.0}]} 12:48:35 INFO - Return code: 0 12:48:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:48:35 INFO - # TBPL SUCCESS # 12:48:35 INFO - Running post-action listener: _resource_record_post_action 12:48:35 INFO - [mozharness: 2016-10-25 19:48:35.585059Z] Finished run-tests step (success) 12:48:35 INFO - Running post-run listener: _resource_record_post_run 12:48:35 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291426304; Read time: 20; Write time: 194208 12:48:35 INFO - TinderboxPrint: CPU usage
12.9% 12:48:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 12:48:35 INFO - TinderboxPrint: I/O write bytes / time
291,426,304 / 194,208 12:48:35 INFO - TinderboxPrint: CPU idle
8,236.5 (87.1%) 12:48:35 INFO - TinderboxPrint: CPU user
1,180.9 (12.5%) 12:48:35 INFO - TinderboxPrint: Swap in / out
0 / 0 12:48:35 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71340032; Read time: 0; Write time: 38920 12:48:35 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220086272; Read time: 20; Write time: 155288 12:48:36 INFO - Running post-run listener: _upload_blobber_files 12:48:36 INFO - Blob upload gear active. 12:48:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:48:36 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 12:48:36 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'] 12:48:36 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 12:48:36 INFO - (blobuploader) - INFO - Open directory for files ... 12:48:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:48:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:48:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:48:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:48:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:48:37 INFO - (blobuploader) - INFO - Done attempting. 12:48:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:48:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:48:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:48:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:48:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:48:38 INFO - (blobuploader) - INFO - Done attempting. 12:48:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:48:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:48:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:48:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:48:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:48:41 INFO - (blobuploader) - INFO - Done attempting. 12:48:41 INFO - (blobuploader) - INFO - Iteration through files over. 12:48:41 INFO - Return code: 0 12:48:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:48:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:48:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24a36b1ca2442c0a21a7021a80b380515e754a055ffdeb085a8069b9244ffdf1901840b82957e345a0396e236e79876fb43a93593cf44d300cc53b1afed17ff4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c51e3b5881f157215c5c87a8039de786281110dee1dedcd8368bdfadb9f950c6f77025e9890f0d478e037dc5bb66d42e515974a779c76a532075ead6ecbd81c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c145ae2501f3dd01505b176d287df0db61bdcda0895d9a9aa8670c7ebff60a1f9212c7e479d56c963063b731bbc8a89d678c08c16c00a60e36417e37064c4ce5"} 12:48:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:48:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:48:41 INFO - Contents: 12:48:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24a36b1ca2442c0a21a7021a80b380515e754a055ffdeb085a8069b9244ffdf1901840b82957e345a0396e236e79876fb43a93593cf44d300cc53b1afed17ff4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c51e3b5881f157215c5c87a8039de786281110dee1dedcd8368bdfadb9f950c6f77025e9890f0d478e037dc5bb66d42e515974a779c76a532075ead6ecbd81c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c145ae2501f3dd01505b176d287df0db61bdcda0895d9a9aa8670c7ebff60a1f9212c7e479d56c963063b731bbc8a89d678c08c16c00a60e36417e37064c4ce5"} 12:48:41 INFO - Running post-run listener: copy_logs_to_upload_dir 12:48:41 INFO - Copying logs to upload dir... 12:48:41 INFO - mkdir: /builds/slave/test/build/upload/logs 12:48:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.467640 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-25 12:48:41.284275) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 12:48:41.287971) ========= 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-1477417183.426719-2064641521 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/24a36b1ca2442c0a21a7021a80b380515e754a055ffdeb085a8069b9244ffdf1901840b82957e345a0396e236e79876fb43a93593cf44d300cc53b1afed17ff4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c51e3b5881f157215c5c87a8039de786281110dee1dedcd8368bdfadb9f950c6f77025e9890f0d478e037dc5bb66d42e515974a779c76a532075ead6ecbd81c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c145ae2501f3dd01505b176d287df0db61bdcda0895d9a9aa8670c7ebff60a1f9212c7e479d56c963063b731bbc8a89d678c08c16c00a60e36417e37064c4ce5"} build_url:https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011509 build_url: 'https://queue.taskcluster.net/v1/task/JsXQOb2aQxurIphyLTm2_Q/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/24a36b1ca2442c0a21a7021a80b380515e754a055ffdeb085a8069b9244ffdf1901840b82957e345a0396e236e79876fb43a93593cf44d300cc53b1afed17ff4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c51e3b5881f157215c5c87a8039de786281110dee1dedcd8368bdfadb9f950c6f77025e9890f0d478e037dc5bb66d42e515974a779c76a532075ead6ecbd81c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c145ae2501f3dd01505b176d287df0db61bdcda0895d9a9aa8670c7ebff60a1f9212c7e479d56c963063b731bbc8a89d678c08c16c00a60e36417e37064c4ce5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 12:48:41.313862) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:48:41.314209) ========= 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-1477417183.426719-2064641521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 12:48:41.398169) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 12:48:41.398497) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 12:48:41.398821) ========= ========= Total master_lag: 0.17 =========