builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1472502578.51 results: success (0) buildid: 20160829102231 builduid: 22fe23d2699145c5873ec15d49d44bb4 revision: 350ef810694c3704431f65c91bd83b9f2e933622 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:38.507002) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:38.507548) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:38.507870) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:38.649558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:38.650362) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 13:29:38-- 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% 103M=0s 2016-08-29 13:29:39 (103 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.343343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:39.012844) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:39.013516) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020067 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:39.063783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 13:29:39.064148) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 350ef810694c3704431f65c91bd83b9f2e933622 --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 350ef810694c3704431f65c91bd83b9f2e933622 --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=6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 13:29:39,183 truncating revision to first 12 chars 2016-08-29 13:29:39,183 Setting DEBUG logging. 2016-08-29 13:29:39,183 attempt 1/10 2016-08-29 13:29:39,183 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/350ef810694c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 13:29:40,072 unpacking tar archive at: mozilla-aurora-350ef810694c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.173948 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 13:29:40.251770) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:40.252142) ========= script_repo_revision: 350ef810694c3704431f65c91bd83b9f2e933622 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:40.252569) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:40.252862) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 13:29:40.262459) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-29 13:29:40.262800) ========= /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=6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:29:40 INFO - MultiFileLogger online at 20160829 13:29:40 in /builds/slave/test 13:29:40 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 13:29:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:29:40 INFO - {'append_to_log': False, 13:29:40 INFO - 'base_work_dir': '/builds/slave/test', 13:29:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:29:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:29:40 INFO - 'branch': 'Mozilla-Aurora', 13:29:40 INFO - 'buildbot_json_path': 'buildprops.json', 13:29:40 INFO - 'config_files': ('talos/linux_config.py',), 13:29:40 INFO - 'default_actions': ('clobber', 13:29:40 INFO - 'read-buildbot-config', 13:29:40 INFO - 'download-and-extract', 13:29:40 INFO - 'populate-webroot', 13:29:40 INFO - 'create-virtualenv', 13:29:40 INFO - 'install', 13:29:40 INFO - 'run-tests'), 13:29:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:29:40 INFO - 'download_minidump_stackwalk': True, 13:29:40 INFO - 'download_symbols': 'ondemand', 13:29:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:29:40 INFO - 'tooltool.py': '/tools/tooltool.py', 13:29:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:29:40 INFO - '/tools/misc-python/virtualenv.py')}, 13:29:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:29:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:29:40 INFO - 'installer_path': 'installer.exe', 13:29:40 INFO - 'log_level': 'info', 13:29:40 INFO - 'log_name': 'talos', 13:29:40 INFO - 'log_to_console': True, 13:29:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:29:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:29:40 INFO - 'opt_config_files': (), 13:29:40 INFO - 'pip_index': False, 13:29:40 INFO - 'sps_profile': False, 13:29:40 INFO - 'sps_profile_interval': 0, 13:29:40 INFO - 'suite': 'dromaeojs', 13:29:40 INFO - 'system_bits': '32', 13:29:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:29:40 INFO - 'title': 'talos-linux64-ix-023', 13:29:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:29:40 INFO - 'use_talos_json': True, 13:29:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:29:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:29:40 INFO - 'work_dir': 'build'} 13:29:40 INFO - [mozharness: 2016-08-29 20:29:40.482993Z] Running clobber step. 13:29:40 INFO - Running pre-action listener: _resource_record_pre_action 13:29:40 INFO - Running main action method: clobber 13:29:40 INFO - rmtree: /builds/slave/test/build 13:29:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:29:41 INFO - Running post-action listener: _resource_record_post_action 13:29:41 INFO - [mozharness: 2016-08-29 20:29:41.448250Z] Finished clobber step (success) 13:29:41 INFO - [mozharness: 2016-08-29 20:29:41.448351Z] Running read-buildbot-config step. 13:29:41 INFO - Running pre-action listener: _resource_record_pre_action 13:29:41 INFO - Running main action method: read_buildbot_config 13:29:41 INFO - Using buildbot properties: 13:29:41 INFO - { 13:29:41 INFO - "project": "", 13:29:41 INFO - "product": "firefox", 13:29:41 INFO - "script_repo_revision": "production", 13:29:41 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:29:41 INFO - "repository": "", 13:29:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:29:41 INFO - "buildid": "20160829102231", 13:29:41 INFO - "pgo_build": "True", 13:29:41 INFO - "basedir": "/builds/slave/test", 13:29:41 INFO - "buildnumber": 501, 13:29:41 INFO - "slavename": "talos-linux64-ix-023", 13:29:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:29:41 INFO - "platform": "ubuntu64_hw", 13:29:41 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:29:41 INFO - "branch": "mozilla-aurora", 13:29:41 INFO - "repo_path": "releases/mozilla-aurora", 13:29:41 INFO - "revision": "350ef810694c3704431f65c91bd83b9f2e933622", 13:29:41 INFO - "stage_platform": "linux64-pgo", 13:29:41 INFO - "builduid": "22fe23d2699145c5873ec15d49d44bb4", 13:29:41 INFO - "slavebuilddir": "test" 13:29:41 INFO - } 13:29:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:29:41 INFO - Running post-action listener: _resource_record_post_action 13:29:41 INFO - [mozharness: 2016-08-29 20:29:41.450794Z] Finished read-buildbot-config step (success) 13:29:41 INFO - [mozharness: 2016-08-29 20:29:41.450882Z] Running download-and-extract step. 13:29:41 INFO - Running pre-action listener: _resource_record_pre_action 13:29:41 INFO - Running main action method: download_and_extract 13:29:41 INFO - mkdir: /builds/slave/test/build/tests 13:29:41 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')]} 13:29:41 INFO - https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:29:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:29:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:29:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:29:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:29:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:29:41 INFO - retry: Failed, sleeping 30 seconds before retrying 13:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:30:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:30:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:30:11 INFO - retry: Failed, sleeping 60 seconds before retrying 13:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:31:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:31:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:31:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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! 13:31:11 INFO - Caught exception: HTTP Error 404: Not Found 13:31:11 INFO - Caught exception: HTTP Error 404: Not Found 13:31:11 INFO - Caught exception: HTTP Error 404: Not Found 13:31:11 INFO - trying https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:31:11 INFO - Downloading https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:31:12 INFO - Downloaded 1361 bytes. 13:31:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:31:12 INFO - Using the following test package requirements: 13:31:12 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:31:12 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:31:12 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:31:12 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'jsshell-linux-x86_64.zip'], 13:31:12 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:31:12 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:31:12 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:31:12 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:31:12 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:31:12 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:31:12 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:31:12 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:12 INFO - https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:31:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:31:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:31:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:31:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:31:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:31:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:31:12 INFO - retry: Failed, sleeping 30 seconds before retrying 13:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:31:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:31:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:31:42 INFO - retry: Failed, sleeping 60 seconds before retrying 13:32:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:32:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:32:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:32:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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! 13:32:42 INFO - Caught exception: HTTP Error 404: Not Found 13:32:42 INFO - Caught exception: HTTP Error 404: Not Found 13:32:42 INFO - Caught exception: HTTP Error 404: Not Found 13:32:42 INFO - trying https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:32:42 INFO - Downloading https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:32:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:32:43 INFO - Downloaded 21769130 bytes. 13:32:43 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'] 13:32:43 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 13:32:44 INFO - Return code: 0 13:32:44 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 13:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:44 INFO - https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:32:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:32:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:32:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:32:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:32:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:32:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:32:44 INFO - retry: Failed, sleeping 30 seconds before retrying 13:33:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:33:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:33:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:33:14 INFO - retry: Failed, sleeping 60 seconds before retrying 13:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:34:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:34:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:34:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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! 13:34:14 INFO - Caught exception: HTTP Error 404: Not Found 13:34:14 INFO - Caught exception: HTTP Error 404: Not Found 13:34:14 INFO - Caught exception: HTTP Error 404: Not Found 13:34:14 INFO - trying https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:34:14 INFO - Downloading https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:34:15 INFO - Downloaded 21769130 bytes. 13:34:15 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'] 13:34:15 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 13:34:16 INFO - Return code: 0 13:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:16 INFO - https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:34:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:34:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:34:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:34:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:34:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:34:16 INFO - retry: Failed, sleeping 30 seconds before retrying 13:34:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:34:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:34:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:34:46 INFO - retry: Failed, sleeping 60 seconds before retrying 13:35:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:35:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:35:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:35:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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! 13:35:46 INFO - Caught exception: HTTP Error 404: Not Found 13:35:46 INFO - Caught exception: HTTP Error 404: Not Found 13:35:46 INFO - Caught exception: HTTP Error 404: Not Found 13:35:46 INFO - trying https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:35:46 INFO - Downloading https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:35:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:35:47 INFO - Downloaded 14413723 bytes. 13:35:47 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'] 13:35:47 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 13:35:47 INFO - Return code: 0 13:35: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')]} 13:35:47 INFO - https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:35:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:35:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:35:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:35:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:35:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:35:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:35:47 INFO - retry: Failed, sleeping 30 seconds before retrying 13:36:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:36:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:36:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:36:17 INFO - retry: Failed, sleeping 60 seconds before retrying 13:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:37:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:37:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:37:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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! 13:37:17 INFO - Caught exception: HTTP Error 404: Not Found 13:37:17 INFO - Caught exception: HTTP Error 404: Not Found 13:37:17 INFO - Caught exception: HTTP Error 404: Not Found 13:37:17 INFO - trying https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:37:17 INFO - Downloading https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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 13:37:19 INFO - Downloaded 63886640 bytes. 13:37:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:37:19 INFO - mkdir: /builds/slave/test/properties 13:37:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:37:19 INFO - Writing to file /builds/slave/test/properties/build_url 13:37:19 INFO - Contents: 13:37:19 INFO - build_url:https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:37:19 INFO - Running post-action listener: _resource_record_post_action 13:37:19 INFO - Running post-action listener: set_extra_try_arguments 13:37:19 INFO - [mozharness: 2016-08-29 20:37:19.719043Z] Finished download-and-extract step (success) 13:37:19 INFO - [mozharness: 2016-08-29 20:37:19.719274Z] Running populate-webroot step. 13:37:19 INFO - Running pre-action listener: _resource_record_pre_action 13:37:19 INFO - Running main action method: populate_webroot 13:37:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:37:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:37:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:37:19 INFO - '--tppagecycles', 13:37:19 INFO - '7'], 13:37:19 INFO - 'tests': ['tsvgm']}}, 13:37:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:37:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:37:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:37:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:37:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:37:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:37:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['damp', 'tps']}, 13:37:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'tests': ['damp', 'tps']}, 13:37:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['dromaeo_dom']}, 13:37:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:37:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['basic_compositor_video']}, 13:37:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:37:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['a11yr', 13:37:19 INFO - 'ts_paint', 13:37:19 INFO - 'tpaint', 13:37:19 INFO - 'sessionrestore', 13:37:19 INFO - 'sessionrestore_no_auto_restore', 13:37:19 INFO - 'tabpaint']}, 13:37:19 INFO - 'other-e10s': {'tests': ['a11yr', 13:37:19 INFO - 'ts_paint', 13:37:19 INFO - 'tpaint', 13:37:19 INFO - 'sessionrestore', 13:37:19 INFO - 'sessionrestore_no_auto_restore', 13:37:19 INFO - 'tabpaint']}, 13:37:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:37:19 INFO - 'ts_paint', 13:37:19 INFO - 'tpaint', 13:37:19 INFO - 'sessionrestore', 13:37:19 INFO - 'sessionrestore_no_auto_restore', 13:37:19 INFO - 'tabpaint']}, 13:37:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:37:19 INFO - 'ts_paint', 13:37:19 INFO - 'tpaint', 13:37:19 INFO - 'sessionrestore', 13:37:19 INFO - 'sessionrestore_no_auto_restore', 13:37:19 INFO - 'tabpaint']}, 13:37:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['a11yr', 13:37:19 INFO - 'ts_paint', 13:37:19 INFO - 'tpaint', 13:37:19 INFO - 'sessionrestore', 13:37:19 INFO - 'sessionrestore_no_auto_restore', 13:37:19 INFO - 'tabpaint']}, 13:37:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['a11yr', 13:37:19 INFO - 'ts_paint', 13:37:19 INFO - 'tpaint', 13:37:19 INFO - 'sessionrestore', 13:37:19 INFO - 'sessionrestore_no_auto_restore', 13:37:19 INFO - 'tabpaint']}, 13:37:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['tsvgx', 13:37:19 INFO - 'tsvgr_opacity', 13:37:19 INFO - 'tart', 13:37:19 INFO - 'tscrollx', 13:37:19 INFO - 'cart']}, 13:37:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:37:19 INFO - 'tsvgr_opacity', 13:37:19 INFO - 'tart', 13:37:19 INFO - 'tscrollx', 13:37:19 INFO - 'cart']}, 13:37:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'talos_options': ['--disable-e10s'], 13:37:19 INFO - 'tests': ['tp5o']}, 13:37:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'tests': ['tp5o']}, 13:37:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'talos_options': ['--disable-e10s', 13:37:19 INFO - '--xperf_path', 13:37:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:37:19 INFO - 'tests': ['tp5n']}, 13:37:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:19 INFO - 'talos_options': ['--xperf_path', 13:37:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:37:19 INFO - 'tests': ['tp5n']}}, 13:37:19 INFO - 'talos.zip': {'path': '', 13:37:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:37:19 INFO - Running post-action listener: _resource_record_post_action 13:37:19 INFO - [mozharness: 2016-08-29 20:37:19.741197Z] Finished populate-webroot step (success) 13:37:19 INFO - [mozharness: 2016-08-29 20:37:19.741285Z] Running create-virtualenv step. 13:37:19 INFO - Running pre-action listener: _resource_record_pre_action 13:37:19 INFO - Running main action method: create_virtualenv 13:37:19 INFO - Creating virtualenv /builds/slave/test/build/venv 13:37:19 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 13:37:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:37:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:37:19 INFO - Using real prefix '/usr' 13:37:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:37:20 INFO - Installing distribute.............................................................................................................................................................................................done. 13:37:22 INFO - Installing pip.................done. 13:37:22 INFO - Return code: 0 13:37:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:37:22 INFO - Using env: {'DISPLAY': ':0', 13:37:22 INFO - 'HOME': '/home/cltbld', 13:37:22 INFO - 'LANG': 'en_US.UTF-8', 13:37:22 INFO - 'LANGUAGE': 'en_US:en', 13:37:22 INFO - 'LOGNAME': 'cltbld', 13:37:22 INFO - 'MAIL': '/var/mail/cltbld', 13:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:22 INFO - 'MOZ_NO_REMOTE': '1', 13:37:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:22 INFO - 'NO_EM_RESTART': '1', 13:37:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:22 INFO - 'PWD': '/builds/slave/test', 13:37:22 INFO - 'SHELL': '/bin/bash', 13:37:22 INFO - 'SHLVL': '1', 13:37:22 INFO - 'TERM': 'linux', 13:37:22 INFO - 'TMOUT': '86400', 13:37:22 INFO - 'USER': 'cltbld', 13:37:22 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:22 INFO - '_': '/tools/buildbot/bin/python'} 13:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:22 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 13:37:22 INFO - Cleaning up... 13:37:22 INFO - Return code: 0 13:37:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:37:22 INFO - Using env: {'DISPLAY': ':0', 13:37:22 INFO - 'HOME': '/home/cltbld', 13:37:22 INFO - 'LANG': 'en_US.UTF-8', 13:37:22 INFO - 'LANGUAGE': 'en_US:en', 13:37:22 INFO - 'LOGNAME': 'cltbld', 13:37:22 INFO - 'MAIL': '/var/mail/cltbld', 13:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:22 INFO - 'MOZ_NO_REMOTE': '1', 13:37:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:22 INFO - 'NO_EM_RESTART': '1', 13:37:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:22 INFO - 'PWD': '/builds/slave/test', 13:37:22 INFO - 'SHELL': '/bin/bash', 13:37:22 INFO - 'SHLVL': '1', 13:37:22 INFO - 'TERM': 'linux', 13:37:22 INFO - 'TMOUT': '86400', 13:37:22 INFO - 'USER': 'cltbld', 13:37:22 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:22 INFO - '_': '/tools/buildbot/bin/python'} 13:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:22 INFO - Downloading/unpacking psutil>=3.1.1 13:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:37:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:37:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:37:24 INFO - Installing collected packages: psutil 13:37:24 INFO - Running setup.py install for psutil 13:37:24 INFO - building 'psutil._psutil_linux' extension 13:37:24 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 13:37:24 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 13:37:24 INFO - building 'psutil._psutil_posix' extension 13:37:24 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 13:37:25 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 13:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:37:25 INFO - Successfully installed psutil 13:37:25 INFO - Cleaning up... 13:37:25 INFO - Return code: 0 13:37:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13: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')]} 13:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13: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')]} 13:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:25 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:25 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 13:37:25 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 13:37:25 INFO - Using env: {'DISPLAY': ':0', 13:37:25 INFO - 'HOME': '/home/cltbld', 13:37:25 INFO - 'LANG': 'en_US.UTF-8', 13:37:25 INFO - 'LANGUAGE': 'en_US:en', 13:37:25 INFO - 'LOGNAME': 'cltbld', 13:37:25 INFO - 'MAIL': '/var/mail/cltbld', 13:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:25 INFO - 'MOZ_NO_REMOTE': '1', 13:37:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:25 INFO - 'NO_EM_RESTART': '1', 13:37:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:25 INFO - 'PWD': '/builds/slave/test', 13:37:25 INFO - 'SHELL': '/bin/bash', 13:37:25 INFO - 'SHLVL': '1', 13:37:25 INFO - 'TERM': 'linux', 13:37:25 INFO - 'TMOUT': '86400', 13:37:25 INFO - 'USER': 'cltbld', 13:37:25 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:25 INFO - '_': '/tools/buildbot/bin/python'} 13:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:37:25 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 13:37:25 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 13:37:25 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 13:37:25 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 13:37:25 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 13:37:25 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 13:37:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:37:27 INFO - Installing collected packages: mozsystemmonitor 13:37:27 INFO - Running setup.py install for mozsystemmonitor 13:37:27 INFO - Successfully installed mozsystemmonitor 13:37:27 INFO - Cleaning up... 13:37:27 INFO - Return code: 0 13:37:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:37:27 INFO - Using env: {'DISPLAY': ':0', 13:37:27 INFO - 'HOME': '/home/cltbld', 13:37:27 INFO - 'LANG': 'en_US.UTF-8', 13:37:27 INFO - 'LANGUAGE': 'en_US:en', 13:37:27 INFO - 'LOGNAME': 'cltbld', 13:37:27 INFO - 'MAIL': '/var/mail/cltbld', 13:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:27 INFO - 'MOZ_NO_REMOTE': '1', 13:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:27 INFO - 'NO_EM_RESTART': '1', 13:37:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:27 INFO - 'PWD': '/builds/slave/test', 13:37:27 INFO - 'SHELL': '/bin/bash', 13:37:27 INFO - 'SHLVL': '1', 13:37:27 INFO - 'TERM': 'linux', 13:37:27 INFO - 'TMOUT': '86400', 13:37:27 INFO - 'USER': 'cltbld', 13:37:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:27 INFO - '_': '/tools/buildbot/bin/python'} 13:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:27 INFO - Downloading/unpacking jsonschema==2.5.1 13:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:37:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:37:29 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 13:37:29 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 13:37:29 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 13:37:29 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 13:37:29 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 13:37:29 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 13:37:29 INFO - Downloading functools32-3.2.3-2.tar.gz 13:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:37:29 INFO - warning: no files found matching '*.txt' 13:37:29 INFO - no previously-included directories found matching 'build' 13:37:29 INFO - no previously-included directories found matching 'dist' 13:37:29 INFO - no previously-included directories found matching '.git*' 13:37:29 INFO - Installing collected packages: jsonschema, functools32 13:37:29 INFO - Running setup.py install for functools32 13:37:30 INFO - warning: no files found matching '*.txt' 13:37:30 INFO - no previously-included directories found matching 'build' 13:37:30 INFO - no previously-included directories found matching 'dist' 13:37:30 INFO - no previously-included directories found matching '.git*' 13:37:30 INFO - Successfully installed jsonschema functools32 13:37:30 INFO - Cleaning up... 13:37:30 INFO - Return code: 0 13:37:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:37:30 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')]} 13:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:30 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')]} 13:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:30 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:30 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 13:37:30 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 13:37:30 INFO - Using env: {'DISPLAY': ':0', 13:37:30 INFO - 'HOME': '/home/cltbld', 13:37:30 INFO - 'LANG': 'en_US.UTF-8', 13:37:30 INFO - 'LANGUAGE': 'en_US:en', 13:37:30 INFO - 'LOGNAME': 'cltbld', 13:37:30 INFO - 'MAIL': '/var/mail/cltbld', 13:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:30 INFO - 'MOZ_NO_REMOTE': '1', 13:37:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:30 INFO - 'NO_EM_RESTART': '1', 13:37:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:30 INFO - 'PWD': '/builds/slave/test', 13:37:30 INFO - 'SHELL': '/bin/bash', 13:37:30 INFO - 'SHLVL': '1', 13:37:30 INFO - 'TERM': 'linux', 13:37:30 INFO - 'TMOUT': '86400', 13:37:30 INFO - 'USER': 'cltbld', 13:37:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:30 INFO - '_': '/tools/buildbot/bin/python'} 13:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:37:30 INFO - Cleaning up... 13:37:30 INFO - Return code: 0 13:37:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:37:30 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')]} 13:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:30 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')]} 13:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:30 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:30 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 13:37:30 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 13:37:30 INFO - Using env: {'DISPLAY': ':0', 13:37:30 INFO - 'HOME': '/home/cltbld', 13:37:30 INFO - 'LANG': 'en_US.UTF-8', 13:37:30 INFO - 'LANGUAGE': 'en_US:en', 13:37:30 INFO - 'LOGNAME': 'cltbld', 13:37:30 INFO - 'MAIL': '/var/mail/cltbld', 13:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:30 INFO - 'MOZ_NO_REMOTE': '1', 13:37:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:30 INFO - 'NO_EM_RESTART': '1', 13:37:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:30 INFO - 'PWD': '/builds/slave/test', 13:37:30 INFO - 'SHELL': '/bin/bash', 13:37:30 INFO - 'SHLVL': '1', 13:37:30 INFO - 'TERM': 'linux', 13:37:30 INFO - 'TMOUT': '86400', 13:37:30 INFO - 'USER': 'cltbld', 13:37:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:30 INFO - '_': '/tools/buildbot/bin/python'} 13:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:30 INFO - Downloading/unpacking blobuploader==1.2.4 13:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:32 INFO - Downloading blobuploader-1.2.4.tar.gz 13:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:37:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:37:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 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 13:37:32 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 13:37:33 INFO - Downloading docopt-0.6.1.tar.gz 13:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:37:33 INFO - Installing collected packages: blobuploader, requests, docopt 13:37:33 INFO - Running setup.py install for blobuploader 13:37:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:37:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:37:33 INFO - Running setup.py install for requests 13:37:33 INFO - Running setup.py install for docopt 13:37:33 INFO - Successfully installed blobuploader requests docopt 13:37:33 INFO - Cleaning up... 13:37:33 INFO - Return code: 0 13:37:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:33 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')]} 13:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:33 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')]} 13:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:33 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:33 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 13:37:33 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 13:37:33 INFO - Using env: {'DISPLAY': ':0', 13:37:33 INFO - 'HOME': '/home/cltbld', 13:37:33 INFO - 'LANG': 'en_US.UTF-8', 13:37:33 INFO - 'LANGUAGE': 'en_US:en', 13:37:33 INFO - 'LOGNAME': 'cltbld', 13:37:33 INFO - 'MAIL': '/var/mail/cltbld', 13:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:33 INFO - 'MOZ_NO_REMOTE': '1', 13:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:33 INFO - 'NO_EM_RESTART': '1', 13:37:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:33 INFO - 'PWD': '/builds/slave/test', 13:37:33 INFO - 'SHELL': '/bin/bash', 13:37:33 INFO - 'SHLVL': '1', 13:37:33 INFO - 'TERM': 'linux', 13:37:33 INFO - 'TMOUT': '86400', 13:37:33 INFO - 'USER': 'cltbld', 13:37:33 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:33 INFO - '_': '/tools/buildbot/bin/python'} 13:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:37:34 INFO - Running setup.py (path:/tmp/pip-gxF3KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:37:34 INFO - Running setup.py (path:/tmp/pip-4eUBtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:37:34 INFO - Running setup.py (path:/tmp/pip-2YHcXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:37:34 INFO - Running setup.py (path:/tmp/pip-e0s0x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:37:34 INFO - Running setup.py (path:/tmp/pip-uE9rTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:37:34 INFO - Running setup.py (path:/tmp/pip-ZWw4HF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:37:34 INFO - Running setup.py (path:/tmp/pip-Kf6dgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:37:34 INFO - Running setup.py (path:/tmp/pip-43_VAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:37:34 INFO - Running setup.py (path:/tmp/pip-fcMj9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:37:34 INFO - Running setup.py (path:/tmp/pip-rzW43y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:37:34 INFO - Running setup.py (path:/tmp/pip-4N_WgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:37:34 INFO - Running setup.py (path:/tmp/pip-QAVquc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:37:34 INFO - Running setup.py (path:/tmp/pip-cLEEty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:37:34 INFO - Running setup.py (path:/tmp/pip-xElCMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:35 INFO - Running setup.py (path:/tmp/pip-t0rzw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:37:35 INFO - Running setup.py (path:/tmp/pip-185dWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:37:35 INFO - Running setup.py (path:/tmp/pip-UfEhyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:37:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:37:35 INFO - Running setup.py install for manifestparser 13:37:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:37:35 INFO - Running setup.py install for mozcrash 13:37:35 INFO - Running setup.py install for mozdebug 13:37:35 INFO - Running setup.py install for mozdevice 13:37:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:37:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:37:35 INFO - Running setup.py install for mozfile 13:37:35 INFO - Running setup.py install for mozhttpd 13:37:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:37:35 INFO - Running setup.py install for mozinfo 13:37:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:37:35 INFO - Running setup.py install for mozInstall 13:37:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Running setup.py install for mozleak 13:37:36 INFO - Running setup.py install for mozlog 13:37:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Running setup.py install for moznetwork 13:37:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Running setup.py install for mozprocess 13:37:36 INFO - Running setup.py install for mozprofile 13:37:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Running setup.py install for mozrunner 13:37:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:37:36 INFO - Running setup.py install for mozscreenshot 13:37:36 INFO - Running setup.py install for moztest 13:37:36 INFO - Running setup.py install for mozversion 13:37:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:37:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:37:37 INFO - Cleaning up... 13:37:37 INFO - Return code: 0 13:37:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:37 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')]} 13:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:37 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')]} 13:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:37 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:37 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 13:37:37 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 13:37:37 INFO - Using env: {'DISPLAY': ':0', 13:37:37 INFO - 'HOME': '/home/cltbld', 13:37:37 INFO - 'LANG': 'en_US.UTF-8', 13:37:37 INFO - 'LANGUAGE': 'en_US:en', 13:37:37 INFO - 'LOGNAME': 'cltbld', 13:37:37 INFO - 'MAIL': '/var/mail/cltbld', 13:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:37 INFO - 'MOZ_NO_REMOTE': '1', 13:37:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:37 INFO - 'NO_EM_RESTART': '1', 13:37:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:37 INFO - 'PWD': '/builds/slave/test', 13:37:37 INFO - 'SHELL': '/bin/bash', 13:37:37 INFO - 'SHLVL': '1', 13:37:37 INFO - 'TERM': 'linux', 13:37:37 INFO - 'TMOUT': '86400', 13:37:37 INFO - 'USER': 'cltbld', 13:37:37 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:37 INFO - '_': '/tools/buildbot/bin/python'} 13:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:37:37 INFO - Running setup.py (path:/tmp/pip-gu9QDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:37:37 INFO - Running setup.py (path:/tmp/pip-mMrXQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:37:37 INFO - Running setup.py (path:/tmp/pip-VhlqiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:37:37 INFO - Running setup.py (path:/tmp/pip-JKrNbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:37:37 INFO - Running setup.py (path:/tmp/pip-Bvx_65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:37:37 INFO - Running setup.py (path:/tmp/pip-2xgD_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:37:37 INFO - Running setup.py (path:/tmp/pip-MM0WnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:37:37 INFO - Running setup.py (path:/tmp/pip-zpkp8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:37:37 INFO - Running setup.py (path:/tmp/pip-yqQ4ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:37:37 INFO - Running setup.py (path:/tmp/pip-zg2BZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:37:37 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)) 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:37:37 INFO - Running setup.py (path:/tmp/pip-tcsl2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:37:38 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)) 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:37:38 INFO - Running setup.py (path:/tmp/pip-_sb_vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:37:38 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)) 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:37:38 INFO - Running setup.py (path:/tmp/pip-Jqb35L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:37:38 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)) 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:37:38 INFO - Running setup.py (path:/tmp/pip-OptUTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:37:38 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)) 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:38 INFO - Running setup.py (path:/tmp/pip-hID_i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:38 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)) 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:37:38 INFO - Running setup.py (path:/tmp/pip-6oBcDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:37:38 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)) 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:37:38 INFO - Running setup.py (path:/tmp/pip-UxgD4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:37:38 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)) 13:37:38 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)) 13:37:38 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)) 13:37:38 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)) 13:37:38 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)) 13:37:38 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)) 13:37:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:37: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 13:37: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 13:37: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 13:37: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 13:37: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 13:37: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 13:37:40 INFO - Downloading blessings-1.6.tar.gz 13:37:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:37:40 INFO - Installing collected packages: blessings 13:37:40 INFO - Running setup.py install for blessings 13:37:40 INFO - Successfully installed blessings 13:37:40 INFO - Cleaning up... 13:37:40 INFO - Return code: 0 13:37:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:37:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:37:40 INFO - Reading from file tmpfile_stdout 13:37:40 INFO - Current package versions: 13:37:40 INFO - argparse == 1.2.1 13:37:40 INFO - blessings == 1.6 13:37:40 INFO - blobuploader == 1.2.4 13:37:40 INFO - docopt == 0.6.1 13:37:40 INFO - functools32 == 3.2.3-2 13:37:40 INFO - jsonschema == 2.5.1 13:37:40 INFO - manifestparser == 1.1 13:37:40 INFO - mozInstall == 1.12 13:37:40 INFO - mozcrash == 0.17 13:37:40 INFO - mozdebug == 0.1 13:37:40 INFO - mozdevice == 0.48 13:37:40 INFO - mozfile == 1.2 13:37:40 INFO - mozhttpd == 0.7 13:37:40 INFO - mozinfo == 0.9 13:37:40 INFO - mozleak == 0.1 13:37:40 INFO - mozlog == 3.3 13:37:40 INFO - moznetwork == 0.27 13:37:40 INFO - mozprocess == 0.23 13:37:40 INFO - mozprofile == 0.28 13:37:40 INFO - mozrunner == 6.12 13:37:40 INFO - mozscreenshot == 0.1 13:37:40 INFO - mozsystemmonitor == 0.3 13:37:40 INFO - moztest == 0.7 13:37:40 INFO - mozversion == 1.4 13:37:40 INFO - psutil == 3.1.1 13:37:40 INFO - requests == 1.2.3 13:37:40 INFO - wsgiref == 0.1.2 13:37:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37: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')]} 13:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37: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')]} 13:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:40 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:40 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 13:37:40 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 13:37:40 INFO - Using env: {'DISPLAY': ':0', 13:37:40 INFO - 'HOME': '/home/cltbld', 13:37:40 INFO - 'LANG': 'en_US.UTF-8', 13:37:40 INFO - 'LANGUAGE': 'en_US:en', 13:37:40 INFO - 'LOGNAME': 'cltbld', 13:37:40 INFO - 'MAIL': '/var/mail/cltbld', 13:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:40 INFO - 'MOZ_NO_REMOTE': '1', 13:37:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:40 INFO - 'NO_EM_RESTART': '1', 13:37:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:40 INFO - 'PWD': '/builds/slave/test', 13:37:40 INFO - 'SHELL': '/bin/bash', 13:37:40 INFO - 'SHLVL': '1', 13:37:40 INFO - 'TERM': 'linux', 13:37:40 INFO - 'TMOUT': '86400', 13:37:40 INFO - 'USER': 'cltbld', 13:37:40 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:40 INFO - '_': '/tools/buildbot/bin/python'} 13:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 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)) 13:37:41 INFO - Cleaning up... 13:37:41 INFO - Return code: 0 13:37:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:37:41 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')]} 13:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:41 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')]} 13:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:41 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 0x2297030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', '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 13:37:41 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 13:37: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 13:37:41 INFO - Using env: {'DISPLAY': ':0', 13:37:41 INFO - 'HOME': '/home/cltbld', 13:37:41 INFO - 'LANG': 'en_US.UTF-8', 13:37:41 INFO - 'LANGUAGE': 'en_US:en', 13:37:41 INFO - 'LOGNAME': 'cltbld', 13:37:41 INFO - 'MAIL': '/var/mail/cltbld', 13:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:41 INFO - 'MOZ_NO_REMOTE': '1', 13:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:41 INFO - 'NO_EM_RESTART': '1', 13:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:41 INFO - 'PWD': '/builds/slave/test', 13:37:41 INFO - 'SHELL': '/bin/bash', 13:37:41 INFO - 'SHLVL': '1', 13:37:41 INFO - 'TERM': 'linux', 13:37:41 INFO - 'TMOUT': '86400', 13:37:41 INFO - 'USER': 'cltbld', 13:37:41 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:41 INFO - '_': '/tools/buildbot/bin/python'} 13:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:37:41 INFO - Cleaning up... 13:37:41 INFO - Return code: 0 13:37:41 INFO - Running post-action listener: _resource_record_post_action 13:37:41 INFO - Running post-action listener: _start_resource_monitoring 13:37:41 INFO - Starting resource monitoring. 13:37:41 INFO - [mozharness: 2016-08-29 20:37:41.311060Z] Finished create-virtualenv step (success) 13:37:41 INFO - [mozharness: 2016-08-29 20:37:41.311482Z] Running install step. 13:37:41 INFO - Running pre-action listener: _resource_record_pre_action 13:37:41 INFO - Running main action method: install 13:37:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:37:41 INFO - Reading from file tmpfile_stdout 13:37:41 INFO - Detecting whether we're running mozinstall >=1.0... 13:37:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:37:41 INFO - Reading from file tmpfile_stdout 13:37:41 INFO - Output received: 13:37:41 INFO - Usage: mozinstall [options] installer 13:37:41 INFO - Options: 13:37:41 INFO - -h, --help show this help message and exit 13:37:41 INFO - -d DEST, --destination=DEST 13:37:41 INFO - Directory to install application into. [default: 13:37:41 INFO - "/builds/slave/test"] 13:37:41 INFO - --app=APP Application being installed. [default: firefox] 13:37:41 INFO - mkdir: /builds/slave/test/build/application 13:37:41 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'] 13:37:41 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 13:37:58 INFO - Reading from file tmpfile_stdout 13:37:58 INFO - Output received: 13:37:58 INFO - /builds/slave/test/build/application/firefox/firefox 13:37:58 INFO - Running post-action listener: _resource_record_post_action 13:37:58 INFO - [mozharness: 2016-08-29 20:37:58.588551Z] Finished install step (success) 13:37:58 INFO - [mozharness: 2016-08-29 20:37:58.588821Z] Running run-tests step. 13:37:58 INFO - Running pre-action listener: _resource_record_pre_action 13:37:58 INFO - Running main action method: run_tests 13:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:37:58 INFO - Python 2.7.3 13:37:58 INFO - Return code: 0 13:37:58 INFO - grabbing minidump binary from tooltool 13:37:58 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')]} 13:37:58 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 0x25563b0>, '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 0x25572b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:37:58 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 13:37:58 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 13:37:58 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 13:37:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:37:58 INFO - Return code: 0 13:37:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:37:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:37:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:37:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:37:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:37:58 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/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 13:37:58 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/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 13:37:58 INFO - Using env: {'DISPLAY': ':0', 13:37:58 INFO - 'HOME': '/home/cltbld', 13:37:58 INFO - 'LANG': 'en_US.UTF-8', 13:37:58 INFO - 'LANGUAGE': 'en_US:en', 13:37:58 INFO - 'LOGNAME': 'cltbld', 13:37:58 INFO - 'MAIL': '/var/mail/cltbld', 13:37:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:37:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:58 INFO - 'MOZ_NO_REMOTE': '1', 13:37:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:37:58 INFO - 'NO_EM_RESTART': '1', 13:37:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:58 INFO - 'PWD': '/builds/slave/test', 13:37:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:37:58 INFO - 'SHELL': '/bin/bash', 13:37:58 INFO - 'SHLVL': '1', 13:37:58 INFO - 'TERM': 'linux', 13:37:58 INFO - 'TMOUT': '86400', 13:37:58 INFO - 'USER': 'cltbld', 13:37:58 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563', 13:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:58 INFO - '_': '/tools/buildbot/bin/python'} 13:37:58 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/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 13:37:58 INFO - mozversion application_buildid: 20160829102231 13:37:58 INFO - mozversion application_changeset: 350ef810694c3704431f65c91bd83b9f2e933622 13:37:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:37:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:37:58 INFO - mozversion application_name: Firefox 13:37:58 INFO - mozversion application_remotingname: firefox-dev 13:37:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:37:58 INFO - mozversion application_vendor: Mozilla 13:37:58 INFO - mozversion application_version: 50.0a2 13:37:58 INFO - mozversion platform_buildid: 20160829102231 13:37:58 INFO - mozversion platform_changeset: 350ef810694c3704431f65c91bd83b9f2e933622 13:37:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:37:58 INFO - mozversion platform_version: 50.0a2 13:37:58 INFO - using testdate: 1472503078 13:37:58 INFO - actual date: 1472503078 13:37:58 INFO - starting webserver on 'localhost:56998' 13:37:58 INFO - SUITE-START | Running 2 tests 13:37:58 INFO - TEST-START | dromaeo_css 13:37:58 INFO - operating with platform_type : linux_ 13:37:58 INFO - Initialising browser for dromaeo_css test... 13:37:58 INFO - TEST-INFO | started process 27612 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoqB545/profile http://localhost:56998/getInfo.html) 13:38:02 INFO - PROCESS | 27612 | __metrics Screen width/height:1600/1200 13:38:02 INFO - PROCESS | 27612 | colorDepth:24 13:38:02 INFO - PROCESS | 27612 | Browser inner width/height: 1024/697 13:38:02 INFO - PROCESS | 27612 | __metrics 13:38:03 INFO - PROCESS | 27612 | 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" 13:38:03 INFO - PROCESS | 27612 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:38:04 INFO - TEST-INFO | 27612: exit 0 13:38:04 INFO - Browser initialized. 13:38:04 INFO - Running cycle 1/1 for dromaeo_css test... 13:38:04 INFO - TEST-INFO | started process 27703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoqB545/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:38:16 INFO - PROCESS | 27703 | RSS: Main: 145686528 13:38:16 INFO - PROCESS | 27703 | 13:39:07 INFO - PROCESS | 27703 | 1472503147559 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] 13:41:16 INFO - PROCESS | 27703 | Cycle 1(1): loaded http://localhost:56998/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56998/tests/dromaeo/cssquery-ext.html) 13:41:17 INFO - PROCESS | 27703 | RSS: Main: 248537088 13:41:17 INFO - PROCESS | 27703 | 13:44:19 INFO - PROCESS | 27703 | Cycle 1(1): loaded http://localhost:56998/tests/dromaeo/cssquery-ext.html (next: http://localhost:56998/tests/dromaeo/cssquery-jquery.html) 13:44:20 INFO - PROCESS | 27703 | RSS: Main: 234807296 13:44:20 INFO - PROCESS | 27703 | 13:47:22 INFO - PROCESS | 27703 | Cycle 1(1): loaded http://localhost:56998/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56998/tests/dromaeo/cssquery-mootools.html) 13:47:22 INFO - PROCESS | 27703 | RSS: Main: 206716928 13:47:22 INFO - PROCESS | 27703 | 13:50:24 INFO - PROCESS | 27703 | Cycle 1(1): loaded http://localhost:56998/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56998/tests/dromaeo/cssquery-prototype.html) 13:50:25 INFO - PROCESS | 27703 | RSS: Main: 175304704 13:50:25 INFO - PROCESS | 27703 | 13:53:28 INFO - PROCESS | 27703 | Cycle 1(1): loaded http://localhost:56998/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56998/tests/dromaeo/cssquery-yui.html) 13:53:28 INFO - PROCESS | 27703 | RSS: Main: 176508928 13:53:28 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | Cycle 1(1): loaded http://localhost:56998/tests/dromaeo/cssquery-yui.html (next: http://localhost:56998/tests/dromaeo/cssquery-dojo.html) 13:56:31 INFO - PROCESS | 27703 | RSS: Main: 254185472 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | __start_tp_report 13:56:31 INFO - PROCESS | 27703 | _x_x_mozilla_page_load 13:56:31 INFO - PROCESS | 27703 | _x_x_mozilla_page_load_details 13:56:31 INFO - PROCESS | 27703 | |i|pagename|runs| 13:56:31 INFO - PROCESS | 27703 | |0;dojo.html;3243.8215898125604;3282.4255755242843;3296.077098412444;3316.7678262521617;3319.4522903720886;5619.522340601049;5721.799737009205;5743.741531631076;5759.337676167242;5784.161296611994;5711.942880571193;5769.451902069297;5769.82690519284;5777.422257774231;5811.6803575803315;5038.546530812227;5103.872403189913;5161.30322406475;5189.481051894818;5251.448597897223;5138.511841375063;5170.172772356424;5191.870203244911;5194.58443324535;5242;5223.660462069954;5317.627766056377;5329.360476742777;5333.253344531769;5337.572994160477;5232.162853943389;5255.369355677305;5317.494837990384;5324.520793128599;5337.306150200468;4831.4202295724735;4999.500049995007;5151.763576741592;5153;5234.42421333653;5805.7387417566315;5812;5823;5830.387809280007;5841.824745257649;6143.723532441051;6187.9480488317095;6223.719932603044;6228.532860035515;6229.532785041095;5965.075413310944;6072.493565586228;6142;6148.6618235997;6247.344028876993;5831.3877042910735;5838.9124163137585;5841.503472204868;5843.678597677126;5844;6318.71565779541;6319.936800631935;6325.7785977491485;6330.588511746721;6335.334789847092;5793.420657934173;5933.169356290123;5973.581849270597;5991.131285963511;6095.451409373178;6109.2528441424865;6142.692865356715;6145.692715364214;6147.323794382614;6154.5384096192965;6114.205153330127;6126.090206827809;6135.171422439398;6137.407629318989;6137.8060289513105;5894.498967587718;5909.409059094142;5952.761889524457;6134.273085652824;6191.1641928339095;4617.930731039037;4626.699264547794;4678.742669153161;4678.95321046792;4680.368150299734;4108.1783643270865;4177.979110104491;4185.497740271187;4210.031692710693;4270.44484217056;5842.152887831241;5846.298444186725;5847.532197424196;5848.444397782204;5852.063669812809;5795.594308398372;6147.907781383283;6159.938400616026;6220.782272620437;6225.439710426083;5761.683107429048;5811.157382179645;5815.621984570989;5884.235049443545;5920.259967504062;5327.3074500314715;5331.120365139708;5357.678539287655;5374.086405311106;5382;6113.628337950562;6426.228852537632;6486.091947127312;6491.805245842632;6501.382368674968;6067.180930574493;6132.938670613236;6140.293866205444;6177.135201071944;6209.534284928739;6434.099226108442;6438.742450302123;6440.066190402272;6443.806685799434;6448.774292899726;5810.68041257718;6056.909756243918;6071.724937762942;6075;6078;6018.8796224074395;6095.994160963392;6102.267727872683;6176;6200.534959877937;6010.459058684739;6014.72933717975;6022.969885150546;6033.367178323181;6056.9697151513965;5801.274840644919;5937.109433584997;5946.137810017437;6005.969970150296;6006.738584897046;5894.941050589439;5902.793402230901;5917.970410147922;6002.129691194665;6035.456808887221;6040.456358927718;6041.126628820441;6070.757169713162;6077.513798896166;6086.269647642312;5722.2561067061015;5885;5887.146363777314;5888;5900.616459930091 13:56:31 INFO - PROCESS | 27703 | |1;ext.html;616.8087892753247;631.4127861089187;648.7632014314777;652.7715299645122;667.4193451697024;34929;47329;48135.75932120343;48229;48263;46710.76644616772;47133.764331178296;47162.52837471624;47210.52789472104;47360;8600.096989816042;9233.49215793131;9392.342536022481;9394.859077113848;11025.503852326625;10757.354558726502;11258.54965801367;11281.041111505532;11284;11284.322940623548;11326.20716549842;11359.886401136007;11387.373694446802;11388.658340249805;11388.715282117972;11270.042046426022;11277.774444511146;11310.943445282803;11315.264507807049;11331.660050198507;11120.777584448266;11141.220114591983;11171.553137874474;11172.720681982933;11179.66461006171;44726.10547789058;45145.097098058184;45161.32258016132;45280;45346;3805.847766089353;3843.13529455873;3894.493715816954;3993.0416699991792;4016.4376987221813;34693.04649245646;35150.68113416753;35284.47073293903;35652.108697282514;36038.63961360405;4558.179527685015;4603.309503574457;4631.4442266927845;4636.930446043312;4716.905661886777;388253;393006;393188;394427;394515;66192.66903665451;66275.33724662692;66823;66864;66875.66562167158;40682.79658601747;41527.169456610696;41534.37698434526;43706.12587748227;70000;17475;17626.29494820193;17627.823721762874;17631.647367052843;17638.91180544089;11591.420428978518;13179.538716144889;13329.400176992058;13441.596752097452;13514.729705405836;20892;21022.36932892016;21082.156713731278;21132.78867211308;21210.621309614824;7193.784186474414;7228.181009406336;7267.672954717051;7399.371053460516;7451.627418629047;979.0810012375823;1003.247564326755;1096.353151640536;1149.0200557130813;1181.716388066875;34484.310313794085;34830.65169348325;34969.47545786815;35140.82429587836;35285.29429411398;1125.4822781520593;1159.4839198938323;1183.7573297473998;1196.3240768965507;1222.1444988507901;18310.725339119923;19383.806161938483;19933;20377.49056273605;20379;678016;1021348;1043149;1043788;1047699;66919.33080669228;67593;67812.66093669599;67896;67981.66009169923;1380.986190138106;1457.453454954392;1466.4500812195427;1471.8822494200438;1514.3185566495122;31291.687083128876;35445.82277088598;35484.29031419409;35948.64051359453;36052.639473604926;54222;54836.45163548473;54864;54880.451195487534;55035.72482137564;484798;488341;490303;490311;491390;28456.715432845405;31149.844250778602;31967.840160799045;32070;32143.678563214067;51905.7404712974;52208.10075997351;52826.47173528215;53031;53550.196747048045;42561;42648.360274596525;43332.56667433411;44171.11657766891;44894.551054490345;43487.34768978531;43652.69041928747;43719;44836.77581612071;44852.77573612111;42581;42714.35928461013;42745.358819618355;43550.346744799484;43987.78006109949;37671.443752544445;39036;39084;39209.41185882157;39602.60397395989;28930.42139157163;29211;29262.707372925997;29271.85364073166;29333.70666293395 13:56:31 INFO - PROCESS | 27703 | |2;jquery.html;410.7679161273878;411.810567139116;412.38760440745494;426.99573004269956;442.6436718441655;19591.804081959177;20999.580008399822;21265.362039138832;21391.67912481314;21780.8910955445;19938.401847944548;20127.29554465594;20426.795732042676;20634.793652063476;20858;5829.154772557977;6008.609440386361;6034.637921724689;6044.75820967163;6051.606645568046;5409.783608655649;5636.943630563683;5662.575306851982;5725.227094342273;5940.405959404046;5699.829005129853;5727;5734.885302293973;5735.08238681811;5914.053751399777;5924.881502369972;5968.462838344548;5988.7604495820115;5998.040313549855;5998.8800223995495;5829.650220986754;5983.042713165873;5986.191864098378;5988.640681559121;5990.311114221877;14920.32858521369;15132.5460236192;15417.845821541865;15477.226138693133;15583.532494025198;2697.8516181610003;2708.526007948601;2976.7023297670275;3017.4870272053804;3067.217859445827;11256.268342557789;11270.267432616936;11275.379854108021;14874.851251487455;14902.105873647615;1827.2051657528884;1909.5798924236647;1989.204318272688;1993.8734614942512;2051.8461115416403;83733.58133209356;84472.15527844765;86814.56592716996;87342.99999999936;87829;11665.708357291136;11739.178257521891;12073.91334779874;12112.515499379926;12158.878411215952;16895;16901.915490422714;16907.408240711517;16924.238409271613;16931;8091.716789912325;8395.538245396561;8469.068402475723;8623.698170564001;8684.56577171139;7843.450958432969;7865.174156713577;7908.604569771488;8090.1909809018625;8098.392620553364;5058.519440653132;5098.311727916758;5110.335656364723;5127.769250383742;5195.766190521435;2000.789917058717;2062.484378905274;2077.7714451410334;2086.467950672569;2095.8637688550502;904.31272233102;957.5308099031424;972.7033254857337;996.7906739584769;1037.4086770540719;20233.392998209783;20393.18427262893;20548.280810171873;20872;20963.895180524;1008.9495525223709;1013.1843865688191;1018.6994836523244;1031.834906414985;1036.9429681367596;10304.536295866705;11277.887221127683;11371.374574398322;11372.715682107848;11393.601223957288;534659;538998;603843;616380;631961;79954.60022699849;88025;93074;98874;103864;495.9404871415453;525.6451894970879;528.4398537550209;530.5278302310946;531.0891820527736;11396.202265841312;11718.414079295831;12296.631101066982;12570.43433045497;12611.93694031524;39262.4110638335;42743;42907;43095.56904430916;44395.33406998833;59796.40203598082;60624.39375606364;60872.69563652154;61741.07388389268;65451.67274163789;12473.87526124727;13027.869721302666;13180.736385272048;13569.321533923066;13626.250556219504;13853.445862165401;13915.234662093277;14160.504382346568;14511.1293322401;15063.472778452702;11860.94069529647;12072.698182545506;12213.755724885275;12732.681682958;12949.676258093623;11369.829452558384;12083.275003499819;12599.244045357307;13343.399547020214;13521.661958451117;12453.501859925502;12572.1199516033;12725.363731813557;12865.217737441228;12931.676708081997;12943.611691649265;13090.410931508295;13116.803247951097;13411.79293863557;13440.991925605424;12116.303717479735;12161.939190303992;12452;12530;12591.24452532851 13:56:31 INFO - PROCESS | 27703 | |3;mootools.html;1577.9132147731873;1617.4338981356525;1844.3821319857848;1906.3041989673773;1934.158640991169;9380.953095234523;10024.649137280197;10027.849582256264;10029.548670309836;10035.598576056958;8539.274161696263;9019.729408117766;9175.724728258147;9239.260859131271;9824.459654718987;2573.485302939415;2604.661394018775;2605.257501612041;2607.1005503101496;2612.699539552947;2508.4606809535944;2576.9613455798176;2578.883950222235;2579.7807186389177;2580.251726999168;2476.962845557318;2483.416397146669;2489.7012358517004;2492.7382624824313;2519.357563821223;2484.254723582927;2491.8504889706674;2494.3514686181543;2522.4702812409414;2527.646129541847;2442.05980697431;2470.2465747946867;2477.417806815397;2480.1567467061286;2484.701835779718;9753.122219000254;9799.853002204973;9877.90122098777;9919.156871665917;9919.454430006346;5820.35976042635;5985.700714964234;5990.490808281302;5995.130706047598;5995.820125396245;3617.819109044537;3714.832832522543;3795;3807.58116607173;3814.5422549293985;1189.881011898812;1218.516674962668;1248.7065539598186;1271.6121582917299;1272.198514935594;13551.39018744159;13775.448982040707;13877.306134693225;14292.785608215885;14334.498292559814;4969.378827646544;5084.237364395371;5110.48895110486;5256.658317209369;5263.131583288715;10517.368957862553;10532.736681583021;10572.15422766177;10598.576056957636;10598.788024239471;5878.676672782953;5879.823605291848;5879.911801322984;5890.087036509348;5902.144189092557;4790.8323208687525;5293.232481290268;5345.037893179187;5385.299911011543;5496.038193316153;4117.341225403981;4226.746395216235;4228.682848786353;4240.915181696349;4272.87181384559;1243.9115773698015;1281.3016905786183;1287.3949243855373;1296.500847173826;1298.2016060122955;1058.4178701714027;1058.772363941756;1072.8122578548723;1078.9892101079047;1094.3379255550437;6550;6558.639274839929;6843.213030501556;6879.793606191723;6947.062146610244;500.93988721353674;519.2004313357463;520.29500027463;1080.8054550181043;1184.2539200303754;4217.5782421758195;4364.8254069837485;4495.19086564415;4508.887277818081;4517.570830771071;12265.448054837376;12681.682957926125;12713.936430317974;12769;12774.552890648783;11828.23116497425;12363.381830908418;12398.380080995914;12657.367131643565;12710.682232944251;9179.265658747285;9346.345755797165;9380.95309523448;9381.29640276982;9388.95305523468;6416.649249052103;6665.7333706653135;6722.899156512558;6754.5947243165565;6814.352636499525;11057.668269951915;11471;11474.885251147716;11552.249103808226;11569.421528923353;11616.419179041182;11764.354751257282;11869.347185904859;11932.76134477323;12086;7504.962475187589;8197.344212463107;8386.329093672613;8427.241548260687;8582.742517724479;9668.75828104303;9673.37123086997;9688.660896868576;9744.269179811685;9745.805083898424;8557.101504341954;8589.570521473775;8660.437071590326;8720.520371379635;8756.649734010569;8490.27832634233;8552.743417697478;8579.270761985052;8690.522021288889;8705.216530512283;7785.299323060725;7808.141104478616;7822.569758663328;7828.569428681477;7847.136814950465;5463.48096930791;5505.449455054462;5535.972320138373;5658.405867383865;5703.344115426613;5737.340205876378;5822.70886455667;5864.912026319524;5923.940760592339;5955.464008239279 13:56:31 INFO - PROCESS | 27703 | |4;prototype.html;338.050079277231;341.4656063260995;344;344.07443975705365;356.71641045368926;5567.805126820562;5595.608307418477;5604.355499117602;5633.971830140844;5667.744951477183;5447.264619276407;5536.695481748502;5563.415841336662;5566.721663916808;5577.2191893135;3934.4098385242155;4142.922840061583;4306;4311.611954924057;4329.134173165364;4289.914201715964;4306.483222013363;4307.6123148916595;4312.5903039211225;4321.956780432187;4286.314189729644;4306.397104405386;4320.740755554677;4322.610965013148;4332.263515202408;4113.25961326961;4206.915861682749;4234.470691163605;4300.290452075404;4312.116016216668;3754.2866855297407;3788.204477059821;3793.468914351993;3794.5636251831115;3801.7909015004166;5378.677279363251;5399.487048730364;5405.297311349539;5406.810761623364;5419.376771671269;4754.286856971447;4863.878403039952;4888.828890988834;4917.60659147271;4973.736949687919;5691.800786972436;5691.857703557403;5708.828735137952;5764.711764411804;5766.538676905838;4205.91588168238;4208.55810139934;4208.726432781859;4212.789360531961;4218.177455396199;5591.496765291144;5691.743871525791;5745.310562732457;5885.6457417033525;5914.526837852962;4244.787760612019;4258.7657678827345;4259.61663450291;4316.352547117957;4318;4367.27939889921;4370.366296886998;4371.78141092944;4375.431193944767;4377.255866502638;4853.39332583427;4897.755112244374;4907.386576677915;4933.827316043993;4950.282209079735;5680.573956953245;5685.857853553612;5686.630369026;5695.288088988877;5744.971275143597;1483.5845963130341;1498.1610298233024;1505.9397624095132;1508.5549762819994;1511.894167408271;934.9902105725799;982.1602529837029;985.3053597213947;993.3245393132595;1004.8090862736057;4917.926231106536;4948.307236986825;4951.504849515091;4952.4304704959395;4955.108080545464;5153.8403859131395;5169.560587350042;5175.249588809602;5190.506901844319;5191.558717509054;4645.674802763774;4935.654504184745;4962.454130045692;4981.501849815062;5013.323201367769;2783.5108217104157;2788.386554957906;2789.8326100433633;2796.5245908195657;2799.384135490189;5366.887205420924;5395.70503079266;5400.648957817729;5406.188926709597;5438.483344082306;5126.436092029874;5137.867784869238;5175.844724658191;5225.6603320784025;5256.632035757537;5343.198520221998;5359;5362.624616276823;5364.624476286701;5370.543503802221;5178.482151784942;5226.790928362823;5228.424873264014;5262.657927234717;5264.657797243167;4833.830815921426;5092.428323696509;5106.310648062464;5132.948670513397;5168.328117344721;5461.890762184655;5938.138969849261;5977.850553736192;6092.939070609415;6109.69451527413;4293.935590966201;4296.269634162138;4343.131373725204;4343.739375637472;4349.151915376598;5765.221695071279;6005.297616072872;6011.158437818622;6011.398860114128;6017.909731354121;5707.486326230659;5786.218860453785;5789.652620842763;5818.4763371296785;5836.649801011953;5697.8860422790485;5736;5811.164631343293;5817.476427121579;5833.329167145833;5705.885882282414;5724.971375143097;5734.136405668091;5760.740766665426;5773.624714393551;5782.710864456676;5826.82519524415;5828.883422331614;5831.883362332815;5846;5479.506844384025;5709.714514274353;5758.971205144115;5759.740811663569;5762.337331206797 13:56:31 INFO - PROCESS | 27703 | |5;yui.html;828.6354004238135;867.8871746672932;882.4484697064335;888.2583043158966;936.1340759797188;15379.769303460444;15765.211739413013;15872.809539284572;16295.266712997915;16484.09337486439;16325.91837040816;16552;16572.254248558846;16576.254068566912;16621.750673739905;728.0462594001848;872.9694460693861;888.822235552892;891.3715830339595;891.8929728432597;871.9346049046316;893.1871996483202;896.1307531694246;901.7610333261691;902.7111324376202;944;946.2477330522252;946.4321407155686;946.6450081219542;947.2800671489683;770.6724642027116;936.3164889630608;938.924886009121;941.0542404883117;941.7833898203384;670.9060731497594;685.116200101868;689.1489011071322;689.2762599270745;689.8275431142215;15105.773413398807;15120.54638360851;15135.394584216618;15168.9381743278;15368.538943831703;15036;15200.315985780666;15215.239238038053;15295.464658736892;15421.460248891235;13200.6699832504;13362.047428223113;13416.731665366638;13787.379567919468;13838.30808459583;4631.907361852778;4831.661783675144;4880.707157570521;4935.629827762932;4952.182889823211;81005.59497202536;81133.59433202796;81689;86052.99999999937;86844.13155868424;11998.64004079879;12188.939055304843;12410.627681169579;12413.255204687748;12501.624951251477;22426;22745.886270568542;22979.96590153447;23058.884705576365;23094.7690523096;8887.73336799897;8992.370534062684;9058.592363343534;9083.18251357381;9126.680566180152;5108.106081435808;5221.712805795718;5243.370795504564;5321.042212401728;5441.238226648273;3806.904827379338;3952.1700442906736;3954.090559171349;3954.387070004154;3956.2680904032845;678.2335960364757;681.6387314723205;683.1494788987742;695.6904177641003;703.729064310234;668.5053060735021;694.8506071194718;731.0897932074602;746.8543633991319;747.6224506624229;9617.663381781604;10030.297879148535;10515.263931524873;10577.62978295756;10593.947030264799;736.7163641997762;740.6157236887796;758.8444368904361;764.9885251721229;770.0489894979696;4863.7324947128245;4915.262710593368;4958.528939750718;5040.344755181876;5063.772130254148;83373.58313208516;125238.3738081322;125547.37226313993;137583.31208344095;139520;110877;115021;116706.41646791712;116873.41563292129;118955;97316;97815;98107.99999999857;98414.50792746134;100011.49994250128;9429.387089839138;9487;9535.666251681158;9559.856602150834;9735.41587504752;52157.21764173464;53062.46937530575;57161;57312.14031789443;59854;66905.33094669186;67896;68403.31596684169;78500.60749696215;80869.59565202137;14043.1574105554;14368.13791172533;14458.361019379501;14574.562763117123;14847.109173449628;15820.446284379992;16018.439354622533;16449.753253701445;16456.753148703017;16553.586160345607;14368.712625747636;14469.782953255499;14538.273086345853;15528.37886484528;16035.118068506345;15258.389664413298;15271.236438178268;15921.283542240859;16355.100469474293;16466.09436481005;13453.058285919891;13991.790123148365;14503.85496145025;16436.260368283696;16444.83555164433;12176.695582610506;12263.325517096646;12419.875801241871;12704.6823829405;12810.935945320214;10403.53184106732;11770.470328835394;12584.18202816814;12696.809547856974;12821.431771346772 13:56:31 INFO - PROCESS | 27703 | __end_tp_report 13:56:31 INFO - PROCESS | 27703 | __start_cc_report 13:56:31 INFO - PROCESS | 27703 | _x_x_mozilla_cycle_collect,931 13:56:31 INFO - PROCESS | 27703 | __end_cc_report 13:56:31 INFO - PROCESS | 27703 | __startTimestamp1472504191807__endTimestamp 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | ------- Summary: start ------- 13:56:31 INFO - PROCESS | 27703 | Number of tests: 6 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | [#0] dojo.html Cycles:165 Average:5706.37 Median:5894.50 stddev:658.75 (11.2%) stddev-sans-first:631.81 13:56:31 INFO - PROCESS | 27703 | Values: 3243.8 3282.4 3296.1 3316.8 3319.5 5619.5 5721.8 5743.7 5759.3 5784.2 5711.9 5769.5 5769.8 5777.4 5811.7 5038.5 5103.9 5161.3 5189.5 5251.4 5138.5 5170.2 5191.9 5194.6 5242.0 5223.7 5317.6 5329.4 5333.3 5337.6 5232.2 5255.4 5317.5 5324.5 5337.3 4831.4 4999.5 5151.8 5153.0 5234.4 5805.7 5812.0 5823.0 5830.4 5841.8 6143.7 6187.9 6223.7 6228.5 6229.5 5965.1 6072.5 6142.0 6148.7 6247.3 5831.4 5838.9 5841.5 5843.7 5844.0 6318.7 6319.9 6325.8 6330.6 6335.3 5793.4 5933.2 5973.6 5991.1 6095.5 6109.3 6142.7 6145.7 6147.3 6154.5 6114.2 6126.1 6135.2 6137.4 6137.8 5894.5 5909.4 5952.8 6134.3 6191.2 4617.9 4626.7 4678.7 4679.0 4680.4 4108.2 4178.0 4185.5 4210.0 4270.4 5842.2 5846.3 5847.5 5848.4 5852.1 5795.6 6147.9 6159.9 6220.8 6225.4 5761.7 5811.2 5815.6 5884.2 5920.3 5327.3 5331.1 5357.7 5374.1 5382.0 6113.6 6426.2 6486.1 6491.8 6501.4 6067.2 6132.9 6140.3 6177.1 6209.5 6434.1 6438.7 6440.1 6443.8 6448.8 5810.7 6056.9 6071.7 6075.0 6078.0 6018.9 6096.0 6102.3 6176.0 6200.5 6010.5 6014.7 6023.0 6033.4 6057.0 5801.3 5937.1 5946.1 6006.0 6006.7 5894.9 5902.8 5918.0 6002.1 6035.5 6040.5 6041.1 6070.8 6077.5 6086.3 5722.3 5885.0 5887.1 5888.0 5900.6 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | [#1] ext.html Cycles:180 Average:76572.59 Median:39060.00 stddev:181255.83 (464.0%) stddev-sans-first:181674.59 13:56:31 INFO - PROCESS | 27703 | Values: 616.8 631.4 648.8 652.8 667.4 34929.0 47329.0 48135.8 48229.0 48263.0 46710.8 47133.8 47162.5 47210.5 47360.0 8600.1 9233.5 9392.3 9394.9 11025.5 10757.4 11258.5 11281.0 11284.0 11284.3 11326.2 11359.9 11387.4 11388.7 11388.7 11270.0 11277.8 11310.9 11315.3 11331.7 11120.8 11141.2 11171.6 11172.7 11179.7 44726.1 45145.1 45161.3 45280.0 45346.0 3805.8 3843.1 3894.5 3993.0 4016.4 34693.0 35150.7 35284.5 35652.1 36038.6 4558.2 4603.3 4631.4 4636.9 4716.9 388253.0 393006.0 393188.0 394427.0 394515.0 66192.7 66275.3 66823.0 66864.0 66875.7 40682.8 41527.2 41534.4 43706.1 70000.0 17475.0 17626.3 17627.8 17631.6 17638.9 11591.4 13179.5 13329.4 13441.6 13514.7 20892.0 21022.4 21082.2 21132.8 21210.6 7193.8 7228.2 7267.7 7399.4 7451.6 979.1 1003.2 1096.4 1149.0 1181.7 34484.3 34830.7 34969.5 35140.8 35285.3 1125.5 1159.5 1183.8 1196.3 1222.1 18310.7 19383.8 19933.0 20377.5 20379.0 678016.0 1021348.0 1043149.0 1043788.0 1047699.0 66919.3 67593.0 67812.7 67896.0 67981.7 1381.0 1457.5 1466.5 1471.9 1514.3 31291.7 35445.8 35484.3 35948.6 36052.6 54222.0 54836.5 54864.0 54880.5 55035.7 484798.0 488341.0 490303.0 490311.0 491390.0 28456.7 31149.8 31967.8 32070.0 32143.7 51905.7 52208.1 52826.5 53031.0 53550.2 42561.0 42648.4 43332.6 44171.1 44894.6 43487.3 43652.7 43719.0 44836.8 44852.8 42581.0 42714.4 42745.4 43550.3 43987.8 37671.4 39036.0 39084.0 39209.4 39602.6 28930.4 29211.0 29262.7 29271.9 29333.7 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | [#2] jquery.html Cycles:180 Average:32270.75 Median:11483.93 stddev:96326.56 (838.8%) stddev-sans-first:96567.08 13:56:31 INFO - PROCESS | 27703 | Values: 410.8 411.8 412.4 427.0 442.6 19591.8 20999.6 21265.4 21391.7 21780.9 19938.4 20127.3 20426.8 20634.8 20858.0 5829.2 6008.6 6034.6 6044.8 6051.6 5409.8 5636.9 5662.6 5725.2 5940.4 5699.8 5727.0 5734.9 5735.1 5914.1 5924.9 5968.5 5988.8 5998.0 5998.9 5829.7 5983.0 5986.2 5988.6 5990.3 14920.3 15132.5 15417.8 15477.2 15583.5 2697.9 2708.5 2976.7 3017.5 3067.2 11256.3 11270.3 11275.4 14874.9 14902.1 1827.2 1909.6 1989.2 1993.9 2051.8 83733.6 84472.2 86814.6 87343.0 87829.0 11665.7 11739.2 12073.9 12112.5 12158.9 16895.0 16901.9 16907.4 16924.2 16931.0 8091.7 8395.5 8469.1 8623.7 8684.6 7843.5 7865.2 7908.6 8090.2 8098.4 5058.5 5098.3 5110.3 5127.8 5195.8 2000.8 2062.5 2077.8 2086.5 2095.9 904.3 957.5 972.7 996.8 1037.4 20233.4 20393.2 20548.3 20872.0 20963.9 1008.9 1013.2 1018.7 1031.8 1036.9 10304.5 11277.9 11371.4 11372.7 11393.6 534659.0 538998.0 603843.0 616380.0 631961.0 79954.6 88025.0 93074.0 98874.0 103864.0 495.9 525.6 528.4 530.5 531.1 11396.2 11718.4 12296.6 12570.4 12611.9 39262.4 42743.0 42907.0 43095.6 44395.3 59796.4 60624.4 60872.7 61741.1 65451.7 12473.9 13027.9 13180.7 13569.3 13626.3 13853.4 13915.2 14160.5 14511.1 15063.5 11860.9 12072.7 12213.8 12732.7 12949.7 11369.8 12083.3 12599.2 13343.4 13521.7 12453.5 12572.1 12725.4 12865.2 12931.7 12943.6 13090.4 13116.8 13411.8 13441.0 12116.3 12161.9 12452.0 12530.0 12591.2 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | [#3] mootools.html Cycles:180 Average:6454.24 Median:4256.89 stddev:3742.91 (87.9%) stddev-sans-first:3735.48 13:56:31 INFO - PROCESS | 27703 | Values: 1577.9 1617.4 1844.4 1906.3 1934.2 9381.0 10024.6 10027.8 10029.5 10035.6 8539.3 9019.7 9175.7 9239.3 9824.5 2573.5 2604.7 2605.3 2607.1 2612.7 2508.5 2577.0 2578.9 2579.8 2580.3 2477.0 2483.4 2489.7 2492.7 2519.4 2484.3 2491.9 2494.4 2522.5 2527.6 2442.1 2470.2 2477.4 2480.2 2484.7 9753.1 9799.9 9877.9 9919.2 9919.5 5820.4 5985.7 5990.5 5995.1 5995.8 3617.8 3714.8 3795.0 3807.6 3814.5 1189.9 1218.5 1248.7 1271.6 1272.2 13551.4 13775.4 13877.3 14292.8 14334.5 4969.4 5084.2 5110.5 5256.7 5263.1 10517.4 10532.7 10572.2 10598.6 10598.8 5878.7 5879.8 5879.9 5890.1 5902.1 4790.8 5293.2 5345.0 5385.3 5496.0 4117.3 4226.7 4228.7 4240.9 4272.9 1243.9 1281.3 1287.4 1296.5 1298.2 1058.4 1058.8 1072.8 1079.0 1094.3 6550.0 6558.6 6843.2 6879.8 6947.1 500.9 519.2 520.3 1080.8 1184.3 4217.6 4364.8 4495.2 4508.9 4517.6 12265.4 12681.7 12713.9 12769.0 12774.6 11828.2 12363.4 12398.4 12657.4 12710.7 9179.3 9346.3 9381.0 9381.3 9389.0 6416.6 6665.7 6722.9 6754.6 6814.4 11057.7 11471.0 11474.9 11552.2 11569.4 11616.4 11764.4 11869.3 11932.8 12086.0 7505.0 8197.3 8386.3 8427.2 8582.7 9668.8 9673.4 9688.7 9744.3 9745.8 8557.1 8589.6 8660.4 8720.5 8756.6 8490.3 8552.7 8579.3 8690.5 8705.2 7785.3 7808.1 7822.6 7828.6 7847.1 5463.5 5505.4 5536.0 5658.4 5703.3 5737.3 5822.7 5864.9 5923.9 5955.5 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | [#4] prototype.html Cycles:180 Average:4698.89 Median:5175.55 stddev:1348.17 (26.0%) stddev-sans-first:1311.62 13:56:31 INFO - PROCESS | 27703 | Values: 338.1 341.5 344.0 344.1 356.7 5567.8 5595.6 5604.4 5634.0 5667.7 5447.3 5536.7 5563.4 5566.7 5577.2 3934.4 4142.9 4306.0 4311.6 4329.1 4289.9 4306.5 4307.6 4312.6 4322.0 4286.3 4306.4 4320.7 4322.6 4332.3 4113.3 4206.9 4234.5 4300.3 4312.1 3754.3 3788.2 3793.5 3794.6 3801.8 5378.7 5399.5 5405.3 5406.8 5419.4 4754.3 4863.9 4888.8 4917.6 4973.7 5691.8 5691.9 5708.8 5764.7 5766.5 4205.9 4208.6 4208.7 4212.8 4218.2 5591.5 5691.7 5745.3 5885.6 5914.5 4244.8 4258.8 4259.6 4316.4 4318.0 4367.3 4370.4 4371.8 4375.4 4377.3 4853.4 4897.8 4907.4 4933.8 4950.3 5680.6 5685.9 5686.6 5695.3 5745.0 1483.6 1498.2 1505.9 1508.6 1511.9 935.0 982.2 985.3 993.3 1004.8 4917.9 4948.3 4951.5 4952.4 4955.1 5153.8 5169.6 5175.2 5190.5 5191.6 4645.7 4935.7 4962.5 4981.5 5013.3 2783.5 2788.4 2789.8 2796.5 2799.4 5366.9 5395.7 5400.6 5406.2 5438.5 5126.4 5137.9 5175.8 5225.7 5256.6 5343.2 5359.0 5362.6 5364.6 5370.5 5178.5 5226.8 5228.4 5262.7 5264.7 4833.8 5092.4 5106.3 5132.9 5168.3 5461.9 5938.1 5977.9 6092.9 6109.7 4293.9 4296.3 4343.1 4343.7 4349.2 5765.2 6005.3 6011.2 6011.4 6017.9 5707.5 5786.2 5789.7 5818.5 5836.6 5697.9 5736.0 5811.2 5817.5 5833.3 5705.9 5725.0 5734.1 5760.7 5773.6 5782.7 5826.8 5828.9 5831.9 5846.0 5479.5 5709.7 5759.0 5759.7 5762.3 13:56:31 INFO - PROCESS | 27703 | 13:56:31 INFO - PROCESS | 27703 | [#5] yui.html Cycles:180 Average:22662.89 Median:4847.70 stddev:33010.57 (681.0%) stddev-sans-first:33062.46 13:56:31 INFO - PROCESS | 27703 | Values: 828.6 867.9 882.4 888.3 936.1 15379.8 15765.2 15872.8 16295.3 16484.1 16325.9 16552.0 16572.3 16576.3 16621.8 728.0 873.0 888.8 891.4 891.9 871.9 893.2 896.1 901.8 902.7 944.0 946.2 946.4 946.6 947.3 770.7 936.3 938.9 941.1 941.8 670.9 685.1 689.1 689.3 689.8 15105.8 15120.5 15135.4 15168.9 15368.5 15036.0 15200.3 15215.2 15295.5 15421.5 13200.7 13362.0 13416.7 13787.4 13838.3 4631.9 4831.7 4880.7 4935.6 4952.2 81005.6 81133.6 81689.0 86053.0 86844.1 11998.6 12188.9 12410.6 12413.3 12501.6 22426.0 22745.9 22980.0 23058.9 23094.8 8887.7 8992.4 9058.6 9083.2 9126.7 5108.1 5221.7 5243.4 5321.0 5441.2 3806.9 3952.2 3954.1 3954.4 3956.3 678.2 681.6 683.1 695.7 703.7 668.5 694.9 731.1 746.9 747.6 9617.7 10030.3 10515.3 10577.6 10593.9 736.7 740.6 758.8 765.0 770.0 4863.7 4915.3 4958.5 5040.3 5063.8 83373.6 125238.4 125547.4 137583.3 139520.0 110877.0 115021.0 116706.4 116873.4 118955.0 97316.0 97815.0 98108.0 98414.5 100011.5 9429.4 9487.0 9535.7 9559.9 9735.4 52157.2 53062.5 57161.0 57312.1 59854.0 66905.3 67896.0 68403.3 78500.6 80869.6 14043.2 14368.1 14458.4 14574.6 14847.1 15820.4 16018.4 16449.8 16456.8 16553.6 14368.7 14469.8 14538.3 15528.4 16035.1 15258.4 15271.2 15921.3 16355.1 16466.1 13453.1 13991.8 14503.9 16436.3 16444.8 12176.7 12263.3 12419.9 12704.7 12810.9 10403.5 11770.5 12584.2 12696.8 12821.4 13:56:31 INFO - PROCESS | 27703 | -------- Summary: end -------- 13:56:31 INFO - PROCESS | 27703 | 13:56:32 INFO - TEST-INFO | 27703: exit 0 13:56:32 INFO - TEST-OK | dromaeo_css | took 1113712ms 13:56:32 INFO - TEST-START | kraken 13:56:32 INFO - operating with platform_type : linux_ 13:56:32 INFO - Initialising browser for kraken test... 13:56:32 INFO - TEST-INFO | started process 27907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFUxfUy/profile http://localhost:56998/getInfo.html) 13:56:36 INFO - PROCESS | 27907 | __metrics Screen width/height:1600/1200 13:56:36 INFO - PROCESS | 27907 | colorDepth:24 13:56:36 INFO - PROCESS | 27907 | Browser inner width/height: 1024/697 13:56:36 INFO - PROCESS | 27907 | __metrics 13:56:37 INFO - PROCESS | 27907 | 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" 13:56:37 INFO - PROCESS | 27907 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:56:38 INFO - TEST-INFO | 27907: exit 0 13:56:38 INFO - Browser initialized. 13:56:38 INFO - Running cycle 1/1 for kraken test... 13:56:38 INFO - TEST-INFO | started process 27993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFUxfUy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:56:50 INFO - PROCESS | 27993 | RSS: Main: 145084416 13:56:50 INFO - PROCESS | 27993 | 13:56:52 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56998/tests/kraken/driver.html?testName=audio-beat-detection) 13:56:53 INFO - PROCESS | 27993 | RSS: Main: 191709184 13:56:53 INFO - PROCESS | 27993 | 13:56:56 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56998/tests/kraken/driver.html?testName=audio-dft) 13:56:56 INFO - PROCESS | 27993 | RSS: Main: 181583872 13:56:56 INFO - PROCESS | 27993 | 13:56:59 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56998/tests/kraken/driver.html?testName=audio-fft) 13:57:00 INFO - PROCESS | 27993 | RSS: Main: 269991936 13:57:00 INFO - PROCESS | 27993 | 13:57:02 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56998/tests/kraken/driver.html?testName=audio-oscillator) 13:57:02 INFO - PROCESS | 27993 | RSS: Main: 175992832 13:57:02 INFO - PROCESS | 27993 | 13:57:04 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56998/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:57:05 INFO - PROCESS | 27993 | RSS: Main: 177520640 13:57:05 INFO - PROCESS | 27993 | 13:57:08 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56998/tests/kraken/driver.html?testName=imaging-darkroom) 13:57:09 INFO - PROCESS | 27993 | RSS: Main: 212856832 13:57:09 INFO - PROCESS | 27993 | 13:57:12 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56998/tests/kraken/driver.html?testName=imaging-desaturate) 13:57:13 INFO - PROCESS | 27993 | RSS: Main: 211791872 13:57:13 INFO - PROCESS | 27993 | 13:57:15 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56998/tests/kraken/driver.html?testName=json-parse-financial) 13:57:16 INFO - PROCESS | 27993 | RSS: Main: 211382272 13:57:16 INFO - PROCESS | 27993 | 13:57:17 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56998/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:57:18 INFO - PROCESS | 27993 | RSS: Main: 175792128 13:57:18 INFO - PROCESS | 27993 | 13:57:20 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:57:20 INFO - PROCESS | 27993 | RSS: Main: 183128064 13:57:20 INFO - PROCESS | 27993 | 13:57:22 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:57:23 INFO - PROCESS | 27993 | RSS: Main: 186826752 13:57:23 INFO - PROCESS | 27993 | 13:57:25 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:57:26 INFO - PROCESS | 27993 | RSS: Main: 181182464 13:57:26 INFO - PROCESS | 27993 | 13:57:29 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:57:29 INFO - PROCESS | 27993 | RSS: Main: 184578048 13:57:29 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | Cycle 1(1): loaded http://localhost:56998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56998/tests/kraken/driver.html?testName=ai-astar) 13:57:31 INFO - PROCESS | 27993 | RSS: Main: 235278336 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | __start_tp_report 13:57:31 INFO - PROCESS | 27993 | _x_x_mozilla_page_load 13:57:31 INFO - PROCESS | 27993 | _x_x_mozilla_page_load_details 13:57:31 INFO - PROCESS | 27993 | |i|pagename|runs| 13:57:31 INFO - PROCESS | 27993 | |0;ai-astar;100;110;110;110;110;108;109;109;110;109 13:57:31 INFO - PROCESS | 27993 | |1;audio-beat-detection;139;115;116;168;115;123;137;116;146;119 13:57:31 INFO - PROCESS | 27993 | |2;audio-dft;160;160;162;161;162;159;161;163;159;163 13:57:31 INFO - PROCESS | 27993 | |3;audio-fft;75;75;74;107;75;75;75;76;99;74 13:57:31 INFO - PROCESS | 27993 | |4;audio-oscillator;83;83;133;83;84;84;123;83;83;128 13:57:31 INFO - PROCESS | 27993 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;117;117;117 13:57:31 INFO - PROCESS | 27993 | |6;imaging-darkroom;146;147;146;147;146;146;146;146;146;145 13:57:31 INFO - PROCESS | 27993 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 13:57:31 INFO - PROCESS | 27993 | |8;json-parse-financial;50;51;53;51;52;51;51;50;50;52 13:57:31 INFO - PROCESS | 27993 | |9;json-stringify-tinderbox;60;60;60;59;60;59;59;59;60;61 13:57:31 INFO - PROCESS | 27993 | |10;stanford-crypto-aes;72;73;72;72;72;72;72;73;81;72 13:57:31 INFO - PROCESS | 27993 | |11;stanford-crypto-ccm;118;125;126;115;126;118;115;124;154;121 13:57:31 INFO - PROCESS | 27993 | |12;stanford-crypto-pbkdf2;149;150;151;153;149;152;162;153;150;153 13:57:31 INFO - PROCESS | 27993 | |13;stanford-crypto-sha256-iterative;64;63;64;61;62;64;61;61;60;60 13:57:31 INFO - PROCESS | 27993 | __end_tp_report 13:57:31 INFO - PROCESS | 27993 | __start_cc_report 13:57:31 INFO - PROCESS | 27993 | _x_x_mozilla_cycle_collect,1626 13:57:31 INFO - PROCESS | 27993 | __end_cc_report 13:57:31 INFO - PROCESS | 27993 | __startTimestamp1472504251555__endTimestamp 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | ------- Summary: start ------- 13:57:31 INFO - PROCESS | 27993 | Number of tests: 14 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.06 (2.8%) stddev-sans-first:0.73 13:57:31 INFO - PROCESS | 27993 | Values: 100.0 110.0 110.0 110.0 110.0 108.0 109.0 109.0 110.0 109.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:130.00 stddev:17.76 (13.7%) stddev-sans-first:18.49 13:57:31 INFO - PROCESS | 27993 | Values: 139.0 115.0 116.0 168.0 115.0 123.0 137.0 116.0 146.0 119.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.49 (0.9%) stddev-sans-first:1.54 13:57:31 INFO - PROCESS | 27993 | Values: 160.0 160.0 162.0 161.0 162.0 159.0 161.0 163.0 159.0 163.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.00 stddev:12.02 (16.0%) stddev-sans-first:12.58 13:57:31 INFO - PROCESS | 27993 | Values: 75.0 75.0 74.0 107.0 75.0 75.0 75.0 76.0 99.0 74.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:83.00 stddev:21.73 (26.2%) stddev-sans-first:22.48 13:57:31 INFO - PROCESS | 27993 | Values: 83.0 83.0 133.0 83.0 84.0 84.0 123.0 83.0 83.0 128.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:57:31 INFO - PROCESS | 27993 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 13:57:31 INFO - PROCESS | 27993 | Values: 146.0 147.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 145.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 13:57:31 INFO - PROCESS | 27993 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.99 (1.9%) stddev-sans-first:0.97 13:57:31 INFO - PROCESS | 27993 | Values: 50.0 51.0 53.0 51.0 52.0 51.0 51.0 50.0 50.0 52.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 13:57:31 INFO - PROCESS | 27993 | Values: 60.0 60.0 60.0 59.0 60.0 59.0 59.0 59.0 60.0 61.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:72.00 stddev:2.81 (3.9%) stddev-sans-first:2.95 13:57:31 INFO - PROCESS | 27993 | Values: 72.0 73.0 72.0 72.0 72.0 72.0 72.0 73.0 81.0 72.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:124.50 stddev:11.31 (9.1%) stddev-sans-first:11.77 13:57:31 INFO - PROCESS | 27993 | Values: 118.0 125.0 126.0 115.0 126.0 118.0 115.0 124.0 154.0 121.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:152.50 stddev:3.79 (2.5%) stddev-sans-first:3.84 13:57:31 INFO - PROCESS | 27993 | Values: 149.0 150.0 151.0 153.0 149.0 152.0 162.0 153.0 150.0 153.0 13:57:31 INFO - PROCESS | 27993 | 13:57:31 INFO - PROCESS | 27993 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.50 stddev:1.63 (2.6%) stddev-sans-first:1.56 13:57:31 INFO - PROCESS | 27993 | Values: 64.0 63.0 64.0 61.0 62.0 64.0 61.0 61.0 60.0 60.0 13:57:31 INFO - PROCESS | 27993 | -------- Summary: end -------- 13:57:31 INFO - PROCESS | 27993 | 13:57:32 INFO - TEST-INFO | 27993: exit 0 13:57:32 INFO - TEST-OK | kraken | took 60022ms 13:57:32 INFO - SUITE-END | took 1173s 13:57:32 INFO - Completed test suite (00:19:33) 13:57:32 INFO - javascript benchmark 13:57:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3243.8215898125604, 3282.4255755242843, 3296.077098412444, 3316.7678262521617, 3319.4522903720886, 5619.522340601049, 5721.799737009205, 5743.741531631076, 5759.337676167242, 5784.161296611994, 5711.942880571193, 5769.451902069297, 5769.82690519284, 5777.422257774231, 5811.6803575803315, 5038.546530812227, 5103.872403189913, 5161.30322406475, 5189.481051894818, 5251.448597897223, 5138.511841375063, 5170.172772356424, 5191.870203244911, 5194.58443324535, 5242.0, 5223.660462069954, 5317.627766056377, 5329.360476742777, 5333.253344531769, 5337.572994160477, 5232.162853943389, 5255.369355677305, 5317.494837990384, 5324.520793128599, 5337.306150200468, 4831.4202295724735, 4999.500049995007, 5151.763576741592, 5153.0, 5234.42421333653, 5805.7387417566315, 5812.0, 5823.0, 5830.387809280007, 5841.824745257649, 6143.723532441051, 6187.9480488317095, 6223.719932603044, 6228.532860035515, 6229.532785041095, 5965.075413310944, 6072.493565586228, 6142.0, 6148.6618235997, 6247.344028876993, 5831.3877042910735, 5838.9124163137585, 5841.503472204868, 5843.678597677126, 5844.0, 6318.71565779541, 6319.936800631935, 6325.7785977491485, 6330.588511746721, 6335.334789847092, 5793.420657934173, 5933.169356290123, 5973.581849270597, 5991.131285963511, 6095.451409373178, 6109.2528441424865, 6142.692865356715, 6145.692715364214, 6147.323794382614, 6154.5384096192965, 6114.205153330127, 6126.090206827809, 6135.171422439398, 6137.407629318989, 6137.8060289513105, 5894.498967587718, 5909.409059094142, 5952.761889524457, 6134.273085652824, 6191.1641928339095, 4617.930731039037, 4626.699264547794, 4678.742669153161, 4678.95321046792, 4680.368150299734, 4108.1783643270865, 4177.979110104491, 4185.497740271187, 4210.031692710693, 4270.44484217056, 5842.152887831241, 5846.298444186725, 5847.532197424196, 5848.444397782204, 5852.063669812809, 5795.594308398372, 6147.907781383283, 6159.938400616026, 6220.782272620437, 6225.439710426083, 5761.683107429048, 5811.157382179645, 5815.621984570989, 5884.235049443545, 5920.259967504062, 5327.3074500314715, 5331.120365139708, 5357.678539287655, 5374.086405311106, 5382.0, 6113.628337950562, 6426.228852537632, 6486.091947127312, 6491.805245842632, 6501.382368674968, 6067.180930574493, 6132.938670613236, 6140.293866205444, 6177.135201071944, 6209.534284928739, 6434.099226108442, 6438.742450302123, 6440.066190402272, 6443.806685799434, 6448.774292899726, 5810.68041257718, 6056.909756243918, 6071.724937762942, 6075.0, 6078.0, 6018.8796224074395, 6095.994160963392, 6102.267727872683, 6176.0, 6200.534959877937, 6010.459058684739, 6014.72933717975, 6022.969885150546, 6033.367178323181, 6056.9697151513965, 5801.274840644919, 5937.109433584997, 5946.137810017437, 6005.969970150296, 6006.738584897046, 5894.941050589439, 5902.793402230901, 5917.970410147922, 6002.129691194665, 6035.456808887221, 6040.456358927718, 6041.126628820441, 6070.757169713162, 6077.513798896166, 6086.269647642312, 5722.2561067061015, 5885.0, 5887.146363777314, 5888.0, 5900.616459930091], "value": 5661.17741329904, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.8087892753247, 631.4127861089187, 648.7632014314777, 652.7715299645122, 667.4193451697024, 34929.0, 47329.0, 48135.75932120343, 48229.0, 48263.0, 46710.76644616772, 47133.764331178296, 47162.52837471624, 47210.52789472104, 47360.0, 8600.096989816042, 9233.49215793131, 9392.342536022481, 9394.859077113848, 11025.503852326625, 10757.354558726502, 11258.54965801367, 11281.041111505532, 11284.0, 11284.322940623548, 11326.20716549842, 11359.886401136007, 11387.373694446802, 11388.658340249805, 11388.715282117972, 11270.042046426022, 11277.774444511146, 11310.943445282803, 11315.264507807049, 11331.660050198507, 11120.777584448266, 11141.220114591983, 11171.553137874474, 11172.720681982933, 11179.66461006171, 44726.10547789058, 45145.097098058184, 45161.32258016132, 45280.0, 45346.0, 3805.847766089353, 3843.13529455873, 3894.493715816954, 3993.0416699991792, 4016.4376987221813, 34693.04649245646, 35150.68113416753, 35284.47073293903, 35652.108697282514, 36038.63961360405, 4558.179527685015, 4603.309503574457, 4631.4442266927845, 4636.930446043312, 4716.905661886777, 388253.0, 393006.0, 393188.0, 394427.0, 394515.0, 66192.66903665451, 66275.33724662692, 66823.0, 66864.0, 66875.66562167158, 40682.79658601747, 41527.169456610696, 41534.37698434526, 43706.12587748227, 70000.0, 17475.0, 17626.29494820193, 17627.823721762874, 17631.647367052843, 17638.91180544089, 11591.420428978518, 13179.538716144889, 13329.400176992058, 13441.596752097452, 13514.729705405836, 20892.0, 21022.36932892016, 21082.156713731278, 21132.78867211308, 21210.621309614824, 7193.784186474414, 7228.181009406336, 7267.672954717051, 7399.371053460516, 7451.627418629047, 979.0810012375823, 1003.247564326755, 1096.353151640536, 1149.0200557130813, 1181.716388066875, 34484.310313794085, 34830.65169348325, 34969.47545786815, 35140.82429587836, 35285.29429411398, 1125.4822781520593, 1159.4839198938323, 1183.7573297473998, 1196.3240768965507, 1222.1444988507901, 18310.725339119923, 19383.806161938483, 19933.0, 20377.49056273605, 20379.0, 678016.0, 1021348.0, 1043149.0, 1043788.0, 1047699.0, 66919.33080669228, 67593.0, 67812.66093669599, 67896.0, 67981.66009169923, 1380.986190138106, 1457.453454954392, 1466.4500812195427, 1471.8822494200438, 1514.3185566495122, 31291.687083128876, 35445.82277088598, 35484.29031419409, 35948.64051359453, 36052.639473604926, 54222.0, 54836.45163548473, 54864.0, 54880.451195487534, 55035.72482137564, 484798.0, 488341.0, 490303.0, 490311.0, 491390.0, 28456.715432845405, 31149.844250778602, 31967.840160799045, 32070.0, 32143.678563214067, 51905.7404712974, 52208.10075997351, 52826.47173528215, 53031.0, 53550.196747048045, 42561.0, 42648.360274596525, 43332.56667433411, 44171.11657766891, 44894.551054490345, 43487.34768978531, 43652.69041928747, 43719.0, 44836.77581612071, 44852.77573612111, 42581.0, 42714.35928461013, 42745.358819618355, 43550.346744799484, 43987.78006109949, 37671.443752544445, 39036.0, 39084.0, 39209.41185882157, 39602.60397395989, 28930.42139157163, 29211.0, 29262.707372925997, 29271.85364073166, 29333.70666293395], "value": 22254.213113274447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.7679161273878, 411.810567139116, 412.38760440745494, 426.99573004269956, 442.6436718441655, 19591.804081959177, 20999.580008399822, 21265.362039138832, 21391.67912481314, 21780.8910955445, 19938.401847944548, 20127.29554465594, 20426.795732042676, 20634.793652063476, 20858.0, 5829.154772557977, 6008.609440386361, 6034.637921724689, 6044.75820967163, 6051.606645568046, 5409.783608655649, 5636.943630563683, 5662.575306851982, 5725.227094342273, 5940.405959404046, 5699.829005129853, 5727.0, 5734.885302293973, 5735.08238681811, 5914.053751399777, 5924.881502369972, 5968.462838344548, 5988.7604495820115, 5998.040313549855, 5998.8800223995495, 5829.650220986754, 5983.042713165873, 5986.191864098378, 5988.640681559121, 5990.311114221877, 14920.32858521369, 15132.5460236192, 15417.845821541865, 15477.226138693133, 15583.532494025198, 2697.8516181610003, 2708.526007948601, 2976.7023297670275, 3017.4870272053804, 3067.217859445827, 11256.268342557789, 11270.267432616936, 11275.379854108021, 14874.851251487455, 14902.105873647615, 1827.2051657528884, 1909.5798924236647, 1989.204318272688, 1993.8734614942512, 2051.8461115416403, 83733.58133209356, 84472.15527844765, 86814.56592716996, 87342.99999999936, 87829.0, 11665.708357291136, 11739.178257521891, 12073.91334779874, 12112.515499379926, 12158.878411215952, 16895.0, 16901.915490422714, 16907.408240711517, 16924.238409271613, 16931.0, 8091.716789912325, 8395.538245396561, 8469.068402475723, 8623.698170564001, 8684.56577171139, 7843.450958432969, 7865.174156713577, 7908.604569771488, 8090.1909809018625, 8098.392620553364, 5058.519440653132, 5098.311727916758, 5110.335656364723, 5127.769250383742, 5195.766190521435, 2000.789917058717, 2062.484378905274, 2077.7714451410334, 2086.467950672569, 2095.8637688550502, 904.31272233102, 957.5308099031424, 972.7033254857337, 996.7906739584769, 1037.4086770540719, 20233.392998209783, 20393.18427262893, 20548.280810171873, 20872.0, 20963.895180524, 1008.9495525223709, 1013.1843865688191, 1018.6994836523244, 1031.834906414985, 1036.9429681367596, 10304.536295866705, 11277.887221127683, 11371.374574398322, 11372.715682107848, 11393.601223957288, 534659.0, 538998.0, 603843.0, 616380.0, 631961.0, 79954.60022699849, 88025.0, 93074.0, 98874.0, 103864.0, 495.9404871415453, 525.6451894970879, 528.4398537550209, 530.5278302310946, 531.0891820527736, 11396.202265841312, 11718.414079295831, 12296.631101066982, 12570.43433045497, 12611.93694031524, 39262.4110638335, 42743.0, 42907.0, 43095.56904430916, 44395.33406998833, 59796.40203598082, 60624.39375606364, 60872.69563652154, 61741.07388389268, 65451.67274163789, 12473.87526124727, 13027.869721302666, 13180.736385272048, 13569.321533923066, 13626.250556219504, 13853.445862165401, 13915.234662093277, 14160.504382346568, 14511.1293322401, 15063.472778452702, 11860.94069529647, 12072.698182545506, 12213.755724885275, 12732.681682958, 12949.676258093623, 11369.829452558384, 12083.275003499819, 12599.244045357307, 13343.399547020214, 13521.661958451117, 12453.501859925502, 12572.1199516033, 12725.363731813557, 12865.217737441228, 12931.676708081997, 12943.611691649265, 13090.410931508295, 13116.803247951097, 13411.79293863557, 13440.991925605424, 12116.303717479735, 12161.939190303992, 12452.0, 12530.0, 12591.24452532851], "value": 9582.888745358847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1577.9132147731873, 1617.4338981356525, 1844.3821319857848, 1906.3041989673773, 1934.158640991169, 9380.953095234523, 10024.649137280197, 10027.849582256264, 10029.548670309836, 10035.598576056958, 8539.274161696263, 9019.729408117766, 9175.724728258147, 9239.260859131271, 9824.459654718987, 2573.485302939415, 2604.661394018775, 2605.257501612041, 2607.1005503101496, 2612.699539552947, 2508.4606809535944, 2576.9613455798176, 2578.883950222235, 2579.7807186389177, 2580.251726999168, 2476.962845557318, 2483.416397146669, 2489.7012358517004, 2492.7382624824313, 2519.357563821223, 2484.254723582927, 2491.8504889706674, 2494.3514686181543, 2522.4702812409414, 2527.646129541847, 2442.05980697431, 2470.2465747946867, 2477.417806815397, 2480.1567467061286, 2484.701835779718, 9753.122219000254, 9799.853002204973, 9877.90122098777, 9919.156871665917, 9919.454430006346, 5820.35976042635, 5985.700714964234, 5990.490808281302, 5995.130706047598, 5995.820125396245, 3617.819109044537, 3714.832832522543, 3795.0, 3807.58116607173, 3814.5422549293985, 1189.881011898812, 1218.516674962668, 1248.7065539598186, 1271.6121582917299, 1272.198514935594, 13551.39018744159, 13775.448982040707, 13877.306134693225, 14292.785608215885, 14334.498292559814, 4969.378827646544, 5084.237364395371, 5110.48895110486, 5256.658317209369, 5263.131583288715, 10517.368957862553, 10532.736681583021, 10572.15422766177, 10598.576056957636, 10598.788024239471, 5878.676672782953, 5879.823605291848, 5879.911801322984, 5890.087036509348, 5902.144189092557, 4790.8323208687525, 5293.232481290268, 5345.037893179187, 5385.299911011543, 5496.038193316153, 4117.341225403981, 4226.746395216235, 4228.682848786353, 4240.915181696349, 4272.87181384559, 1243.9115773698015, 1281.3016905786183, 1287.3949243855373, 1296.500847173826, 1298.2016060122955, 1058.4178701714027, 1058.772363941756, 1072.8122578548723, 1078.9892101079047, 1094.3379255550437, 6550.0, 6558.639274839929, 6843.213030501556, 6879.793606191723, 6947.062146610244, 500.93988721353674, 519.2004313357463, 520.29500027463, 1080.8054550181043, 1184.2539200303754, 4217.5782421758195, 4364.8254069837485, 4495.19086564415, 4508.887277818081, 4517.570830771071, 12265.448054837376, 12681.682957926125, 12713.936430317974, 12769.0, 12774.552890648783, 11828.23116497425, 12363.381830908418, 12398.380080995914, 12657.367131643565, 12710.682232944251, 9179.265658747285, 9346.345755797165, 9380.95309523448, 9381.29640276982, 9388.95305523468, 6416.649249052103, 6665.7333706653135, 6722.899156512558, 6754.5947243165565, 6814.352636499525, 11057.668269951915, 11471.0, 11474.885251147716, 11552.249103808226, 11569.421528923353, 11616.419179041182, 11764.354751257282, 11869.347185904859, 11932.76134477323, 12086.0, 7504.962475187589, 8197.344212463107, 8386.329093672613, 8427.241548260687, 8582.742517724479, 9668.75828104303, 9673.37123086997, 9688.660896868576, 9744.269179811685, 9745.805083898424, 8557.101504341954, 8589.570521473775, 8660.437071590326, 8720.520371379635, 8756.649734010569, 8490.27832634233, 8552.743417697478, 8579.270761985052, 8690.522021288889, 8705.216530512283, 7785.299323060725, 7808.141104478616, 7822.569758663328, 7828.569428681477, 7847.136814950465, 5463.48096930791, 5505.449455054462, 5535.972320138373, 5658.405867383865, 5703.344115426613, 5737.340205876378, 5822.70886455667, 5864.912026319524, 5923.940760592339, 5955.464008239279], "value": 5092.439719858872, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.050079277231, 341.4656063260995, 344.0, 344.07443975705365, 356.71641045368926, 5567.805126820562, 5595.608307418477, 5604.355499117602, 5633.971830140844, 5667.744951477183, 5447.264619276407, 5536.695481748502, 5563.415841336662, 5566.721663916808, 5577.2191893135, 3934.4098385242155, 4142.922840061583, 4306.0, 4311.611954924057, 4329.134173165364, 4289.914201715964, 4306.483222013363, 4307.6123148916595, 4312.5903039211225, 4321.956780432187, 4286.314189729644, 4306.397104405386, 4320.740755554677, 4322.610965013148, 4332.263515202408, 4113.25961326961, 4206.915861682749, 4234.470691163605, 4300.290452075404, 4312.116016216668, 3754.2866855297407, 3788.204477059821, 3793.468914351993, 3794.5636251831115, 3801.7909015004166, 5378.677279363251, 5399.487048730364, 5405.297311349539, 5406.810761623364, 5419.376771671269, 4754.286856971447, 4863.878403039952, 4888.828890988834, 4917.60659147271, 4973.736949687919, 5691.800786972436, 5691.857703557403, 5708.828735137952, 5764.711764411804, 5766.538676905838, 4205.91588168238, 4208.55810139934, 4208.726432781859, 4212.789360531961, 4218.177455396199, 5591.496765291144, 5691.743871525791, 5745.310562732457, 5885.6457417033525, 5914.526837852962, 4244.787760612019, 4258.7657678827345, 4259.61663450291, 4316.352547117957, 4318.0, 4367.27939889921, 4370.366296886998, 4371.78141092944, 4375.431193944767, 4377.255866502638, 4853.39332583427, 4897.755112244374, 4907.386576677915, 4933.827316043993, 4950.282209079735, 5680.573956953245, 5685.857853553612, 5686.630369026, 5695.288088988877, 5744.971275143597, 1483.5845963130341, 1498.1610298233024, 1505.9397624095132, 1508.5549762819994, 1511.894167408271, 934.9902105725799, 982.1602529837029, 985.3053597213947, 993.3245393132595, 1004.8090862736057, 4917.926231106536, 4948.307236986825, 4951.504849515091, 4952.4304704959395, 4955.108080545464, 5153.8403859131395, 5169.560587350042, 5175.249588809602, 5190.506901844319, 5191.558717509054, 4645.674802763774, 4935.654504184745, 4962.454130045692, 4981.501849815062, 5013.323201367769, 2783.5108217104157, 2788.386554957906, 2789.8326100433633, 2796.5245908195657, 2799.384135490189, 5366.887205420924, 5395.70503079266, 5400.648957817729, 5406.188926709597, 5438.483344082306, 5126.436092029874, 5137.867784869238, 5175.844724658191, 5225.6603320784025, 5256.632035757537, 5343.198520221998, 5359.0, 5362.624616276823, 5364.624476286701, 5370.543503802221, 5178.482151784942, 5226.790928362823, 5228.424873264014, 5262.657927234717, 5264.657797243167, 4833.830815921426, 5092.428323696509, 5106.310648062464, 5132.948670513397, 5168.328117344721, 5461.890762184655, 5938.138969849261, 5977.850553736192, 6092.939070609415, 6109.69451527413, 4293.935590966201, 4296.269634162138, 4343.131373725204, 4343.739375637472, 4349.151915376598, 5765.221695071279, 6005.297616072872, 6011.158437818622, 6011.398860114128, 6017.909731354121, 5707.486326230659, 5786.218860453785, 5789.652620842763, 5818.4763371296785, 5836.649801011953, 5697.8860422790485, 5736.0, 5811.164631343293, 5817.476427121579, 5833.329167145833, 5705.885882282414, 5724.971375143097, 5734.136405668091, 5760.740766665426, 5773.624714393551, 5782.710864456676, 5826.82519524415, 5828.883422331614, 5831.883362332815, 5846.0, 5479.506844384025, 5709.714514274353, 5758.971205144115, 5759.740811663569, 5762.337331206797], "value": 4274.512944521368, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.6354004238135, 867.8871746672932, 882.4484697064335, 888.2583043158966, 936.1340759797188, 15379.769303460444, 15765.211739413013, 15872.809539284572, 16295.266712997915, 16484.09337486439, 16325.91837040816, 16552.0, 16572.254248558846, 16576.254068566912, 16621.750673739905, 728.0462594001848, 872.9694460693861, 888.822235552892, 891.3715830339595, 891.8929728432597, 871.9346049046316, 893.1871996483202, 896.1307531694246, 901.7610333261691, 902.7111324376202, 944.0, 946.2477330522252, 946.4321407155686, 946.6450081219542, 947.2800671489683, 770.6724642027116, 936.3164889630608, 938.924886009121, 941.0542404883117, 941.7833898203384, 670.9060731497594, 685.116200101868, 689.1489011071322, 689.2762599270745, 689.8275431142215, 15105.773413398807, 15120.54638360851, 15135.394584216618, 15168.9381743278, 15368.538943831703, 15036.0, 15200.315985780666, 15215.239238038053, 15295.464658736892, 15421.460248891235, 13200.6699832504, 13362.047428223113, 13416.731665366638, 13787.379567919468, 13838.30808459583, 4631.907361852778, 4831.661783675144, 4880.707157570521, 4935.629827762932, 4952.182889823211, 81005.59497202536, 81133.59433202796, 81689.0, 86052.99999999937, 86844.13155868424, 11998.64004079879, 12188.939055304843, 12410.627681169579, 12413.255204687748, 12501.624951251477, 22426.0, 22745.886270568542, 22979.96590153447, 23058.884705576365, 23094.7690523096, 8887.73336799897, 8992.370534062684, 9058.592363343534, 9083.18251357381, 9126.680566180152, 5108.106081435808, 5221.712805795718, 5243.370795504564, 5321.042212401728, 5441.238226648273, 3806.904827379338, 3952.1700442906736, 3954.090559171349, 3954.387070004154, 3956.2680904032845, 678.2335960364757, 681.6387314723205, 683.1494788987742, 695.6904177641003, 703.729064310234, 668.5053060735021, 694.8506071194718, 731.0897932074602, 746.8543633991319, 747.6224506624229, 9617.663381781604, 10030.297879148535, 10515.263931524873, 10577.62978295756, 10593.947030264799, 736.7163641997762, 740.6157236887796, 758.8444368904361, 764.9885251721229, 770.0489894979696, 4863.7324947128245, 4915.262710593368, 4958.528939750718, 5040.344755181876, 5063.772130254148, 83373.58313208516, 125238.3738081322, 125547.37226313993, 137583.31208344095, 139520.0, 110877.0, 115021.0, 116706.41646791712, 116873.41563292129, 118955.0, 97316.0, 97815.0, 98107.99999999857, 98414.50792746134, 100011.49994250128, 9429.387089839138, 9487.0, 9535.666251681158, 9559.856602150834, 9735.41587504752, 52157.21764173464, 53062.46937530575, 57161.0, 57312.14031789443, 59854.0, 66905.33094669186, 67896.0, 68403.31596684169, 78500.60749696215, 80869.59565202137, 14043.1574105554, 14368.13791172533, 14458.361019379501, 14574.562763117123, 14847.109173449628, 15820.446284379992, 16018.439354622533, 16449.753253701445, 16456.753148703017, 16553.586160345607, 14368.712625747636, 14469.782953255499, 14538.273086345853, 15528.37886484528, 16035.118068506345, 15258.389664413298, 15271.236438178268, 15921.283542240859, 16355.100469474293, 16466.09436481005, 13453.058285919891, 13991.790123148365, 14503.85496145025, 16436.260368283696, 16444.83555164433, 12176.695582610506, 12263.325517096646, 12419.875801241871, 12704.6823829405, 12810.935945320214, 10403.53184106732, 11770.470328835394, 12584.18202816814, 12696.809547856974, 12821.431771346772], "value": 8228.968432273035, "unit": "score"}], "name": "dromaeo_css", "value": 7747.644920973852, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 110.0, 110.0, 110.0, 108.0, 109.0, 109.0, 110.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 115.0, 116.0, 168.0, 115.0, 123.0, 137.0, 116.0, 146.0, 119.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 161.0, 162.0, 159.0, 161.0, 163.0, 159.0, 163.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 107.0, 75.0, 75.0, 75.0, 76.0, 99.0, 74.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 133.0, 83.0, 84.0, 84.0, 123.0, 83.0, 83.0, 128.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 53.0, 51.0, 52.0, 51.0, 51.0, 50.0, 50.0, 52.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 61.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 72.0, 72.0, 72.0, 72.0, 73.0, 81.0, 72.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 125.0, 126.0, 115.0, 126.0, 118.0, 115.0, 124.0, 154.0, 121.0], "value": 124.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 151.0, 153.0, 149.0, 152.0, 162.0, 153.0, 150.0, 153.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 61.0, 62.0, 64.0, 61.0, 61.0, 60.0, 60.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1456.2, "alertThreshold": 2.0}]} 13:57:32 INFO - Return code: 0 13:57:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:57:32 INFO - # TBPL SUCCESS # 13:57:32 INFO - Running post-action listener: _resource_record_post_action 13:57:32 INFO - [mozharness: 2016-08-29 20:57:32.872928Z] Finished run-tests step (success) 13:57:32 INFO - Running post-run listener: _resource_record_post_run 13:57:32 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 270336; Write bytes: 329207808; Read time: 284; Write time: 248256 13:57:32 INFO - TinderboxPrint: CPU usage
13.0% 13:57:32 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 284 13:57:32 INFO - TinderboxPrint: I/O write bytes / time
329,207,808 / 248,256 13:57:32 INFO - TinderboxPrint: CPU idle
8,269.0 (87.0%) 13:57:32 INFO - TinderboxPrint: CPU user
1,189.8 (12.5%) 13:57:32 INFO - TinderboxPrint: Swap in / out
0 / 0 13:57:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79708160; Read time: 0; Write time: 79744 13:57:32 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249499648; Read time: 272; Write time: 168512 13:57:33 INFO - Running post-run listener: _upload_blobber_files 13:57:33 INFO - Blob upload gear active. 13:57:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:57:33 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 13:57:33 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'] 13:57:33 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 13:57:33 INFO - (blobuploader) - INFO - Open directory for files ... 13:57:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:57:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:57:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:57:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:57:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:57:35 INFO - (blobuploader) - INFO - Done attempting. 13:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:57:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:57:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:57:35 INFO - (blobuploader) - INFO - Done attempting. 13:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:57:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:57:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:57:39 INFO - (blobuploader) - INFO - Done attempting. 13:57:39 INFO - (blobuploader) - INFO - Iteration through files over. 13:57:39 INFO - Return code: 0 13:57:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:57:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:57:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8555cd41233a048dd965c1a8a451f6b20e0e2ab1b4dd3e70354736c3c9196b18884fc23da0a06ab2c2330e5b40f1577c63096bdbf3300f1af4b668ee24b936d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6152191a7947158c3f78d481f010811dd30f57a321bd15a94096ab37d39f09381f862545c7acb51b4b02232051f59faa57cc7350a0eff80627f62d6017503c34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49edc5e355738db73951f6dd748b186c447a209efd35743c1123e80c671a4f43afb92fb7dd9ffcb9c387a00457e7c15ffb254fcec71ae5a571d655487fddcc6c"} 13:57:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:57:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:57:39 INFO - Contents: 13:57:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8555cd41233a048dd965c1a8a451f6b20e0e2ab1b4dd3e70354736c3c9196b18884fc23da0a06ab2c2330e5b40f1577c63096bdbf3300f1af4b668ee24b936d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6152191a7947158c3f78d481f010811dd30f57a321bd15a94096ab37d39f09381f862545c7acb51b4b02232051f59faa57cc7350a0eff80627f62d6017503c34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49edc5e355738db73951f6dd748b186c447a209efd35743c1123e80c671a4f43afb92fb7dd9ffcb9c387a00457e7c15ffb254fcec71ae5a571d655487fddcc6c"} 13:57:39 INFO - Running post-run listener: copy_logs_to_upload_dir 13:57:39 INFO - Copying logs to upload dir... 13:57:39 INFO - mkdir: /builds/slave/test/build/upload/logs 13:57:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.999970 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-29 13:57:39.260117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 13:57:39.263449) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563 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/8555cd41233a048dd965c1a8a451f6b20e0e2ab1b4dd3e70354736c3c9196b18884fc23da0a06ab2c2330e5b40f1577c63096bdbf3300f1af4b668ee24b936d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6152191a7947158c3f78d481f010811dd30f57a321bd15a94096ab37d39f09381f862545c7acb51b4b02232051f59faa57cc7350a0eff80627f62d6017503c34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49edc5e355738db73951f6dd748b186c447a209efd35743c1123e80c671a4f43afb92fb7dd9ffcb9c387a00457e7c15ffb254fcec71ae5a571d655487fddcc6c"} build_url:https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/cQZgZ3OATKiRrUDD4u0HFw/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/8555cd41233a048dd965c1a8a451f6b20e0e2ab1b4dd3e70354736c3c9196b18884fc23da0a06ab2c2330e5b40f1577c63096bdbf3300f1af4b668ee24b936d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6152191a7947158c3f78d481f010811dd30f57a321bd15a94096ab37d39f09381f862545c7acb51b4b02232051f59faa57cc7350a0eff80627f62d6017503c34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49edc5e355738db73951f6dd748b186c447a209efd35743c1123e80c671a4f43afb92fb7dd9ffcb9c387a00457e7c15ffb254fcec71ae5a571d655487fddcc6c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 13:57:39.288636) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:57:39.288971) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472502576.966761-989078563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:57:39.373571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 13:57:39.373899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 13:57:39.374192) ========= ========= Total master_lag: 0.15 =========