builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1471775750.83 results: success (0) buildid: 20160821004002 builduid: efe60eae01b94725ba805607aae92784 revision: 6f1c9caedd87771d03d5e2808a4e821136679221 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:50.832957) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:50.833407) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:50.833726) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:50.875171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:50.875616) ========= 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=232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-21 03:35:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 17.7M=0.001s 2016-08-21 03:35:52 (17.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100962 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:51.000850) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:51.001180) ========= 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=232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020477 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:51.063373) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-21 03:35:51.063721) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f1c9caedd87771d03d5e2808a4e821136679221 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f1c9caedd87771d03d5e2808a4e821136679221 --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=232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-21 03:35:52,536 truncating revision to first 12 chars 2016-08-21 03:35:52,536 Setting DEBUG logging. 2016-08-21 03:35:52,536 attempt 1/10 2016-08-21 03:35:52,536 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6f1c9caedd87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-21 03:35:53,979 unpacking tar archive at: mozilla-aurora-6f1c9caedd87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.752771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-21 03:35:52.835238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:52.835603) ========= script_repo_revision: 6f1c9caedd87771d03d5e2808a4e821136679221 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:52.836094) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:52.836399) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 03:35:52.847836) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 37 secs) (at 2016-08-21 03:35:52.848565) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:35:54 INFO - MultiFileLogger online at 20160821 03:35:54 in /builds/slave/test 03:35:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:35:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:54 INFO - {'append_to_log': False, 03:35:54 INFO - 'base_work_dir': '/builds/slave/test', 03:35:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:35:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:54 INFO - 'branch': 'Mozilla-Aurora', 03:35:54 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:54 INFO - 'config_files': ('talos/linux_config.py',), 03:35:54 INFO - 'default_actions': ('clobber', 03:35:54 INFO - 'read-buildbot-config', 03:35:54 INFO - 'download-and-extract', 03:35:54 INFO - 'populate-webroot', 03:35:54 INFO - 'create-virtualenv', 03:35:54 INFO - 'install', 03:35:54 INFO - 'run-tests'), 03:35:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:54 INFO - 'download_minidump_stackwalk': True, 03:35:54 INFO - 'download_symbols': 'ondemand', 03:35:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:54 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:54 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:54 INFO - 'installer_path': 'installer.exe', 03:35:54 INFO - 'log_level': 'info', 03:35:54 INFO - 'log_name': 'talos', 03:35:54 INFO - 'log_to_console': True, 03:35:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:35:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:35:54 INFO - 'opt_config_files': (), 03:35:54 INFO - 'pip_index': False, 03:35:54 INFO - 'sps_profile': False, 03:35:54 INFO - 'sps_profile_interval': 0, 03:35:54 INFO - 'suite': 'dromaeojs', 03:35:54 INFO - 'system_bits': '32', 03:35:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:54 INFO - 'title': 'talos-linux64-ix-025', 03:35:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:54 INFO - 'use_talos_json': True, 03:35:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:54 INFO - 'work_dir': 'build'} 03:35:54 INFO - [mozharness: 2016-08-21 10:35:54.410797Z] Running clobber step. 03:35:54 INFO - Running pre-action listener: _resource_record_pre_action 03:35:54 INFO - Running main action method: clobber 03:35:54 INFO - rmtree: /builds/slave/test/build 03:35:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:54 INFO - Running post-action listener: _resource_record_post_action 03:35:54 INFO - [mozharness: 2016-08-21 10:35:54.871161Z] Finished clobber step (success) 03:35:54 INFO - [mozharness: 2016-08-21 10:35:54.871263Z] Running read-buildbot-config step. 03:35:54 INFO - Running pre-action listener: _resource_record_pre_action 03:35:54 INFO - Running main action method: read_buildbot_config 03:35:54 INFO - Using buildbot properties: 03:35:54 INFO - { 03:35:54 INFO - "project": "", 03:35:54 INFO - "product": "firefox", 03:35:54 INFO - "script_repo_revision": "production", 03:35:54 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:35:54 INFO - "repository": "", 03:35:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:35:54 INFO - "buildid": "20160821004002", 03:35:54 INFO - "stage_platform": "linux64-pgo", 03:35:54 INFO - "basedir": "/builds/slave/test", 03:35:54 INFO - "nightly_build": "True", 03:35:54 INFO - "buildnumber": 480, 03:35:54 INFO - "slavename": "talos-linux64-ix-025", 03:35:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:35:54 INFO - "platform": "ubuntu64_hw", 03:35:54 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:35:54 INFO - "branch": "mozilla-aurora", 03:35:54 INFO - "repo_path": "releases/mozilla-aurora", 03:35:54 INFO - "revision": "6f1c9caedd87771d03d5e2808a4e821136679221", 03:35:54 INFO - "pgo_build": "True", 03:35:54 INFO - "builduid": "efe60eae01b94725ba805607aae92784", 03:35:54 INFO - "slavebuilddir": "test" 03:35:54 INFO - } 03:35:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:35:54 INFO - Running post-action listener: _resource_record_post_action 03:35:54 INFO - [mozharness: 2016-08-21 10:35:54.873793Z] Finished read-buildbot-config step (success) 03:35:54 INFO - [mozharness: 2016-08-21 10:35:54.873881Z] Running download-and-extract step. 03:35:54 INFO - Running pre-action listener: _resource_record_pre_action 03:35:54 INFO - Running main action method: download_and_extract 03:35:54 INFO - mkdir: /builds/slave/test/build/tests 03:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:35:54 INFO - https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:35:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:35:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:54 INFO - retry: Failed, sleeping 30 seconds before retrying 03:36:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:36:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:36:24 INFO - retry: Failed, sleeping 60 seconds before retrying 03:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:37:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:37:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:37:24 INFO - Caught exception: HTTP Error 404: Not Found 03:37:24 INFO - Caught exception: HTTP Error 404: Not Found 03:37:24 INFO - Caught exception: HTTP Error 404: Not Found 03:37:24 INFO - trying https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:24 INFO - Downloading https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:37:25 INFO - Downloaded 1361 bytes. 03:37:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:25 INFO - Using the following test package requirements: 03:37:25 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:25 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:37:25 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:37:25 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'jsshell-linux-x86_64.zip'], 03:37:25 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:37:25 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:25 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:37:25 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:37:25 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:37:25 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:37:25 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:25 INFO - https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:37:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:37:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:37:25 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:37:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:55 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:38:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:38:55 INFO - Caught exception: HTTP Error 404: Not Found 03:38:55 INFO - Caught exception: HTTP Error 404: Not Found 03:38:55 INFO - Caught exception: HTTP Error 404: Not Found 03:38:55 INFO - trying https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - Downloading https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:38:56 INFO - Downloaded 21769197 bytes. 03:38:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:38:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:38:57 INFO - Return code: 0 03:38:57 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:57 INFO - https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:38:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:38:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:38:57 INFO - retry: Failed, sleeping 30 seconds before retrying 03:39:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:39:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:39:27 INFO - retry: Failed, sleeping 60 seconds before retrying 03:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:40:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:40:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:40:27 INFO - Caught exception: HTTP Error 404: Not Found 03:40:27 INFO - Caught exception: HTTP Error 404: Not Found 03:40:27 INFO - Caught exception: HTTP Error 404: Not Found 03:40:27 INFO - trying https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:27 INFO - Downloading https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:40:28 INFO - Downloaded 21769197 bytes. 03:40:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:40:29 INFO - Return code: 0 03:40:29 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')]} 03:40:29 INFO - https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:40:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:40:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:40:29 INFO - retry: Failed, sleeping 30 seconds before retrying 03:40:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:40:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:40:59 INFO - retry: Failed, sleeping 60 seconds before retrying 03:41:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:42:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:42:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:42:00 INFO - Caught exception: HTTP Error 404: Not Found 03:42:00 INFO - Caught exception: HTTP Error 404: Not Found 03:42:00 INFO - Caught exception: HTTP Error 404: Not Found 03:42:00 INFO - trying https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:00 INFO - Downloading https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:42:00 INFO - Downloaded 14413723 bytes. 03:42:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:42:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:42:00 INFO - Return code: 0 03:42:00 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')]} 03:42:00 INFO - https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:42:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:42:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:42:00 INFO - retry: Failed, sleeping 30 seconds before retrying 03:42:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:42:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:42:30 INFO - retry: Failed, sleeping 60 seconds before retrying 03:43:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:43:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:43:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:43:31 INFO - Caught exception: HTTP Error 404: Not Found 03:43:31 INFO - Caught exception: HTTP Error 404: Not Found 03:43:31 INFO - Caught exception: HTTP Error 404: Not Found 03:43:31 INFO - trying https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:31 INFO - Downloading https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:43:32 INFO - Downloaded 63736225 bytes. 03:43:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:32 INFO - mkdir: /builds/slave/test/properties 03:43:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:43:32 INFO - Writing to file /builds/slave/test/properties/build_url 03:43:32 INFO - Contents: 03:43:32 INFO - build_url:https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:33 INFO - Running post-action listener: _resource_record_post_action 03:43:33 INFO - Running post-action listener: set_extra_try_arguments 03:43:33 INFO - [mozharness: 2016-08-21 10:43:33.139512Z] Finished download-and-extract step (success) 03:43:33 INFO - [mozharness: 2016-08-21 10:43:33.139748Z] Running populate-webroot step. 03:43:33 INFO - Running pre-action listener: _resource_record_pre_action 03:43:33 INFO - Running main action method: populate_webroot 03:43:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:43:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:43:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:43:33 INFO - '--tppagecycles', 03:43:33 INFO - '7'], 03:43:33 INFO - 'tests': ['tsvgm']}}, 03:43:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:43:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:43:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:43:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:43:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['damp', 'tps']}, 03:43:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'tests': ['damp', 'tps']}, 03:43:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['dromaeo_dom']}, 03:43:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:43:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['basic_compositor_video']}, 03:43:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:43:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['a11yr', 03:43:33 INFO - 'ts_paint', 03:43:33 INFO - 'tpaint', 03:43:33 INFO - 'sessionrestore', 03:43:33 INFO - 'sessionrestore_no_auto_restore', 03:43:33 INFO - 'tabpaint']}, 03:43:33 INFO - 'other-e10s': {'tests': ['a11yr', 03:43:33 INFO - 'ts_paint', 03:43:33 INFO - 'tpaint', 03:43:33 INFO - 'sessionrestore', 03:43:33 INFO - 'sessionrestore_no_auto_restore', 03:43:33 INFO - 'tabpaint']}, 03:43:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:43:33 INFO - 'ts_paint', 03:43:33 INFO - 'tpaint', 03:43:33 INFO - 'sessionrestore', 03:43:33 INFO - 'sessionrestore_no_auto_restore', 03:43:33 INFO - 'tabpaint']}, 03:43:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:43:33 INFO - 'ts_paint', 03:43:33 INFO - 'tpaint', 03:43:33 INFO - 'sessionrestore', 03:43:33 INFO - 'sessionrestore_no_auto_restore', 03:43:33 INFO - 'tabpaint']}, 03:43:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['a11yr', 03:43:33 INFO - 'ts_paint', 03:43:33 INFO - 'tpaint', 03:43:33 INFO - 'sessionrestore', 03:43:33 INFO - 'sessionrestore_no_auto_restore', 03:43:33 INFO - 'tabpaint']}, 03:43:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['a11yr', 03:43:33 INFO - 'ts_paint', 03:43:33 INFO - 'tpaint', 03:43:33 INFO - 'sessionrestore', 03:43:33 INFO - 'sessionrestore_no_auto_restore', 03:43:33 INFO - 'tabpaint']}, 03:43:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['tsvgx', 03:43:33 INFO - 'tsvgr_opacity', 03:43:33 INFO - 'tart', 03:43:33 INFO - 'tscrollx', 03:43:33 INFO - 'cart']}, 03:43:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:43:33 INFO - 'tsvgr_opacity', 03:43:33 INFO - 'tart', 03:43:33 INFO - 'tscrollx', 03:43:33 INFO - 'cart']}, 03:43:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'talos_options': ['--disable-e10s'], 03:43:33 INFO - 'tests': ['tp5o']}, 03:43:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'tests': ['tp5o']}, 03:43:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'talos_options': ['--disable-e10s', 03:43:33 INFO - '--xperf_path', 03:43:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:33 INFO - 'tests': ['tp5n']}, 03:43:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:33 INFO - 'talos_options': ['--xperf_path', 03:43:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:33 INFO - 'tests': ['tp5n']}}, 03:43:33 INFO - 'talos.zip': {'path': '', 03:43:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:43:33 INFO - Running post-action listener: _resource_record_post_action 03:43:33 INFO - [mozharness: 2016-08-21 10:43:33.156798Z] Finished populate-webroot step (success) 03:43:33 INFO - [mozharness: 2016-08-21 10:43:33.156888Z] Running create-virtualenv step. 03:43:33 INFO - Running pre-action listener: _resource_record_pre_action 03:43:33 INFO - Running main action method: create_virtualenv 03:43:33 INFO - Creating virtualenv /builds/slave/test/build/venv 03:43:33 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 03:43:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:43:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:43:33 INFO - Using real prefix '/usr' 03:43:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:43:33 INFO - Installing distribute.............................................................................................................................................................................................done. 03:43:35 INFO - Installing pip.................done. 03:43:35 INFO - Return code: 0 03:43:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:35 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 03:43:35 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 03:43:35 INFO - Using env: {'DISPLAY': ':0', 03:43:35 INFO - 'HOME': '/home/cltbld', 03:43:35 INFO - 'LANG': 'en_US.UTF-8', 03:43:35 INFO - 'LANGUAGE': 'en_US:en', 03:43:35 INFO - 'LOGNAME': 'cltbld', 03:43:35 INFO - 'MAIL': '/var/mail/cltbld', 03:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:35 INFO - 'MOZ_NO_REMOTE': '1', 03:43:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:35 INFO - 'NO_EM_RESTART': '1', 03:43:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:35 INFO - 'PWD': '/builds/slave/test', 03:43:35 INFO - 'SHELL': '/bin/bash', 03:43:35 INFO - 'SHLVL': '1', 03:43:35 INFO - 'TERM': 'linux', 03:43:35 INFO - 'TMOUT': '86400', 03:43:35 INFO - 'USER': 'cltbld', 03:43:35 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:35 INFO - '_': '/tools/buildbot/bin/python'} 03:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:35 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 03:43:35 INFO - Cleaning up... 03:43:35 INFO - Return code: 0 03:43:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:35 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 03:43:35 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 03:43:35 INFO - Using env: {'DISPLAY': ':0', 03:43:35 INFO - 'HOME': '/home/cltbld', 03:43:35 INFO - 'LANG': 'en_US.UTF-8', 03:43:35 INFO - 'LANGUAGE': 'en_US:en', 03:43:35 INFO - 'LOGNAME': 'cltbld', 03:43:35 INFO - 'MAIL': '/var/mail/cltbld', 03:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:35 INFO - 'MOZ_NO_REMOTE': '1', 03:43:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:35 INFO - 'NO_EM_RESTART': '1', 03:43:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:35 INFO - 'PWD': '/builds/slave/test', 03:43:35 INFO - 'SHELL': '/bin/bash', 03:43:35 INFO - 'SHLVL': '1', 03:43:35 INFO - 'TERM': 'linux', 03:43:35 INFO - 'TMOUT': '86400', 03:43:35 INFO - 'USER': 'cltbld', 03:43:35 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:35 INFO - '_': '/tools/buildbot/bin/python'} 03:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:36 INFO - Downloading/unpacking psutil>=3.1.1 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:43:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:38 INFO - Installing collected packages: psutil 03:43:38 INFO - Running setup.py install for psutil 03:43:38 INFO - building 'psutil._psutil_linux' extension 03:43:38 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 03:43:38 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 03:43:38 INFO - building 'psutil._psutil_posix' extension 03:43:38 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 03:43:38 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 03:43:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:38 INFO - Successfully installed psutil 03:43:38 INFO - Cleaning up... 03:43:38 INFO - Return code: 0 03:43:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:43:38 INFO - Using env: {'DISPLAY': ':0', 03:43:38 INFO - 'HOME': '/home/cltbld', 03:43:38 INFO - 'LANG': 'en_US.UTF-8', 03:43:38 INFO - 'LANGUAGE': 'en_US:en', 03:43:38 INFO - 'LOGNAME': 'cltbld', 03:43:38 INFO - 'MAIL': '/var/mail/cltbld', 03:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:38 INFO - 'MOZ_NO_REMOTE': '1', 03:43:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:38 INFO - 'NO_EM_RESTART': '1', 03:43:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:38 INFO - 'PWD': '/builds/slave/test', 03:43:38 INFO - 'SHELL': '/bin/bash', 03:43:38 INFO - 'SHLVL': '1', 03:43:38 INFO - 'TERM': 'linux', 03:43:38 INFO - 'TMOUT': '86400', 03:43:38 INFO - 'USER': 'cltbld', 03:43:38 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:38 INFO - '_': '/tools/buildbot/bin/python'} 03:43:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:43:40 INFO - Installing collected packages: mozsystemmonitor 03:43:40 INFO - Running setup.py install for mozsystemmonitor 03:43:40 INFO - Successfully installed mozsystemmonitor 03:43:40 INFO - Cleaning up... 03:43:40 INFO - Return code: 0 03:43:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:43:40 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')]} 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:40 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')]} 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:40 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:40 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 03:43:41 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 03:43:41 INFO - Using env: {'DISPLAY': ':0', 03:43:41 INFO - 'HOME': '/home/cltbld', 03:43:41 INFO - 'LANG': 'en_US.UTF-8', 03:43:41 INFO - 'LANGUAGE': 'en_US:en', 03:43:41 INFO - 'LOGNAME': 'cltbld', 03:43:41 INFO - 'MAIL': '/var/mail/cltbld', 03:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:41 INFO - 'MOZ_NO_REMOTE': '1', 03:43:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:41 INFO - 'NO_EM_RESTART': '1', 03:43:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:41 INFO - 'PWD': '/builds/slave/test', 03:43:41 INFO - 'SHELL': '/bin/bash', 03:43:41 INFO - 'SHLVL': '1', 03:43:41 INFO - 'TERM': 'linux', 03:43:41 INFO - 'TMOUT': '86400', 03:43:41 INFO - 'USER': 'cltbld', 03:43:41 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:41 INFO - '_': '/tools/buildbot/bin/python'} 03:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:41 INFO - Downloading/unpacking jsonschema==2.5.1 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:43 INFO - warning: no files found matching '*.txt' 03:43:43 INFO - no previously-included directories found matching 'build' 03:43:43 INFO - no previously-included directories found matching 'dist' 03:43:43 INFO - no previously-included directories found matching '.git*' 03:43:43 INFO - Installing collected packages: jsonschema, functools32 03:43:43 INFO - Running setup.py install for functools32 03:43:43 INFO - warning: no files found matching '*.txt' 03:43:43 INFO - no previously-included directories found matching 'build' 03:43:43 INFO - no previously-included directories found matching 'dist' 03:43:43 INFO - no previously-included directories found matching '.git*' 03:43:43 INFO - Successfully installed jsonschema functools32 03:43:43 INFO - Cleaning up... 03:43:43 INFO - Return code: 0 03:43:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:43 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:43 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 03:43:43 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 03:43:43 INFO - Using env: {'DISPLAY': ':0', 03:43:43 INFO - 'HOME': '/home/cltbld', 03:43:43 INFO - 'LANG': 'en_US.UTF-8', 03:43:43 INFO - 'LANGUAGE': 'en_US:en', 03:43:43 INFO - 'LOGNAME': 'cltbld', 03:43:43 INFO - 'MAIL': '/var/mail/cltbld', 03:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:43 INFO - 'MOZ_NO_REMOTE': '1', 03:43:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:43 INFO - 'NO_EM_RESTART': '1', 03:43:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:43 INFO - 'PWD': '/builds/slave/test', 03:43:43 INFO - 'SHELL': '/bin/bash', 03:43:43 INFO - 'SHLVL': '1', 03:43:43 INFO - 'TERM': 'linux', 03:43:43 INFO - 'TMOUT': '86400', 03:43:43 INFO - 'USER': 'cltbld', 03:43:43 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:43 INFO - '_': '/tools/buildbot/bin/python'} 03:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:43 INFO - Cleaning up... 03:43:43 INFO - Return code: 0 03:43:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:43 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:43 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 03:43:43 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 03:43:43 INFO - Using env: {'DISPLAY': ':0', 03:43:43 INFO - 'HOME': '/home/cltbld', 03:43:43 INFO - 'LANG': 'en_US.UTF-8', 03:43:43 INFO - 'LANGUAGE': 'en_US:en', 03:43:43 INFO - 'LOGNAME': 'cltbld', 03:43:43 INFO - 'MAIL': '/var/mail/cltbld', 03:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:43 INFO - 'MOZ_NO_REMOTE': '1', 03:43:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:43 INFO - 'NO_EM_RESTART': '1', 03:43:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:43 INFO - 'PWD': '/builds/slave/test', 03:43:43 INFO - 'SHELL': '/bin/bash', 03:43:43 INFO - 'SHLVL': '1', 03:43:43 INFO - 'TERM': 'linux', 03:43:43 INFO - 'TMOUT': '86400', 03:43:43 INFO - 'USER': 'cltbld', 03:43:43 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:43 INFO - '_': '/tools/buildbot/bin/python'} 03:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:43 INFO - Downloading/unpacking blobuploader==1.2.4 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:45 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43:45 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 03:43:45 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 03:43:45 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 03:43:45 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 03:43:45 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 03:43:45 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 03:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43:46 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 03:43:46 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 03:43:46 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 03:43:46 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 03:43:46 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 03:43:46 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 03:43:46 INFO - Downloading docopt-0.6.1.tar.gz 03:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:46 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:46 INFO - Running setup.py install for blobuploader 03:43:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:46 INFO - Running setup.py install for requests 03:43:47 INFO - Running setup.py install for docopt 03:43:47 INFO - Successfully installed blobuploader requests docopt 03:43:47 INFO - Cleaning up... 03:43:47 INFO - Return code: 0 03:43:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:47 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')]} 03:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:47 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')]} 03:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:47 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:47 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 03:43:47 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 03:43:47 INFO - Using env: {'DISPLAY': ':0', 03:43:47 INFO - 'HOME': '/home/cltbld', 03:43:47 INFO - 'LANG': 'en_US.UTF-8', 03:43:47 INFO - 'LANGUAGE': 'en_US:en', 03:43:47 INFO - 'LOGNAME': 'cltbld', 03:43:47 INFO - 'MAIL': '/var/mail/cltbld', 03:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:47 INFO - 'MOZ_NO_REMOTE': '1', 03:43:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:47 INFO - 'NO_EM_RESTART': '1', 03:43:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:47 INFO - 'PWD': '/builds/slave/test', 03:43:47 INFO - 'SHELL': '/bin/bash', 03:43:47 INFO - 'SHLVL': '1', 03:43:47 INFO - 'TERM': 'linux', 03:43:47 INFO - 'TMOUT': '86400', 03:43:47 INFO - 'USER': 'cltbld', 03:43:47 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:47 INFO - '_': '/tools/buildbot/bin/python'} 03:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:47 INFO - Running setup.py (path:/tmp/pip-XgYsGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:47 INFO - Running setup.py (path:/tmp/pip-Qa2Ien-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:47 INFO - Running setup.py (path:/tmp/pip-yyOpNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:47 INFO - Running setup.py (path:/tmp/pip-JkKQFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:47 INFO - Running setup.py (path:/tmp/pip-rIVYxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:47 INFO - Running setup.py (path:/tmp/pip-F22n68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:47 INFO - Running setup.py (path:/tmp/pip-qw3jlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:47 INFO - Running setup.py (path:/tmp/pip-zr2AJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:47 INFO - Running setup.py (path:/tmp/pip-QJbkVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:48 INFO - Running setup.py (path:/tmp/pip-W8KQp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:48 INFO - Running setup.py (path:/tmp/pip-coxdYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:48 INFO - Running setup.py (path:/tmp/pip-caj2iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:48 INFO - Running setup.py (path:/tmp/pip-CR_nuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:48 INFO - Running setup.py (path:/tmp/pip-l1bUOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:48 INFO - Running setup.py (path:/tmp/pip-ZkN2N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:48 INFO - Running setup.py (path:/tmp/pip-AbUmL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:48 INFO - Running setup.py (path:/tmp/pip-G4h54d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:48 INFO - Running setup.py install for manifestparser 03:43:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:48 INFO - Running setup.py install for mozcrash 03:43:48 INFO - Running setup.py install for mozdebug 03:43:48 INFO - Running setup.py install for mozdevice 03:43:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for mozfile 03:43:49 INFO - Running setup.py install for mozhttpd 03:43:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for mozinfo 03:43:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for mozInstall 03:43:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for mozleak 03:43:49 INFO - Running setup.py install for mozlog 03:43:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for moznetwork 03:43:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for mozprocess 03:43:49 INFO - Running setup.py install for mozprofile 03:43:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Running setup.py install for mozrunner 03:43:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:50 INFO - Running setup.py install for mozscreenshot 03:43:50 INFO - Running setup.py install for moztest 03:43:50 INFO - Running setup.py install for mozversion 03:43:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:50 INFO - Cleaning up... 03:43:50 INFO - Return code: 0 03:43:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43: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')]} 03:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:50 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:50 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 03:43:50 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 03:43:50 INFO - Using env: {'DISPLAY': ':0', 03:43:50 INFO - 'HOME': '/home/cltbld', 03:43:50 INFO - 'LANG': 'en_US.UTF-8', 03:43:50 INFO - 'LANGUAGE': 'en_US:en', 03:43:50 INFO - 'LOGNAME': 'cltbld', 03:43:50 INFO - 'MAIL': '/var/mail/cltbld', 03:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:50 INFO - 'MOZ_NO_REMOTE': '1', 03:43:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:50 INFO - 'NO_EM_RESTART': '1', 03:43:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:50 INFO - 'PWD': '/builds/slave/test', 03:43:50 INFO - 'SHELL': '/bin/bash', 03:43:50 INFO - 'SHLVL': '1', 03:43:50 INFO - 'TERM': 'linux', 03:43:50 INFO - 'TMOUT': '86400', 03:43:50 INFO - 'USER': 'cltbld', 03:43:50 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:50 INFO - '_': '/tools/buildbot/bin/python'} 03:43:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:50 INFO - Running setup.py (path:/tmp/pip-pNuHOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:50 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)) 03:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:50 INFO - Running setup.py (path:/tmp/pip-encTWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:50 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)) 03:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:50 INFO - Running setup.py (path:/tmp/pip-wdnhkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:50 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)) 03:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:50 INFO - Running setup.py (path:/tmp/pip-l0M66L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:50 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)) 03:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:50 INFO - Running setup.py (path:/tmp/pip-_FKdpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:51 INFO - Running setup.py (path:/tmp/pip-Rk1XW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:51 INFO - Running setup.py (path:/tmp/pip-58rat8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:51 INFO - Running setup.py (path:/tmp/pip-EUmDOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:51 INFO - Running setup.py (path:/tmp/pip-u8S9Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:51 INFO - Running setup.py (path:/tmp/pip-TfgRj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:51 INFO - Running setup.py (path:/tmp/pip-7i_LOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:51 INFO - Running setup.py (path:/tmp/pip-NkGGES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:51 INFO - Running setup.py (path:/tmp/pip-oIBana-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:51 INFO - Running setup.py (path:/tmp/pip-ZDP5tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:51 INFO - Running setup.py (path:/tmp/pip-wtQAG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:51 INFO - Running setup.py (path:/tmp/pip-tXjLzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:51 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)) 03:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:51 INFO - Running setup.py (path:/tmp/pip-enJrgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:51 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)) 03:43:51 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)) 03:43:51 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)) 03:43:51 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)) 03:43:51 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)) 03:43:51 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)) 03:43:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:51 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 03:43:51 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 03:43:51 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 03:43:51 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 03:43:51 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 03:43:51 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 03:43:53 INFO - Downloading blessings-1.6.tar.gz 03:43:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:53 INFO - Installing collected packages: blessings 03:43:53 INFO - Running setup.py install for blessings 03:43:53 INFO - Successfully installed blessings 03:43:53 INFO - Cleaning up... 03:43:54 INFO - Return code: 0 03:43:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:54 INFO - Reading from file tmpfile_stdout 03:43:54 INFO - Current package versions: 03:43:54 INFO - argparse == 1.2.1 03:43:54 INFO - blessings == 1.6 03:43:54 INFO - blobuploader == 1.2.4 03:43:54 INFO - docopt == 0.6.1 03:43:54 INFO - functools32 == 3.2.3-2 03:43:54 INFO - jsonschema == 2.5.1 03:43:54 INFO - manifestparser == 1.1 03:43:54 INFO - mozInstall == 1.12 03:43:54 INFO - mozcrash == 0.17 03:43:54 INFO - mozdebug == 0.1 03:43:54 INFO - mozdevice == 0.48 03:43:54 INFO - mozfile == 1.2 03:43:54 INFO - mozhttpd == 0.7 03:43:54 INFO - mozinfo == 0.9 03:43:54 INFO - mozleak == 0.1 03:43:54 INFO - mozlog == 3.3 03:43:54 INFO - moznetwork == 0.27 03:43:54 INFO - mozprocess == 0.23 03:43:54 INFO - mozprofile == 0.28 03:43:54 INFO - mozrunner == 6.12 03:43:54 INFO - mozscreenshot == 0.1 03:43:54 INFO - mozsystemmonitor == 0.3 03:43:54 INFO - moztest == 0.7 03:43:54 INFO - mozversion == 1.4 03:43:54 INFO - psutil == 3.1.1 03:43:54 INFO - requests == 1.2.3 03:43:54 INFO - wsgiref == 0.1.2 03:43:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:54 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 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:54 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 03:43:54 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 03:43:54 INFO - Using env: {'DISPLAY': ':0', 03:43:54 INFO - 'HOME': '/home/cltbld', 03:43:54 INFO - 'LANG': 'en_US.UTF-8', 03:43:54 INFO - 'LANGUAGE': 'en_US:en', 03:43:54 INFO - 'LOGNAME': 'cltbld', 03:43:54 INFO - 'MAIL': '/var/mail/cltbld', 03:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:54 INFO - 'MOZ_NO_REMOTE': '1', 03:43:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:54 INFO - 'NO_EM_RESTART': '1', 03:43:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:54 INFO - 'PWD': '/builds/slave/test', 03:43:54 INFO - 'SHELL': '/bin/bash', 03:43:54 INFO - 'SHLVL': '1', 03:43:54 INFO - 'TERM': 'linux', 03:43:54 INFO - 'TMOUT': '86400', 03:43:54 INFO - 'USER': 'cltbld', 03:43:54 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:54 INFO - '_': '/tools/buildbot/bin/python'} 03:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 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)) 03:43:54 INFO - Cleaning up... 03:43:54 INFO - Return code: 0 03:43:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2963180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, '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': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', '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 03:43:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:43:54 INFO - Using env: {'DISPLAY': ':0', 03:43:54 INFO - 'HOME': '/home/cltbld', 03:43:54 INFO - 'LANG': 'en_US.UTF-8', 03:43:54 INFO - 'LANGUAGE': 'en_US:en', 03:43:54 INFO - 'LOGNAME': 'cltbld', 03:43:54 INFO - 'MAIL': '/var/mail/cltbld', 03:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:54 INFO - 'MOZ_NO_REMOTE': '1', 03:43:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:54 INFO - 'NO_EM_RESTART': '1', 03:43:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:54 INFO - 'PWD': '/builds/slave/test', 03:43:54 INFO - 'SHELL': '/bin/bash', 03:43:54 INFO - 'SHLVL': '1', 03:43:54 INFO - 'TERM': 'linux', 03:43:54 INFO - 'TMOUT': '86400', 03:43:54 INFO - 'USER': 'cltbld', 03:43:54 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:54 INFO - '_': '/tools/buildbot/bin/python'} 03:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:54 INFO - Cleaning up... 03:43:54 INFO - Return code: 0 03:43:54 INFO - Running post-action listener: _resource_record_post_action 03:43:54 INFO - Running post-action listener: _start_resource_monitoring 03:43:54 INFO - Starting resource monitoring. 03:43:54 INFO - [mozharness: 2016-08-21 10:43:54.702949Z] Finished create-virtualenv step (success) 03:43:54 INFO - [mozharness: 2016-08-21 10:43:54.703379Z] Running install step. 03:43:54 INFO - Running pre-action listener: _resource_record_pre_action 03:43:54 INFO - Running main action method: install 03:43:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:54 INFO - Reading from file tmpfile_stdout 03:43:54 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:54 INFO - Reading from file tmpfile_stdout 03:43:54 INFO - Output received: 03:43:54 INFO - Usage: mozinstall [options] installer 03:43:54 INFO - Options: 03:43:54 INFO - -h, --help show this help message and exit 03:43:54 INFO - -d DEST, --destination=DEST 03:43:54 INFO - Directory to install application into. [default: 03:43:54 INFO - "/builds/slave/test"] 03:43:54 INFO - --app=APP Application being installed. [default: firefox] 03:43:54 INFO - mkdir: /builds/slave/test/build/application 03:43:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:44:11 INFO - Reading from file tmpfile_stdout 03:44:11 INFO - Output received: 03:44:11 INFO - /builds/slave/test/build/application/firefox/firefox 03:44:11 INFO - Running post-action listener: _resource_record_post_action 03:44:11 INFO - [mozharness: 2016-08-21 10:44:11.580958Z] Finished install step (success) 03:44:11 INFO - [mozharness: 2016-08-21 10:44:11.581242Z] Running run-tests step. 03:44:11 INFO - Running pre-action listener: _resource_record_pre_action 03:44:11 INFO - Running main action method: run_tests 03:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:44:11 INFO - Python 2.7.3 03:44:11 INFO - Return code: 0 03:44:11 INFO - grabbing minidump binary from tooltool 03:44:11 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')]} 03:44:11 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 0x29613b0>, '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 0x29622b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:44:11 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 03:44:11 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 03:44:11 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 03:44:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:44:11 INFO - Return code: 0 03:44:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:44:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:44:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:44:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:44:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 03:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 03:44:11 INFO - Using env: {'DISPLAY': ':0', 03:44:11 INFO - 'HOME': '/home/cltbld', 03:44:11 INFO - 'LANG': 'en_US.UTF-8', 03:44:11 INFO - 'LANGUAGE': 'en_US:en', 03:44:11 INFO - 'LOGNAME': 'cltbld', 03:44:11 INFO - 'MAIL': '/var/mail/cltbld', 03:44:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:11 INFO - 'MOZ_NO_REMOTE': '1', 03:44:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:11 INFO - 'NO_EM_RESTART': '1', 03:44:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:11 INFO - 'PWD': '/builds/slave/test', 03:44:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:11 INFO - 'SHELL': '/bin/bash', 03:44:11 INFO - 'SHLVL': '1', 03:44:11 INFO - 'TERM': 'linux', 03:44:11 INFO - 'TMOUT': '86400', 03:44:11 INFO - 'USER': 'cltbld', 03:44:11 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404', 03:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:11 INFO - '_': '/tools/buildbot/bin/python'} 03:44:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 03:44:11 INFO - mozversion application_buildid: 20160821004002 03:44:11 INFO - mozversion application_changeset: 6f1c9caedd87771d03d5e2808a4e821136679221 03:44:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:44:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:44:11 INFO - mozversion application_name: Firefox 03:44:11 INFO - mozversion application_remotingname: firefox-dev 03:44:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:44:11 INFO - mozversion application_vendor: Mozilla 03:44:11 INFO - mozversion application_version: 50.0a2 03:44:11 INFO - mozversion platform_buildid: 20160821004002 03:44:11 INFO - mozversion platform_changeset: 6f1c9caedd87771d03d5e2808a4e821136679221 03:44:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:44:11 INFO - mozversion platform_version: 50.0a2 03:44:11 INFO - using testdate: 1471776251 03:44:11 INFO - actual date: 1471776251 03:44:11 INFO - starting webserver on 'localhost:36273' 03:44:11 INFO - SUITE-START | Running 2 tests 03:44:11 INFO - TEST-START | dromaeo_css 03:44:11 INFO - operating with platform_type : linux_ 03:44:11 INFO - Initialising browser for dromaeo_css test... 03:44:11 INFO - TEST-INFO | started process 21830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXc_T7H/profile http://localhost:36273/getInfo.html) 03:44:15 INFO - PROCESS | 21830 | __metrics Screen width/height:1600/1200 03:44:15 INFO - PROCESS | 21830 | colorDepth:24 03:44:15 INFO - PROCESS | 21830 | Browser inner width/height: 1024/697 03:44:15 INFO - PROCESS | 21830 | __metrics 03:44:16 INFO - PROCESS | 21830 | 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" 03:44:16 INFO - PROCESS | 21830 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:17 INFO - TEST-INFO | 21830: exit 0 03:44:17 INFO - Browser initialized. 03:44:17 INFO - Running cycle 1/1 for dromaeo_css test... 03:44:17 INFO - TEST-INFO | started process 21918 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXc_T7H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:29 INFO - PROCESS | 21918 | RSS: Main: 145383424 03:44:29 INFO - PROCESS | 21918 | 03:45:19 INFO - PROCESS | 21918 | 1471776319822 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] 03:47:28 INFO - PROCESS | 21918 | Cycle 1(1): loaded http://localhost:36273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36273/tests/dromaeo/cssquery-ext.html) 03:47:30 INFO - PROCESS | 21918 | RSS: Main: 284618752 03:47:30 INFO - PROCESS | 21918 | 03:50:32 INFO - PROCESS | 21918 | Cycle 1(1): loaded http://localhost:36273/tests/dromaeo/cssquery-ext.html (next: http://localhost:36273/tests/dromaeo/cssquery-jquery.html) 03:50:33 INFO - PROCESS | 21918 | RSS: Main: 233750528 03:50:33 INFO - PROCESS | 21918 | 03:53:35 INFO - PROCESS | 21918 | Cycle 1(1): loaded http://localhost:36273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36273/tests/dromaeo/cssquery-mootools.html) 03:53:35 INFO - PROCESS | 21918 | RSS: Main: 236797952 03:53:35 INFO - PROCESS | 21918 | 03:56:37 INFO - PROCESS | 21918 | Cycle 1(1): loaded http://localhost:36273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36273/tests/dromaeo/cssquery-prototype.html) 03:56:38 INFO - PROCESS | 21918 | RSS: Main: 173797376 03:56:38 INFO - PROCESS | 21918 | 03:59:40 INFO - PROCESS | 21918 | Cycle 1(1): loaded http://localhost:36273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36273/tests/dromaeo/cssquery-yui.html) 03:59:41 INFO - PROCESS | 21918 | RSS: Main: 177750016 03:59:41 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | Cycle 1(1): loaded http://localhost:36273/tests/dromaeo/cssquery-yui.html (next: http://localhost:36273/tests/dromaeo/cssquery-dojo.html) 04:02:44 INFO - PROCESS | 21918 | RSS: Main: 269180928 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | __start_tp_report 04:02:44 INFO - PROCESS | 21918 | _x_x_mozilla_page_load 04:02:44 INFO - PROCESS | 21918 | _x_x_mozilla_page_load_details 04:02:44 INFO - PROCESS | 21918 | |i|pagename|runs| 04:02:44 INFO - PROCESS | 21918 | |0;dojo.html;3301.636819949805;3314.6353901070884;3316.4196265653504;3337.5160601712737;3355.4295769719147;5634.943650563493;5712.057510510771;5715.9428405715935;5732.5987180897355;5737.942620573793;5742.913856292149;5810.099434587635;5813.476787089161;5820.854478638036;5825.8834823303505;5098.388193416777;5193.272941788153;5243.292155558989;5270.525652691257;5292.206169074631;5146.433892271847;5249.081410753122;5287.550558202557;5295.338082739658;5307;5097.260897169908;5192.974035129819;5213.218017297417;5233.555147812422;5262.684238945656;5319.361676598814;5331.786728530854;5332.946670533283;5336.279602253685;5360.03519366512;5100.438951715309;5114.846554603368;5157.767900444452;5225.817096401645;5236.293100431431;5667.773289068432;5698.6580805151825;5722.599418040739;5763.9135412968835;5775.48020678141;6239.344868788802;6252.906206406908;6256.249250090018;6266.686665666744;6269.905951410732;6111.774065234695;6117.5717699761035;6132.933533233383;6250.34371391002;6300.086487459293;5832.212651292064;5842.094475356326;5847.3275573308765;5849.678267695275;5851.444112809277;6118.7410088057595;6298.653574053471;6352.269489008823;6363.745450181941;6369;6012;6060.9078415360445;6084.574079814371;6172.753089876354;6179;6245.96877015612;6248.4688801451475;6248.50011999039;6250.531210159256;6254.030625253093;6004.3395226525045;6199.1011303361665;6213.03697926822;6227.06594010902;6229.159063526366;6094.421030002142;6128.233970753656;6130.141780150782;6152.661603611755;6166.691665416712;4623.260278355447;4660.557247061523;4666.510016448292;4687.039156972813;4693.530646935279;4086.7956602169775;4165.541790403053;4177.874663760092;4184.497860256789;4189.685773566995;5701.914471282934;5714.828555143353;5746.569007324468;5853.268341457318;5880.7353669085;6166.260048794174;6167.167432396569;6168.814935551941;6169.2288463942;6175.104609831549;5726.99777538929;5844.035734103825;5862.325832529228;5864.44287792659;5865.266841644794;5250.264962905197;5296.523312901857;5320.441353657888;5370.650907690988;5375.381831089397;6380.3619638035825;6394.968025159845;6427.100205971168;6438.678066096676;6442.098106265033;6172.321044685171;6213.068039794067;6223.315435302023;6223.471004964629;6231.376862313732;6120.846978825565;6233.096201050913;6326.398992095744;6333.303336632875;6437.839054023687;6117.785877494266;6122.571420000557;6122.877542449215;6126.387361263838;6131.754729810757;6223.533235007302;6232.937670623417;6237.033259844731;6237.781677641261;6239.438450539473;5825.300963884361;5835.474807267196;5894.38108998549;5978.4021597839865;5979.970100149471;5915.497182739516;5935.851603709942;5954.46409823118;6018.398160183947;6109.877802443837;6011.60924539921;6015.699215039143;6020.969895150496;6021.217241758543;6025.126356678169;6066.332703402534;6095.445661356326;6098.32918378969;6106.255006044545;6136.478399336106;5704.229928959703;5863.560232982458;5904.9704751475965;5937.406259374028;6022.247219097613 04:02:44 INFO - PROCESS | 21918 | |1;ext.html;603.827909045922;638.5498223752255;660.9985871404825;664.5447868210276;671.2783757460725;35465.29069418614;46804.297935530994;48169.759151204285;48392.516074839245;51355;49106.75446622762;49547.50452495474;49596.752016239865;49603;49694.25458618124;8645.351598630097;9413.905860941388;9585.185259252938;9588.185004274645;11181.72045698856;11327.943360283187;11433.256838305484;11440.084793216523;11445.08439324852;11447.542098316098;11538.538458461651;11574.479148438299;11580.305181689126;11591.594294199705;11598.536058557647;11388.487518061707;11389.886101138965;11423.828642570368;11483.65549033526;11513.251638643462;11351;11429.59996400122;11431.599894003753;11440.370779607118;11444.542218311257;45439.77280113611;45753.542464575265;45756.771216144036;45757.77121114373;45857.312140317925;3948.6841052715713;4169.395437661552;4305.203537345601;4339.934900976488;4632.235681112612;35698.097113186246;35942.8202858984;37030.62969370299;37099;37516;4774.785134668949;4815.759212039384;4874.975625121887;4903.509649035103;4959.156943319645;369873;369875;369910;370392;373734;62250.68874655598;63133;63141.36858631355;63161.368386315546;63222;39939.80030099831;40796.79601602032;40910.38634420486;42759.786201069415;42791.572084278756;17207.225674844663;17366.739498907275;17387.391441299493;17404.825951740575;17437.7384339235;12463.376831158406;12599.496020159275;12685.175463595022;12784.6803829905;12810.615681529569;21251.89374053151;21280.574388512145;21299.148034078462;21329.573408532055;21412.929353532574;7735.110462296908;7748.147703752583;7871.685132594632;7910.129885712567;7948.125706172316;1051.7107795356185;1062.984055239172;1099.6426161497386;1135.6309199510026;1183.828344889986;34757.47863782045;35114.12214694602;35498.29003419969;35756.82121589375;35812.925612231156;1132.8867113288604;1143.5597295041302;1144.0504381363448;1159.657900919214;1181.0964612071773;16369.754453682966;18427.26290948374;19207.135678894203;19441;19590.902045489966;605754;608520;609610;611479.8278025833;611798;64244.35755642477;64635.353646462936;64806;64813.35186648167;64841.67579162074;1316.1708123881892;1387.7848933415337;1413.3074793351184;1414.8302203735618;1422.615893708693;32921.17697057497;36487.27025459529;36584.45123323205;36866.44700329444;36950;53517.46482535281;54015.45984540104;54018.729906350214;54042.729786350814;54141;357734;358303;358999;359031;360099.3990060066;30382.696173038872;31251.53122703116;31441.52837707482;32535.837320813243;32604;54136.45863541472;54139.72930135324;54246.72876635592;54729.17906231489;54781;44550.777246114856;45468.54531454775;46132.769336153106;47064.294035588806;48233.5176648229;46733;47248.763756181;47300.29049564191;47606.52393476021;47728.400452533424;45398.77300613476;46192.538074618824;46346.768266158455;46554.767226163654;46643.30035049545;38485.42271865868;40289.79855100823;41071.38392924049;41166.58833411747;41224.175516490104;30580.694193057785;30699.38601227918;30743.2314192147;30857.69142308548;30906.690933091282 04:02:44 INFO - PROCESS | 21918 | |2;jquery.html;398.2930298719774;413.36961134270234;429.2895258347435;432.67981693546784;442.89813342931126;15671.529854104367;20222;20743.273985410495;21633.675494867588;22371.217007404743;20836.479088022807;21312.786872131273;21354.25260115896;21451.249206277782;21701.782982170174;5603.271574695284;5829.941700583004;6052.818415447544;6117.541184411165;6118.357572454895;5789.797357092502;5965.642061476326;5993.490553302974;6005;6007.909881351783;5887.646741195521;5891.469767720904;5910.46805787479;5911.822645320647;5915.1718759373725;5748.971255143698;5906.88186236275;5917.142014407908;5920.171176035358;5989.341172471025;5915;5920;5924.6149000314845;5931.64410135389;5937.406259374071;14415.927920360331;25060.49879002428;25171.748282517307;25241.24276271715;25868.870655646788;2400.7239167495613;2467.124170919316;2508.4732206236713;2568.3065572295563;2766.7786577073784;9924.454155021544;10217.489125543692;10390.792184156275;10466.738331541696;10664.360138391643;1968.1930408532576;2039.7450318710162;2052.2611859730487;2070.7411573553304;2076.9480762980897;88122.55938720329;91069;91721.54139229328;92218.5389073057;92827.07172928251;9909.405435673883;10363.585456581652;10510.684679459628;10513.52689128991;10612.57549698019;14099.85900141006;14148.363323650461;14163.433462661378;14165.645858853612;14192.361343739556;7811.765647030598;7834.647440865175;7946.721864734706;7951.363890888715;8053.476524025919;7641.350485208708;7685.269899359552;7741;7844.843103137905;7911.327537159406;4343.087951530151;4348.478182618106;4352.804123814436;4421.7789110544345;4443.244648409778;1929.6333696597883;1952.2288695965299;2029.0869108900758;2035.8269547088664;2044.4275602830933;1010.2675560218782;1017.2218253036433;1031.200819364998;1086.6445643030365;1148.8851114888444;20050.699239511418;20596.794032059788;20865.58268834615;20885.791142088383;20928.26751063736;1046.942418167008;1052.315994603514;1054.393723608928;1105.4307031878589;1149.7930372532858;11035.227534072537;11389.829152562717;11517.193796434336;11546.19176657626;11576.189666723418;489339;495559;516041;520243.7914673044;520443;92289.53855230681;96352.51823740976;101360.49319753502;104686.47656761667;105539;1011.3628414099071;1036.9325993810528;1048.6749107776614;1057.957681692739;1061.7982583309147;17684.292628294723;18042.729359059886;18246.543836404195;18447.907760461112;18754.343597974006;45684.314735278334;46116.30825537552;46251.53748462472;46762;47243.05513889634;64809.67595161994;65077.67461162664;65607.34392656204;65671.67164164149;66395;8144.59277036134;8151.714689986059;8302.54336011525;8305.294050005576;8443.831123377375;12241.387930603612;12544.686382840502;12731.427085780977;12895.971695425827;13214.273214972884;10349.482525873827;10736.51685674131;11684.065274778168;11906.40467976615;12141.903814527592;10973.23187376876;11107.111431085656;11376.829347559627;11727.941360293144;12060.095492837896;11319.603813866475;11400.48697808584;12329.321887296284;12386;12416.937915310365;8287.58562071906;8417.70538031166;8436.915630843858;8455.915440845512;8586.356023298402;11205.71985700364;11517.94241028823;11737.237079589799;11803.350815704875;12382.318972456602 04:02:44 INFO - PROCESS | 21918 | |3;mootools.html;1442.0770706747676;1527.8166620005602;1583.4378795527587;1772.3885259585447;1898.7341772151901;9225.261979041677;9499.620015199393;9633.807323853518;9841.311108222428;9853.310268281222;9640.469774162435;9678.274129440286;9754.85367719483;9756.756081097976;9766.462844543546;2487.1543675150447;2522.7351128131468;2524.192258477288;2526.8502831211845;2528.7724104830563;2423.503181847717;2517.1064272183385;2522.8612426316545;2525.4949010198034;2526.2800101970943;2384.904603815854;2440.389902524369;2499.462615537659;2500.174942269047;2504.4365017871014;2441.7802397784285;2444.7433019532873;2469.0494159748628;2479.9752002479927;2485.72657007729;2409.9156529521556;2427.6965379327585;2432.8540287582623;2435.0625009371342;2468.0744720729726;9516.904830951671;9606;9622.951885240598;9681.370710903839;9683.854742178873;5582.078956972095;5802;5807.854803629901;5809.883802323972;5810.070388737781;3700.888973330804;3738.401855703102;3759.2293579816073;3798.5631652360057;3800.277947190025;1241.0567968344033;1241.8944389726885;1258.7482503499336;1270.1299609767339;1290.084040331366;14192.50326238587;14328.211948342732;14354;14365.92817035908;14385.136891786526;4783;4864.902701946012;4882.877928051827;4995.925061124086;5041.193409054534;10824.72938176536;10856.891431085745;10859.2398532102;10863.728406789893;10872;5726.4273572642405;5731.140328950691;5742.540596752333;5743.971280143573;5761.366249712612;4662.137504561667;5142.202958541432;5210.452902445189;5279.788808447696;5373.516383525502;4265.50946641134;4315.050688848449;4328.285832837547;4366.126774645084;4469.374287599739;1286.5497076023505;1304.1327517201146;1311.3050083455785;1313.0874042540343;1320.1551007355301;835.4026870787295;928.2666693312343;1084.4615285032166;1096.221682605359;1107.2636696596835;6597.538172327891;6715.7985260442265;6821.2155602106395;6863.416609588141;7011.4390848733;490.2841850897658;661.8146918862726;1104.4864138175626;1137.6018393562188;1159.1248607301434;4664.416947881515;4741.028089241766;4810.302506136591;4869.220924652039;4957.727324997088;11859.34773587461;11900.583479578174;12295.631131066082;12305.876941230474;12320.876791232153;12373.75252494945;12438.875611243771;12473.93763031179;12493.562725304753;12516.311602861748;9105.362624616346;9295.72112836616;9298.67454639098;9302.720918372459;9325.673601423918;6661.100751398693;6887.139107611549;6945.965270173617;6967.65161741921;7218.639068046472;11134.944325278646;11459.4270286487;11646.301221926711;11759.176857619885;11761;11416.657500275005;11441;11737.530498780296;11745.647630571097;11814.94092529532;8100.71647492335;8490.617922193393;8767.956160219159;8777.868331975153;8885.955570222366;9890.258230632588;9960.601575936882;9965.850512242467;9972.950135249277;9976.650817221362;8430.620622072143;8453.450525715809;8470.618822153141;8753.518556479456;8906.510141942255;8181.427300089174;8481.533515656698;8499.447535910393;8560.14398560139;8662.82674346522;7942.841143177219;8015.278624923785;8084.959575202086;8102;8111.83776324482;5853.946289667901;5961.880762384815;6025.216721826135;6138.5396095292135;6201.410865967726;5835.153902684172;6082;6085.117657939485;6161.845953851011;6519.217693876765 04:02:44 INFO - PROCESS | 21918 | |4;prototype.html;349.10106475824756;350.60731980182186;354.5143153879186;360.8592648866942;362.5902729915196;5552.944470555299;5621.128725047619;5621.887562248752;5631.774729010845;5643.6049476536655;5296.3379577552805;5373.623846330758;5400.324959380077;5422.53908417784;5600.243967064445;4226.788660566975;4239.894002649935;4329.393884856123;4390.143921935224;4393.450818647669;4337.306031035035;4357.150355680644;4357.782110894458;4381.277089280265;4404.603585677289;4250.234957707611;4318.287482565373;4330.935035974463;4341.196878577473;4372.519022907478;4351.651867850596;4357.542458041908;4358.956410435902;4361.80371883266;4387.9561204387865;3888.786116763577;3921.4313924481075;3923.9215215695813;3931.8230679619487;3948.269570129535;5134.435212126663;5438.428964958702;5465.562754979632;5487.780488780444;5493.945060549383;4975.378077740283;4978.153713868614;4978.153713868651;4979.128652485837;4995.400551933755;5749.9712501437225;5806.2451881255165;5809.24479817621;5858.326292476378;5881.647101173943;4113.1568028554075;4119.702293777451;4131.9173616527805;4154.584541545852;4162.396452514398;5981.641101533878;5998.670073145975;6017.48851347636;6024.337322894522;6030.66831324277;4378.190034843564;4389.407429996973;4389.968357436;4390.429244198233;4395.890102747457;4192.287311157111;4394.1651086293505;4395.384646149541;4411.492678341968;4439.489458712224;4955.727434991038;4961.0899803575885;4962.206047032458;4962.255661650781;4964.379452568429;5822.854428639318;5830.7959221427045;5830.970845145747;5836.591438599342;5850.707464626752;1403.8245219347582;1454.403694485256;1489.3819065087866;1513.9091654500764;1528.9541313760606;1004.0311099788739;1007.7430255285002;1018.6587493189865;1023.9555653233808;1033.7053939627322;5616.719164041781;5629.437056294338;5643.463870932255;5659.5755318351285;5679.716014199274;5791.523161593766;5829.446202610745;5847.619904706181;5887.20522729426;5891.23413956183;5399.950037471896;5538.224648549206;5560.414407300625;5565.666060036411;5571.192177134374;2729.3040274730215;2735.2341344423594;2741.643586333764;2750.0399424828183;2774.3064233941514;5307.037919545238;5309;5320.361556613154;5350.30446042012;5418.783248670088;4939.604831613462;5186.5850731941355;5231.744381348449;5244;5279.577633789365;5325.467453254643;5328.440513746078;5341.679499230058;5351.919721204185;5364.91952620711;5213.452587478412;5232.843014709565;5238.3975842777045;5252.684838909678;5261.921071184011;5566;5956.166136740946;5981.491573216325;5996;6010.54920880927;6232.220972378453;6242.781502647385;6256.937430625818;6265.843353916189;6335.65153916539;4241.257779888446;4319.956800432081;4333.783310834383;4347.7173983690955;4351.956480435155;6039.184710064261;6041.643758934107;6046.939530604637;6048.60684055535;6060.939390606038;5650.85872853182;5742.913856292075;5863.120531920075;5875.383084776208;5907.5273978080795;5863.501602363676;5866.5893387462465;5884.382139875252;5888.528917686489;5895.793647222326;5615.080158317734;5779.566532510163;5806.187133801187;5852.385499522489;5859.882802343844;5877.324107727666;5877.441643043904;5892.823215303548;5934.109883517334;5941.6827437298625;5609.186667933045;5740.339860915881;5813.244278243801;5819.970900145641;5861.706914654165 04:02:44 INFO - PROCESS | 21918 | |5;yui.html;843.2916350265775;860.6084231674585;879.7096958003859;903.6791938861708;909.0188148060033;17176.570585735364;18282.81717182828;18324.54188645283;18349.541261468436;18350.44948651541;16445;16565;16613.584660383498;16632.25154868031;16669.416570420006;742.4060751398887;753.9321461068503;876.1851478125341;892.2371372476553;903.0969030969031;880.885484886967;905.2622112977934;905.3571963905634;905.9773505662346;906.134641417448;957.1098878043417;957.1290125985355;958.2860768727307;958.5782255807458;958.8417911044669;776.5728849132954;923.1230331185334;955.6082006377387;956.9282303827241;959.4387283439196;678.6301465701205;687.4603436302529;687.4637782529635;687.6149356360454;688.0367485520312;16291.511254662379;16311.429099981562;16328;16348.182590870529;16353;15478.226088695521;15682.29429675656;15783.842161578352;16073.678526429407;16180.595485112848;12723.363831808372;12772.552960646333;12897.161684490478;13391.263480508567;13647.86352136476;4729.527047295277;4782.0914026334885;4851.611871050308;5028.7988480460735;5031.874203144914;84084.15915840825;85026.57486712527;85749.14250857473;87557;87630.561847191;12378.690532736753;12526.937365313115;12545.435455404346;12575.622731318075;12731.936340318425;22123;22149.224777133044;22160.335189944326;22163.889180553993;22205.555888882456;9079.228265597498;9095.181433671001;9453.054694530492;9514.19129374011;9580.95209523948;4857.392825896763;5074.3657042869645;5106.540411362921;5224.947750522522;5342.91985620216;3852.287326844487;3998.8400463981116;4008.819603117867;4035.899102522402;4036.0313524753847;668.9596729973562;679.8776220280298;687.1307795638521;687.5840116729307;736.1203361982487;746.5707218349471;828.5525816059381;845.1251014100296;853.3812985585525;859.6346552715071;10544.314619549705;10553.894461055292;11132;11489.885101148882;11604.94197529007;764.577885133651;779.6647441600167;787.5117427195175;799.9480033797785;819.1030821250721;5124.666896651705;5166.483351664879;5176.7152806596;5227.817026404134;5261.684298942076;127155.72844271624;131504;139965;142473;144403.2779836115;69853;72269.63865180641;86750.56624716963;111978;121191;105201.47399263107;105919;105950.4702476498;107192.46403767931;107422;9951.601935922483;10044.146247569039;10073.395596264247;10211.7447063824;10254.23093267993;39295.2140957185;39874;53111;62643;63205.36794631995;56951.43048569627;79418.60290698509;80642.19357806347;84199;85653.14346856452;14102.788458173341;14290.42838286457;14362.712745745235;14493.420263189355;14566.417343306573;16499.917500412423;16651.167441628113;17012.06433646161;17151.22819473102;18084.276628934695;15108;15336.233188340762;15503.379864805282;15519.22403879824;16511.256993435083;15507.612309692347;15527.611809704396;16597.41909033178;16698.332066717194;16783.244753985855;14858.182799945675;14923.403063877324;15867.20663966774;15951.361945522049;16004.839951600801;12290.20113692607;12587.181833180814;12710.682232944251;12850.935745321214;12990;11159.330440173615;11682.065434765369;11881.702957426134;12790.61628151157;13079 04:02:44 INFO - PROCESS | 21918 | __end_tp_report 04:02:44 INFO - PROCESS | 21918 | __start_cc_report 04:02:44 INFO - PROCESS | 21918 | _x_x_mozilla_cycle_collect,1167 04:02:44 INFO - PROCESS | 21918 | __end_cc_report 04:02:44 INFO - PROCESS | 21918 | __startTimestamp1471777364289__endTimestamp 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | ------- Summary: start ------- 04:02:44 INFO - PROCESS | 21918 | Number of tests: 6 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | [#0] dojo.html Cycles:165 Average:5729.98 Median:5935.85 stddev:663.60 (11.2%) stddev-sans-first:637.71 04:02:44 INFO - PROCESS | 21918 | Values: 3301.6 3314.6 3316.4 3337.5 3355.4 5634.9 5712.1 5715.9 5732.6 5737.9 5742.9 5810.1 5813.5 5820.9 5825.9 5098.4 5193.3 5243.3 5270.5 5292.2 5146.4 5249.1 5287.6 5295.3 5307.0 5097.3 5193.0 5213.2 5233.6 5262.7 5319.4 5331.8 5332.9 5336.3 5360.0 5100.4 5114.8 5157.8 5225.8 5236.3 5667.8 5698.7 5722.6 5763.9 5775.5 6239.3 6252.9 6256.2 6266.7 6269.9 6111.8 6117.6 6132.9 6250.3 6300.1 5832.2 5842.1 5847.3 5849.7 5851.4 6118.7 6298.7 6352.3 6363.7 6369.0 6012.0 6060.9 6084.6 6172.8 6179.0 6246.0 6248.5 6248.5 6250.5 6254.0 6004.3 6199.1 6213.0 6227.1 6229.2 6094.4 6128.2 6130.1 6152.7 6166.7 4623.3 4660.6 4666.5 4687.0 4693.5 4086.8 4165.5 4177.9 4184.5 4189.7 5701.9 5714.8 5746.6 5853.3 5880.7 6166.3 6167.2 6168.8 6169.2 6175.1 5727.0 5844.0 5862.3 5864.4 5865.3 5250.3 5296.5 5320.4 5370.7 5375.4 6380.4 6395.0 6427.1 6438.7 6442.1 6172.3 6213.1 6223.3 6223.5 6231.4 6120.8 6233.1 6326.4 6333.3 6437.8 6117.8 6122.6 6122.9 6126.4 6131.8 6223.5 6232.9 6237.0 6237.8 6239.4 5825.3 5835.5 5894.4 5978.4 5980.0 5915.5 5935.9 5954.5 6018.4 6109.9 6011.6 6015.7 6021.0 6021.2 6025.1 6066.3 6095.4 6098.3 6106.3 6136.5 5704.2 5863.6 5905.0 5937.4 6022.2 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | [#1] ext.html Cycles:180 Average:62644.33 Median:205625.00 stddev:122221.26 (59.4%) stddev-sans-first:122475.36 04:02:44 INFO - PROCESS | 21918 | Values: 603.8 638.5 661.0 664.5 671.3 35465.3 46804.3 48169.8 48392.5 51355.0 49106.8 49547.5 49596.8 49603.0 49694.3 8645.4 9413.9 9585.2 9588.2 11181.7 11327.9 11433.3 11440.1 11445.1 11447.5 11538.5 11574.5 11580.3 11591.6 11598.5 11388.5 11389.9 11423.8 11483.7 11513.3 11351.0 11429.6 11431.6 11440.4 11444.5 45439.8 45753.5 45756.8 45757.8 45857.3 3948.7 4169.4 4305.2 4339.9 4632.2 35698.1 35942.8 37030.6 37099.0 37516.0 4774.8 4815.8 4875.0 4903.5 4959.2 369873.0 369875.0 369910.0 370392.0 373734.0 62250.7 63133.0 63141.4 63161.4 63222.0 39939.8 40796.8 40910.4 42759.8 42791.6 17207.2 17366.7 17387.4 17404.8 17437.7 12463.4 12599.5 12685.2 12784.7 12810.6 21251.9 21280.6 21299.1 21329.6 21412.9 7735.1 7748.1 7871.7 7910.1 7948.1 1051.7 1063.0 1099.6 1135.6 1183.8 34757.5 35114.1 35498.3 35756.8 35812.9 1132.9 1143.6 1144.1 1159.7 1181.1 16369.8 18427.3 19207.1 19441.0 19590.9 605754.0 608520.0 609610.0 611479.8 611798.0 64244.4 64635.4 64806.0 64813.4 64841.7 1316.2 1387.8 1413.3 1414.8 1422.6 32921.2 36487.3 36584.5 36866.4 36950.0 53517.5 54015.5 54018.7 54042.7 54141.0 357734.0 358303.0 358999.0 359031.0 360099.4 30382.7 31251.5 31441.5 32535.8 32604.0 54136.5 54139.7 54246.7 54729.2 54781.0 44550.8 45468.5 46132.8 47064.3 48233.5 46733.0 47248.8 47300.3 47606.5 47728.4 45398.8 46192.5 46346.8 46554.8 46643.3 38485.4 40289.8 41071.4 41166.6 41224.2 30580.7 30699.4 30743.2 30857.7 30906.7 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | [#2] jquery.html Cycles:180 Average:30475.06 Median:22036.50 stddev:84205.13 (382.1%) stddev-sans-first:84411.07 04:02:44 INFO - PROCESS | 21918 | Values: 398.3 413.4 429.3 432.7 442.9 15671.5 20222.0 20743.3 21633.7 22371.2 20836.5 21312.8 21354.3 21451.2 21701.8 5603.3 5829.9 6052.8 6117.5 6118.4 5789.8 5965.6 5993.5 6005.0 6007.9 5887.6 5891.5 5910.5 5911.8 5915.2 5749.0 5906.9 5917.1 5920.2 5989.3 5915.0 5920.0 5924.6 5931.6 5937.4 14415.9 25060.5 25171.7 25241.2 25868.9 2400.7 2467.1 2508.5 2568.3 2766.8 9924.5 10217.5 10390.8 10466.7 10664.4 1968.2 2039.7 2052.3 2070.7 2076.9 88122.6 91069.0 91721.5 92218.5 92827.1 9909.4 10363.6 10510.7 10513.5 10612.6 14099.9 14148.4 14163.4 14165.6 14192.4 7811.8 7834.6 7946.7 7951.4 8053.5 7641.4 7685.3 7741.0 7844.8 7911.3 4343.1 4348.5 4352.8 4421.8 4443.2 1929.6 1952.2 2029.1 2035.8 2044.4 1010.3 1017.2 1031.2 1086.6 1148.9 20050.7 20596.8 20865.6 20885.8 20928.3 1046.9 1052.3 1054.4 1105.4 1149.8 11035.2 11389.8 11517.2 11546.2 11576.2 489339.0 495559.0 516041.0 520243.8 520443.0 92289.5 96352.5 101360.5 104686.5 105539.0 1011.4 1036.9 1048.7 1058.0 1061.8 17684.3 18042.7 18246.5 18447.9 18754.3 45684.3 46116.3 46251.5 46762.0 47243.1 64809.7 65077.7 65607.3 65671.7 66395.0 8144.6 8151.7 8302.5 8305.3 8443.8 12241.4 12544.7 12731.4 12896.0 13214.3 10349.5 10736.5 11684.1 11906.4 12141.9 10973.2 11107.1 11376.8 11727.9 12060.1 11319.6 11400.5 12329.3 12386.0 12416.9 8287.6 8417.7 8436.9 8455.9 8586.4 11205.7 11517.9 11737.2 11803.4 12382.3 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | [#3] mootools.html Cycles:180 Average:6493.45 Median:4837.60 stddev:3759.43 (77.7%) stddev-sans-first:3750.80 04:02:44 INFO - PROCESS | 21918 | Values: 1442.1 1527.8 1583.4 1772.4 1898.7 9225.3 9499.6 9633.8 9841.3 9853.3 9640.5 9678.3 9754.9 9756.8 9766.5 2487.2 2522.7 2524.2 2526.9 2528.8 2423.5 2517.1 2522.9 2525.5 2526.3 2384.9 2440.4 2499.5 2500.2 2504.4 2441.8 2444.7 2469.0 2480.0 2485.7 2409.9 2427.7 2432.9 2435.1 2468.1 9516.9 9606.0 9623.0 9681.4 9683.9 5582.1 5802.0 5807.9 5809.9 5810.1 3700.9 3738.4 3759.2 3798.6 3800.3 1241.1 1241.9 1258.7 1270.1 1290.1 14192.5 14328.2 14354.0 14365.9 14385.1 4783.0 4864.9 4882.9 4995.9 5041.2 10824.7 10856.9 10859.2 10863.7 10872.0 5726.4 5731.1 5742.5 5744.0 5761.4 4662.1 5142.2 5210.5 5279.8 5373.5 4265.5 4315.1 4328.3 4366.1 4469.4 1286.5 1304.1 1311.3 1313.1 1320.2 835.4 928.3 1084.5 1096.2 1107.3 6597.5 6715.8 6821.2 6863.4 7011.4 490.3 661.8 1104.5 1137.6 1159.1 4664.4 4741.0 4810.3 4869.2 4957.7 11859.3 11900.6 12295.6 12305.9 12320.9 12373.8 12438.9 12473.9 12493.6 12516.3 9105.4 9295.7 9298.7 9302.7 9325.7 6661.1 6887.1 6946.0 6967.7 7218.6 11134.9 11459.4 11646.3 11759.2 11761.0 11416.7 11441.0 11737.5 11745.6 11814.9 8100.7 8490.6 8768.0 8777.9 8886.0 9890.3 9960.6 9965.9 9973.0 9976.7 8430.6 8453.5 8470.6 8753.5 8906.5 8181.4 8481.5 8499.4 8560.1 8662.8 7942.8 8015.3 8085.0 8102.0 8111.8 5853.9 5961.9 6025.2 6138.5 6201.4 5835.2 6082.0 6085.1 6161.8 6519.2 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | [#4] prototype.html Cycles:180 Average:4823.00 Median:5326.95 stddev:1404.37 (26.4%) stddev-sans-first:1367.57 04:02:44 INFO - PROCESS | 21918 | Values: 349.1 350.6 354.5 360.9 362.6 5552.9 5621.1 5621.9 5631.8 5643.6 5296.3 5373.6 5400.3 5422.5 5600.2 4226.8 4239.9 4329.4 4390.1 4393.5 4337.3 4357.2 4357.8 4381.3 4404.6 4250.2 4318.3 4330.9 4341.2 4372.5 4351.7 4357.5 4359.0 4361.8 4388.0 3888.8 3921.4 3923.9 3931.8 3948.3 5134.4 5438.4 5465.6 5487.8 5493.9 4975.4 4978.2 4978.2 4979.1 4995.4 5750.0 5806.2 5809.2 5858.3 5881.6 4113.2 4119.7 4131.9 4154.6 4162.4 5981.6 5998.7 6017.5 6024.3 6030.7 4378.2 4389.4 4390.0 4390.4 4395.9 4192.3 4394.2 4395.4 4411.5 4439.5 4955.7 4961.1 4962.2 4962.3 4964.4 5822.9 5830.8 5831.0 5836.6 5850.7 1403.8 1454.4 1489.4 1513.9 1529.0 1004.0 1007.7 1018.7 1024.0 1033.7 5616.7 5629.4 5643.5 5659.6 5679.7 5791.5 5829.4 5847.6 5887.2 5891.2 5400.0 5538.2 5560.4 5565.7 5571.2 2729.3 2735.2 2741.6 2750.0 2774.3 5307.0 5309.0 5320.4 5350.3 5418.8 4939.6 5186.6 5231.7 5244.0 5279.6 5325.5 5328.4 5341.7 5351.9 5364.9 5213.5 5232.8 5238.4 5252.7 5261.9 5566.0 5956.2 5981.5 5996.0 6010.5 6232.2 6242.8 6256.9 6265.8 6335.7 4241.3 4320.0 4333.8 4347.7 4352.0 6039.2 6041.6 6046.9 6048.6 6060.9 5650.9 5742.9 5863.1 5875.4 5907.5 5863.5 5866.6 5884.4 5888.5 5895.8 5615.1 5779.6 5806.2 5852.4 5859.9 5877.3 5877.4 5892.8 5934.1 5941.7 5609.2 5740.3 5813.2 5820.0 5861.7 04:02:44 INFO - PROCESS | 21918 | 04:02:44 INFO - PROCESS | 21918 | [#5] yui.html Cycles:180 Average:22951.89 Median:13028.92 stddev:33564.86 (257.6%) stddev-sans-first:33617.97 04:02:44 INFO - PROCESS | 21918 | Values: 843.3 860.6 879.7 903.7 909.0 17176.6 18282.8 18324.5 18349.5 18350.4 16445.0 16565.0 16613.6 16632.3 16669.4 742.4 753.9 876.2 892.2 903.1 880.9 905.3 905.4 906.0 906.1 957.1 957.1 958.3 958.6 958.8 776.6 923.1 955.6 956.9 959.4 678.6 687.5 687.5 687.6 688.0 16291.5 16311.4 16328.0 16348.2 16353.0 15478.2 15682.3 15783.8 16073.7 16180.6 12723.4 12772.6 12897.2 13391.3 13647.9 4729.5 4782.1 4851.6 5028.8 5031.9 84084.2 85026.6 85749.1 87557.0 87630.6 12378.7 12526.9 12545.4 12575.6 12731.9 22123.0 22149.2 22160.3 22163.9 22205.6 9079.2 9095.2 9453.1 9514.2 9581.0 4857.4 5074.4 5106.5 5224.9 5342.9 3852.3 3998.8 4008.8 4035.9 4036.0 669.0 679.9 687.1 687.6 736.1 746.6 828.6 845.1 853.4 859.6 10544.3 10553.9 11132.0 11489.9 11604.9 764.6 779.7 787.5 799.9 819.1 5124.7 5166.5 5176.7 5227.8 5261.7 127155.7 131504.0 139965.0 142473.0 144403.3 69853.0 72269.6 86750.6 111978.0 121191.0 105201.5 105919.0 105950.5 107192.5 107422.0 9951.6 10044.1 10073.4 10211.7 10254.2 39295.2 39874.0 53111.0 62643.0 63205.4 56951.4 79418.6 80642.2 84199.0 85653.1 14102.8 14290.4 14362.7 14493.4 14566.4 16499.9 16651.2 17012.1 17151.2 18084.3 15108.0 15336.2 15503.4 15519.2 16511.3 15507.6 15527.6 16597.4 16698.3 16783.2 14858.2 14923.4 15867.2 15951.4 16004.8 12290.2 12587.2 12710.7 12850.9 12990.0 11159.3 11682.1 11881.7 12790.6 13079.0 04:02:44 INFO - PROCESS | 21918 | -------- Summary: end -------- 04:02:44 INFO - PROCESS | 21918 | 04:02:45 INFO - TEST-INFO | 21918: exit 0 04:02:45 INFO - TEST-OK | dromaeo_css | took 1113218ms 04:02:45 INFO - TEST-START | kraken 04:02:45 INFO - operating with platform_type : linux_ 04:02:45 INFO - Initialising browser for kraken test... 04:02:45 INFO - TEST-INFO | started process 22122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7J0wWV/profile http://localhost:36273/getInfo.html) 04:02:48 INFO - PROCESS | 22122 | __metrics Screen width/height:1600/1200 04:02:48 INFO - PROCESS | 22122 | colorDepth:24 04:02:48 INFO - PROCESS | 22122 | Browser inner width/height: 1024/697 04:02:48 INFO - PROCESS | 22122 | __metrics 04:02:49 INFO - PROCESS | 22122 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:02:49 INFO - PROCESS | 22122 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:02:50 INFO - TEST-INFO | 22122: exit 0 04:02:50 INFO - Browser initialized. 04:02:50 INFO - Running cycle 1/1 for kraken test... 04:02:50 INFO - TEST-INFO | started process 22210 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7J0wWV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:03:02 INFO - PROCESS | 22210 | RSS: Main: 145735680 04:03:02 INFO - PROCESS | 22210 | 04:03:04 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36273/tests/kraken/driver.html?testName=audio-beat-detection) 04:03:05 INFO - PROCESS | 22210 | RSS: Main: 191057920 04:03:05 INFO - PROCESS | 22210 | 04:03:08 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36273/tests/kraken/driver.html?testName=audio-dft) 04:03:08 INFO - PROCESS | 22210 | RSS: Main: 181854208 04:03:08 INFO - PROCESS | 22210 | 04:03:11 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36273/tests/kraken/driver.html?testName=audio-fft) 04:03:12 INFO - PROCESS | 22210 | RSS: Main: 269414400 04:03:12 INFO - PROCESS | 22210 | 04:03:14 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36273/tests/kraken/driver.html?testName=audio-oscillator) 04:03:14 INFO - PROCESS | 22210 | RSS: Main: 175304704 04:03:14 INFO - PROCESS | 22210 | 04:03:17 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:03:17 INFO - PROCESS | 22210 | RSS: Main: 177106944 04:03:17 INFO - PROCESS | 22210 | 04:03:20 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36273/tests/kraken/driver.html?testName=imaging-darkroom) 04:03:21 INFO - PROCESS | 22210 | RSS: Main: 212934656 04:03:21 INFO - PROCESS | 22210 | 04:03:24 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36273/tests/kraken/driver.html?testName=imaging-desaturate) 04:03:25 INFO - PROCESS | 22210 | RSS: Main: 211591168 04:03:25 INFO - PROCESS | 22210 | 04:03:28 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36273/tests/kraken/driver.html?testName=json-parse-financial) 04:03:28 INFO - PROCESS | 22210 | RSS: Main: 210755584 04:03:28 INFO - PROCESS | 22210 | 04:03:30 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:03:30 INFO - PROCESS | 22210 | RSS: Main: 175730688 04:03:30 INFO - PROCESS | 22210 | 04:03:32 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:33 INFO - PROCESS | 22210 | RSS: Main: 182341632 04:03:33 INFO - PROCESS | 22210 | 04:03:35 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:03:35 INFO - PROCESS | 22210 | RSS: Main: 185675776 04:03:35 INFO - PROCESS | 22210 | 04:03:38 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:03:38 INFO - PROCESS | 22210 | RSS: Main: 181030912 04:03:38 INFO - PROCESS | 22210 | 04:03:41 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:41 INFO - PROCESS | 22210 | RSS: Main: 184418304 04:03:41 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | Cycle 1(1): loaded http://localhost:36273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36273/tests/kraken/driver.html?testName=ai-astar) 04:03:43 INFO - PROCESS | 22210 | RSS: Main: 235696128 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | __start_tp_report 04:03:43 INFO - PROCESS | 22210 | _x_x_mozilla_page_load 04:03:43 INFO - PROCESS | 22210 | _x_x_mozilla_page_load_details 04:03:43 INFO - PROCESS | 22210 | |i|pagename|runs| 04:03:43 INFO - PROCESS | 22210 | |0;ai-astar;101;111;109;111;110;110;109;109;111;110 04:03:43 INFO - PROCESS | 22210 | |1;audio-beat-detection;150;115;116;181;116;117;146;119;148;116 04:03:43 INFO - PROCESS | 22210 | |2;audio-dft;162;161;161;159;159;161;158;165;160;160 04:03:43 INFO - PROCESS | 22210 | |3;audio-fft;76;75;74;111;75;75;75;75;107;74 04:03:43 INFO - PROCESS | 22210 | |4;audio-oscillator;87;83;118;83;83;83;114;82;83;128 04:03:43 INFO - PROCESS | 22210 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 04:03:43 INFO - PROCESS | 22210 | |6;imaging-darkroom;145;146;146;146;148;147;146;147;146;146 04:03:43 INFO - PROCESS | 22210 | |7;imaging-desaturate;95;94;94;94;94;95;95;94;95;94 04:03:43 INFO - PROCESS | 22210 | |8;json-parse-financial;52;50;50;51;50;51;51;51;51;51 04:03:43 INFO - PROCESS | 22210 | |9;json-stringify-tinderbox;60;59;58;58;59;58;59;58;58;59 04:03:43 INFO - PROCESS | 22210 | |10;stanford-crypto-aes;73;78;72;72;81;72;72;72;73;72 04:03:43 INFO - PROCESS | 22210 | |11;stanford-crypto-ccm;119;115;121;120;124;121;124;117;150;116 04:03:43 INFO - PROCESS | 22210 | |12;stanford-crypto-pbkdf2;152;153;156;150;150;151;150;149;151;150 04:03:43 INFO - PROCESS | 22210 | |13;stanford-crypto-sha256-iterative;61;60;62;60;62;61;61;61;64;60 04:03:43 INFO - PROCESS | 22210 | __end_tp_report 04:03:43 INFO - PROCESS | 22210 | __start_cc_report 04:03:43 INFO - PROCESS | 22210 | _x_x_mozilla_cycle_collect,1615 04:03:43 INFO - PROCESS | 22210 | __end_cc_report 04:03:43 INFO - PROCESS | 22210 | __startTimestamp1471777423860__endTimestamp 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | ------- Summary: start ------- 04:03:43 INFO - PROCESS | 22210 | Number of tests: 14 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:2.96 (2.7%) stddev-sans-first:0.87 04:03:43 INFO - PROCESS | 22210 | Values: 101.0 111.0 109.0 111.0 110.0 110.0 109.0 109.0 111.0 110.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:132.50 stddev:22.67 (17.1%) stddev-sans-first:23.14 04:03:43 INFO - PROCESS | 22210 | Values: 150.0 115.0 116.0 181.0 116.0 117.0 146.0 119.0 148.0 116.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.96 (1.2%) stddev-sans-first:2.01 04:03:43 INFO - PROCESS | 22210 | Values: 162.0 161.0 161.0 159.0 159.0 161.0 158.0 165.0 160.0 160.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:14.43 (19.2%) stddev-sans-first:15.16 04:03:43 INFO - PROCESS | 22210 | Values: 76.0 75.0 74.0 111.0 75.0 75.0 75.0 75.0 107.0 74.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:83.00 stddev:18.04 (21.7%) stddev-sans-first:18.93 04:03:43 INFO - PROCESS | 22210 | Values: 87.0 83.0 118.0 83.0 83.0 83.0 114.0 82.0 83.0 128.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 04:03:43 INFO - PROCESS | 22210 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 04:03:43 INFO - PROCESS | 22210 | Values: 145.0 146.0 146.0 146.0 148.0 147.0 146.0 147.0 146.0 146.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:03:43 INFO - PROCESS | 22210 | Values: 95.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 95.0 94.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.63 (1.2%) stddev-sans-first:0.50 04:03:43 INFO - PROCESS | 22210 | Values: 52.0 50.0 50.0 51.0 50.0 51.0 51.0 51.0 51.0 51.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.53 04:03:43 INFO - PROCESS | 22210 | Values: 60.0 59.0 58.0 58.0 59.0 58.0 59.0 58.0 58.0 59.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:72.50 stddev:3.16 (4.4%) stddev-sans-first:3.35 04:03:43 INFO - PROCESS | 22210 | Values: 73.0 78.0 72.0 72.0 81.0 72.0 72.0 72.0 73.0 72.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#11] stanford-crypto-ccm Cycles:10 Average:122.70 Median:121.00 stddev:10.07 (8.3%) stddev-sans-first:10.59 04:03:43 INFO - PROCESS | 22210 | Values: 119.0 115.0 121.0 120.0 124.0 121.0 124.0 117.0 150.0 116.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:151.00 stddev:2.04 (1.4%) stddev-sans-first:2.15 04:03:43 INFO - PROCESS | 22210 | Values: 152.0 153.0 156.0 150.0 150.0 151.0 150.0 149.0 151.0 150.0 04:03:43 INFO - PROCESS | 22210 | 04:03:43 INFO - PROCESS | 22210 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 04:03:43 INFO - PROCESS | 22210 | Values: 61.0 60.0 62.0 60.0 62.0 61.0 61.0 61.0 64.0 60.0 04:03:43 INFO - PROCESS | 22210 | -------- Summary: end -------- 04:03:43 INFO - PROCESS | 22210 | 04:03:44 INFO - TEST-INFO | 22210: exit 0 04:03:44 INFO - TEST-OK | kraken | took 59833ms 04:03:44 INFO - SUITE-END | took 1173s 04:03:45 INFO - Completed test suite (00:19:33) 04:03:45 INFO - javascript benchmark 04:03:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.636819949805, 3314.6353901070884, 3316.4196265653504, 3337.5160601712737, 3355.4295769719147, 5634.943650563493, 5712.057510510771, 5715.9428405715935, 5732.5987180897355, 5737.942620573793, 5742.913856292149, 5810.099434587635, 5813.476787089161, 5820.854478638036, 5825.8834823303505, 5098.388193416777, 5193.272941788153, 5243.292155558989, 5270.525652691257, 5292.206169074631, 5146.433892271847, 5249.081410753122, 5287.550558202557, 5295.338082739658, 5307.0, 5097.260897169908, 5192.974035129819, 5213.218017297417, 5233.555147812422, 5262.684238945656, 5319.361676598814, 5331.786728530854, 5332.946670533283, 5336.279602253685, 5360.03519366512, 5100.438951715309, 5114.846554603368, 5157.767900444452, 5225.817096401645, 5236.293100431431, 5667.773289068432, 5698.6580805151825, 5722.599418040739, 5763.9135412968835, 5775.48020678141, 6239.344868788802, 6252.906206406908, 6256.249250090018, 6266.686665666744, 6269.905951410732, 6111.774065234695, 6117.5717699761035, 6132.933533233383, 6250.34371391002, 6300.086487459293, 5832.212651292064, 5842.094475356326, 5847.3275573308765, 5849.678267695275, 5851.444112809277, 6118.7410088057595, 6298.653574053471, 6352.269489008823, 6363.745450181941, 6369.0, 6012.0, 6060.9078415360445, 6084.574079814371, 6172.753089876354, 6179.0, 6245.96877015612, 6248.4688801451475, 6248.50011999039, 6250.531210159256, 6254.030625253093, 6004.3395226525045, 6199.1011303361665, 6213.03697926822, 6227.06594010902, 6229.159063526366, 6094.421030002142, 6128.233970753656, 6130.141780150782, 6152.661603611755, 6166.691665416712, 4623.260278355447, 4660.557247061523, 4666.510016448292, 4687.039156972813, 4693.530646935279, 4086.7956602169775, 4165.541790403053, 4177.874663760092, 4184.497860256789, 4189.685773566995, 5701.914471282934, 5714.828555143353, 5746.569007324468, 5853.268341457318, 5880.7353669085, 6166.260048794174, 6167.167432396569, 6168.814935551941, 6169.2288463942, 6175.104609831549, 5726.99777538929, 5844.035734103825, 5862.325832529228, 5864.44287792659, 5865.266841644794, 5250.264962905197, 5296.523312901857, 5320.441353657888, 5370.650907690988, 5375.381831089397, 6380.3619638035825, 6394.968025159845, 6427.100205971168, 6438.678066096676, 6442.098106265033, 6172.321044685171, 6213.068039794067, 6223.315435302023, 6223.471004964629, 6231.376862313732, 6120.846978825565, 6233.096201050913, 6326.398992095744, 6333.303336632875, 6437.839054023687, 6117.785877494266, 6122.571420000557, 6122.877542449215, 6126.387361263838, 6131.754729810757, 6223.533235007302, 6232.937670623417, 6237.033259844731, 6237.781677641261, 6239.438450539473, 5825.300963884361, 5835.474807267196, 5894.38108998549, 5978.4021597839865, 5979.970100149471, 5915.497182739516, 5935.851603709942, 5954.46409823118, 6018.398160183947, 6109.877802443837, 6011.60924539921, 6015.699215039143, 6020.969895150496, 6021.217241758543, 6025.126356678169, 6066.332703402534, 6095.445661356326, 6098.32918378969, 6106.255006044545, 6136.478399336106, 5704.229928959703, 5863.560232982458, 5904.9704751475965, 5937.406259374028, 6022.247219097613], "value": 5684.244713371286, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.827909045922, 638.5498223752255, 660.9985871404825, 664.5447868210276, 671.2783757460725, 35465.29069418614, 46804.297935530994, 48169.759151204285, 48392.516074839245, 51355.0, 49106.75446622762, 49547.50452495474, 49596.752016239865, 49603.0, 49694.25458618124, 8645.351598630097, 9413.905860941388, 9585.185259252938, 9588.185004274645, 11181.72045698856, 11327.943360283187, 11433.256838305484, 11440.084793216523, 11445.08439324852, 11447.542098316098, 11538.538458461651, 11574.479148438299, 11580.305181689126, 11591.594294199705, 11598.536058557647, 11388.487518061707, 11389.886101138965, 11423.828642570368, 11483.65549033526, 11513.251638643462, 11351.0, 11429.59996400122, 11431.599894003753, 11440.370779607118, 11444.542218311257, 45439.77280113611, 45753.542464575265, 45756.771216144036, 45757.77121114373, 45857.312140317925, 3948.6841052715713, 4169.395437661552, 4305.203537345601, 4339.934900976488, 4632.235681112612, 35698.097113186246, 35942.8202858984, 37030.62969370299, 37099.0, 37516.0, 4774.785134668949, 4815.759212039384, 4874.975625121887, 4903.509649035103, 4959.156943319645, 369873.0, 369875.0, 369910.0, 370392.0, 373734.0, 62250.68874655598, 63133.0, 63141.36858631355, 63161.368386315546, 63222.0, 39939.80030099831, 40796.79601602032, 40910.38634420486, 42759.786201069415, 42791.572084278756, 17207.225674844663, 17366.739498907275, 17387.391441299493, 17404.825951740575, 17437.7384339235, 12463.376831158406, 12599.496020159275, 12685.175463595022, 12784.6803829905, 12810.615681529569, 21251.89374053151, 21280.574388512145, 21299.148034078462, 21329.573408532055, 21412.929353532574, 7735.110462296908, 7748.147703752583, 7871.685132594632, 7910.129885712567, 7948.125706172316, 1051.7107795356185, 1062.984055239172, 1099.6426161497386, 1135.6309199510026, 1183.828344889986, 34757.47863782045, 35114.12214694602, 35498.29003419969, 35756.82121589375, 35812.925612231156, 1132.8867113288604, 1143.5597295041302, 1144.0504381363448, 1159.657900919214, 1181.0964612071773, 16369.754453682966, 18427.26290948374, 19207.135678894203, 19441.0, 19590.902045489966, 605754.0, 608520.0, 609610.0, 611479.8278025833, 611798.0, 64244.35755642477, 64635.353646462936, 64806.0, 64813.35186648167, 64841.67579162074, 1316.1708123881892, 1387.7848933415337, 1413.3074793351184, 1414.8302203735618, 1422.615893708693, 32921.17697057497, 36487.27025459529, 36584.45123323205, 36866.44700329444, 36950.0, 53517.46482535281, 54015.45984540104, 54018.729906350214, 54042.729786350814, 54141.0, 357734.0, 358303.0, 358999.0, 359031.0, 360099.3990060066, 30382.696173038872, 31251.53122703116, 31441.52837707482, 32535.837320813243, 32604.0, 54136.45863541472, 54139.72930135324, 54246.72876635592, 54729.17906231489, 54781.0, 44550.777246114856, 45468.54531454775, 46132.769336153106, 47064.294035588806, 48233.5176648229, 46733.0, 47248.763756181, 47300.29049564191, 47606.52393476021, 47728.400452533424, 45398.77300613476, 46192.538074618824, 46346.768266158455, 46554.767226163654, 46643.30035049545, 38485.42271865868, 40289.79855100823, 41071.38392924049, 41166.58833411747, 41224.175516490104, 30580.694193057785, 30699.38601227918, 30743.2314192147, 30857.69142308548, 30906.690933091282], "value": 22004.134195414634, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.2930298719774, 413.36961134270234, 429.2895258347435, 432.67981693546784, 442.89813342931126, 15671.529854104367, 20222.0, 20743.273985410495, 21633.675494867588, 22371.217007404743, 20836.479088022807, 21312.786872131273, 21354.25260115896, 21451.249206277782, 21701.782982170174, 5603.271574695284, 5829.941700583004, 6052.818415447544, 6117.541184411165, 6118.357572454895, 5789.797357092502, 5965.642061476326, 5993.490553302974, 6005.0, 6007.909881351783, 5887.646741195521, 5891.469767720904, 5910.46805787479, 5911.822645320647, 5915.1718759373725, 5748.971255143698, 5906.88186236275, 5917.142014407908, 5920.171176035358, 5989.341172471025, 5915.0, 5920.0, 5924.6149000314845, 5931.64410135389, 5937.406259374071, 14415.927920360331, 25060.49879002428, 25171.748282517307, 25241.24276271715, 25868.870655646788, 2400.7239167495613, 2467.124170919316, 2508.4732206236713, 2568.3065572295563, 2766.7786577073784, 9924.454155021544, 10217.489125543692, 10390.792184156275, 10466.738331541696, 10664.360138391643, 1968.1930408532576, 2039.7450318710162, 2052.2611859730487, 2070.7411573553304, 2076.9480762980897, 88122.55938720329, 91069.0, 91721.54139229328, 92218.5389073057, 92827.07172928251, 9909.405435673883, 10363.585456581652, 10510.684679459628, 10513.52689128991, 10612.57549698019, 14099.85900141006, 14148.363323650461, 14163.433462661378, 14165.645858853612, 14192.361343739556, 7811.765647030598, 7834.647440865175, 7946.721864734706, 7951.363890888715, 8053.476524025919, 7641.350485208708, 7685.269899359552, 7741.0, 7844.843103137905, 7911.327537159406, 4343.087951530151, 4348.478182618106, 4352.804123814436, 4421.7789110544345, 4443.244648409778, 1929.6333696597883, 1952.2288695965299, 2029.0869108900758, 2035.8269547088664, 2044.4275602830933, 1010.2675560218782, 1017.2218253036433, 1031.200819364998, 1086.6445643030365, 1148.8851114888444, 20050.699239511418, 20596.794032059788, 20865.58268834615, 20885.791142088383, 20928.26751063736, 1046.942418167008, 1052.315994603514, 1054.393723608928, 1105.4307031878589, 1149.7930372532858, 11035.227534072537, 11389.829152562717, 11517.193796434336, 11546.19176657626, 11576.189666723418, 489339.0, 495559.0, 516041.0, 520243.7914673044, 520443.0, 92289.53855230681, 96352.51823740976, 101360.49319753502, 104686.47656761667, 105539.0, 1011.3628414099071, 1036.9325993810528, 1048.6749107776614, 1057.957681692739, 1061.7982583309147, 17684.292628294723, 18042.729359059886, 18246.543836404195, 18447.907760461112, 18754.343597974006, 45684.314735278334, 46116.30825537552, 46251.53748462472, 46762.0, 47243.05513889634, 64809.67595161994, 65077.67461162664, 65607.34392656204, 65671.67164164149, 66395.0, 8144.59277036134, 8151.714689986059, 8302.54336011525, 8305.294050005576, 8443.831123377375, 12241.387930603612, 12544.686382840502, 12731.427085780977, 12895.971695425827, 13214.273214972884, 10349.482525873827, 10736.51685674131, 11684.065274778168, 11906.40467976615, 12141.903814527592, 10973.23187376876, 11107.111431085656, 11376.829347559627, 11727.941360293144, 12060.095492837896, 11319.603813866475, 11400.48697808584, 12329.321887296284, 12386.0, 12416.937915310365, 8287.58562071906, 8417.70538031166, 8436.915630843858, 8455.915440845512, 8586.356023298402, 11205.71985700364, 11517.94241028823, 11737.237079589799, 11803.350815704875, 12382.318972456602], "value": 9493.624019964998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.0770706747676, 1527.8166620005602, 1583.4378795527587, 1772.3885259585447, 1898.7341772151901, 9225.261979041677, 9499.620015199393, 9633.807323853518, 9841.311108222428, 9853.310268281222, 9640.469774162435, 9678.274129440286, 9754.85367719483, 9756.756081097976, 9766.462844543546, 2487.1543675150447, 2522.7351128131468, 2524.192258477288, 2526.8502831211845, 2528.7724104830563, 2423.503181847717, 2517.1064272183385, 2522.8612426316545, 2525.4949010198034, 2526.2800101970943, 2384.904603815854, 2440.389902524369, 2499.462615537659, 2500.174942269047, 2504.4365017871014, 2441.7802397784285, 2444.7433019532873, 2469.0494159748628, 2479.9752002479927, 2485.72657007729, 2409.9156529521556, 2427.6965379327585, 2432.8540287582623, 2435.0625009371342, 2468.0744720729726, 9516.904830951671, 9606.0, 9622.951885240598, 9681.370710903839, 9683.854742178873, 5582.078956972095, 5802.0, 5807.854803629901, 5809.883802323972, 5810.070388737781, 3700.888973330804, 3738.401855703102, 3759.2293579816073, 3798.5631652360057, 3800.277947190025, 1241.0567968344033, 1241.8944389726885, 1258.7482503499336, 1270.1299609767339, 1290.084040331366, 14192.50326238587, 14328.211948342732, 14354.0, 14365.92817035908, 14385.136891786526, 4783.0, 4864.902701946012, 4882.877928051827, 4995.925061124086, 5041.193409054534, 10824.72938176536, 10856.891431085745, 10859.2398532102, 10863.728406789893, 10872.0, 5726.4273572642405, 5731.140328950691, 5742.540596752333, 5743.971280143573, 5761.366249712612, 4662.137504561667, 5142.202958541432, 5210.452902445189, 5279.788808447696, 5373.516383525502, 4265.50946641134, 4315.050688848449, 4328.285832837547, 4366.126774645084, 4469.374287599739, 1286.5497076023505, 1304.1327517201146, 1311.3050083455785, 1313.0874042540343, 1320.1551007355301, 835.4026870787295, 928.2666693312343, 1084.4615285032166, 1096.221682605359, 1107.2636696596835, 6597.538172327891, 6715.7985260442265, 6821.2155602106395, 6863.416609588141, 7011.4390848733, 490.2841850897658, 661.8146918862726, 1104.4864138175626, 1137.6018393562188, 1159.1248607301434, 4664.416947881515, 4741.028089241766, 4810.302506136591, 4869.220924652039, 4957.727324997088, 11859.34773587461, 11900.583479578174, 12295.631131066082, 12305.876941230474, 12320.876791232153, 12373.75252494945, 12438.875611243771, 12473.93763031179, 12493.562725304753, 12516.311602861748, 9105.362624616346, 9295.72112836616, 9298.67454639098, 9302.720918372459, 9325.673601423918, 6661.100751398693, 6887.139107611549, 6945.965270173617, 6967.65161741921, 7218.639068046472, 11134.944325278646, 11459.4270286487, 11646.301221926711, 11759.176857619885, 11761.0, 11416.657500275005, 11441.0, 11737.530498780296, 11745.647630571097, 11814.94092529532, 8100.71647492335, 8490.617922193393, 8767.956160219159, 8777.868331975153, 8885.955570222366, 9890.258230632588, 9960.601575936882, 9965.850512242467, 9972.950135249277, 9976.650817221362, 8430.620622072143, 8453.450525715809, 8470.618822153141, 8753.518556479456, 8906.510141942255, 8181.427300089174, 8481.533515656698, 8499.447535910393, 8560.14398560139, 8662.82674346522, 7942.841143177219, 8015.278624923785, 8084.959575202086, 8102.0, 8111.83776324482, 5853.946289667901, 5961.880762384815, 6025.216721826135, 6138.5396095292135, 6201.410865967726, 5835.153902684172, 6082.0, 6085.117657939485, 6161.845953851011, 6519.217693876765], "value": 5124.868559437909, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.10106475824756, 350.60731980182186, 354.5143153879186, 360.8592648866942, 362.5902729915196, 5552.944470555299, 5621.128725047619, 5621.887562248752, 5631.774729010845, 5643.6049476536655, 5296.3379577552805, 5373.623846330758, 5400.324959380077, 5422.53908417784, 5600.243967064445, 4226.788660566975, 4239.894002649935, 4329.393884856123, 4390.143921935224, 4393.450818647669, 4337.306031035035, 4357.150355680644, 4357.782110894458, 4381.277089280265, 4404.603585677289, 4250.234957707611, 4318.287482565373, 4330.935035974463, 4341.196878577473, 4372.519022907478, 4351.651867850596, 4357.542458041908, 4358.956410435902, 4361.80371883266, 4387.9561204387865, 3888.786116763577, 3921.4313924481075, 3923.9215215695813, 3931.8230679619487, 3948.269570129535, 5134.435212126663, 5438.428964958702, 5465.562754979632, 5487.780488780444, 5493.945060549383, 4975.378077740283, 4978.153713868614, 4978.153713868651, 4979.128652485837, 4995.400551933755, 5749.9712501437225, 5806.2451881255165, 5809.24479817621, 5858.326292476378, 5881.647101173943, 4113.1568028554075, 4119.702293777451, 4131.9173616527805, 4154.584541545852, 4162.396452514398, 5981.641101533878, 5998.670073145975, 6017.48851347636, 6024.337322894522, 6030.66831324277, 4378.190034843564, 4389.407429996973, 4389.968357436, 4390.429244198233, 4395.890102747457, 4192.287311157111, 4394.1651086293505, 4395.384646149541, 4411.492678341968, 4439.489458712224, 4955.727434991038, 4961.0899803575885, 4962.206047032458, 4962.255661650781, 4964.379452568429, 5822.854428639318, 5830.7959221427045, 5830.970845145747, 5836.591438599342, 5850.707464626752, 1403.8245219347582, 1454.403694485256, 1489.3819065087866, 1513.9091654500764, 1528.9541313760606, 1004.0311099788739, 1007.7430255285002, 1018.6587493189865, 1023.9555653233808, 1033.7053939627322, 5616.719164041781, 5629.437056294338, 5643.463870932255, 5659.5755318351285, 5679.716014199274, 5791.523161593766, 5829.446202610745, 5847.619904706181, 5887.20522729426, 5891.23413956183, 5399.950037471896, 5538.224648549206, 5560.414407300625, 5565.666060036411, 5571.192177134374, 2729.3040274730215, 2735.2341344423594, 2741.643586333764, 2750.0399424828183, 2774.3064233941514, 5307.037919545238, 5309.0, 5320.361556613154, 5350.30446042012, 5418.783248670088, 4939.604831613462, 5186.5850731941355, 5231.744381348449, 5244.0, 5279.577633789365, 5325.467453254643, 5328.440513746078, 5341.679499230058, 5351.919721204185, 5364.91952620711, 5213.452587478412, 5232.843014709565, 5238.3975842777045, 5252.684838909678, 5261.921071184011, 5566.0, 5956.166136740946, 5981.491573216325, 5996.0, 6010.54920880927, 6232.220972378453, 6242.781502647385, 6256.937430625818, 6265.843353916189, 6335.65153916539, 4241.257779888446, 4319.956800432081, 4333.783310834383, 4347.7173983690955, 4351.956480435155, 6039.184710064261, 6041.643758934107, 6046.939530604637, 6048.60684055535, 6060.939390606038, 5650.85872853182, 5742.913856292075, 5863.120531920075, 5875.383084776208, 5907.5273978080795, 5863.501602363676, 5866.5893387462465, 5884.382139875252, 5888.528917686489, 5895.793647222326, 5615.080158317734, 5779.566532510163, 5806.187133801187, 5852.385499522489, 5859.882802343844, 5877.324107727666, 5877.441643043904, 5892.823215303548, 5934.109883517334, 5941.6827437298625, 5609.186667933045, 5740.339860915881, 5813.244278243801, 5819.970900145641, 5861.706914654165], "value": 4380.06150679441, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.2916350265775, 860.6084231674585, 879.7096958003859, 903.6791938861708, 909.0188148060033, 17176.570585735364, 18282.81717182828, 18324.54188645283, 18349.541261468436, 18350.44948651541, 16445.0, 16565.0, 16613.584660383498, 16632.25154868031, 16669.416570420006, 742.4060751398887, 753.9321461068503, 876.1851478125341, 892.2371372476553, 903.0969030969031, 880.885484886967, 905.2622112977934, 905.3571963905634, 905.9773505662346, 906.134641417448, 957.1098878043417, 957.1290125985355, 958.2860768727307, 958.5782255807458, 958.8417911044669, 776.5728849132954, 923.1230331185334, 955.6082006377387, 956.9282303827241, 959.4387283439196, 678.6301465701205, 687.4603436302529, 687.4637782529635, 687.6149356360454, 688.0367485520312, 16291.511254662379, 16311.429099981562, 16328.0, 16348.182590870529, 16353.0, 15478.226088695521, 15682.29429675656, 15783.842161578352, 16073.678526429407, 16180.595485112848, 12723.363831808372, 12772.552960646333, 12897.161684490478, 13391.263480508567, 13647.86352136476, 4729.527047295277, 4782.0914026334885, 4851.611871050308, 5028.7988480460735, 5031.874203144914, 84084.15915840825, 85026.57486712527, 85749.14250857473, 87557.0, 87630.561847191, 12378.690532736753, 12526.937365313115, 12545.435455404346, 12575.622731318075, 12731.936340318425, 22123.0, 22149.224777133044, 22160.335189944326, 22163.889180553993, 22205.555888882456, 9079.228265597498, 9095.181433671001, 9453.054694530492, 9514.19129374011, 9580.95209523948, 4857.392825896763, 5074.3657042869645, 5106.540411362921, 5224.947750522522, 5342.91985620216, 3852.287326844487, 3998.8400463981116, 4008.819603117867, 4035.899102522402, 4036.0313524753847, 668.9596729973562, 679.8776220280298, 687.1307795638521, 687.5840116729307, 736.1203361982487, 746.5707218349471, 828.5525816059381, 845.1251014100296, 853.3812985585525, 859.6346552715071, 10544.314619549705, 10553.894461055292, 11132.0, 11489.885101148882, 11604.94197529007, 764.577885133651, 779.6647441600167, 787.5117427195175, 799.9480033797785, 819.1030821250721, 5124.666896651705, 5166.483351664879, 5176.7152806596, 5227.817026404134, 5261.684298942076, 127155.72844271624, 131504.0, 139965.0, 142473.0, 144403.2779836115, 69853.0, 72269.63865180641, 86750.56624716963, 111978.0, 121191.0, 105201.47399263107, 105919.0, 105950.4702476498, 107192.46403767931, 107422.0, 9951.601935922483, 10044.146247569039, 10073.395596264247, 10211.7447063824, 10254.23093267993, 39295.2140957185, 39874.0, 53111.0, 62643.0, 63205.36794631995, 56951.43048569627, 79418.60290698509, 80642.19357806347, 84199.0, 85653.14346856452, 14102.788458173341, 14290.42838286457, 14362.712745745235, 14493.420263189355, 14566.417343306573, 16499.917500412423, 16651.167441628113, 17012.06433646161, 17151.22819473102, 18084.276628934695, 15108.0, 15336.233188340762, 15503.379864805282, 15519.22403879824, 16511.256993435083, 15507.612309692347, 15527.611809704396, 16597.41909033178, 16698.332066717194, 16783.244753985855, 14858.182799945675, 14923.403063877324, 15867.20663966774, 15951.361945522049, 16004.839951600801, 12290.20113692607, 12587.181833180814, 12710.682232944251, 12850.935745321214, 12990.0, 11159.330440173615, 11682.065434765369, 11881.702957426134, 12790.61628151157, 13079.0], "value": 8401.869128077838, "unit": "score"}], "name": "dromaeo_css", "value": 7792.889220660823, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 109.0, 111.0, 110.0, 110.0, 109.0, 109.0, 111.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 116.0, 181.0, 116.0, 117.0, 146.0, 119.0, 148.0, 116.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 159.0, 159.0, 161.0, 158.0, 165.0, 160.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 111.0, 75.0, 75.0, 75.0, 75.0, 107.0, 74.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 118.0, 83.0, 83.0, 83.0, 114.0, 82.0, 83.0, 128.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 148.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 58.0, 59.0, 58.0, 59.0, 58.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 72.0, 72.0, 81.0, 72.0, 72.0, 72.0, 73.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 115.0, 121.0, 120.0, 124.0, 121.0, 124.0, 117.0, 150.0, 116.0], "value": 122.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 156.0, 150.0, 150.0, 151.0, 150.0, 149.0, 151.0, 150.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 62.0, 60.0, 62.0, 61.0, 61.0, 61.0, 64.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1454.1000000000001, "alertThreshold": 2.0}]} 04:03:45 INFO - Return code: 0 04:03:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:45 INFO - # TBPL SUCCESS # 04:03:45 INFO - Running post-action listener: _resource_record_post_action 04:03:45 INFO - [mozharness: 2016-08-21 11:03:45.165202Z] Finished run-tests step (success) 04:03:45 INFO - Running post-run listener: _resource_record_post_run 04:03:45 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 329035776; Read time: 248; Write time: 211040 04:03:45 INFO - TinderboxPrint: CPU usage
12.9% 04:03:45 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 248 04:03:45 INFO - TinderboxPrint: I/O write bytes / time
329,035,776 / 211,040 04:03:45 INFO - TinderboxPrint: CPU idle
8,267.7 (87.0%) 04:03:45 INFO - TinderboxPrint: CPU user
1,185.3 (12.5%) 04:03:45 INFO - TinderboxPrint: Swap in / out
0 / 0 04:03:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78249984; Read time: 0; Write time: 45424 04:03:45 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249880576; Read time: 248; Write time: 165596 04:03:45 INFO - Running post-run listener: _upload_blobber_files 04:03:45 INFO - Blob upload gear active. 04:03:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:03:46 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:03:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:03:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:46 INFO - (blobuploader) - INFO - Done attempting. 04:03:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:50 INFO - (blobuploader) - INFO - Done attempting. 04:03:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:03:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:51 INFO - (blobuploader) - INFO - Done attempting. 04:03:51 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:51 INFO - Return code: 0 04:03:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6e294159e3d1efa085aae1966e7f96a8fdde832e3a1e2502a45a7e7ad995153b490dd301891e3c0df3bbd4297f3c7891937688f8f15c7abc0a68a168ac4e98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f58b2e8d3c5d9fd13f3ebbd9e1b65cc57ae79d278c4165df39ba5c34ce5f88ad9cba1cb970222d3dcacd410435f3a0c82ae2efd8e0dcd6f89ab75b5d93e6c8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c500bb3be5980de98c2e693b32745b1ab2c7b09093128461d3d29301025b38e998b1330b9166ea2d2cc54694d6f94333dafae5cbedbfb9df32c3669304bf4cc6"} 04:03:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:51 INFO - Contents: 04:03:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6e294159e3d1efa085aae1966e7f96a8fdde832e3a1e2502a45a7e7ad995153b490dd301891e3c0df3bbd4297f3c7891937688f8f15c7abc0a68a168ac4e98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f58b2e8d3c5d9fd13f3ebbd9e1b65cc57ae79d278c4165df39ba5c34ce5f88ad9cba1cb970222d3dcacd410435f3a0c82ae2efd8e0dcd6f89ab75b5d93e6c8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c500bb3be5980de98c2e693b32745b1ab2c7b09093128461d3d29301025b38e998b1330b9166ea2d2cc54694d6f94333dafae5cbedbfb9df32c3669304bf4cc6"} 04:03:51 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:51 INFO - Copying logs to upload dir... 04:03:51 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.975021 ========= master_lag: 40.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 37 secs) (at 2016-08-21 04:04:30.032615) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 04:04:30.036364) ========= 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=232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6e294159e3d1efa085aae1966e7f96a8fdde832e3a1e2502a45a7e7ad995153b490dd301891e3c0df3bbd4297f3c7891937688f8f15c7abc0a68a168ac4e98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f58b2e8d3c5d9fd13f3ebbd9e1b65cc57ae79d278c4165df39ba5c34ce5f88ad9cba1cb970222d3dcacd410435f3a0c82ae2efd8e0dcd6f89ab75b5d93e6c8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c500bb3be5980de98c2e693b32745b1ab2c7b09093128461d3d29301025b38e998b1330b9166ea2d2cc54694d6f94333dafae5cbedbfb9df32c3669304bf4cc6"} build_url:https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/AAiqYeZ8Qx-zT5IF7VKVyg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6e294159e3d1efa085aae1966e7f96a8fdde832e3a1e2502a45a7e7ad995153b490dd301891e3c0df3bbd4297f3c7891937688f8f15c7abc0a68a168ac4e98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f58b2e8d3c5d9fd13f3ebbd9e1b65cc57ae79d278c4165df39ba5c34ce5f88ad9cba1cb970222d3dcacd410435f3a0c82ae2efd8e0dcd6f89ab75b5d93e6c8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c500bb3be5980de98c2e693b32745b1ab2c7b09093128461d3d29301025b38e998b1330b9166ea2d2cc54694d6f94333dafae5cbedbfb9df32c3669304bf4cc6"}' ========= master_lag: 0.56 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 04:04:30.609077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 04:04:30.609407) ========= 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=232498196343f89f466a4a9a000001c8-1471768408.451919-1702404404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 04:04:30.788934) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 04:04:30.789608) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 04:04:30.789999) ========= ========= Total master_lag: 41.03 =========