builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1474324252.09 results: success (0) buildid: 20160919064031 builduid: ae1839bfaf4840dab36e395afce28275 revision: 01d67bfe6c8198416ec190cbc216d953c1625b94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.091052) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.091513) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.091800) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.106655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.107073) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 15:30:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 98.7M=0s 2016-09-19 15:30:52 (98.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121731 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.242670) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.242998) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018450 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:52.295266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 15:30:52.296121) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 01d67bfe6c8198416ec190cbc216d953c1625b94 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 01d67bfe6c8198416ec190cbc216d953c1625b94 --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=a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 15:30:52,934 truncating revision to first 12 chars 2016-09-19 15:30:52,934 Setting DEBUG logging. 2016-09-19 15:30:52,934 attempt 1/10 2016-09-19 15:30:52,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/01d67bfe6c81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 15:30:53,881 unpacking tar archive at: mozilla-beta-01d67bfe6c81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.271649 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 15:30:53.583469) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:53.583800) ========= script_repo_revision: 01d67bfe6c8198416ec190cbc216d953c1625b94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:53.584172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:53.584434) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 15:30:53.593758) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-19 15:30:53.594101) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:30:54 INFO - MultiFileLogger online at 20160919 15:30:54 in /builds/slave/test 15:30:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:30:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:30:54 INFO - {'append_to_log': False, 15:30:54 INFO - 'base_work_dir': '/builds/slave/test', 15:30:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:30:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:30:54 INFO - 'branch': 'Mozilla-Beta', 15:30:54 INFO - 'buildbot_json_path': 'buildprops.json', 15:30:54 INFO - 'config_files': ('talos/linux_config.py',), 15:30:54 INFO - 'default_actions': ('clobber', 15:30:54 INFO - 'read-buildbot-config', 15:30:54 INFO - 'download-and-extract', 15:30:54 INFO - 'populate-webroot', 15:30:54 INFO - 'create-virtualenv', 15:30:54 INFO - 'install', 15:30:54 INFO - 'run-tests'), 15:30:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:30:54 INFO - 'download_minidump_stackwalk': True, 15:30:54 INFO - 'download_symbols': 'ondemand', 15:30:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:30:54 INFO - 'tooltool.py': '/tools/tooltool.py', 15:30:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:30:54 INFO - '/tools/misc-python/virtualenv.py')}, 15:30:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:30:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:30:54 INFO - 'installer_path': 'installer.exe', 15:30:54 INFO - 'log_level': 'info', 15:30:54 INFO - 'log_name': 'talos', 15:30:54 INFO - 'log_to_console': True, 15:30:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:30:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:30:54 INFO - 'opt_config_files': (), 15:30:54 INFO - 'pip_index': False, 15:30:54 INFO - 'sps_profile': False, 15:30:54 INFO - 'sps_profile_interval': 0, 15:30:54 INFO - 'suite': 'dromaeojs', 15:30:54 INFO - 'system_bits': '32', 15:30:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:30:54 INFO - 'title': 'talos-linux64-ix-064', 15:30:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:30:54 INFO - 'use_talos_json': True, 15:30:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:30:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:30:54 INFO - 'work_dir': 'build'} 15:30:54 INFO - [mozharness: 2016-09-19 22:30:54.320926Z] Running clobber step. 15:30:54 INFO - Running pre-action listener: _resource_record_pre_action 15:30:54 INFO - Running main action method: clobber 15:30:54 INFO - rmtree: /builds/slave/test/build 15:30:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:30:54 INFO - Running post-action listener: _resource_record_post_action 15:30:54 INFO - [mozharness: 2016-09-19 22:30:54.777793Z] Finished clobber step (success) 15:30:54 INFO - [mozharness: 2016-09-19 22:30:54.777894Z] Running read-buildbot-config step. 15:30:54 INFO - Running pre-action listener: _resource_record_pre_action 15:30:54 INFO - Running main action method: read_buildbot_config 15:30:54 INFO - Using buildbot properties: 15:30:54 INFO - { 15:30:54 INFO - "project": "", 15:30:54 INFO - "product": "firefox", 15:30:54 INFO - "script_repo_revision": "production", 15:30:54 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:30:54 INFO - "repository": "", 15:30:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:30:54 INFO - "buildid": "20160919064031", 15:30:54 INFO - "pgo_build": "True", 15:30:54 INFO - "basedir": "/builds/slave/test", 15:30:54 INFO - "buildnumber": 240, 15:30:54 INFO - "slavename": "talos-linux64-ix-064", 15:30:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:30:54 INFO - "platform": "ubuntu64_hw", 15:30:54 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:30:54 INFO - "branch": "mozilla-beta", 15:30:54 INFO - "repo_path": "releases/mozilla-beta", 15:30:54 INFO - "revision": "01d67bfe6c8198416ec190cbc216d953c1625b94", 15:30:54 INFO - "stage_platform": "linux64-pgo", 15:30:54 INFO - "builduid": "ae1839bfaf4840dab36e395afce28275", 15:30:54 INFO - "slavebuilddir": "test" 15:30:54 INFO - } 15:30:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:30:54 INFO - Running post-action listener: _resource_record_post_action 15:30:54 INFO - [mozharness: 2016-09-19 22:30:54.780314Z] Finished read-buildbot-config step (success) 15:30:54 INFO - [mozharness: 2016-09-19 22:30:54.780402Z] Running download-and-extract step. 15:30:54 INFO - Running pre-action listener: _resource_record_pre_action 15:30:54 INFO - Running main action method: download_and_extract 15:30:54 INFO - mkdir: /builds/slave/test/build/tests 15:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:54 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:30:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:30:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:30:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:30:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:30:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:30:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:30:54 INFO - retry: Failed, sleeping 30 seconds before retrying 15:31:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:31:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:31:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:31:24 INFO - retry: Failed, sleeping 60 seconds before retrying 15:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:32:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:32:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 15:32:24 INFO - Caught exception: HTTP Error 404: Not Found 15:32:24 INFO - Caught exception: HTTP Error 404: Not Found 15:32:24 INFO - Caught exception: HTTP Error 404: Not Found 15:32:24 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:24 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:32:25 INFO - Downloaded 1327 bytes. 15:32:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:25 INFO - Using the following test package requirements: 15:32:25 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:32:25 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:32:25 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:32:25 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'jsshell-linux-x86_64.zip'], 15:32:25 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:32:25 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:32:25 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:32:25 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:32:25 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:32:25 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:25 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:32:25 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:32: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')]} 15:32:25 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:32:25 INFO - retry: Failed, sleeping 30 seconds before retrying 15:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:32:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:32:55 INFO - retry: Failed, sleeping 60 seconds before retrying 15:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:33:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:33:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:33:55 INFO - Caught exception: HTTP Error 404: Not Found 15:33:55 INFO - Caught exception: HTTP Error 404: Not Found 15:33:55 INFO - Caught exception: HTTP Error 404: Not Found 15:33:55 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:55 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:33:56 INFO - Downloaded 21772072 bytes. 15:33:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:33:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:33:57 INFO - Return code: 0 15:33:57 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:57 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:33:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:33:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:33:57 INFO - retry: Failed, sleeping 30 seconds before retrying 15:34:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:34:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:34:27 INFO - retry: Failed, sleeping 60 seconds before retrying 15:35:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:35:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:35:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:35:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:35:28 INFO - Caught exception: HTTP Error 404: Not Found 15:35:28 INFO - Caught exception: HTTP Error 404: Not Found 15:35:28 INFO - Caught exception: HTTP Error 404: Not Found 15:35:28 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:35:28 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:35:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:35:28 INFO - Downloaded 21772072 bytes. 15:35:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:35:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:35:30 INFO - Return code: 0 15:35: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')]} 15:35:30 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:35:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:35:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:35:30 INFO - retry: Failed, sleeping 30 seconds before retrying 15:36:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:36:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:36:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:36:00 INFO - retry: Failed, sleeping 60 seconds before retrying 15:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:37:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:37:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:37:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 15:37:00 INFO - Caught exception: HTTP Error 404: Not Found 15:37:00 INFO - Caught exception: HTTP Error 404: Not Found 15:37:00 INFO - Caught exception: HTTP Error 404: Not Found 15:37:00 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:37:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:37:01 INFO - Downloaded 14413740 bytes. 15:37:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:37:01 INFO - Return code: 0 15:37:01 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')]} 15:37:01 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:37:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:37:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:37:01 INFO - retry: Failed, sleeping 30 seconds before retrying 15:37:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:37:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:37:31 INFO - retry: Failed, sleeping 60 seconds before retrying 15:38:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:38:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:38:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 15:38:31 INFO - Caught exception: HTTP Error 404: Not Found 15:38:31 INFO - Caught exception: HTTP Error 404: Not Found 15:38:31 INFO - Caught exception: HTTP Error 404: Not Found 15:38:31 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:31 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:38:32 INFO - Downloaded 56767399 bytes. 15:38:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:32 INFO - mkdir: /builds/slave/test/properties 15:38:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:32 INFO - Contents: 15:38:32 INFO - build_url:https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:33 INFO - Running post-action listener: _resource_record_post_action 15:38:33 INFO - Running post-action listener: set_extra_try_arguments 15:38:33 INFO - [mozharness: 2016-09-19 22:38:33.241765Z] Finished download-and-extract step (success) 15:38:33 INFO - [mozharness: 2016-09-19 22:38:33.241996Z] Running populate-webroot step. 15:38:33 INFO - Running pre-action listener: _resource_record_pre_action 15:38:33 INFO - Running main action method: populate_webroot 15:38:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:38:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:38:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:38:33 INFO - '--tppagecycles', 15:38:33 INFO - '7'], 15:38:33 INFO - 'tests': ['tsvgm']}}, 15:38:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:38:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:38:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['damp', 'tps']}, 15:38:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'tests': ['damp', 'tps']}, 15:38:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['dromaeo_dom']}, 15:38:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['basic_compositor_video']}, 15:38:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:38:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tsvgx', 15:38:33 INFO - 'tsvgr_opacity', 15:38:33 INFO - 'tart', 15:38:33 INFO - 'tscrollx', 15:38:33 INFO - 'cart']}, 15:38:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:33 INFO - 'tsvgr_opacity', 15:38:33 INFO - 'tart', 15:38:33 INFO - 'tscrollx', 15:38:33 INFO - 'cart']}, 15:38:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tp5o']}, 15:38:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'tests': ['tp5o']}, 15:38:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s', 15:38:33 INFO - '--xperf_path', 15:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:33 INFO - 'tests': ['tp5n']}, 15:38:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--xperf_path', 15:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:33 INFO - 'tests': ['tp5n']}}, 15:38:33 INFO - 'talos.zip': {'path': '', 15:38:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:38:33 INFO - Running post-action listener: _resource_record_post_action 15:38:33 INFO - [mozharness: 2016-09-19 22:38:33.257140Z] Finished populate-webroot step (success) 15:38:33 INFO - [mozharness: 2016-09-19 22:38:33.257229Z] Running create-virtualenv step. 15:38:33 INFO - Running pre-action listener: _resource_record_pre_action 15:38:33 INFO - Running main action method: create_virtualenv 15:38:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:38:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:38:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:38:33 INFO - Using real prefix '/usr' 15:38:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:38:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:38:35 INFO - Installing pip.................done. 15:38:35 INFO - Return code: 0 15:38:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:38:35 INFO - Using env: {'DISPLAY': ':0', 15:38:35 INFO - 'HOME': '/home/cltbld', 15:38:35 INFO - 'LANG': 'en_US.UTF-8', 15:38:35 INFO - 'LANGUAGE': 'en_US:en', 15:38:35 INFO - 'LOGNAME': 'cltbld', 15:38:35 INFO - 'MAIL': '/var/mail/cltbld', 15:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:35 INFO - 'MOZ_NO_REMOTE': '1', 15:38:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:35 INFO - 'NO_EM_RESTART': '1', 15:38:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:35 INFO - 'PWD': '/builds/slave/test', 15:38:35 INFO - 'SHELL': '/bin/bash', 15:38:35 INFO - 'SHLVL': '1', 15:38:35 INFO - 'TERM': 'linux', 15:38:35 INFO - 'TMOUT': '86400', 15:38:35 INFO - 'USER': 'cltbld', 15:38:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:35 INFO - '_': '/tools/buildbot/bin/python'} 15:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:36 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 15:38:36 INFO - Cleaning up... 15:38:36 INFO - Return code: 0 15:38:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:38:36 INFO - Using env: {'DISPLAY': ':0', 15:38:36 INFO - 'HOME': '/home/cltbld', 15:38:36 INFO - 'LANG': 'en_US.UTF-8', 15:38:36 INFO - 'LANGUAGE': 'en_US:en', 15:38:36 INFO - 'LOGNAME': 'cltbld', 15:38:36 INFO - 'MAIL': '/var/mail/cltbld', 15:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:36 INFO - 'MOZ_NO_REMOTE': '1', 15:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:36 INFO - 'NO_EM_RESTART': '1', 15:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:36 INFO - 'PWD': '/builds/slave/test', 15:38:36 INFO - 'SHELL': '/bin/bash', 15:38:36 INFO - 'SHLVL': '1', 15:38:36 INFO - 'TERM': 'linux', 15:38:36 INFO - 'TMOUT': '86400', 15:38:36 INFO - 'USER': 'cltbld', 15:38:36 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:36 INFO - '_': '/tools/buildbot/bin/python'} 15:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:36 INFO - Downloading/unpacking psutil>=3.1.1 15:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:38:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:38 INFO - Installing collected packages: psutil 15:38:38 INFO - Running setup.py install for psutil 15:38:38 INFO - building 'psutil._psutil_linux' extension 15:38:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:38:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:38:38 INFO - building 'psutil._psutil_posix' extension 15:38:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:38:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:38:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:38 INFO - Successfully installed psutil 15:38:38 INFO - Cleaning up... 15:38:38 INFO - Return code: 0 15:38:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:38:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:38:38 INFO - Using env: {'DISPLAY': ':0', 15:38:38 INFO - 'HOME': '/home/cltbld', 15:38:38 INFO - 'LANG': 'en_US.UTF-8', 15:38:38 INFO - 'LANGUAGE': 'en_US:en', 15:38:38 INFO - 'LOGNAME': 'cltbld', 15:38:38 INFO - 'MAIL': '/var/mail/cltbld', 15:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:38 INFO - 'MOZ_NO_REMOTE': '1', 15:38:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:38 INFO - 'NO_EM_RESTART': '1', 15:38:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:38 INFO - 'PWD': '/builds/slave/test', 15:38:38 INFO - 'SHELL': '/bin/bash', 15:38:38 INFO - 'SHLVL': '1', 15:38:38 INFO - 'TERM': 'linux', 15:38:38 INFO - 'TMOUT': '86400', 15:38:38 INFO - 'USER': 'cltbld', 15:38:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:38 INFO - '_': '/tools/buildbot/bin/python'} 15:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:38:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:38:40 INFO - Installing collected packages: mozsystemmonitor 15:38:40 INFO - Running setup.py install for mozsystemmonitor 15:38:41 INFO - Successfully installed mozsystemmonitor 15:38:41 INFO - Cleaning up... 15:38:41 INFO - Return code: 0 15:38:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38: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==2.5.1'] in /builds/slave/test/build 15:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:38:41 INFO - Using env: {'DISPLAY': ':0', 15:38:41 INFO - 'HOME': '/home/cltbld', 15:38:41 INFO - 'LANG': 'en_US.UTF-8', 15:38:41 INFO - 'LANGUAGE': 'en_US:en', 15:38:41 INFO - 'LOGNAME': 'cltbld', 15:38:41 INFO - 'MAIL': '/var/mail/cltbld', 15:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:41 INFO - 'MOZ_NO_REMOTE': '1', 15:38:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:41 INFO - 'NO_EM_RESTART': '1', 15:38:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:41 INFO - 'PWD': '/builds/slave/test', 15:38:41 INFO - 'SHELL': '/bin/bash', 15:38:41 INFO - 'SHLVL': '1', 15:38:41 INFO - 'TERM': 'linux', 15:38:41 INFO - 'TMOUT': '86400', 15:38:41 INFO - 'USER': 'cltbld', 15:38:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:41 INFO - '_': '/tools/buildbot/bin/python'} 15:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:41 INFO - Downloading/unpacking jsonschema==2.5.1 15:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:38:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:38:43 INFO - warning: no files found matching '*.txt' 15:38:43 INFO - no previously-included directories found matching 'build' 15:38:43 INFO - no previously-included directories found matching 'dist' 15:38:43 INFO - no previously-included directories found matching '.git*' 15:38:43 INFO - Installing collected packages: jsonschema, functools32 15:38:43 INFO - Running setup.py install for functools32 15:38:43 INFO - warning: no files found matching '*.txt' 15:38:43 INFO - no previously-included directories found matching 'build' 15:38:43 INFO - no previously-included directories found matching 'dist' 15:38:43 INFO - no previously-included directories found matching '.git*' 15:38:43 INFO - Successfully installed jsonschema functools32 15:38:43 INFO - Cleaning up... 15:38:43 INFO - Return code: 0 15:38:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:38:43 INFO - Using env: {'DISPLAY': ':0', 15:38:43 INFO - 'HOME': '/home/cltbld', 15:38:43 INFO - 'LANG': 'en_US.UTF-8', 15:38:43 INFO - 'LANGUAGE': 'en_US:en', 15:38:43 INFO - 'LOGNAME': 'cltbld', 15:38:43 INFO - 'MAIL': '/var/mail/cltbld', 15:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:43 INFO - 'MOZ_NO_REMOTE': '1', 15:38:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:43 INFO - 'NO_EM_RESTART': '1', 15:38:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:43 INFO - 'PWD': '/builds/slave/test', 15:38:43 INFO - 'SHELL': '/bin/bash', 15:38:43 INFO - 'SHLVL': '1', 15:38:43 INFO - 'TERM': 'linux', 15:38:43 INFO - 'TMOUT': '86400', 15:38:43 INFO - 'USER': 'cltbld', 15:38:43 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:43 INFO - '_': '/tools/buildbot/bin/python'} 15:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:38:43 INFO - Cleaning up... 15:38:43 INFO - Return code: 0 15:38:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:38:43 INFO - Using env: {'DISPLAY': ':0', 15:38:43 INFO - 'HOME': '/home/cltbld', 15:38:43 INFO - 'LANG': 'en_US.UTF-8', 15:38:43 INFO - 'LANGUAGE': 'en_US:en', 15:38:43 INFO - 'LOGNAME': 'cltbld', 15:38:43 INFO - 'MAIL': '/var/mail/cltbld', 15:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:43 INFO - 'MOZ_NO_REMOTE': '1', 15:38:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:43 INFO - 'NO_EM_RESTART': '1', 15:38:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:43 INFO - 'PWD': '/builds/slave/test', 15:38:43 INFO - 'SHELL': '/bin/bash', 15:38:43 INFO - 'SHLVL': '1', 15:38:43 INFO - 'TERM': 'linux', 15:38:43 INFO - 'TMOUT': '86400', 15:38:43 INFO - 'USER': 'cltbld', 15:38:43 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:43 INFO - '_': '/tools/buildbot/bin/python'} 15:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:44 INFO - Downloading/unpacking blobuploader==1.2.4 15:38:44 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 15:38:44 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 15:38:44 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 15:38:44 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 15:38:44 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 15:38:44 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 15:38:45 INFO - Downloading blobuploader-1.2.4.tar.gz 15:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:38:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:38:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:46 INFO - Downloading docopt-0.6.1.tar.gz 15:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:38:46 INFO - Installing collected packages: blobuploader, requests, docopt 15:38:46 INFO - Running setup.py install for blobuploader 15:38:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:38:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:38:46 INFO - Running setup.py install for requests 15:38:47 INFO - Running setup.py install for docopt 15:38:47 INFO - Successfully installed blobuploader requests docopt 15:38:47 INFO - Cleaning up... 15:38:47 INFO - Return code: 0 15:38:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:38:47 INFO - Using env: {'DISPLAY': ':0', 15:38:47 INFO - 'HOME': '/home/cltbld', 15:38:47 INFO - 'LANG': 'en_US.UTF-8', 15:38:47 INFO - 'LANGUAGE': 'en_US:en', 15:38:47 INFO - 'LOGNAME': 'cltbld', 15:38:47 INFO - 'MAIL': '/var/mail/cltbld', 15:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:47 INFO - 'MOZ_NO_REMOTE': '1', 15:38:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:47 INFO - 'NO_EM_RESTART': '1', 15:38:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:47 INFO - 'PWD': '/builds/slave/test', 15:38:47 INFO - 'SHELL': '/bin/bash', 15:38:47 INFO - 'SHLVL': '1', 15:38:47 INFO - 'TERM': 'linux', 15:38:47 INFO - 'TMOUT': '86400', 15:38:47 INFO - 'USER': 'cltbld', 15:38:47 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:47 INFO - '_': '/tools/buildbot/bin/python'} 15:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:47 INFO - Running setup.py (path:/tmp/pip-SLOr6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:47 INFO - Running setup.py (path:/tmp/pip-ZLEIJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:47 INFO - Running setup.py (path:/tmp/pip-UlEcO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:47 INFO - Running setup.py (path:/tmp/pip-mQqhxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:47 INFO - Running setup.py (path:/tmp/pip-MlEv8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:47 INFO - Running setup.py (path:/tmp/pip-5U14DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:48 INFO - Running setup.py (path:/tmp/pip-sREoPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:48 INFO - Running setup.py (path:/tmp/pip-caZkhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:48 INFO - Running setup.py (path:/tmp/pip-3qSmST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:48 INFO - Running setup.py (path:/tmp/pip-kh6aXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:48 INFO - Running setup.py (path:/tmp/pip-tkl71g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:48 INFO - Running setup.py (path:/tmp/pip-Ky6cHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:48 INFO - Running setup.py (path:/tmp/pip-qx087V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:48 INFO - Running setup.py (path:/tmp/pip-AmuwA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:48 INFO - Running setup.py (path:/tmp/pip-Jy0pmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:48 INFO - Running setup.py (path:/tmp/pip-NUTV08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:48 INFO - Running setup.py (path:/tmp/pip-lgm9fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:38:48 INFO - Running setup.py install for manifestparser 15:38:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:38:48 INFO - Running setup.py install for mozcrash 15:38:48 INFO - Running setup.py install for mozdebug 15:38:49 INFO - Running setup.py install for mozdevice 15:38:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Running setup.py install for mozfile 15:38:49 INFO - Running setup.py install for mozhttpd 15:38:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Running setup.py install for mozinfo 15:38:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Running setup.py install for mozInstall 15:38:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Running setup.py install for mozleak 15:38:49 INFO - Running setup.py install for mozlog 15:38:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Running setup.py install for moznetwork 15:38:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:38:49 INFO - Running setup.py install for mozprocess 15:38:50 INFO - Running setup.py install for mozprofile 15:38:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:38:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:38:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:38:50 INFO - Running setup.py install for mozrunner 15:38:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:38:50 INFO - Running setup.py install for mozscreenshot 15:38:50 INFO - Running setup.py install for moztest 15:38:50 INFO - Running setup.py install for mozversion 15:38:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:38:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:38:50 INFO - Cleaning up... 15:38:50 INFO - Return code: 0 15:38:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:38:50 INFO - Using env: {'DISPLAY': ':0', 15:38:50 INFO - 'HOME': '/home/cltbld', 15:38:50 INFO - 'LANG': 'en_US.UTF-8', 15:38:50 INFO - 'LANGUAGE': 'en_US:en', 15:38:50 INFO - 'LOGNAME': 'cltbld', 15:38:50 INFO - 'MAIL': '/var/mail/cltbld', 15:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:50 INFO - 'MOZ_NO_REMOTE': '1', 15:38:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:50 INFO - 'NO_EM_RESTART': '1', 15:38:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:50 INFO - 'PWD': '/builds/slave/test', 15:38:50 INFO - 'SHELL': '/bin/bash', 15:38:50 INFO - 'SHLVL': '1', 15:38:50 INFO - 'TERM': 'linux', 15:38:50 INFO - 'TMOUT': '86400', 15:38:50 INFO - 'USER': 'cltbld', 15:38:50 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:50 INFO - '_': '/tools/buildbot/bin/python'} 15:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:50 INFO - Running setup.py (path:/tmp/pip-bxqjEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:50 INFO - Running setup.py (path:/tmp/pip-VgYDV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:50 INFO - Running setup.py (path:/tmp/pip-ksVuzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38:51 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)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:51 INFO - Running setup.py (path:/tmp/pip-VQktWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:51 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)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:51 INFO - Running setup.py (path:/tmp/pip-Hx89mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:51 INFO - Running setup.py (path:/tmp/pip-u_Llgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:51 INFO - Running setup.py (path:/tmp/pip-O1u_pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:51 INFO - Running setup.py (path:/tmp/pip-pS6WMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:51 INFO - Running setup.py (path:/tmp/pip-VasvRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:51 INFO - Running setup.py (path:/tmp/pip-oEY9Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:51 INFO - Running setup.py (path:/tmp/pip-eEZrvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:51 INFO - Running setup.py (path:/tmp/pip-rnPQD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:51 INFO - Running setup.py (path:/tmp/pip-hE_BiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:51 INFO - Running setup.py (path:/tmp/pip-WMYCLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:51 INFO - Running setup.py (path:/tmp/pip-1iWIy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:51 INFO - Running setup.py (path:/tmp/pip-X1uHqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:51 INFO - Running setup.py (path:/tmp/pip-iapblz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38:52 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)) 15:38:52 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)) 15:38:52 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)) 15:38:52 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)) 15:38:52 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)) 15:38:52 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)) 15:38:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 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 15:38:53 INFO - Downloading blessings-1.6.tar.gz 15:38:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:38:54 INFO - Installing collected packages: blessings 15:38:54 INFO - Running setup.py install for blessings 15:38:54 INFO - Successfully installed blessings 15:38:54 INFO - Cleaning up... 15:38:54 INFO - Return code: 0 15:38:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:38:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:54 INFO - Reading from file tmpfile_stdout 15:38:54 INFO - Current package versions: 15:38:54 INFO - argparse == 1.2.1 15:38:54 INFO - blessings == 1.6 15:38:54 INFO - blobuploader == 1.2.4 15:38:54 INFO - docopt == 0.6.1 15:38:54 INFO - functools32 == 3.2.3-2 15:38:54 INFO - jsonschema == 2.5.1 15:38:54 INFO - manifestparser == 1.1 15:38:54 INFO - mozInstall == 1.12 15:38:54 INFO - mozcrash == 0.17 15:38:54 INFO - mozdebug == 0.1 15:38:54 INFO - mozdevice == 0.48 15:38:54 INFO - mozfile == 1.2 15:38:54 INFO - mozhttpd == 0.7 15:38:54 INFO - mozinfo == 0.9 15:38:54 INFO - mozleak == 0.1 15:38:54 INFO - mozlog == 3.3 15:38:54 INFO - moznetwork == 0.27 15:38:54 INFO - mozprocess == 0.23 15:38:54 INFO - mozprofile == 0.28 15:38:54 INFO - mozrunner == 6.12 15:38:54 INFO - mozscreenshot == 0.1 15:38:54 INFO - mozsystemmonitor == 0.3 15:38:54 INFO - moztest == 0.7 15:38:54 INFO - mozversion == 1.4 15:38:54 INFO - psutil == 3.1.1 15:38:54 INFO - requests == 1.2.3 15:38:54 INFO - wsgiref == 0.1.2 15:38:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:38:54 INFO - Using env: {'DISPLAY': ':0', 15:38:54 INFO - 'HOME': '/home/cltbld', 15:38:54 INFO - 'LANG': 'en_US.UTF-8', 15:38:54 INFO - 'LANGUAGE': 'en_US:en', 15:38:54 INFO - 'LOGNAME': 'cltbld', 15:38:54 INFO - 'MAIL': '/var/mail/cltbld', 15:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:54 INFO - 'MOZ_NO_REMOTE': '1', 15:38:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:54 INFO - 'NO_EM_RESTART': '1', 15:38:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:54 INFO - 'PWD': '/builds/slave/test', 15:38:54 INFO - 'SHELL': '/bin/bash', 15:38:54 INFO - 'SHLVL': '1', 15:38:54 INFO - 'TERM': 'linux', 15:38:54 INFO - 'TMOUT': '86400', 15:38:54 INFO - 'USER': 'cltbld', 15:38:54 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:54 INFO - '_': '/tools/buildbot/bin/python'} 15:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:38:54 INFO - Cleaning up... 15:38:54 INFO - Return code: 0 15:38:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', '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 15:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:38:54 INFO - Using env: {'DISPLAY': ':0', 15:38:54 INFO - 'HOME': '/home/cltbld', 15:38:54 INFO - 'LANG': 'en_US.UTF-8', 15:38:54 INFO - 'LANGUAGE': 'en_US:en', 15:38:54 INFO - 'LOGNAME': 'cltbld', 15:38:54 INFO - 'MAIL': '/var/mail/cltbld', 15:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:54 INFO - 'MOZ_NO_REMOTE': '1', 15:38:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:54 INFO - 'NO_EM_RESTART': '1', 15:38:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:54 INFO - 'PWD': '/builds/slave/test', 15:38:54 INFO - 'SHELL': '/bin/bash', 15:38:54 INFO - 'SHLVL': '1', 15:38:54 INFO - 'TERM': 'linux', 15:38:54 INFO - 'TMOUT': '86400', 15:38:54 INFO - 'USER': 'cltbld', 15:38:54 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:54 INFO - '_': '/tools/buildbot/bin/python'} 15:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:38:54 INFO - Cleaning up... 15:38:54 INFO - Return code: 0 15:38:54 INFO - Running post-action listener: _resource_record_post_action 15:38:54 INFO - Running post-action listener: _start_resource_monitoring 15:38:54 INFO - Starting resource monitoring. 15:38:54 INFO - [mozharness: 2016-09-19 22:38:54.870055Z] Finished create-virtualenv step (success) 15:38:54 INFO - [mozharness: 2016-09-19 22:38:54.870525Z] Running install step. 15:38:54 INFO - Running pre-action listener: _resource_record_pre_action 15:38:54 INFO - Running main action method: install 15:38:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:55 INFO - Reading from file tmpfile_stdout 15:38:55 INFO - Detecting whether we're running mozinstall >=1.0... 15:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:38:55 INFO - Reading from file tmpfile_stdout 15:38:55 INFO - Output received: 15:38:55 INFO - Usage: mozinstall [options] installer 15:38:55 INFO - Options: 15:38:55 INFO - -h, --help show this help message and exit 15:38:55 INFO - -d DEST, --destination=DEST 15:38:55 INFO - Directory to install application into. [default: 15:38:55 INFO - "/builds/slave/test"] 15:38:55 INFO - --app=APP Application being installed. [default: firefox] 15:38:55 INFO - mkdir: /builds/slave/test/build/application 15:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:39:09 INFO - Reading from file tmpfile_stdout 15:39:09 INFO - Output received: 15:39:09 INFO - /builds/slave/test/build/application/firefox/firefox 15:39:09 INFO - Running post-action listener: _resource_record_post_action 15:39:09 INFO - [mozharness: 2016-09-19 22:39:09.461595Z] Finished install step (success) 15:39:09 INFO - [mozharness: 2016-09-19 22:39:09.461871Z] Running run-tests step. 15:39:09 INFO - Running pre-action listener: _resource_record_pre_action 15:39:09 INFO - Running main action method: run_tests 15:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:39:09 INFO - Python 2.7.3 15:39:09 INFO - Return code: 0 15:39:09 INFO - grabbing minidump binary from tooltool 15:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:09 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 0x1c1d3b0>, '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 0x1c1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:39:09 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 15:39:09 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 15:39:09 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 15:39:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:39:09 INFO - Return code: 0 15:39:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:39:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:39:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:39:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:39:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 15:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 15:39:09 INFO - Using env: {'DISPLAY': ':0', 15:39:09 INFO - 'HOME': '/home/cltbld', 15:39:09 INFO - 'LANG': 'en_US.UTF-8', 15:39:09 INFO - 'LANGUAGE': 'en_US:en', 15:39:09 INFO - 'LOGNAME': 'cltbld', 15:39:09 INFO - 'MAIL': '/var/mail/cltbld', 15:39:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:09 INFO - 'MOZ_NO_REMOTE': '1', 15:39:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:09 INFO - 'NO_EM_RESTART': '1', 15:39:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:09 INFO - 'PWD': '/builds/slave/test', 15:39:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:09 INFO - 'SHELL': '/bin/bash', 15:39:09 INFO - 'SHLVL': '1', 15:39:09 INFO - 'TERM': 'linux', 15:39:09 INFO - 'TMOUT': '86400', 15:39:09 INFO - 'USER': 'cltbld', 15:39:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262', 15:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:09 INFO - '_': '/tools/buildbot/bin/python'} 15:39:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 15:39:09 INFO - mozversion application_buildid: 20160919064031 15:39:09 INFO - mozversion application_changeset: 01d67bfe6c8198416ec190cbc216d953c1625b94 15:39:09 INFO - mozversion application_display_name: Firefox 15:39:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:09 INFO - mozversion application_name: Firefox 15:39:09 INFO - mozversion application_remotingname: firefox 15:39:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:39:09 INFO - mozversion application_vendor: Mozilla 15:39:09 INFO - mozversion application_version: 50.0 15:39:09 INFO - mozversion platform_buildid: 20160919064031 15:39:09 INFO - mozversion platform_changeset: 01d67bfe6c8198416ec190cbc216d953c1625b94 15:39:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:39:09 INFO - mozversion platform_version: 50.0 15:39:09 INFO - using testdate: 1474324749 15:39:09 INFO - actual date: 1474324749 15:39:09 INFO - starting webserver on 'localhost:34523' 15:39:09 INFO - SUITE-START | Running 2 tests 15:39:09 INFO - TEST-START | dromaeo_css 15:39:09 INFO - operating with platform_type : linux_ 15:39:09 INFO - Initialising browser for dromaeo_css test... 15:39:09 INFO - TEST-INFO | started process 11722 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6j4pCC/profile http://localhost:34523/getInfo.html) 15:39:13 INFO - PROCESS | 11722 | __metrics Screen width/height:1600/1200 15:39:13 INFO - PROCESS | 11722 | colorDepth:24 15:39:13 INFO - PROCESS | 11722 | Browser inner width/height: 1024/697 15:39:13 INFO - PROCESS | 11722 | __metrics 15:39:14 INFO - PROCESS | 11722 | 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" 15:39:14 INFO - PROCESS | 11722 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:39:15 INFO - TEST-INFO | 11722: exit 0 15:39:15 INFO - Browser initialized. 15:39:15 INFO - Running cycle 1/1 for dromaeo_css test... 15:39:15 INFO - TEST-INFO | started process 11812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6j4pCC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:27 INFO - PROCESS | 11812 | RSS: Main: 143699968 15:39:27 INFO - PROCESS | 11812 | 15:40:18 INFO - PROCESS | 11812 | 1474324818449 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] 15:42:27 INFO - PROCESS | 11812 | Cycle 1(1): loaded http://localhost:34523/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34523/tests/dromaeo/cssquery-ext.html) 15:42:28 INFO - PROCESS | 11812 | RSS: Main: 223985664 15:42:28 INFO - PROCESS | 11812 | 15:45:30 INFO - PROCESS | 11812 | Cycle 1(1): loaded http://localhost:34523/tests/dromaeo/cssquery-ext.html (next: http://localhost:34523/tests/dromaeo/cssquery-jquery.html) 15:45:31 INFO - PROCESS | 11812 | RSS: Main: 235282432 15:45:31 INFO - PROCESS | 11812 | 15:48:33 INFO - PROCESS | 11812 | Cycle 1(1): loaded http://localhost:34523/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34523/tests/dromaeo/cssquery-mootools.html) 15:48:33 INFO - PROCESS | 11812 | RSS: Main: 177807360 15:48:33 INFO - PROCESS | 11812 | 15:51:35 INFO - PROCESS | 11812 | Cycle 1(1): loaded http://localhost:34523/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34523/tests/dromaeo/cssquery-prototype.html) 15:51:36 INFO - PROCESS | 11812 | RSS: Main: 215793664 15:51:36 INFO - PROCESS | 11812 | 15:54:38 INFO - PROCESS | 11812 | Cycle 1(1): loaded http://localhost:34523/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34523/tests/dromaeo/cssquery-yui.html) 15:54:39 INFO - PROCESS | 11812 | RSS: Main: 177356800 15:54:39 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | Cycle 1(1): loaded http://localhost:34523/tests/dromaeo/cssquery-yui.html (next: http://localhost:34523/tests/dromaeo/cssquery-dojo.html) 15:57:41 INFO - PROCESS | 11812 | RSS: Main: 262230016 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | __start_tp_report 15:57:41 INFO - PROCESS | 11812 | _x_x_mozilla_page_load 15:57:41 INFO - PROCESS | 11812 | _x_x_mozilla_page_load_details 15:57:41 INFO - PROCESS | 11812 | |i|pagename|runs| 15:57:41 INFO - PROCESS | 11812 | |0;dojo.html;3247.8863239786597;3249.951250731239;3257.6253730820963;3284.458064419371;3298.785578937369;5525.557955363572;5617.915731264029;5621.859453513664;5627.7467513961865;5637.069883469233;5703.914441283384;5777.45114214149;5781.219535362725;5784;5787.71061446927;5046.873828154307;5230.398504172012;5233.188855727359;5294.57643388528;5301.098813201765;5089.9565589054155;5179.637425380225;5212.921806172911;5227.86930326743;5230.34620672416;5085.74571271435;5124.590032797368;5130.230465430178;5145.202493613496;5165.9225111623355;5183.896322073575;5198.558122559587;5219.660722053073;5226.451222621608;5252.0283747506455;5056.6713163644245;5061;5116.846494605168;5146.485351464861;5147.691138531663;5573.080441727151;5582.637128586629;5593.916091258634;5599.272094627672;5627.043402621564;6076.696165191723;6085.42188492097;6092.9695351523405;6099.786507472261;6107.87784244317;6073.726682299307;6098.634081955097;6117.510599152057;6135.754569817246;6142.907856382158;5724.685142317171;5745.540356771448;5747.741351639186;5768.6250393724695;5782.132680097977;6086.609060486471;6275.3724627537795;6276.968615156987;6277.24673039229;6278.529110316745;5927;5956.463918247378;5984.311804142492;6055.515558755289;6055.576109672281;6085.862870946158;6087.05650624154;6087.573869829158;6090.6041107328765;6137.048757442603;5929.4070592941225;6073.089036644538;6077.088436734525;6086.513078953762;6089.5737298388685;5962.970185149046;6025.427584379477;6034.63792172471;6046.667433291123;6084.878302433926;4610.170169369546;4629.745364005012;4642.744649044335;4670.673052886334;4698.083873644675;4097.590240975878;4126.979365103155;4139.337705967031;4159.230542349675;4180.581941805795;5741.971290143522;5754.827355179267;5763.942360576424;5800.9129863052085;5842.328132264759;6095.908561371583;6102.664353460514;6102.7558897644485;6106.664133472702;6107.969460152671;5722.141678748221;5789.044807606698;5791.884162316815;5796.768129274782;5798.043322851682;5249.868753281198;5301.867453313621;5330.600204984642;5346.8395948121615;5356.946430535645;6353.745850166034;6393.520485963571;6397.776077837253;6409.871802563923;6417.679116044273;5967;6152.292486364126;6174.25908890936;6184.319724830355;6191.597546159485;5969.791057313147;6214.5028397728975;6336.08126821599;6401.199850018747;6409.647469389229;6029.668368239789;6030.3668114847305;6032.939670603238;6041.426064523863;6042.667653279112;6004.939950600438;6144.434497165096;6162.383761623802;6167.321594624498;6174.567780255339;5882.441168089024;5901.114832775118;5933.732982015906;5987;6033.909491357721;5919.556033297606;5958.106284057426;5964.433378829004;5974.253218347707;5976.7908123215475;5820.447057529528;5919.792807251725;5928.851778705567;5938.168656388023;5938.61399009063;5893.2044174035955;5936.821895343147;5945.494632956247;5979.730912108878;6016.5487588431915;5776.624519406225;5779.7110144493445;5781.8554536136935;5816.680082595498;5820.214271073351 15:57:41 INFO - PROCESS | 11812 | |1;ext.html;600.7266693654387;635.1837888313515;638.1193952345762;659.574574399512;669.3808227389667;33490.832545837264;44563.10873782523;45236.77381613095;45244.0951180977;45404.999999999956;46837;47145.29282060772;47193;47257.52742472574;47275.29087063688;8436.789080272982;9219.078092190777;9374.578143983537;9375.437473751563;10993.120550355952;10787.676369708883;11204.551817927273;11210;11210.04714599256;11215.831762523569;11270.380129092897;11315.547378104866;11322;11327;11327.376994265313;11184;11210.607628732996;11253.381064041474;11267.774644507064;11270.605528806534;11089.778204435866;11125.109991200765;11157.27477713946;11166.106711463064;11169.0506306964;44609;44943.32585011228;44955.550444495464;44997.10005799866;45001.549984500394;3636.30910127075;3665.2852693724735;3814.1036856338737;3838.0980469589904;3852.57621661617;34168.658313416796;34503.137421564665;34838.651613483795;36504.26991460183;36573.451398229045;4670.276107203389;4678.134545109166;4793.113274044313;4812.965212479334;4902.2156454967035;395999.99999999715;396027.9999999971;396965;397165;397876;56622;61588.69205653943;65422.672886635264;65896.67051664807;65900.67049664816;60898;66275;66497.66751166213;67162.66418667875;67178.66410668014;19262.71105933384;19478.318258860872;19488.8051119487;19548;19552.413427597196;13779.173249605055;14663.706725865422;14699.485518007032;15138.545843624708;15143.545693629208;17973.19120639548;18056.638867222846;18089.819101808815;18134;18248.726269105973;6815.773160830999;6996.6851491683;7009.9649501752165;7116.430685545248;7245.8550828983125;995.6415690351467;1048.1667074675652;1053.536443964653;1069.5294070608907;1102.1513434655271;32103.51844722331;33634;34126.48810267848;34718.47922281168;34734;1139.9544018239178;1201.3392634051236;1203.1517779965104;1253.367049639926;1255.9638298403854;18095.909520452577;18108.185131669106;18109.96325313734;18186.272549098154;18214.180361883482;621737;624572;626600;627194;627519;64859.67570162119;64939.675301624135;65036.674816625615;65058;65148;1351.1352734250088;1421.1118051217989;1439.2084353605476;1458.8103546539094;1481.8592233737777;35491.46762798508;36938.261234775695;36951.63048369482;36996.63003370039;37015;56272.437275628356;56730;56790;56884;56969;445575;447296;447628;447701;448167;27885.302867428476;30182.09453716392;30401.69598303989;31167.844160779958;31674.68325316717;46628;52599.47400525946;52894.471055290494;53534;54433.455665444424;44974.77512612416;45314.546854531036;45965.77017114893;46285;46613.3008004887;44571.76991811287;46376.768116159205;46493.76753116213;46994.53005469902;47533.524664754295;43914.78042609766;44031;44321.33517997297;44403.33394999142;45227.54772452365;37599.81200093982;39184.60815391924;39705.40441893432;40565.56606603613;40621.79689101654;28998;29548;29608.525271912782;29703.851480742458;30099.398012039197 15:57:41 INFO - PROCESS | 11812 | |2;jquery.html;420.1848414076689;421.6288897426046;432.4378308199342;435.22747123855163;435.84527492740074;20626.381208563747;20678.793212067874;21171;21355.466113347135;21559.892200538994;19924.40226793195;21110;21213.893930530325;21327.25354612589;21335.6799648005;5670;5984.192134061889;5989.221401217836;5995.970020149894;5996.4902983246475;5678.403767604404;5879.941200588003;5902.498287645555;5919.1713160157615;5920.378360272174;5884.617499862496;5886.5290776737975;5888.322842873082;5890.88218235635;5891.057430811071;5657.745401456944;5667.4049224831415;5712.314522257356;5717.971410142923;5718.485336319731;5089.720065396402;5120.3855537335385;5121.359830021247;5126.897462050775;5135.84592462223;14648.121112733164;14851.851481485155;23264.185753498714;23540;23943.640845387334;2350;2361.8937147828387;2482.15606693725;2525.06572568151;2996.5654980027775;10052.59789608422;10700;11169.497372618252;11303.886961130365;12955.805662914969;1905.6950887858015;1954.2378472395776;1987.6422243996076;1992.3126521350111;2086.1655337864822;89813;90021;90431;93876;94662.52668736612;11520.308781473139;11690.824637630272;11943.104267179997;12199.573014944433;12221.694457638452;16922.15389230558;16957.660846782994;17010.659786804194;17011.574710632085;17019;8462.449940753831;8920.598573064228;9024.8646270306;9054.592543335566;9161.587728552231;8571.357148213785;8657.696980605648;8721.825563488821;8908.064653211319;8954.686585969459;5101.928594995018;5112.977404519111;5179.19722443022;5224.373075230997;5274.4198138204765;2020.3029954665617;2053.2300387354744;2105.7894210579125;2134.402367337148;2140.9036593353335;1012.594962015185;1021.3973755484151;1029.7940411917646;1056.1867362131115;1082.8592283003159;20840.374788756362;21555.67666485004;21616.89191554032;21629;21648.458788530097;1012.1143999000874;1026.3790406803926;1047.874255089379;1066.1311031509294;1075.800976819291;10693;11919.761604767857;11970.82043769344;12000.279983201035;12034.458449369624;561056;561242;581847;603194;607797;94698;97744.51127744316;99951.50024249832;103318;104082.47958760157;1024.7591815923251;1069.8395240713955;1091.2197778588347;1108.7394462301352;1109.4619109731773;18279.908600457446;18528.88826670404;19081.61836763229;19149.712754308974;19348.70976935375;41337;42095.57904421039;43238.135237295704;46284.53715462802;46299.768501157276;61455.38544614497;61601;62615.37384626277;66410.33589664042;68162.65918670375;8450.323974082181;8595.785105372415;8679.956600216958;8750.47497150173;8933.508657023925;12170.817437738264;13464.528741494001;13608.25154616466;13790;13848.238346890616;11456.427178641201;11517.53929842797;11725.76548469043;11961;12139.089568282237;5524.392316845224;8789.692360767342;11735.941320293345;11753.706157345792;11755.823662644896;11835.82246266324;12130.211536250115;12424.813627795409;12447.626571202878;12630.305333206394;8580.742577722678;8643.438176518506;8723.302135829246;8812.07473215303;8971.910280897107;11000.504977276201;11519.07847372225;11688.94155529217;11850.407479626158;11913.463894124958 15:57:41 INFO - PROCESS | 11812 | |3;mootools.html;1428.3144090836392;1506.8041154649893;1543.3209387869338;1676.7149584570623;1799.154397433206;8324.250817426439;9103.635854565819;9418.340716149873;9429.198518125968;9681;9423.528823558812;9457.337986340941;9476.43141411514;9501.619935202601;9504.38221515601;2512.660790793238;2561.065211197918;2561.2700380391593;2561.3212498687863;2564.358910272432;2379.4884099918513;2475.5296493666237;2483.888225029869;2484.3416494628937;2485.763852434016;2493.6882889638796;2503.2114883811632;2537.023246050275;2538.6826646669165;2547.8726063696836;2423.4789520253053;2435.695538057743;2442.584760590695;2475.8638274894874;2487.2289590227088;2438.6951631046118;2467.383154211447;2478.2689106713565;2481.404462928884;2482.3049546127113;9105.544722763836;9170.770730731785;9245.445273283558;9450.33847630666;9530.142287194116;5541.639793413416;5692.5161361284345;5742.827715168552;5784.30588329399;5798.623089499211;3647.0699971507097;3668.413053911388;3677.191017976041;3708.7774733515807;3745.250949810049;1176.905847532204;1202.3988005997;1202.4949521201117;1213.5449206547544;1217.257472941505;12967.481300747959;13170.143940643828;13190.07669463138;13222.07445478817;13233.669158271025;4883.853484395474;4890.608751299887;5063.493650634907;5063.493650634907;5163.483651634881;11322.94338528302;11332.886671133349;11334.319940803578;11348.09215262777;11348.54605815758;5817.825465236049;5818.040023396092;5820.70896455184;5822.155787410887;5822.446867547576;4597.494275629678;4597.632189424838;5101.05630458359;5129.717865517432;5352.2506849041165;4302.741835489881;4312.37470566766;4324.1567894260315;4339.652827773771;4458.71018383804;1402.9649258768409;1412.9081609695336;1429.0211205324385;1440.5390275112072;1445.7614493608646;910;962.9277804164576;1032.0092710997376;1036.7356324137297;1048.7168464514539;6053.031514957674;6064.939350606437;6115.84710382244;6159.538034647419;6451.580647257913;967.8354679704468;982.9655962041294;994.6170724271208;1000.9349392289478;1027.5684212630713;4071.307877660805;4232.238197124486;4238.533761286256;4282.892927676833;4318.848840290575;11709.590164344207;11989.340586267665;12100.697482563006;12107.878921210851;12114.515419383126;11976.461059252355;11978.880211197951;11983.64049078529;11987.520499179935;12007.819882701766;8390.916090839135;8638.395312328203;8663.220310171988;8843.734687959372;9016.954915225382;6192.721327540362;6235.719392627433;6278.46633036197;6290.905636415366;6359.841003974938;10874.510647021061;10947.34315941046;10962;11084.501197446296;11117.499712512794;10834.133269338592;10974.561017559208;11106.111511079256;11219.887801121884;11396.715082123013;8468.280196183394;8514.10601886793;8636;8657.82684346297;8705.695300664447;9404.858927115962;9416.529173541432;9474.478903660365;9480.715578532656;9482.95258523703;8092.109867914407;8215.178482151738;8244.29923456513;8292.709755158781;8425.368097392597;8256.091829898587;8305.211004954519;8319.084900661044;8323.875141872755;8361.749147525585;7214.242504536948;7265.309795569412;7371;7411.5553066816165;7577.621118943921;5396.83809485716;5399.892002160013;5457.590680699042;5473.507384335429;5668.886622267614;5342.091844386541;5350.785968561369;5352.143657014859;5442.755076021509;5506.779728810803 15:57:41 INFO - PROCESS | 11812 | |4;prototype.html;333.0062736003331;343.7181511160848;344.11047442361496;349.3310310754906;352.4079546362114;5306.416294207634;5416.268803711498;5500.7996801279505;5502.266785962419;5550.7510810067715;5420.4850539198815;5430.5384042356345;5431.728413579315;5436.565074794017;5446.2647542581735;4305.892352691177;4317.992133657848;4326.502452218004;4326.762028088454;4337.175936572057;4189.7221347489;4298.677599180074;4344.717593356438;4359.149965756678;4362.825486980517;4273.444452221207;4287.2711639021445;4288.935665965013;4292.141571685675;4314.935275970863;4275.230458517465;4293.527711951699;4294.389603898538;4302.139572085595;4320.351947207912;3745.906352341186;3835.789031603261;3841.154945911895;3841.1741475582603;3852.9229415411537;5297.973510132463;5323.494268044529;5328.24168024551;5328.66783304174;5345.679259244458;4892.828750993698;4903.4606193318705;4907.533784290486;4910.852674419737;4913.434954980187;5453.7818487260465;5623.128415095667;5654.688992105433;5673.12066629669;5685.033544297479;4168.791560421967;4174.20690068886;4174.290370636999;4180.8118634661205;4189.224993376235;5964.284285885721;5972.432618901197;5975.625606110575;5987.9101813472835;6003.27960644721;3986.920261594752;4166.33338665812;4281.373078230253;4285.7642829644665;4287.356896465555;4221.113566151082;4230.132822771325;4230.1539692061715;4230.238557059759;4253.553376895444;4761.595264402565;4838.395200599925;4846.321514987904;4848.757562121879;4852.50096200457;5482.862928426821;5674.602777805514;5700.4299570043495;5729;5734.10427734;1452.8401875793866;1460.9561713148623;1467.4130347860942;1467.7284702330103;1471.139383460666;914.9908500914905;934.72425634438;938.2306508662831;947.488356287597;955.5332564513438;4916.483769204254;4986.401631804134;4990.251462280615;4992.825251116194;5068.746562671851;5172.948270517322;5184.792608295701;5195.818146364859;5215.504527069922;5238.009556368555;4641.350210970467;4957.90084198314;4966.329545511382;4970.304157417964;4982.7259500727805;2762.4613200425724;2768.8094119528796;2771.0716909835432;2782.4296019315993;2792.9526427589653;5263.6315457918345;5276.388187846964;5331.306930099062;5370.248165256867;5385.8114965976;5250.448702886219;5272.762725677354;5576.888462230732;6031.668258245751;6058.273007239071;6044.758209671564;6056.939430605726;6059.545534084873;6065.272167339947;6122.173506576555;5661.886762264814;5924.7926322578505;5939.46544810969;5944.554158438221;5950.494207992369;5769.278840144982;5831.446012628793;5859.882802344015;5878.941210587839;5880.441358070976;6066.484348830399;6150.477209437248;6159.784407545714;6176.382361763787;6212.254529456313;4018.7990600470443;4075.7758323292505;4097.262492751333;4181.60274773896;4183.163367326486;5815.273090863642;5819.388964158872;5819.94180058194;5823.7379317931645;5828.979928512408;5574.442555744556;5690.800821971211;5703.971480142573;5734;5746.281714785652;5664.660120392789;5711.171880077282;5719.370869204467;5721.284839395075;5725.599208055396;5635.112237400353;5660.613149778331;5663.7451314690115;5687.971560142173;5691.3170419549915;5555.30558680165;5728.770849165987;5735.827925162252;5744.885102298014;5776.191333213437;5552.611317207919;5555.2222688822785;5591.888162236651;5609.88780224385;5620.4098569649595 15:57:41 INFO - PROCESS | 11812 | |5;yui.html;736.7531876821265;746.9327760501553;757.9128400233973;812.2162113560413;899.7300809757071;15554.455594054196;16725.581860453465;16734.828562000665;16743.24655390507;16750;14739.705205895876;14952.252387380642;14965.25173741314;14971.550853474413;14984.325705343259;737.71229220604;748.4162353364385;864.0538610221829;884.8539990901494;887.7558671365362;893.13366034946;894.5571941888774;896.3053633434075;896.3232759266745;896.9641214351451;942.1897168435174;946.3895787217241;951.4814426137772;952.8332541805191;953.4803532075035;937.4937533731777;940.2337095267335;940.6284517615503;940.6331530703031;941.1200527506769;686.6635348679162;689.0422312984955;691.2189226174442;693.015917397297;693.4417793676089;14718.632034199123;14762.852371476256;14771.187584682839;14781.187034713086;14809.333579988926;15064.397424103023;15644.530664080095;15769.132697701622;15921;16094.5976350591;12723.363831808465;12982.805257921138;12996.805047924288;13031.739365212643;13116.934415327862;4131.215069136854;4173.937390939138;4196.118815048843;4252.192083504156;4252.787360631956;82876.58561707214;82994.58502707448;83280.58359708224;84846;84963.57518212369;12388.876111238953;12389.194702344319;12442.440090195963;12529;12642.2414655121;23975.160869369487;23982;23995.760042399703;24005;24024.51950960971;9059.547022648841;9114.81770364589;9375.765605859908;9477.099675530813;9663.806723865484;4743.241081426955;4948.331975183304;5023.522765337287;5054.974725126351;5055.9241611375855;3736.0473079365133;3846.8845934622004;3848.8845334640005;3857.7492462989817;3868.903277418087;574.873527823878;680.6290571638469;690.7340673840607;692.0760784352946;692.8441100752291;614.0359635372422;683.3337495941418;737.741790373365;753.7851712261979;754.9962250188823;9259.35184537076;9631.807363852684;9880.950595246977;10043.799124017478;10090;738.8780851159498;744.6090802328713;775.1279810213509;776.343989329016;795.157133438557;4720.268358404453;4748.75978174146;4763.642726795435;4789.664723469394;4808.519148085164;126724;134147;136286.3185684065;139814;140050.2997485006;93503;98134;110668.01993910293;114355.42822285835;114593.4270328643;59697;78841;93270.53364733132;94569;96084.23494590347;9347.158755712018;9392.483413412328;9657.565409556728;9717.465539395118;9789;55157;55506.44493555013;55546.72226638841;55761.539076874564;56295.15557266562;78358.6082069586;83876.58061709652;84681.57659211665;85677.57161214155;88618.67071994054;12658.493660253487;12999;13026.609201723963;13138.934305328412;13175.538856139989;13486;13789.241591712553;13912.095713778977;14387.640308992359;15279.541613751606;12267.202631828903;12867.935660321638;12928.095033347576;12971.935140324238;14217.075890067112;12996;13206.075574709677;13278.203307801563;14201.502947396792;14536.200508972108;8469.26693278857;12075.698107547381;12799.808002879778;13277.269750163834;13527.458901643824;11429.257098288585;11577.131715121568;11630;12057.247386241268;12229.3273869938;10762.408067556047;11163.776724465302;11344.716382090513;11390.829137562778;11438.313701177956 15:57:41 INFO - PROCESS | 11812 | __end_tp_report 15:57:41 INFO - PROCESS | 11812 | __start_cc_report 15:57:41 INFO - PROCESS | 11812 | _x_x_mozilla_cycle_collect,1027 15:57:41 INFO - PROCESS | 11812 | __end_cc_report 15:57:41 INFO - PROCESS | 11812 | __startTimestamp1474325861813__endTimestamp 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | ------- Summary: start ------- 15:57:41 INFO - PROCESS | 11812 | Number of tests: 6 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | [#0] dojo.html Cycles:165 Average:5659.67 Median:5893.20 stddev:650.07 (11.0%) stddev-sans-first:623.93 15:57:41 INFO - PROCESS | 11812 | Values: 3247.9 3250.0 3257.6 3284.5 3298.8 5525.6 5617.9 5621.9 5627.7 5637.1 5703.9 5777.5 5781.2 5784.0 5787.7 5046.9 5230.4 5233.2 5294.6 5301.1 5090.0 5179.6 5212.9 5227.9 5230.3 5085.7 5124.6 5130.2 5145.2 5165.9 5183.9 5198.6 5219.7 5226.5 5252.0 5056.7 5061.0 5116.8 5146.5 5147.7 5573.1 5582.6 5593.9 5599.3 5627.0 6076.7 6085.4 6093.0 6099.8 6107.9 6073.7 6098.6 6117.5 6135.8 6142.9 5724.7 5745.5 5747.7 5768.6 5782.1 6086.6 6275.4 6277.0 6277.2 6278.5 5927.0 5956.5 5984.3 6055.5 6055.6 6085.9 6087.1 6087.6 6090.6 6137.0 5929.4 6073.1 6077.1 6086.5 6089.6 5963.0 6025.4 6034.6 6046.7 6084.9 4610.2 4629.7 4642.7 4670.7 4698.1 4097.6 4127.0 4139.3 4159.2 4180.6 5742.0 5754.8 5763.9 5800.9 5842.3 6095.9 6102.7 6102.8 6106.7 6108.0 5722.1 5789.0 5791.9 5796.8 5798.0 5249.9 5301.9 5330.6 5346.8 5356.9 6353.7 6393.5 6397.8 6409.9 6417.7 5967.0 6152.3 6174.3 6184.3 6191.6 5969.8 6214.5 6336.1 6401.2 6409.6 6029.7 6030.4 6032.9 6041.4 6042.7 6004.9 6144.4 6162.4 6167.3 6174.6 5882.4 5901.1 5933.7 5987.0 6033.9 5919.6 5958.1 5964.4 5974.3 5976.8 5820.4 5919.8 5928.9 5938.2 5938.6 5893.2 5936.8 5945.5 5979.7 6016.5 5776.6 5779.7 5781.9 5816.7 5820.2 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | [#1] ext.html Cycles:180 Average:66481.35 Median:218335.20 stddev:132603.33 (60.7%) stddev-sans-first:132883.06 15:57:41 INFO - PROCESS | 11812 | Values: 600.7 635.2 638.1 659.6 669.4 33490.8 44563.1 45236.8 45244.1 45405.0 46837.0 47145.3 47193.0 47257.5 47275.3 8436.8 9219.1 9374.6 9375.4 10993.1 10787.7 11204.6 11210.0 11210.0 11215.8 11270.4 11315.5 11322.0 11327.0 11327.4 11184.0 11210.6 11253.4 11267.8 11270.6 11089.8 11125.1 11157.3 11166.1 11169.1 44609.0 44943.3 44955.6 44997.1 45001.5 3636.3 3665.3 3814.1 3838.1 3852.6 34168.7 34503.1 34838.7 36504.3 36573.5 4670.3 4678.1 4793.1 4813.0 4902.2 396000.0 396028.0 396965.0 397165.0 397876.0 56622.0 61588.7 65422.7 65896.7 65900.7 60898.0 66275.0 66497.7 67162.7 67178.7 19262.7 19478.3 19488.8 19548.0 19552.4 13779.2 14663.7 14699.5 15138.5 15143.5 17973.2 18056.6 18089.8 18134.0 18248.7 6815.8 6996.7 7010.0 7116.4 7245.9 995.6 1048.2 1053.5 1069.5 1102.2 32103.5 33634.0 34126.5 34718.5 34734.0 1140.0 1201.3 1203.2 1253.4 1256.0 18095.9 18108.2 18110.0 18186.3 18214.2 621737.0 624572.0 626600.0 627194.0 627519.0 64859.7 64939.7 65036.7 65058.0 65148.0 1351.1 1421.1 1439.2 1458.8 1481.9 35491.5 36938.3 36951.6 36996.6 37015.0 56272.4 56730.0 56790.0 56884.0 56969.0 445575.0 447296.0 447628.0 447701.0 448167.0 27885.3 30182.1 30401.7 31167.8 31674.7 46628.0 52599.5 52894.5 53534.0 54433.5 44974.8 45314.5 45965.8 46285.0 46613.3 44571.8 46376.8 46493.8 46994.5 47533.5 43914.8 44031.0 44321.3 44403.3 45227.5 37599.8 39184.6 39705.4 40565.6 40621.8 28998.0 29548.0 29608.5 29703.9 30099.4 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | [#2] jquery.html Cycles:180 Average:32635.76 Median:21638.73 stddev:96079.70 (444.0%) stddev-sans-first:96318.78 15:57:41 INFO - PROCESS | 11812 | Values: 420.2 421.6 432.4 435.2 435.8 20626.4 20678.8 21171.0 21355.5 21559.9 19924.4 21110.0 21213.9 21327.3 21335.7 5670.0 5984.2 5989.2 5996.0 5996.5 5678.4 5879.9 5902.5 5919.2 5920.4 5884.6 5886.5 5888.3 5890.9 5891.1 5657.7 5667.4 5712.3 5718.0 5718.5 5089.7 5120.4 5121.4 5126.9 5135.8 14648.1 14851.9 23264.2 23540.0 23943.6 2350.0 2361.9 2482.2 2525.1 2996.6 10052.6 10700.0 11169.5 11303.9 12955.8 1905.7 1954.2 1987.6 1992.3 2086.2 89813.0 90021.0 90431.0 93876.0 94662.5 11520.3 11690.8 11943.1 12199.6 12221.7 16922.2 16957.7 17010.7 17011.6 17019.0 8462.4 8920.6 9024.9 9054.6 9161.6 8571.4 8657.7 8721.8 8908.1 8954.7 5101.9 5113.0 5179.2 5224.4 5274.4 2020.3 2053.2 2105.8 2134.4 2140.9 1012.6 1021.4 1029.8 1056.2 1082.9 20840.4 21555.7 21616.9 21629.0 21648.5 1012.1 1026.4 1047.9 1066.1 1075.8 10693.0 11919.8 11970.8 12000.3 12034.5 561056.0 561242.0 581847.0 603194.0 607797.0 94698.0 97744.5 99951.5 103318.0 104082.5 1024.8 1069.8 1091.2 1108.7 1109.5 18279.9 18528.9 19081.6 19149.7 19348.7 41337.0 42095.6 43238.1 46284.5 46299.8 61455.4 61601.0 62615.4 66410.3 68162.7 8450.3 8595.8 8680.0 8750.5 8933.5 12170.8 13464.5 13608.3 13790.0 13848.2 11456.4 11517.5 11725.8 11961.0 12139.1 5524.4 8789.7 11735.9 11753.7 11755.8 11835.8 12130.2 12424.8 12447.6 12630.3 8580.7 8643.4 8723.3 8812.1 8971.9 11000.5 11519.1 11688.9 11850.4 11913.5 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | [#3] mootools.html Cycles:180 Average:6250.15 Median:4597.56 stddev:3590.56 (78.1%) stddev-sans-first:3582.35 15:57:41 INFO - PROCESS | 11812 | Values: 1428.3 1506.8 1543.3 1676.7 1799.2 8324.3 9103.6 9418.3 9429.2 9681.0 9423.5 9457.3 9476.4 9501.6 9504.4 2512.7 2561.1 2561.3 2561.3 2564.4 2379.5 2475.5 2483.9 2484.3 2485.8 2493.7 2503.2 2537.0 2538.7 2547.9 2423.5 2435.7 2442.6 2475.9 2487.2 2438.7 2467.4 2478.3 2481.4 2482.3 9105.5 9170.8 9245.4 9450.3 9530.1 5541.6 5692.5 5742.8 5784.3 5798.6 3647.1 3668.4 3677.2 3708.8 3745.3 1176.9 1202.4 1202.5 1213.5 1217.3 12967.5 13170.1 13190.1 13222.1 13233.7 4883.9 4890.6 5063.5 5063.5 5163.5 11322.9 11332.9 11334.3 11348.1 11348.5 5817.8 5818.0 5820.7 5822.2 5822.4 4597.5 4597.6 5101.1 5129.7 5352.3 4302.7 4312.4 4324.2 4339.7 4458.7 1403.0 1412.9 1429.0 1440.5 1445.8 910.0 962.9 1032.0 1036.7 1048.7 6053.0 6064.9 6115.8 6159.5 6451.6 967.8 983.0 994.6 1000.9 1027.6 4071.3 4232.2 4238.5 4282.9 4318.8 11709.6 11989.3 12100.7 12107.9 12114.5 11976.5 11978.9 11983.6 11987.5 12007.8 8390.9 8638.4 8663.2 8843.7 9017.0 6192.7 6235.7 6278.5 6290.9 6359.8 10874.5 10947.3 10962.0 11084.5 11117.5 10834.1 10974.6 11106.1 11219.9 11396.7 8468.3 8514.1 8636.0 8657.8 8705.7 9404.9 9416.5 9474.5 9480.7 9483.0 8092.1 8215.2 8244.3 8292.7 8425.4 8256.1 8305.2 8319.1 8323.9 8361.7 7214.2 7265.3 7371.0 7411.6 7577.6 5396.8 5399.9 5457.6 5473.5 5668.9 5342.1 5350.8 5352.1 5442.8 5506.8 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | [#4] prototype.html Cycles:180 Average:4741.53 Median:5314.96 stddev:1390.77 (26.2%) stddev-sans-first:1354.74 15:57:41 INFO - PROCESS | 11812 | Values: 333.0 343.7 344.1 349.3 352.4 5306.4 5416.3 5500.8 5502.3 5550.8 5420.5 5430.5 5431.7 5436.6 5446.3 4305.9 4318.0 4326.5 4326.8 4337.2 4189.7 4298.7 4344.7 4359.1 4362.8 4273.4 4287.3 4288.9 4292.1 4314.9 4275.2 4293.5 4294.4 4302.1 4320.4 3745.9 3835.8 3841.2 3841.2 3852.9 5298.0 5323.5 5328.2 5328.7 5345.7 4892.8 4903.5 4907.5 4910.9 4913.4 5453.8 5623.1 5654.7 5673.1 5685.0 4168.8 4174.2 4174.3 4180.8 4189.2 5964.3 5972.4 5975.6 5987.9 6003.3 3986.9 4166.3 4281.4 4285.8 4287.4 4221.1 4230.1 4230.2 4230.2 4253.6 4761.6 4838.4 4846.3 4848.8 4852.5 5482.9 5674.6 5700.4 5729.0 5734.1 1452.8 1461.0 1467.4 1467.7 1471.1 915.0 934.7 938.2 947.5 955.5 4916.5 4986.4 4990.3 4992.8 5068.7 5172.9 5184.8 5195.8 5215.5 5238.0 4641.4 4957.9 4966.3 4970.3 4982.7 2762.5 2768.8 2771.1 2782.4 2793.0 5263.6 5276.4 5331.3 5370.2 5385.8 5250.4 5272.8 5576.9 6031.7 6058.3 6044.8 6056.9 6059.5 6065.3 6122.2 5661.9 5924.8 5939.5 5944.6 5950.5 5769.3 5831.4 5859.9 5878.9 5880.4 6066.5 6150.5 6159.8 6176.4 6212.3 4018.8 4075.8 4097.3 4181.6 4183.2 5815.3 5819.4 5819.9 5823.7 5829.0 5574.4 5690.8 5704.0 5734.0 5746.3 5664.7 5711.2 5719.4 5721.3 5725.6 5635.1 5660.6 5663.7 5688.0 5691.3 5555.3 5728.8 5735.8 5744.9 5776.2 5552.6 5555.2 5591.9 5609.9 5620.4 15:57:41 INFO - PROCESS | 11812 | 15:57:41 INFO - PROCESS | 11812 | [#5] yui.html Cycles:180 Average:22326.88 Median:4746.00 stddev:33299.63 (701.6%) stddev-sans-first:33353.59 15:57:41 INFO - PROCESS | 11812 | Values: 736.8 746.9 757.9 812.2 899.7 15554.5 16725.6 16734.8 16743.2 16750.0 14739.7 14952.3 14965.3 14971.6 14984.3 737.7 748.4 864.1 884.9 887.8 893.1 894.6 896.3 896.3 897.0 942.2 946.4 951.5 952.8 953.5 937.5 940.2 940.6 940.6 941.1 686.7 689.0 691.2 693.0 693.4 14718.6 14762.9 14771.2 14781.2 14809.3 15064.4 15644.5 15769.1 15921.0 16094.6 12723.4 12982.8 12996.8 13031.7 13116.9 4131.2 4173.9 4196.1 4252.2 4252.8 82876.6 82994.6 83280.6 84846.0 84963.6 12388.9 12389.2 12442.4 12529.0 12642.2 23975.2 23982.0 23995.8 24005.0 24024.5 9059.5 9114.8 9375.8 9477.1 9663.8 4743.2 4948.3 5023.5 5055.0 5055.9 3736.0 3846.9 3848.9 3857.7 3868.9 574.9 680.6 690.7 692.1 692.8 614.0 683.3 737.7 753.8 755.0 9259.4 9631.8 9881.0 10043.8 10090.0 738.9 744.6 775.1 776.3 795.2 4720.3 4748.8 4763.6 4789.7 4808.5 126724.0 134147.0 136286.3 139814.0 140050.3 93503.0 98134.0 110668.0 114355.4 114593.4 59697.0 78841.0 93270.5 94569.0 96084.2 9347.2 9392.5 9657.6 9717.5 9789.0 55157.0 55506.4 55546.7 55761.5 56295.2 78358.6 83876.6 84681.6 85677.6 88618.7 12658.5 12999.0 13026.6 13138.9 13175.5 13486.0 13789.2 13912.1 14387.6 15279.5 12267.2 12867.9 12928.1 12971.9 14217.1 12996.0 13206.1 13278.2 14201.5 14536.2 8469.3 12075.7 12799.8 13277.3 13527.5 11429.3 11577.1 11630.0 12057.2 12229.3 10762.4 11163.8 11344.7 11390.8 11438.3 15:57:41 INFO - PROCESS | 11812 | -------- Summary: end -------- 15:57:41 INFO - PROCESS | 11812 | 15:57:42 INFO - TEST-INFO | 11812: exit 0 15:57:42 INFO - TEST-OK | dromaeo_css | took 1112808ms 15:57:42 INFO - TEST-START | kraken 15:57:42 INFO - operating with platform_type : linux_ 15:57:42 INFO - Initialising browser for kraken test... 15:57:42 INFO - TEST-INFO | started process 12000 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprTuX6t/profile http://localhost:34523/getInfo.html) 15:57:46 INFO - PROCESS | 12000 | __metrics Screen width/height:1600/1200 15:57:46 INFO - PROCESS | 12000 | colorDepth:24 15:57:46 INFO - PROCESS | 12000 | Browser inner width/height: 1024/697 15:57:46 INFO - PROCESS | 12000 | __metrics 15:57:47 INFO - PROCESS | 12000 | 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" 15:57:47 INFO - PROCESS | 12000 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:57:48 INFO - TEST-INFO | 12000: exit 0 15:57:48 INFO - Browser initialized. 15:57:48 INFO - Running cycle 1/1 for kraken test... 15:57:48 INFO - TEST-INFO | started process 12090 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprTuX6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:00 INFO - PROCESS | 12090 | RSS: Main: 144056320 15:58:00 INFO - PROCESS | 12090 | 15:58:02 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34523/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:03 INFO - PROCESS | 12090 | RSS: Main: 199331840 15:58:03 INFO - PROCESS | 12090 | 15:58:06 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34523/tests/kraken/driver.html?testName=audio-dft) 15:58:06 INFO - PROCESS | 12090 | RSS: Main: 183500800 15:58:06 INFO - PROCESS | 12090 | 15:58:09 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34523/tests/kraken/driver.html?testName=audio-fft) 15:58:10 INFO - PROCESS | 12090 | RSS: Main: 274481152 15:58:10 INFO - PROCESS | 12090 | 15:58:12 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34523/tests/kraken/driver.html?testName=audio-oscillator) 15:58:12 INFO - PROCESS | 12090 | RSS: Main: 180588544 15:58:12 INFO - PROCESS | 12090 | 15:58:15 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34523/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:15 INFO - PROCESS | 12090 | RSS: Main: 178679808 15:58:15 INFO - PROCESS | 12090 | 15:58:18 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34523/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:19 INFO - PROCESS | 12090 | RSS: Main: 214523904 15:58:19 INFO - PROCESS | 12090 | 15:58:22 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34523/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:23 INFO - PROCESS | 12090 | RSS: Main: 213475328 15:58:23 INFO - PROCESS | 12090 | 15:58:25 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34523/tests/kraken/driver.html?testName=json-parse-financial) 15:58:26 INFO - PROCESS | 12090 | RSS: Main: 214171648 15:58:26 INFO - PROCESS | 12090 | 15:58:28 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34523/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:58:28 INFO - PROCESS | 12090 | RSS: Main: 175894528 15:58:28 INFO - PROCESS | 12090 | 15:58:30 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:58:30 INFO - PROCESS | 12090 | RSS: Main: 182480896 15:58:30 INFO - PROCESS | 12090 | 15:58:32 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:58:33 INFO - PROCESS | 12090 | RSS: Main: 184307712 15:58:33 INFO - PROCESS | 12090 | 15:58:36 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:58:36 INFO - PROCESS | 12090 | RSS: Main: 185020416 15:58:36 INFO - PROCESS | 12090 | 15:58:39 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:58:39 INFO - PROCESS | 12090 | RSS: Main: 182640640 15:58:39 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | Cycle 1(1): loaded http://localhost:34523/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34523/tests/kraken/driver.html?testName=ai-astar) 15:58:41 INFO - PROCESS | 12090 | RSS: Main: 234405888 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | __start_tp_report 15:58:41 INFO - PROCESS | 12090 | _x_x_mozilla_page_load 15:58:41 INFO - PROCESS | 12090 | _x_x_mozilla_page_load_details 15:58:41 INFO - PROCESS | 12090 | |i|pagename|runs| 15:58:41 INFO - PROCESS | 12090 | |0;ai-astar;110;110;121;114;112;112;111;113;113;112 15:58:41 INFO - PROCESS | 12090 | |1;audio-beat-detection;145;116;145;168;116;145;140;118;180;116 15:58:41 INFO - PROCESS | 12090 | |2;audio-dft;163;162;163;163;161;163;163;161;162;159 15:58:41 INFO - PROCESS | 12090 | |3;audio-fft;75;76;76;170;76;74;74;76;171;75 15:58:41 INFO - PROCESS | 12090 | |4;audio-oscillator;83;83;160;84;84;83;144;82;82;146 15:58:41 INFO - PROCESS | 12090 | |5;imaging-gaussian-blur;117;117;117;116;116;116;117;116;116;117 15:58:41 INFO - PROCESS | 12090 | |6;imaging-darkroom;146;146;146;146;145;145;146;147;146;146 15:58:41 INFO - PROCESS | 12090 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;95;95 15:58:41 INFO - PROCESS | 12090 | |8;json-parse-financial;55;54;53;53;55;55;55;53;54;53 15:58:41 INFO - PROCESS | 12090 | |9;json-stringify-tinderbox;59;59;58;59;59;59;58;59;58;58 15:58:41 INFO - PROCESS | 12090 | |10;stanford-crypto-aes;72;75;72;72;80;72;72;71;72;72 15:58:41 INFO - PROCESS | 12090 | |11;stanford-crypto-ccm;131;129;117;122;123;121;118;118;144;121 15:58:41 INFO - PROCESS | 12090 | |12;stanford-crypto-pbkdf2;157;151;162;150;150;150;152;152;152;155 15:58:41 INFO - PROCESS | 12090 | |13;stanford-crypto-sha256-iterative;61;62;61;65;61;62;63;61;60;61 15:58:41 INFO - PROCESS | 12090 | __end_tp_report 15:58:41 INFO - PROCESS | 12090 | __start_cc_report 15:58:41 INFO - PROCESS | 12090 | _x_x_mozilla_cycle_collect,1649 15:58:41 INFO - PROCESS | 12090 | __end_cc_report 15:58:41 INFO - PROCESS | 12090 | __startTimestamp1474325921757__endTimestamp 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | ------- Summary: start ------- 15:58:41 INFO - PROCESS | 12090 | Number of tests: 14 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#0] ai-astar Cycles:10 Average:112.80 Median:112.50 stddev:3.16 (2.8%) stddev-sans-first:3.18 15:58:41 INFO - PROCESS | 12090 | Values: 110.0 110.0 121.0 114.0 112.0 112.0 111.0 113.0 113.0 112.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:145.00 stddev:22.75 (15.7%) stddev-sans-first:24.02 15:58:41 INFO - PROCESS | 12090 | Values: 145.0 116.0 145.0 168.0 116.0 145.0 140.0 118.0 180.0 116.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#2] audio-dft Cycles:10 Average:162.00 Median:163.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 15:58:41 INFO - PROCESS | 12090 | Values: 163.0 162.0 163.0 163.0 161.0 163.0 163.0 161.0 162.0 159.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.50 stddev:40.17 (53.2%) stddev-sans-first:41.99 15:58:41 INFO - PROCESS | 12090 | Values: 75.0 76.0 76.0 170.0 76.0 74.0 74.0 76.0 171.0 75.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:83.00 stddev:32.63 (39.3%) stddev-sans-first:33.79 15:58:41 INFO - PROCESS | 12090 | Values: 83.0 83.0 160.0 84.0 84.0 83.0 144.0 82.0 82.0 146.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 15:58:41 INFO - PROCESS | 12090 | Values: 117.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 117.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 15:58:41 INFO - PROCESS | 12090 | Values: 146.0 146.0 146.0 146.0 145.0 145.0 146.0 147.0 146.0 146.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:58:41 INFO - PROCESS | 12090 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:0.94 (1.7%) stddev-sans-first:0.93 15:58:41 INFO - PROCESS | 12090 | Values: 55.0 54.0 53.0 53.0 55.0 55.0 55.0 53.0 54.0 53.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 15:58:41 INFO - PROCESS | 12090 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 58.0 59.0 58.0 58.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:72.00 stddev:2.67 (3.7%) stddev-sans-first:2.80 15:58:41 INFO - PROCESS | 12090 | Values: 72.0 75.0 72.0 72.0 80.0 72.0 72.0 71.0 72.0 72.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:122.50 stddev:8.28 (6.8%) stddev-sans-first:8.43 15:58:41 INFO - PROCESS | 12090 | Values: 131.0 129.0 117.0 122.0 123.0 121.0 118.0 118.0 144.0 121.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.10 Median:152.00 stddev:3.87 (2.5%) stddev-sans-first:3.84 15:58:41 INFO - PROCESS | 12090 | Values: 157.0 151.0 162.0 150.0 150.0 150.0 152.0 152.0 152.0 155.0 15:58:41 INFO - PROCESS | 12090 | 15:58:41 INFO - PROCESS | 12090 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.50 stddev:1.42 (2.3%) stddev-sans-first:1.48 15:58:41 INFO - PROCESS | 12090 | Values: 61.0 62.0 61.0 65.0 61.0 62.0 63.0 61.0 60.0 61.0 15:58:41 INFO - PROCESS | 12090 | -------- Summary: end -------- 15:58:41 INFO - PROCESS | 12090 | 15:58:42 INFO - TEST-INFO | 12090: exit 0 15:58:42 INFO - TEST-OK | kraken | took 60153ms 15:58:42 INFO - SUITE-END | took 1172s 15:58:42 INFO - Completed test suite (00:19:33) 15:58:42 INFO - javascript benchmark 15:58:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3247.8863239786597, 3249.951250731239, 3257.6253730820963, 3284.458064419371, 3298.785578937369, 5525.557955363572, 5617.915731264029, 5621.859453513664, 5627.7467513961865, 5637.069883469233, 5703.914441283384, 5777.45114214149, 5781.219535362725, 5784.0, 5787.71061446927, 5046.873828154307, 5230.398504172012, 5233.188855727359, 5294.57643388528, 5301.098813201765, 5089.9565589054155, 5179.637425380225, 5212.921806172911, 5227.86930326743, 5230.34620672416, 5085.74571271435, 5124.590032797368, 5130.230465430178, 5145.202493613496, 5165.9225111623355, 5183.896322073575, 5198.558122559587, 5219.660722053073, 5226.451222621608, 5252.0283747506455, 5056.6713163644245, 5061.0, 5116.846494605168, 5146.485351464861, 5147.691138531663, 5573.080441727151, 5582.637128586629, 5593.916091258634, 5599.272094627672, 5627.043402621564, 6076.696165191723, 6085.42188492097, 6092.9695351523405, 6099.786507472261, 6107.87784244317, 6073.726682299307, 6098.634081955097, 6117.510599152057, 6135.754569817246, 6142.907856382158, 5724.685142317171, 5745.540356771448, 5747.741351639186, 5768.6250393724695, 5782.132680097977, 6086.609060486471, 6275.3724627537795, 6276.968615156987, 6277.24673039229, 6278.529110316745, 5927.0, 5956.463918247378, 5984.311804142492, 6055.515558755289, 6055.576109672281, 6085.862870946158, 6087.05650624154, 6087.573869829158, 6090.6041107328765, 6137.048757442603, 5929.4070592941225, 6073.089036644538, 6077.088436734525, 6086.513078953762, 6089.5737298388685, 5962.970185149046, 6025.427584379477, 6034.63792172471, 6046.667433291123, 6084.878302433926, 4610.170169369546, 4629.745364005012, 4642.744649044335, 4670.673052886334, 4698.083873644675, 4097.590240975878, 4126.979365103155, 4139.337705967031, 4159.230542349675, 4180.581941805795, 5741.971290143522, 5754.827355179267, 5763.942360576424, 5800.9129863052085, 5842.328132264759, 6095.908561371583, 6102.664353460514, 6102.7558897644485, 6106.664133472702, 6107.969460152671, 5722.141678748221, 5789.044807606698, 5791.884162316815, 5796.768129274782, 5798.043322851682, 5249.868753281198, 5301.867453313621, 5330.600204984642, 5346.8395948121615, 5356.946430535645, 6353.745850166034, 6393.520485963571, 6397.776077837253, 6409.871802563923, 6417.679116044273, 5967.0, 6152.292486364126, 6174.25908890936, 6184.319724830355, 6191.597546159485, 5969.791057313147, 6214.5028397728975, 6336.08126821599, 6401.199850018747, 6409.647469389229, 6029.668368239789, 6030.3668114847305, 6032.939670603238, 6041.426064523863, 6042.667653279112, 6004.939950600438, 6144.434497165096, 6162.383761623802, 6167.321594624498, 6174.567780255339, 5882.441168089024, 5901.114832775118, 5933.732982015906, 5987.0, 6033.909491357721, 5919.556033297606, 5958.106284057426, 5964.433378829004, 5974.253218347707, 5976.7908123215475, 5820.447057529528, 5919.792807251725, 5928.851778705567, 5938.168656388023, 5938.61399009063, 5893.2044174035955, 5936.821895343147, 5945.494632956247, 5979.730912108878, 6016.5487588431915, 5776.624519406225, 5779.7110144493445, 5781.8554536136935, 5816.680082595498, 5820.214271073351], "value": 5615.090415172548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.7266693654387, 635.1837888313515, 638.1193952345762, 659.574574399512, 669.3808227389667, 33490.832545837264, 44563.10873782523, 45236.77381613095, 45244.0951180977, 45404.999999999956, 46837.0, 47145.29282060772, 47193.0, 47257.52742472574, 47275.29087063688, 8436.789080272982, 9219.078092190777, 9374.578143983537, 9375.437473751563, 10993.120550355952, 10787.676369708883, 11204.551817927273, 11210.0, 11210.04714599256, 11215.831762523569, 11270.380129092897, 11315.547378104866, 11322.0, 11327.0, 11327.376994265313, 11184.0, 11210.607628732996, 11253.381064041474, 11267.774644507064, 11270.605528806534, 11089.778204435866, 11125.109991200765, 11157.27477713946, 11166.106711463064, 11169.0506306964, 44609.0, 44943.32585011228, 44955.550444495464, 44997.10005799866, 45001.549984500394, 3636.30910127075, 3665.2852693724735, 3814.1036856338737, 3838.0980469589904, 3852.57621661617, 34168.658313416796, 34503.137421564665, 34838.651613483795, 36504.26991460183, 36573.451398229045, 4670.276107203389, 4678.134545109166, 4793.113274044313, 4812.965212479334, 4902.2156454967035, 395999.99999999715, 396027.9999999971, 396965.0, 397165.0, 397876.0, 56622.0, 61588.69205653943, 65422.672886635264, 65896.67051664807, 65900.67049664816, 60898.0, 66275.0, 66497.66751166213, 67162.66418667875, 67178.66410668014, 19262.71105933384, 19478.318258860872, 19488.8051119487, 19548.0, 19552.413427597196, 13779.173249605055, 14663.706725865422, 14699.485518007032, 15138.545843624708, 15143.545693629208, 17973.19120639548, 18056.638867222846, 18089.819101808815, 18134.0, 18248.726269105973, 6815.773160830999, 6996.6851491683, 7009.9649501752165, 7116.430685545248, 7245.8550828983125, 995.6415690351467, 1048.1667074675652, 1053.536443964653, 1069.5294070608907, 1102.1513434655271, 32103.51844722331, 33634.0, 34126.48810267848, 34718.47922281168, 34734.0, 1139.9544018239178, 1201.3392634051236, 1203.1517779965104, 1253.367049639926, 1255.9638298403854, 18095.909520452577, 18108.185131669106, 18109.96325313734, 18186.272549098154, 18214.180361883482, 621737.0, 624572.0, 626600.0, 627194.0, 627519.0, 64859.67570162119, 64939.675301624135, 65036.674816625615, 65058.0, 65148.0, 1351.1352734250088, 1421.1118051217989, 1439.2084353605476, 1458.8103546539094, 1481.8592233737777, 35491.46762798508, 36938.261234775695, 36951.63048369482, 36996.63003370039, 37015.0, 56272.437275628356, 56730.0, 56790.0, 56884.0, 56969.0, 445575.0, 447296.0, 447628.0, 447701.0, 448167.0, 27885.302867428476, 30182.09453716392, 30401.69598303989, 31167.844160779958, 31674.68325316717, 46628.0, 52599.47400525946, 52894.471055290494, 53534.0, 54433.455665444424, 44974.77512612416, 45314.546854531036, 45965.77017114893, 46285.0, 46613.3008004887, 44571.76991811287, 46376.768116159205, 46493.76753116213, 46994.53005469902, 47533.524664754295, 43914.78042609766, 44031.0, 44321.33517997297, 44403.33394999142, 45227.54772452365, 37599.81200093982, 39184.60815391924, 39705.40441893432, 40565.56606603613, 40621.79689101654, 28998.0, 29548.0, 29608.525271912782, 29703.851480742458, 30099.398012039197], "value": 22082.867187775308, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.1848414076689, 421.6288897426046, 432.4378308199342, 435.22747123855163, 435.84527492740074, 20626.381208563747, 20678.793212067874, 21171.0, 21355.466113347135, 21559.892200538994, 19924.40226793195, 21110.0, 21213.893930530325, 21327.25354612589, 21335.6799648005, 5670.0, 5984.192134061889, 5989.221401217836, 5995.970020149894, 5996.4902983246475, 5678.403767604404, 5879.941200588003, 5902.498287645555, 5919.1713160157615, 5920.378360272174, 5884.617499862496, 5886.5290776737975, 5888.322842873082, 5890.88218235635, 5891.057430811071, 5657.745401456944, 5667.4049224831415, 5712.314522257356, 5717.971410142923, 5718.485336319731, 5089.720065396402, 5120.3855537335385, 5121.359830021247, 5126.897462050775, 5135.84592462223, 14648.121112733164, 14851.851481485155, 23264.185753498714, 23540.0, 23943.640845387334, 2350.0, 2361.8937147828387, 2482.15606693725, 2525.06572568151, 2996.5654980027775, 10052.59789608422, 10700.0, 11169.497372618252, 11303.886961130365, 12955.805662914969, 1905.6950887858015, 1954.2378472395776, 1987.6422243996076, 1992.3126521350111, 2086.1655337864822, 89813.0, 90021.0, 90431.0, 93876.0, 94662.52668736612, 11520.308781473139, 11690.824637630272, 11943.104267179997, 12199.573014944433, 12221.694457638452, 16922.15389230558, 16957.660846782994, 17010.659786804194, 17011.574710632085, 17019.0, 8462.449940753831, 8920.598573064228, 9024.8646270306, 9054.592543335566, 9161.587728552231, 8571.357148213785, 8657.696980605648, 8721.825563488821, 8908.064653211319, 8954.686585969459, 5101.928594995018, 5112.977404519111, 5179.19722443022, 5224.373075230997, 5274.4198138204765, 2020.3029954665617, 2053.2300387354744, 2105.7894210579125, 2134.402367337148, 2140.9036593353335, 1012.594962015185, 1021.3973755484151, 1029.7940411917646, 1056.1867362131115, 1082.8592283003159, 20840.374788756362, 21555.67666485004, 21616.89191554032, 21629.0, 21648.458788530097, 1012.1143999000874, 1026.3790406803926, 1047.874255089379, 1066.1311031509294, 1075.800976819291, 10693.0, 11919.761604767857, 11970.82043769344, 12000.279983201035, 12034.458449369624, 561056.0, 561242.0, 581847.0, 603194.0, 607797.0, 94698.0, 97744.51127744316, 99951.50024249832, 103318.0, 104082.47958760157, 1024.7591815923251, 1069.8395240713955, 1091.2197778588347, 1108.7394462301352, 1109.4619109731773, 18279.908600457446, 18528.88826670404, 19081.61836763229, 19149.712754308974, 19348.70976935375, 41337.0, 42095.57904421039, 43238.135237295704, 46284.53715462802, 46299.768501157276, 61455.38544614497, 61601.0, 62615.37384626277, 66410.33589664042, 68162.65918670375, 8450.323974082181, 8595.785105372415, 8679.956600216958, 8750.47497150173, 8933.508657023925, 12170.817437738264, 13464.528741494001, 13608.25154616466, 13790.0, 13848.238346890616, 11456.427178641201, 11517.53929842797, 11725.76548469043, 11961.0, 12139.089568282237, 5524.392316845224, 8789.692360767342, 11735.941320293345, 11753.706157345792, 11755.823662644896, 11835.82246266324, 12130.211536250115, 12424.813627795409, 12447.626571202878, 12630.305333206394, 8580.742577722678, 8643.438176518506, 8723.302135829246, 8812.07473215303, 8971.910280897107, 11000.504977276201, 11519.07847372225, 11688.94155529217, 11850.407479626158, 11913.463894124958], "value": 9683.235173500465, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.3144090836392, 1506.8041154649893, 1543.3209387869338, 1676.7149584570623, 1799.154397433206, 8324.250817426439, 9103.635854565819, 9418.340716149873, 9429.198518125968, 9681.0, 9423.528823558812, 9457.337986340941, 9476.43141411514, 9501.619935202601, 9504.38221515601, 2512.660790793238, 2561.065211197918, 2561.2700380391593, 2561.3212498687863, 2564.358910272432, 2379.4884099918513, 2475.5296493666237, 2483.888225029869, 2484.3416494628937, 2485.763852434016, 2493.6882889638796, 2503.2114883811632, 2537.023246050275, 2538.6826646669165, 2547.8726063696836, 2423.4789520253053, 2435.695538057743, 2442.584760590695, 2475.8638274894874, 2487.2289590227088, 2438.6951631046118, 2467.383154211447, 2478.2689106713565, 2481.404462928884, 2482.3049546127113, 9105.544722763836, 9170.770730731785, 9245.445273283558, 9450.33847630666, 9530.142287194116, 5541.639793413416, 5692.5161361284345, 5742.827715168552, 5784.30588329399, 5798.623089499211, 3647.0699971507097, 3668.413053911388, 3677.191017976041, 3708.7774733515807, 3745.250949810049, 1176.905847532204, 1202.3988005997, 1202.4949521201117, 1213.5449206547544, 1217.257472941505, 12967.481300747959, 13170.143940643828, 13190.07669463138, 13222.07445478817, 13233.669158271025, 4883.853484395474, 4890.608751299887, 5063.493650634907, 5063.493650634907, 5163.483651634881, 11322.94338528302, 11332.886671133349, 11334.319940803578, 11348.09215262777, 11348.54605815758, 5817.825465236049, 5818.040023396092, 5820.70896455184, 5822.155787410887, 5822.446867547576, 4597.494275629678, 4597.632189424838, 5101.05630458359, 5129.717865517432, 5352.2506849041165, 4302.741835489881, 4312.37470566766, 4324.1567894260315, 4339.652827773771, 4458.71018383804, 1402.9649258768409, 1412.9081609695336, 1429.0211205324385, 1440.5390275112072, 1445.7614493608646, 910.0, 962.9277804164576, 1032.0092710997376, 1036.7356324137297, 1048.7168464514539, 6053.031514957674, 6064.939350606437, 6115.84710382244, 6159.538034647419, 6451.580647257913, 967.8354679704468, 982.9655962041294, 994.6170724271208, 1000.9349392289478, 1027.5684212630713, 4071.307877660805, 4232.238197124486, 4238.533761286256, 4282.892927676833, 4318.848840290575, 11709.590164344207, 11989.340586267665, 12100.697482563006, 12107.878921210851, 12114.515419383126, 11976.461059252355, 11978.880211197951, 11983.64049078529, 11987.520499179935, 12007.819882701766, 8390.916090839135, 8638.395312328203, 8663.220310171988, 8843.734687959372, 9016.954915225382, 6192.721327540362, 6235.719392627433, 6278.46633036197, 6290.905636415366, 6359.841003974938, 10874.510647021061, 10947.34315941046, 10962.0, 11084.501197446296, 11117.499712512794, 10834.133269338592, 10974.561017559208, 11106.111511079256, 11219.887801121884, 11396.715082123013, 8468.280196183394, 8514.10601886793, 8636.0, 8657.82684346297, 8705.695300664447, 9404.858927115962, 9416.529173541432, 9474.478903660365, 9480.715578532656, 9482.95258523703, 8092.109867914407, 8215.178482151738, 8244.29923456513, 8292.709755158781, 8425.368097392597, 8256.091829898587, 8305.211004954519, 8319.084900661044, 8323.875141872755, 8361.749147525585, 7214.242504536948, 7265.309795569412, 7371.0, 7411.5553066816165, 7577.621118943921, 5396.83809485716, 5399.892002160013, 5457.590680699042, 5473.507384335429, 5668.886622267614, 5342.091844386541, 5350.785968561369, 5352.143657014859, 5442.755076021509, 5506.779728810803], "value": 4981.215028050358, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.0062736003331, 343.7181511160848, 344.11047442361496, 349.3310310754906, 352.4079546362114, 5306.416294207634, 5416.268803711498, 5500.7996801279505, 5502.266785962419, 5550.7510810067715, 5420.4850539198815, 5430.5384042356345, 5431.728413579315, 5436.565074794017, 5446.2647542581735, 4305.892352691177, 4317.992133657848, 4326.502452218004, 4326.762028088454, 4337.175936572057, 4189.7221347489, 4298.677599180074, 4344.717593356438, 4359.149965756678, 4362.825486980517, 4273.444452221207, 4287.2711639021445, 4288.935665965013, 4292.141571685675, 4314.935275970863, 4275.230458517465, 4293.527711951699, 4294.389603898538, 4302.139572085595, 4320.351947207912, 3745.906352341186, 3835.789031603261, 3841.154945911895, 3841.1741475582603, 3852.9229415411537, 5297.973510132463, 5323.494268044529, 5328.24168024551, 5328.66783304174, 5345.679259244458, 4892.828750993698, 4903.4606193318705, 4907.533784290486, 4910.852674419737, 4913.434954980187, 5453.7818487260465, 5623.128415095667, 5654.688992105433, 5673.12066629669, 5685.033544297479, 4168.791560421967, 4174.20690068886, 4174.290370636999, 4180.8118634661205, 4189.224993376235, 5964.284285885721, 5972.432618901197, 5975.625606110575, 5987.9101813472835, 6003.27960644721, 3986.920261594752, 4166.33338665812, 4281.373078230253, 4285.7642829644665, 4287.356896465555, 4221.113566151082, 4230.132822771325, 4230.1539692061715, 4230.238557059759, 4253.553376895444, 4761.595264402565, 4838.395200599925, 4846.321514987904, 4848.757562121879, 4852.50096200457, 5482.862928426821, 5674.602777805514, 5700.4299570043495, 5729.0, 5734.10427734, 1452.8401875793866, 1460.9561713148623, 1467.4130347860942, 1467.7284702330103, 1471.139383460666, 914.9908500914905, 934.72425634438, 938.2306508662831, 947.488356287597, 955.5332564513438, 4916.483769204254, 4986.401631804134, 4990.251462280615, 4992.825251116194, 5068.746562671851, 5172.948270517322, 5184.792608295701, 5195.818146364859, 5215.504527069922, 5238.009556368555, 4641.350210970467, 4957.90084198314, 4966.329545511382, 4970.304157417964, 4982.7259500727805, 2762.4613200425724, 2768.8094119528796, 2771.0716909835432, 2782.4296019315993, 2792.9526427589653, 5263.6315457918345, 5276.388187846964, 5331.306930099062, 5370.248165256867, 5385.8114965976, 5250.448702886219, 5272.762725677354, 5576.888462230732, 6031.668258245751, 6058.273007239071, 6044.758209671564, 6056.939430605726, 6059.545534084873, 6065.272167339947, 6122.173506576555, 5661.886762264814, 5924.7926322578505, 5939.46544810969, 5944.554158438221, 5950.494207992369, 5769.278840144982, 5831.446012628793, 5859.882802344015, 5878.941210587839, 5880.441358070976, 6066.484348830399, 6150.477209437248, 6159.784407545714, 6176.382361763787, 6212.254529456313, 4018.7990600470443, 4075.7758323292505, 4097.262492751333, 4181.60274773896, 4183.163367326486, 5815.273090863642, 5819.388964158872, 5819.94180058194, 5823.7379317931645, 5828.979928512408, 5574.442555744556, 5690.800821971211, 5703.971480142573, 5734.0, 5746.281714785652, 5664.660120392789, 5711.171880077282, 5719.370869204467, 5721.284839395075, 5725.599208055396, 5635.112237400353, 5660.613149778331, 5663.7451314690115, 5687.971560142173, 5691.3170419549915, 5555.30558680165, 5728.770849165987, 5735.827925162252, 5744.885102298014, 5776.191333213437, 5552.611317207919, 5555.2222688822785, 5591.888162236651, 5609.88780224385, 5620.4098569649595], "value": 4298.684942071748, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.7531876821265, 746.9327760501553, 757.9128400233973, 812.2162113560413, 899.7300809757071, 15554.455594054196, 16725.581860453465, 16734.828562000665, 16743.24655390507, 16750.0, 14739.705205895876, 14952.252387380642, 14965.25173741314, 14971.550853474413, 14984.325705343259, 737.71229220604, 748.4162353364385, 864.0538610221829, 884.8539990901494, 887.7558671365362, 893.13366034946, 894.5571941888774, 896.3053633434075, 896.3232759266745, 896.9641214351451, 942.1897168435174, 946.3895787217241, 951.4814426137772, 952.8332541805191, 953.4803532075035, 937.4937533731777, 940.2337095267335, 940.6284517615503, 940.6331530703031, 941.1200527506769, 686.6635348679162, 689.0422312984955, 691.2189226174442, 693.015917397297, 693.4417793676089, 14718.632034199123, 14762.852371476256, 14771.187584682839, 14781.187034713086, 14809.333579988926, 15064.397424103023, 15644.530664080095, 15769.132697701622, 15921.0, 16094.5976350591, 12723.363831808465, 12982.805257921138, 12996.805047924288, 13031.739365212643, 13116.934415327862, 4131.215069136854, 4173.937390939138, 4196.118815048843, 4252.192083504156, 4252.787360631956, 82876.58561707214, 82994.58502707448, 83280.58359708224, 84846.0, 84963.57518212369, 12388.876111238953, 12389.194702344319, 12442.440090195963, 12529.0, 12642.2414655121, 23975.160869369487, 23982.0, 23995.760042399703, 24005.0, 24024.51950960971, 9059.547022648841, 9114.81770364589, 9375.765605859908, 9477.099675530813, 9663.806723865484, 4743.241081426955, 4948.331975183304, 5023.522765337287, 5054.974725126351, 5055.9241611375855, 3736.0473079365133, 3846.8845934622004, 3848.8845334640005, 3857.7492462989817, 3868.903277418087, 574.873527823878, 680.6290571638469, 690.7340673840607, 692.0760784352946, 692.8441100752291, 614.0359635372422, 683.3337495941418, 737.741790373365, 753.7851712261979, 754.9962250188823, 9259.35184537076, 9631.807363852684, 9880.950595246977, 10043.799124017478, 10090.0, 738.8780851159498, 744.6090802328713, 775.1279810213509, 776.343989329016, 795.157133438557, 4720.268358404453, 4748.75978174146, 4763.642726795435, 4789.664723469394, 4808.519148085164, 126724.0, 134147.0, 136286.3185684065, 139814.0, 140050.2997485006, 93503.0, 98134.0, 110668.01993910293, 114355.42822285835, 114593.4270328643, 59697.0, 78841.0, 93270.53364733132, 94569.0, 96084.23494590347, 9347.158755712018, 9392.483413412328, 9657.565409556728, 9717.465539395118, 9789.0, 55157.0, 55506.44493555013, 55546.72226638841, 55761.539076874564, 56295.15557266562, 78358.6082069586, 83876.58061709652, 84681.57659211665, 85677.57161214155, 88618.67071994054, 12658.493660253487, 12999.0, 13026.609201723963, 13138.934305328412, 13175.538856139989, 13486.0, 13789.241591712553, 13912.095713778977, 14387.640308992359, 15279.541613751606, 12267.202631828903, 12867.935660321638, 12928.095033347576, 12971.935140324238, 14217.075890067112, 12996.0, 13206.075574709677, 13278.203307801563, 14201.502947396792, 14536.200508972108, 8469.26693278857, 12075.698107547381, 12799.808002879778, 13277.269750163834, 13527.458901643824, 11429.257098288585, 11577.131715121568, 11630.0, 12057.247386241268, 12229.3273869938, 10762.408067556047, 11163.776724465302, 11344.716382090513, 11390.829137562778, 11438.313701177956], "value": 7934.985356419809, "unit": "score"}], "name": "dromaeo_css", "value": 7672.716504128165, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 121.0, 114.0, 112.0, 112.0, 111.0, 113.0, 113.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 145.0, 168.0, 116.0, 145.0, 140.0, 118.0, 180.0, 116.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 163.0, 161.0, 163.0, 163.0, 161.0, 162.0, 159.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 170.0, 76.0, 74.0, 74.0, 76.0, 171.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 160.0, 84.0, 84.0, 83.0, 144.0, 82.0, 82.0, 146.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 147.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 53.0, 55.0, 55.0, 55.0, 53.0, 54.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 72.0, 80.0, 72.0, 72.0, 71.0, 72.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 129.0, 117.0, 122.0, 123.0, 121.0, 118.0, 118.0, 144.0, 121.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 151.0, 162.0, 150.0, 150.0, 150.0, 152.0, 152.0, 152.0, 155.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 65.0, 61.0, 62.0, 63.0, 61.0, 60.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1492.6, "alertThreshold": 2.0}]} 15:58:42 INFO - Return code: 0 15:58:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:58:43 INFO - # TBPL SUCCESS # 15:58:43 INFO - Running post-action listener: _resource_record_post_action 15:58:43 INFO - [mozharness: 2016-09-19 22:58:43.049106Z] Finished run-tests step (success) 15:58:43 INFO - Running post-run listener: _resource_record_post_run 15:58:43 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 287547392; Read time: 0; Write time: 245896 15:58:43 INFO - TinderboxPrint: CPU usage
12.9% 15:58:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:58:43 INFO - TinderboxPrint: I/O write bytes / time
287,547,392 / 245,896 15:58:43 INFO - TinderboxPrint: CPU idle
8,257.7 (87.1%) 15:58:43 INFO - TinderboxPrint: CPU user
1,179.8 (12.4%) 15:58:43 INFO - TinderboxPrint: Swap in / out
0 / 0 15:58:43 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71282688; Read time: 0; Write time: 57196 15:58:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 216264704; Read time: 0; Write time: 188700 15:58:43 INFO - Running post-run listener: _upload_blobber_files 15:58:43 INFO - Blob upload gear active. 15:58:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:58:43 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 15:58:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:58:44 INFO - (blobuploader) - INFO - Open directory for files ... 15:58:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:58:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:58:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:45 INFO - (blobuploader) - INFO - Done attempting. 15:58:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:58:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:58:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:50 INFO - (blobuploader) - INFO - Done attempting. 15:58:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:58:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:58:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:51 INFO - (blobuploader) - INFO - Done attempting. 15:58:51 INFO - (blobuploader) - INFO - Iteration through files over. 15:58:51 INFO - Return code: 0 15:58:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:58:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:58:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1716499b271542251f7ca8b004a75ab62a9f80fc360d03cc5b592c2842da15bc48bc5b3286419d282b7d5521240efce278310b7cd7007d2d34a5064115934d02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f8a5ad9b5100b0d7a7b941c73c3e2e231c4662585ceea1262a4ac6ca28cfc9b6dc90be0612f5f717ddb4e5f4f7196e8ee676579edcd83a8930d1445211330c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7591071367b438a7390e1b820b0cafbe73b441d993caa4c241851e6feda822d75581c4348e44d1bcccc1ddec87771df79d1269fac8b9db7367fae627f6c2bb66"} 15:58:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:58:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:58:51 INFO - Contents: 15:58:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1716499b271542251f7ca8b004a75ab62a9f80fc360d03cc5b592c2842da15bc48bc5b3286419d282b7d5521240efce278310b7cd7007d2d34a5064115934d02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f8a5ad9b5100b0d7a7b941c73c3e2e231c4662585ceea1262a4ac6ca28cfc9b6dc90be0612f5f717ddb4e5f4f7196e8ee676579edcd83a8930d1445211330c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7591071367b438a7390e1b820b0cafbe73b441d993caa4c241851e6feda822d75581c4348e44d1bcccc1ddec87771df79d1269fac8b9db7367fae627f6c2bb66"} 15:58:51 INFO - Running post-run listener: copy_logs_to_upload_dir 15:58:51 INFO - Copying logs to upload dir... 15:58:51 INFO - mkdir: /builds/slave/test/build/upload/logs 15:58:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.379766 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-19 15:58:50.953703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 15:58:50.957102) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1716499b271542251f7ca8b004a75ab62a9f80fc360d03cc5b592c2842da15bc48bc5b3286419d282b7d5521240efce278310b7cd7007d2d34a5064115934d02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f8a5ad9b5100b0d7a7b941c73c3e2e231c4662585ceea1262a4ac6ca28cfc9b6dc90be0612f5f717ddb4e5f4f7196e8ee676579edcd83a8930d1445211330c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7591071367b438a7390e1b820b0cafbe73b441d993caa4c241851e6feda822d75581c4348e44d1bcccc1ddec87771df79d1269fac8b9db7367fae627f6c2bb66"} build_url:https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1716499b271542251f7ca8b004a75ab62a9f80fc360d03cc5b592c2842da15bc48bc5b3286419d282b7d5521240efce278310b7cd7007d2d34a5064115934d02", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f8a5ad9b5100b0d7a7b941c73c3e2e231c4662585ceea1262a4ac6ca28cfc9b6dc90be0612f5f717ddb4e5f4f7196e8ee676579edcd83a8930d1445211330c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7591071367b438a7390e1b820b0cafbe73b441d993caa4c241851e6feda822d75581c4348e44d1bcccc1ddec87771df79d1269fac8b9db7367fae627f6c2bb66"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 15:58:50.982299) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:58:50.982614) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1474324251.763770-2109807262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:58:51.068579) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 15:58:51.068897) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 15:58:51.069190) ========= ========= Total master_lag: 0.14 =========