builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1469660740.42 results: success (0) buildid: 20160727132927 builduid: d66bb7e5b40743cc8db59b6327363edd revision: dc272cf9d820263c646fc42f6354b55203019e3b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.419293) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.419777) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.420070) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.432009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.432358) ========= 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=17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 16:05:40-- 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% 220M=0s 2016-07-27 16:05:40 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121322 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.566201) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.566551) ========= 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=17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023202 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.617710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:40.618243) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc272cf9d820263c646fc42f6354b55203019e3b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc272cf9d820263c646fc42f6354b55203019e3b --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=17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 16:05:40,806 truncating revision to first 12 chars 2016-07-27 16:05:40,806 Setting DEBUG logging. 2016-07-27 16:05:40,806 attempt 1/10 2016-07-27 16:05:40,806 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc272cf9d820?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 16:05:41,128 unpacking tar archive at: mozilla-aurora-dc272cf9d820/testing/mozharness/ program finished with exit code 0 elapsedTime=0.676925 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:41.309552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:41.309936) ========= script_repo_revision: dc272cf9d820263c646fc42f6354b55203019e3b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:41.310434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:41.310712) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 16:05:41.321906) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-27 16:05:41.322587) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:05:41 INFO - MultiFileLogger online at 20160727 16:05:41 in /builds/slave/test 16:05:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:05:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:05:41 INFO - {'append_to_log': False, 16:05:41 INFO - 'base_work_dir': '/builds/slave/test', 16:05:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:05:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:05:41 INFO - 'branch': 'Mozilla-Aurora', 16:05:41 INFO - 'buildbot_json_path': 'buildprops.json', 16:05:41 INFO - 'config_files': ('talos/linux_config.py',), 16:05:41 INFO - 'default_actions': ('clobber', 16:05:41 INFO - 'read-buildbot-config', 16:05:41 INFO - 'download-and-extract', 16:05:41 INFO - 'populate-webroot', 16:05:41 INFO - 'create-virtualenv', 16:05:41 INFO - 'install', 16:05:41 INFO - 'run-tests'), 16:05:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:05:41 INFO - 'download_minidump_stackwalk': True, 16:05:41 INFO - 'download_symbols': 'ondemand', 16:05:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:05:41 INFO - 'tooltool.py': '/tools/tooltool.py', 16:05:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:05:41 INFO - '/tools/misc-python/virtualenv.py')}, 16:05:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:05:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:05:41 INFO - 'installer_path': 'installer.exe', 16:05:41 INFO - 'log_level': 'info', 16:05:41 INFO - 'log_name': 'talos', 16:05:41 INFO - 'log_to_console': True, 16:05:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:05:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:05:41 INFO - 'opt_config_files': (), 16:05:41 INFO - 'pip_index': False, 16:05:41 INFO - 'sps_profile': False, 16:05:41 INFO - 'sps_profile_interval': 0, 16:05:41 INFO - 'suite': 'dromaeojs', 16:05:41 INFO - 'system_bits': '32', 16:05:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:05:41 INFO - 'title': 'talos-linux64-ix-009', 16:05:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:05:41 INFO - 'use_talos_json': True, 16:05:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:05:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:05:41 INFO - 'work_dir': 'build'} 16:05:41 INFO - ##### 16:05:41 INFO - ##### Running clobber step. 16:05:41 INFO - ##### 16:05:41 INFO - Running pre-action listener: _resource_record_pre_action 16:05:41 INFO - Running main action method: clobber 16:05:41 INFO - rmtree: /builds/slave/test/build 16:05:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:05:42 INFO - Running post-action listener: _resource_record_post_action 16:05:42 INFO - ##### 16:05:42 INFO - ##### Finished clobber step (success) 16:05:42 INFO - ##### 16:05:42 INFO - ##### 16:05:42 INFO - ##### Running read-buildbot-config step. 16:05:42 INFO - ##### 16:05:42 INFO - Running pre-action listener: _resource_record_pre_action 16:05:42 INFO - Running main action method: read_buildbot_config 16:05:42 INFO - Using buildbot properties: 16:05:42 INFO - { 16:05:42 INFO - "project": "", 16:05:42 INFO - "product": "firefox", 16:05:42 INFO - "script_repo_revision": "production", 16:05:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:05:42 INFO - "repository": "", 16:05:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:05:42 INFO - "buildid": "20160727132927", 16:05:42 INFO - "pgo_build": "True", 16:05:42 INFO - "basedir": "/builds/slave/test", 16:05:42 INFO - "buildnumber": 636, 16:05:42 INFO - "slavename": "talos-linux64-ix-009", 16:05:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:05:42 INFO - "platform": "ubuntu64_hw", 16:05:42 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:05:42 INFO - "branch": "mozilla-aurora", 16:05:42 INFO - "repo_path": "releases/mozilla-aurora", 16:05:42 INFO - "revision": "dc272cf9d820263c646fc42f6354b55203019e3b", 16:05:42 INFO - "stage_platform": "linux64-pgo", 16:05:42 INFO - "builduid": "d66bb7e5b40743cc8db59b6327363edd", 16:05:42 INFO - "slavebuilddir": "test" 16:05:42 INFO - } 16:05:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 16:05:42 INFO - Running post-action listener: _resource_record_post_action 16:05:42 INFO - ##### 16:05:42 INFO - ##### Finished read-buildbot-config step (success) 16:05:42 INFO - ##### 16:05:42 INFO - ##### 16:05:42 INFO - ##### Running download-and-extract step. 16:05:42 INFO - ##### 16:05:42 INFO - Running pre-action listener: _resource_record_pre_action 16:05:42 INFO - Running main action method: download_and_extract 16:05:42 INFO - mkdir: /builds/slave/test/build/tests 16:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:42 INFO - https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:05:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:05:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:05:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:05:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:05:42 INFO - Downloaded 1361 bytes. 16:05:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:05:42 INFO - Using the following test package requirements: 16:05:42 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 16:05:42 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:05:42 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:05:42 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'jsshell-linux-x86_64.zip'], 16:05:42 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:05:42 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 16:05:42 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:05:42 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:05:42 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:05:42 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:05:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:05:42 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:42 INFO - https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:05:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:05:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:05:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:05:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:05:43 INFO - Downloaded 24494799 bytes. 16:05:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:05:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:05:45 INFO - Return code: 0 16:05:45 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:45 INFO - https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:05:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:05:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:05:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:05:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:05:46 INFO - Downloaded 24494799 bytes. 16:05:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:05:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:05:47 INFO - Return code: 0 16:05: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')]} 16:05:47 INFO - https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:05:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 16:05:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 16:05:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 16:05:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:05:48 INFO - Downloaded 10833369 bytes. 16:05:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:05:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:05:48 INFO - Return code: 0 16:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:48 INFO - https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:05:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:05:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:05:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:05:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:05:50 INFO - Downloaded 63638930 bytes. 16:05:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:05:50 INFO - mkdir: /builds/slave/test/properties 16:05:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:05:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:05:50 INFO - Contents: 16:05:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:05:50 INFO - Running post-action listener: _resource_record_post_action 16:05:50 INFO - Running post-action listener: set_extra_try_arguments 16:05:50 INFO - ##### 16:05:50 INFO - ##### Finished download-and-extract step (success) 16:05:50 INFO - ##### 16:05:50 INFO - ##### 16:05:50 INFO - ##### Running populate-webroot step. 16:05:50 INFO - ##### 16:05:50 INFO - Running pre-action listener: _resource_record_pre_action 16:05:50 INFO - Running main action method: populate_webroot 16:05:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:05:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:05:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:05:50 INFO - '--tppagecycles', 16:05:50 INFO - '7'], 16:05:50 INFO - 'tests': ['tsvgm']}}, 16:05:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:05:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:05:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:05:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:05:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:05:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:05:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['damp', 'tps']}, 16:05:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'tests': ['damp', 'tps']}, 16:05:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['dromaeo_dom']}, 16:05:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:05:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['a11yr', 16:05:50 INFO - 'ts_paint', 16:05:50 INFO - 'tpaint', 16:05:50 INFO - 'sessionrestore', 16:05:50 INFO - 'sessionrestore_no_auto_restore', 16:05:50 INFO - 'tabpaint']}, 16:05:50 INFO - 'other-e10s': {'tests': ['a11yr', 16:05:50 INFO - 'ts_paint', 16:05:50 INFO - 'tpaint', 16:05:50 INFO - 'sessionrestore', 16:05:50 INFO - 'sessionrestore_no_auto_restore', 16:05:50 INFO - 'tabpaint']}, 16:05:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:05:50 INFO - 'ts_paint', 16:05:50 INFO - 'tpaint', 16:05:50 INFO - 'sessionrestore', 16:05:50 INFO - 'sessionrestore_no_auto_restore', 16:05:50 INFO - 'tabpaint']}, 16:05:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:05:50 INFO - 'ts_paint', 16:05:50 INFO - 'tpaint', 16:05:50 INFO - 'sessionrestore', 16:05:50 INFO - 'sessionrestore_no_auto_restore', 16:05:50 INFO - 'tabpaint']}, 16:05:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['a11yr', 16:05:50 INFO - 'ts_paint', 16:05:50 INFO - 'tpaint', 16:05:50 INFO - 'sessionrestore', 16:05:50 INFO - 'sessionrestore_no_auto_restore', 16:05:50 INFO - 'tabpaint']}, 16:05:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['a11yr', 16:05:50 INFO - 'ts_paint', 16:05:50 INFO - 'tpaint', 16:05:50 INFO - 'sessionrestore', 16:05:50 INFO - 'sessionrestore_no_auto_restore', 16:05:50 INFO - 'tabpaint']}, 16:05:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['tsvgx', 16:05:50 INFO - 'tsvgr_opacity', 16:05:50 INFO - 'tart', 16:05:50 INFO - 'tscrollx', 16:05:50 INFO - 'cart']}, 16:05:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:05:50 INFO - 'tsvgr_opacity', 16:05:50 INFO - 'tart', 16:05:50 INFO - 'tscrollx', 16:05:50 INFO - 'cart']}, 16:05:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'talos_options': ['--disable-e10s'], 16:05:50 INFO - 'tests': ['tp5o']}, 16:05:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'tests': ['tp5o']}, 16:05:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'talos_options': ['--disable-e10s', 16:05:50 INFO - '--xperf_path', 16:05:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:05:50 INFO - 'tests': ['tp5n']}, 16:05:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:50 INFO - 'talos_options': ['--xperf_path', 16:05:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:05:50 INFO - 'tests': ['tp5n']}}, 16:05:50 INFO - 'talos.zip': {'path': '', 16:05:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:05:50 INFO - Running post-action listener: _resource_record_post_action 16:05:50 INFO - ##### 16:05:50 INFO - ##### Finished populate-webroot step (success) 16:05:50 INFO - ##### 16:05:50 INFO - ##### 16:05:50 INFO - ##### Running create-virtualenv step. 16:05:50 INFO - ##### 16:05:50 INFO - Running pre-action listener: _resource_record_pre_action 16:05:50 INFO - Running main action method: create_virtualenv 16:05:50 INFO - Creating virtualenv /builds/slave/test/build/venv 16:05:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:05:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:05:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:05:51 INFO - Using real prefix '/usr' 16:05:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:05:51 INFO - Installing distribute.............................................................................................................................................................................................done. 16:05:53 INFO - Installing pip.................done. 16:05:53 INFO - Return code: 0 16:05:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:53 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:05:53 INFO - Using env: {'DISPLAY': ':0', 16:05:53 INFO - 'HOME': '/home/cltbld', 16:05:53 INFO - 'LANG': 'en_US.UTF-8', 16:05:53 INFO - 'LANGUAGE': 'en_US:en', 16:05:53 INFO - 'LOGNAME': 'cltbld', 16:05:53 INFO - 'MAIL': '/var/mail/cltbld', 16:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:53 INFO - 'MOZ_NO_REMOTE': '1', 16:05:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:53 INFO - 'NO_EM_RESTART': '1', 16:05:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:53 INFO - 'PWD': '/builds/slave/test', 16:05:53 INFO - 'SHELL': '/bin/bash', 16:05:53 INFO - 'SHLVL': '1', 16:05:53 INFO - 'TERM': 'linux', 16:05:53 INFO - 'TMOUT': '86400', 16:05:53 INFO - 'USER': 'cltbld', 16:05:53 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:53 INFO - '_': '/tools/buildbot/bin/python'} 16:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:05:53 INFO - Cleaning up... 16:05:53 INFO - Return code: 0 16:05:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:53 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:05:53 INFO - Using env: {'DISPLAY': ':0', 16:05:53 INFO - 'HOME': '/home/cltbld', 16:05:53 INFO - 'LANG': 'en_US.UTF-8', 16:05:53 INFO - 'LANGUAGE': 'en_US:en', 16:05:53 INFO - 'LOGNAME': 'cltbld', 16:05:53 INFO - 'MAIL': '/var/mail/cltbld', 16:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:53 INFO - 'MOZ_NO_REMOTE': '1', 16:05:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:53 INFO - 'NO_EM_RESTART': '1', 16:05:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:53 INFO - 'PWD': '/builds/slave/test', 16:05:53 INFO - 'SHELL': '/bin/bash', 16:05:53 INFO - 'SHLVL': '1', 16:05:53 INFO - 'TERM': 'linux', 16:05:53 INFO - 'TMOUT': '86400', 16:05:53 INFO - 'USER': 'cltbld', 16:05:53 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:53 INFO - '_': '/tools/buildbot/bin/python'} 16:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:53 INFO - Downloading/unpacking psutil>=3.1.1 16:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:05:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:05:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:05:56 INFO - Installing collected packages: psutil 16:05:56 INFO - Running setup.py install for psutil 16:05:56 INFO - building 'psutil._psutil_linux' extension 16:05:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:05:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:05:56 INFO - building 'psutil._psutil_posix' extension 16:05:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:05:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:05:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:05:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:05:56 INFO - Successfully installed psutil 16:05:56 INFO - Cleaning up... 16:05:56 INFO - Return code: 0 16:05:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:56 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:05:56 INFO - Using env: {'DISPLAY': ':0', 16:05:56 INFO - 'HOME': '/home/cltbld', 16:05:56 INFO - 'LANG': 'en_US.UTF-8', 16:05:56 INFO - 'LANGUAGE': 'en_US:en', 16:05:56 INFO - 'LOGNAME': 'cltbld', 16:05:56 INFO - 'MAIL': '/var/mail/cltbld', 16:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:56 INFO - 'MOZ_NO_REMOTE': '1', 16:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:56 INFO - 'NO_EM_RESTART': '1', 16:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:56 INFO - 'PWD': '/builds/slave/test', 16:05:56 INFO - 'SHELL': '/bin/bash', 16:05:56 INFO - 'SHLVL': '1', 16:05:56 INFO - 'TERM': 'linux', 16:05:56 INFO - 'TMOUT': '86400', 16:05:56 INFO - 'USER': 'cltbld', 16:05:56 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:56 INFO - '_': '/tools/buildbot/bin/python'} 16:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:05:58 INFO - Installing collected packages: mozsystemmonitor 16:05:58 INFO - Running setup.py install for mozsystemmonitor 16:05:58 INFO - Successfully installed mozsystemmonitor 16:05:58 INFO - Cleaning up... 16:05:59 INFO - Return code: 0 16:05:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:05:59 INFO - Using env: {'DISPLAY': ':0', 16:05:59 INFO - 'HOME': '/home/cltbld', 16:05:59 INFO - 'LANG': 'en_US.UTF-8', 16:05:59 INFO - 'LANGUAGE': 'en_US:en', 16:05:59 INFO - 'LOGNAME': 'cltbld', 16:05:59 INFO - 'MAIL': '/var/mail/cltbld', 16:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:59 INFO - 'MOZ_NO_REMOTE': '1', 16:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:59 INFO - 'NO_EM_RESTART': '1', 16:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:59 INFO - 'PWD': '/builds/slave/test', 16:05:59 INFO - 'SHELL': '/bin/bash', 16:05:59 INFO - 'SHLVL': '1', 16:05:59 INFO - 'TERM': 'linux', 16:05:59 INFO - 'TMOUT': '86400', 16:05:59 INFO - 'USER': 'cltbld', 16:05:59 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:59 INFO - '_': '/tools/buildbot/bin/python'} 16:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:59 INFO - Downloading/unpacking jsonschema==2.5.1 16:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:06:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:01 INFO - Downloading functools32-3.2.3-2.tar.gz 16:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:06:01 INFO - warning: no files found matching '*.txt' 16:06:01 INFO - no previously-included directories found matching 'build' 16:06:01 INFO - no previously-included directories found matching 'dist' 16:06:01 INFO - no previously-included directories found matching '.git*' 16:06:01 INFO - Installing collected packages: jsonschema, functools32 16:06:01 INFO - Running setup.py install for functools32 16:06:01 INFO - warning: no files found matching '*.txt' 16:06:01 INFO - no previously-included directories found matching 'build' 16:06:01 INFO - no previously-included directories found matching 'dist' 16:06:01 INFO - no previously-included directories found matching '.git*' 16:06:01 INFO - Successfully installed jsonschema functools32 16:06:01 INFO - Cleaning up... 16:06:01 INFO - Return code: 0 16:06:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:06: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')]} 16:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:01 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:06:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:06:01 INFO - Using env: {'DISPLAY': ':0', 16:06:01 INFO - 'HOME': '/home/cltbld', 16:06:01 INFO - 'LANG': 'en_US.UTF-8', 16:06:01 INFO - 'LANGUAGE': 'en_US:en', 16:06:01 INFO - 'LOGNAME': 'cltbld', 16:06:01 INFO - 'MAIL': '/var/mail/cltbld', 16:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:01 INFO - 'MOZ_NO_REMOTE': '1', 16:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:01 INFO - 'NO_EM_RESTART': '1', 16:06:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:01 INFO - 'PWD': '/builds/slave/test', 16:06:01 INFO - 'SHELL': '/bin/bash', 16:06:01 INFO - 'SHLVL': '1', 16:06:01 INFO - 'TERM': 'linux', 16:06:01 INFO - 'TMOUT': '86400', 16:06:01 INFO - 'USER': 'cltbld', 16:06:01 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:01 INFO - '_': '/tools/buildbot/bin/python'} 16:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:01 INFO - Downloading/unpacking blobuploader==1.2.4 16:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:03 INFO - Downloading blobuploader-1.2.4.tar.gz 16:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:06:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:06:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:04 INFO - Downloading docopt-0.6.1.tar.gz 16:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:06:04 INFO - Installing collected packages: blobuploader, requests, docopt 16:06:04 INFO - Running setup.py install for blobuploader 16:06:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:06:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:06:04 INFO - Running setup.py install for requests 16:06:05 INFO - Running setup.py install for docopt 16:06:05 INFO - Successfully installed blobuploader requests docopt 16:06:05 INFO - Cleaning up... 16:06:05 INFO - Return code: 0 16:06:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:06:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:06:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:06:05 INFO - Using env: {'DISPLAY': ':0', 16:06:05 INFO - 'HOME': '/home/cltbld', 16:06:05 INFO - 'LANG': 'en_US.UTF-8', 16:06:05 INFO - 'LANGUAGE': 'en_US:en', 16:06:05 INFO - 'LOGNAME': 'cltbld', 16:06:05 INFO - 'MAIL': '/var/mail/cltbld', 16:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:05 INFO - 'MOZ_NO_REMOTE': '1', 16:06:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:05 INFO - 'NO_EM_RESTART': '1', 16:06:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:05 INFO - 'PWD': '/builds/slave/test', 16:06:05 INFO - 'SHELL': '/bin/bash', 16:06:05 INFO - 'SHLVL': '1', 16:06:05 INFO - 'TERM': 'linux', 16:06:05 INFO - 'TMOUT': '86400', 16:06:05 INFO - 'USER': 'cltbld', 16:06:05 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:05 INFO - '_': '/tools/buildbot/bin/python'} 16:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:05 INFO - Running setup.py (path:/tmp/pip-uqUjTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:05 INFO - Running setup.py (path:/tmp/pip-vKPaII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:05 INFO - Running setup.py (path:/tmp/pip-gX5Dla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:05 INFO - Running setup.py (path:/tmp/pip-xBz65M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:05 INFO - Running setup.py (path:/tmp/pip-Bg24h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:05 INFO - Running setup.py (path:/tmp/pip-eUiFaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:05 INFO - Running setup.py (path:/tmp/pip-hMWSay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:05 INFO - Running setup.py (path:/tmp/pip-K0bA3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:06 INFO - Running setup.py (path:/tmp/pip-FEIwWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:06 INFO - Running setup.py (path:/tmp/pip-xoFTJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:06 INFO - Running setup.py (path:/tmp/pip-NWNOer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:06 INFO - Running setup.py (path:/tmp/pip-vFsqxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:06 INFO - Running setup.py (path:/tmp/pip-QO0eXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:06 INFO - Running setup.py (path:/tmp/pip-gU3aZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:06 INFO - Running setup.py (path:/tmp/pip-1Q6WUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:06 INFO - Running setup.py (path:/tmp/pip-wGeghO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:06 INFO - Running setup.py (path:/tmp/pip-14LoH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:06:06 INFO - Running setup.py install for manifestparser 16:06:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:06:06 INFO - Running setup.py install for mozcrash 16:06:06 INFO - Running setup.py install for mozdebug 16:06:06 INFO - Running setup.py install for mozdevice 16:06:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Running setup.py install for mozfile 16:06:07 INFO - Running setup.py install for mozhttpd 16:06:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Running setup.py install for mozinfo 16:06:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Running setup.py install for mozInstall 16:06:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Running setup.py install for mozleak 16:06:07 INFO - Running setup.py install for mozlog 16:06:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Running setup.py install for moznetwork 16:06:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:06:07 INFO - Running setup.py install for mozprocess 16:06:07 INFO - Running setup.py install for mozprofile 16:06:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:06:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:06:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:06:08 INFO - Running setup.py install for mozrunner 16:06:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:06:08 INFO - Running setup.py install for mozscreenshot 16:06:08 INFO - Running setup.py install for moztest 16:06:08 INFO - Running setup.py install for mozversion 16:06:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:06:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:06:08 INFO - Cleaning up... 16:06:08 INFO - Return code: 0 16:06:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:08 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:06:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:06:08 INFO - Using env: {'DISPLAY': ':0', 16:06:08 INFO - 'HOME': '/home/cltbld', 16:06:08 INFO - 'LANG': 'en_US.UTF-8', 16:06:08 INFO - 'LANGUAGE': 'en_US:en', 16:06:08 INFO - 'LOGNAME': 'cltbld', 16:06:08 INFO - 'MAIL': '/var/mail/cltbld', 16:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:08 INFO - 'MOZ_NO_REMOTE': '1', 16:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:08 INFO - 'NO_EM_RESTART': '1', 16:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:08 INFO - 'PWD': '/builds/slave/test', 16:06:08 INFO - 'SHELL': '/bin/bash', 16:06:08 INFO - 'SHLVL': '1', 16:06:08 INFO - 'TERM': 'linux', 16:06:08 INFO - 'TMOUT': '86400', 16:06:08 INFO - 'USER': 'cltbld', 16:06:08 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:08 INFO - '_': '/tools/buildbot/bin/python'} 16:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:08 INFO - Running setup.py (path:/tmp/pip-wGKPMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:08 INFO - Running setup.py (path:/tmp/pip-BHu8P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:08 INFO - Running setup.py (path:/tmp/pip-4O0Z1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:08 INFO - Running setup.py (path:/tmp/pip-8vrUoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:08 INFO - Running setup.py (path:/tmp/pip-dAw88O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:09 INFO - Running setup.py (path:/tmp/pip-VWXHnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:09 INFO - Running setup.py (path:/tmp/pip-_ChGey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:09 INFO - Running setup.py (path:/tmp/pip-wBcPKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:09 INFO - Running setup.py (path:/tmp/pip-QUn0UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:09 INFO - Running setup.py (path:/tmp/pip-XoPLT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:09 INFO - Running setup.py (path:/tmp/pip-tOWOr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:09 INFO - Running setup.py (path:/tmp/pip-oOghqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:09 INFO - Running setup.py (path:/tmp/pip-zEMXN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:09 INFO - Running setup.py (path:/tmp/pip-FOGku0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:09 INFO - Running setup.py (path:/tmp/pip-bgLp1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:09 INFO - Running setup.py (path:/tmp/pip-KHowAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:09 INFO - Running setup.py (path:/tmp/pip-OgVgFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:06:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:11 INFO - Downloading blessings-1.6.tar.gz 16:06:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:06:11 INFO - Installing collected packages: blessings 16:06:11 INFO - Running setup.py install for blessings 16:06:12 INFO - Successfully installed blessings 16:06:12 INFO - Cleaning up... 16:06:12 INFO - Return code: 0 16:06:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:06:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:12 INFO - Reading from file tmpfile_stdout 16:06:12 INFO - Current package versions: 16:06:12 INFO - argparse == 1.2.1 16:06:12 INFO - blessings == 1.6 16:06:12 INFO - blobuploader == 1.2.4 16:06:12 INFO - docopt == 0.6.1 16:06:12 INFO - functools32 == 3.2.3-2 16:06:12 INFO - jsonschema == 2.5.1 16:06:12 INFO - manifestparser == 1.1 16:06:12 INFO - mozInstall == 1.12 16:06:12 INFO - mozcrash == 0.17 16:06:12 INFO - mozdebug == 0.1 16:06:12 INFO - mozdevice == 0.48 16:06:12 INFO - mozfile == 1.2 16:06:12 INFO - mozhttpd == 0.7 16:06:12 INFO - mozinfo == 0.9 16:06:12 INFO - mozleak == 0.1 16:06:12 INFO - mozlog == 3.2 16:06:12 INFO - moznetwork == 0.27 16:06:12 INFO - mozprocess == 0.23 16:06:12 INFO - mozprofile == 0.28 16:06:12 INFO - mozrunner == 6.12 16:06:12 INFO - mozscreenshot == 0.1 16:06:12 INFO - mozsystemmonitor == 0.3 16:06:12 INFO - moztest == 0.7 16:06:12 INFO - mozversion == 1.4 16:06:12 INFO - psutil == 3.1.1 16:06:12 INFO - requests == 1.2.3 16:06:12 INFO - wsgiref == 0.1.2 16:06:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:12 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:06:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:06:12 INFO - Using env: {'DISPLAY': ':0', 16:06:12 INFO - 'HOME': '/home/cltbld', 16:06:12 INFO - 'LANG': 'en_US.UTF-8', 16:06:12 INFO - 'LANGUAGE': 'en_US:en', 16:06:12 INFO - 'LOGNAME': 'cltbld', 16:06:12 INFO - 'MAIL': '/var/mail/cltbld', 16:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:12 INFO - 'MOZ_NO_REMOTE': '1', 16:06:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:12 INFO - 'NO_EM_RESTART': '1', 16:06:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:12 INFO - 'PWD': '/builds/slave/test', 16:06:12 INFO - 'SHELL': '/bin/bash', 16:06:12 INFO - 'SHLVL': '1', 16:06:12 INFO - 'TERM': 'linux', 16:06:12 INFO - 'TMOUT': '86400', 16:06:12 INFO - 'USER': 'cltbld', 16:06:12 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:12 INFO - '_': '/tools/buildbot/bin/python'} 16:06:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:06:12 INFO - Cleaning up... 16:06:12 INFO - Return code: 0 16:06:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:12 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 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:06:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:06:12 INFO - Using env: {'DISPLAY': ':0', 16:06:12 INFO - 'HOME': '/home/cltbld', 16:06:12 INFO - 'LANG': 'en_US.UTF-8', 16:06:12 INFO - 'LANGUAGE': 'en_US:en', 16:06:12 INFO - 'LOGNAME': 'cltbld', 16:06:12 INFO - 'MAIL': '/var/mail/cltbld', 16:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:12 INFO - 'MOZ_NO_REMOTE': '1', 16:06:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:12 INFO - 'NO_EM_RESTART': '1', 16:06:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:12 INFO - 'PWD': '/builds/slave/test', 16:06:12 INFO - 'SHELL': '/bin/bash', 16:06:12 INFO - 'SHLVL': '1', 16:06:12 INFO - 'TERM': 'linux', 16:06:12 INFO - 'TMOUT': '86400', 16:06:12 INFO - 'USER': 'cltbld', 16:06:12 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:12 INFO - '_': '/tools/buildbot/bin/python'} 16:06:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:06:12 INFO - Cleaning up... 16:06:12 INFO - Return code: 0 16:06:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:12 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83be3def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:06:12 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', 'flake8'] in /builds/slave/test/build 16:06:12 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 flake8 16:06:12 INFO - Using env: {'DISPLAY': ':0', 16:06:12 INFO - 'HOME': '/home/cltbld', 16:06:12 INFO - 'LANG': 'en_US.UTF-8', 16:06:12 INFO - 'LANGUAGE': 'en_US:en', 16:06:12 INFO - 'LOGNAME': 'cltbld', 16:06:12 INFO - 'MAIL': '/var/mail/cltbld', 16:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:12 INFO - 'MOZ_NO_REMOTE': '1', 16:06:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:12 INFO - 'NO_EM_RESTART': '1', 16:06:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:12 INFO - 'PWD': '/builds/slave/test', 16:06:12 INFO - 'SHELL': '/bin/bash', 16:06:12 INFO - 'SHLVL': '1', 16:06:12 INFO - 'TERM': 'linux', 16:06:12 INFO - 'TMOUT': '86400', 16:06:12 INFO - 'USER': 'cltbld', 16:06:12 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:12 INFO - '_': '/tools/buildbot/bin/python'} 16:06:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:12 INFO - Downloading/unpacking flake8 16:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:06:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:06:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:06:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:06:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:06:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:06:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:06:15 INFO - no previously-included directories found matching 'docs/_build' 16:06:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:06:15 INFO - Running setup.py install for pep8 16:06:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:06:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:06:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:06:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:06:15 INFO - no previously-included directories found matching 'docs/_build' 16:06:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:06:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:06:15 INFO - Cleaning up... 16:06:15 INFO - Return code: 0 16:06:15 INFO - Running post-action listener: _resource_record_post_action 16:06:15 INFO - Running post-action listener: _start_resource_monitoring 16:06:15 INFO - Starting resource monitoring. 16:06:15 INFO - ##### 16:06:15 INFO - ##### Finished create-virtualenv step (success) 16:06:15 INFO - ##### 16:06:15 INFO - ##### 16:06:15 INFO - ##### Running install step. 16:06:15 INFO - ##### 16:06:15 INFO - Running pre-action listener: _resource_record_pre_action 16:06:15 INFO - Running main action method: install 16:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:16 INFO - Reading from file tmpfile_stdout 16:06:16 INFO - Detecting whether we're running mozinstall >=1.0... 16:06:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:06:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:06:16 INFO - Reading from file tmpfile_stdout 16:06:16 INFO - Output received: 16:06:16 INFO - Usage: mozinstall [options] installer 16:06:16 INFO - Options: 16:06:16 INFO - -h, --help show this help message and exit 16:06:16 INFO - -d DEST, --destination=DEST 16:06:16 INFO - Directory to install application into. [default: 16:06:16 INFO - "/builds/slave/test"] 16:06:16 INFO - --app=APP Application being installed. [default: firefox] 16:06:16 INFO - mkdir: /builds/slave/test/build/application 16:06:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:06:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:06:32 INFO - Reading from file tmpfile_stdout 16:06:32 INFO - Output received: 16:06:32 INFO - /builds/slave/test/build/application/firefox/firefox 16:06:32 INFO - Running post-action listener: _resource_record_post_action 16:06:32 INFO - ##### 16:06:32 INFO - ##### Finished install step (success) 16:06:32 INFO - ##### 16:06:32 INFO - ##### 16:06:32 INFO - ##### Running run-tests step. 16:06:32 INFO - ##### 16:06:32 INFO - Running pre-action listener: _resource_record_pre_action 16:06:32 INFO - Running main action method: run_tests 16:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:06:32 INFO - Python 2.7.3 16:06:32 INFO - Return code: 0 16:06:32 INFO - grabbing minidump binary from tooltool 16:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:32 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 0x2c792e0>, '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 0x2be5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be55d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:06:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:06:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:06:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:06:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:06:32 INFO - Return code: 0 16:06:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:06:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:06:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:06:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:06:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:06:33 INFO - Return code: 0 16:06:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:06:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:06:33 INFO - Using env: {'DISPLAY': ':0', 16:06:33 INFO - 'HOME': '/home/cltbld', 16:06:33 INFO - 'LANG': 'en_US.UTF-8', 16:06:33 INFO - 'LANGUAGE': 'en_US:en', 16:06:33 INFO - 'LOGNAME': 'cltbld', 16:06:33 INFO - 'MAIL': '/var/mail/cltbld', 16:06:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:06:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:06:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:33 INFO - 'MOZ_NO_REMOTE': '1', 16:06:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:06:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:33 INFO - 'NO_EM_RESTART': '1', 16:06:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:33 INFO - 'PWD': '/builds/slave/test', 16:06:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:06:33 INFO - 'SHELL': '/bin/bash', 16:06:33 INFO - 'SHLVL': '1', 16:06:33 INFO - 'TERM': 'linux', 16:06:33 INFO - 'TMOUT': '86400', 16:06:33 INFO - 'USER': 'cltbld', 16:06:33 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807', 16:06:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:33 INFO - '_': '/tools/buildbot/bin/python'} 16:06:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:06:33 INFO - mozversion application_buildid: 20160727132927 16:06:33 INFO - mozversion application_changeset: dc272cf9d820263c646fc42f6354b55203019e3b 16:06:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:06:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:06:33 INFO - mozversion application_name: Firefox 16:06:33 INFO - mozversion application_remotingname: firefox-dev 16:06:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:06:33 INFO - mozversion application_vendor: Mozilla 16:06:33 INFO - mozversion application_version: 49.0a2 16:06:33 INFO - mozversion platform_buildid: 20160727132927 16:06:33 INFO - mozversion platform_changeset: dc272cf9d820263c646fc42f6354b55203019e3b 16:06:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:06:33 INFO - mozversion platform_version: 49.0a2 16:06:33 INFO - using testdate: 1469660793 16:06:33 INFO - actual date: 1469660793 16:06:33 INFO - starting webserver on 'localhost:43336' 16:06:33 INFO - SUITE-START | Running 2 tests 16:06:33 INFO - TEST-START | dromaeo_css 16:06:33 INFO - operating with platform_type : linux_ 16:06:33 INFO - Initialising browser for dromaeo_css test... 16:06:33 INFO - TEST-INFO | started process 29461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVlXLnr/profile http://localhost:43336/getInfo.html) 16:06:37 INFO - PROCESS | 29461 | __metrics Screen width/height:1600/1200 16:06:37 INFO - PROCESS | 29461 | colorDepth:24 16:06:37 INFO - PROCESS | 29461 | Browser inner width/height: 1024/697 16:06:37 INFO - PROCESS | 29461 | __metrics 16:06:38 INFO - PROCESS | 29461 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:06:38 ERROR - PROCESS | 29461 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:06:39 INFO - TEST-INFO | 29461: exit 0 16:06:39 INFO - Browser initialized. 16:06:39 INFO - Running cycle 1/1 for dromaeo_css test... 16:06:39 INFO - TEST-INFO | started process 29557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVlXLnr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:51 INFO - PROCESS | 29557 | RSS: Main: 145645568 16:06:51 INFO - PROCESS | 29557 | 16:07:42 INFO - PROCESS | 29557 | 1469660862426 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:09:50 INFO - PROCESS | 29557 | Cycle 1(1): loaded http://localhost:43336/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43336/tests/dromaeo/cssquery-ext.html) 16:09:51 INFO - PROCESS | 29557 | RSS: Main: 254275584 16:09:51 INFO - PROCESS | 29557 | 16:12:53 INFO - PROCESS | 29557 | Cycle 1(1): loaded http://localhost:43336/tests/dromaeo/cssquery-ext.html (next: http://localhost:43336/tests/dromaeo/cssquery-jquery.html) 16:12:54 INFO - PROCESS | 29557 | RSS: Main: 233152512 16:12:54 INFO - PROCESS | 29557 | 16:15:56 INFO - PROCESS | 29557 | Cycle 1(1): loaded http://localhost:43336/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43336/tests/dromaeo/cssquery-mootools.html) 16:15:56 INFO - PROCESS | 29557 | RSS: Main: 227221504 16:15:56 INFO - PROCESS | 29557 | 16:18:58 INFO - PROCESS | 29557 | Cycle 1(1): loaded http://localhost:43336/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43336/tests/dromaeo/cssquery-prototype.html) 16:18:59 INFO - PROCESS | 29557 | RSS: Main: 224444416 16:18:59 INFO - PROCESS | 29557 | 16:22:02 INFO - PROCESS | 29557 | Cycle 1(1): loaded http://localhost:43336/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43336/tests/dromaeo/cssquery-yui.html) 16:22:02 INFO - PROCESS | 29557 | RSS: Main: 217571328 16:22:02 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | Cycle 1(1): loaded http://localhost:43336/tests/dromaeo/cssquery-yui.html (next: http://localhost:43336/tests/dromaeo/cssquery-dojo.html) 16:25:05 INFO - PROCESS | 29557 | RSS: Main: 262402048 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | __start_tp_report 16:25:05 INFO - PROCESS | 29557 | _x_x_mozilla_page_load 16:25:05 INFO - PROCESS | 29557 | _x_x_mozilla_page_load_details 16:25:05 INFO - PROCESS | 29557 | |i|pagename|runs| 16:25:05 INFO - PROCESS | 29557 | |0;dojo.html;3314.6353901070884;3314.6685331466856;3351.2627222011165;3378.324335132974;3395.62648108708;5464.562834973202;5546.556275497961;5555.555555555556;5563.582731295154;5578.274824272849;5448.264484294619;5542.39033706293;5543.223948647183;5550.306211723535;5573.6098473106895;4845.200541910598;4953.876153096166;5032.320636714052;5052.873678158038;5101.795928162869;4803.447603525587;4938.975305123469;4968.87577810554;4983.202687569989;4989.65072444929;4927.77824997876;4998.825041123561;5041.621878359119;5067.5185857343495;5068.214426763839;5123.026624941249;5124.385073791169;5126.794928202868;5136.974315128437;5167.3282473278605;4798.424189097331;4933.827316043921;4943.728094954776;5042.697438153723;5051.292819005343;5275.28783614211;5335.306410166692;5336.17289320156;5368.114261146906;5388.245645609618;5892;5938.495227905633;5966.075258334965;5972.790952316692;5978.730957106897;5792.623479473863;5836.533077353844;5853.443922827325;5859.355470898197;5975.043992961106;5488.862778430531;5502.284702988586;5527.143292789584;5528.612997090206;5540.141278101901;5747.36778954323;6014.729337179838;6020.307664618537;6027.547933904974;6033.155358249848;5662.150677398341;5691.743871525791;5729.598928074995;5804;5821.15593238978;5866.354700982974;5867.9413205867395;5868.677222752704;5898.882022359615;5903.439173278618;5602.215689803431;5769.88460230793;5844.620099693507;5856.472917437451;5860.033094539353;5805.94194058054;5883.499902508249;5895.852603684943;5896.64620122794;5921.2302400688495;4524.298733696282;4541.069080838421;4541.72749635023;4541.909161816745;4566.223741963808;3959.9604003960167;3963.2667956428154;4037.7779222143063;4063.187362527506;4106.7125301229225;5640.943590564124;5659.377468478464;5667.943320566824;5668.801591944345;5673.8297851064535;5927.673977931169;5935.465808077293;5942.940570594325;5946.613470124428;5964.671943043174;5345.599080068932;5603.187537807076;5653.943460565424;5660.433956604389;5661.971690141605;5048.646594738407;5048.798048078109;5054.393472783289;5082.542571168538;5097.260897169889;6140.938590614126;6183.536234782409;6190.876182476415;6191.504679625619;6196.752129914753;5600.831975040755;5836.153757705024;5926.851828704317;5930.584859059997;5996.070609055603;6211.689415529296;6251.124842521959;6253.843653908689;6259.593126446766;6266.122742816101;5513.255710479034;5873.794417195378;5879.176915231786;5881.029630110984;5904.734286957183;5794.565407594363;5839.240898683144;5870.9412905870395;5932.347441781487;5939.67331796738;5753.137029445449;5782.710864456844;5788.363280039276;5794.449527294901;5798.246227990334;5517.44825517445;5688.658680479184;5748.6838223898085;5803.390643982321;5810.360860305447;5623.128415095667;5701.802621449542;5704.657720536781;5710.771569137354;5740.483356497935;5797.362290147996;5805.94194058054;5819.359870414336;5836.824895253149;5836.970815145897;5379.435159308373;5606.131049687305;5628.521575666115;5655.519280861173;5687.118496633028 16:25:05 INFO - PROCESS | 29557 | |1;ext.html;590.8552404660857;628.858506835962;641.9101325814387;642.3448082955385;656.7077650445548;32081;35353.646463535355;35388.469172962425;35392.823035884816;35446.468302975474;35425;36417;51277.23084153741;52493.737531312385;52819.735901320535;9012.143846334604;9147.313951453634;9150.313726470507;9163.175314221719;9171.220446262076;9040.457572545636;9115.908840911572;9134.223590994774;9165.450072995642;9165.862512062324;8452.408331416804;8639.179277968615;8639.39524233304;10952.34285942842;11201.66395008151;11051.336919784839;11105.222634415593;11117.166212534092;11133.443327833576;11149.163812713998;10868.45657717119;10932.07077398422;10942.124630029659;10947.671569852917;10948.945255273673;35765;44519.33221001687;44905;45054.77472612649;48821.26768098481;3820.7325487215626;3847.1343947611845;3873.2834425631067;3945.447637330776;4087.570805065485;34213.48679769805;35044.47433288502;35057;35105.648943510496;35148.472772908426;4656.324832899245;4726.976365118187;4822.734749588772;4845.709257444564;4859.048976521366;369451;378654;379851.10074449726;380152;381260;38502.80748596295;39507.20985580272;39562;41895.7905210472;41957.370639440436;41882;41956.79021604872;42035.579644203164;42044.15911681749;42055.57944420578;16004.199790010452;16128.274227659784;16135.838641613434;16144.273507692182;16150.75773863393;13713.520026799213;13829.446822127002;13830.930845345709;13863.514776982756;13888.09727367738;8625.827483450421;9327.860082098774;9395.342326037111;9402.529873506297;9407.153356197976;3497.510348551154;3608.2061946371755;3616.2405894762396;3643.0710168906758;3674.412094064937;1025.5795123999123;1089.722120859176;1106.0985296982933;1129.2151954391784;1182;33500.329993399995;34053.48919766206;34120.65879341224;34141;34228.48657270143;1169.6900321414832;1176.0591526778544;1231.949009062853;1260.5714057220405;1280.6478218489804;17069.829301707072;17707.911460442872;17730.93614383141;17844.28622855071;17864.196111175028;618001;626561;627840;628560;629228;56282;60515.69742151261;61612.69193654003;63467.36532634707;65095;1419.531554586984;1421.694335717805;1425.3443416028742;1432.302721275292;1455.548779878241;32339.838300808344;34514.137146571535;34765.65234347725;34806.82596587001;34868.82565587257;57291;57637.13544296923;57676.423235767106;57907;58044.4195558039;472920;756096;762862;766442;770411;30618.387632247675;30628.693713062585;30725.692743072283;30727.2318192047;30755.84709070433;48412.757936210095;50229.246561300875;50503.49496505135;50879.74560127324;51368.48631513787;41492;42298.78850605727;42399.152016960106;42492;43297.35053974256;41133.382999255635;41210;41763.79118104512;42373.1525369497;43250.78374608107;41774.1645167101;42213.57786422096;42251.3662295072;42757.35863961981;42928.35607465828;36618.8169059153;37140.44289335608;37268.813655931546;37529;37908.241835163695;28157.71842281551;28454.43091138207;28667.713322866504;28677.85661071681;28727.71272287334 16:25:05 INFO - PROCESS | 29557 | |2;jquery.html;433.37377489527626;433.45783369372907;435.1514546634064;439.52970321755737;445.1142226968334;18544.07279636018;19718.901405492972;20197.091130899127;21193.788062119373;21232.57534849302;16793.3206679332;17570.560735981606;17586.912065439654;17985.37051203208;18100.185491652875;4966.528179822929;5295.576353891699;5298.390685071208;5339.145736682131;5345.866353341159;5285.577153827685;5353.357597088336;5599.300087489064;5951.970240148793;5970.73131709072;5905.645661260338;5917.023691090965;5924.526037916978;5926.822195334147;5945.64326140433;5557.666540007593;5836.270466191726;5855.443732845394;5910.88178236435;5927.614705044159;5647.548196144298;5754.1944127822135;5780.104083866965;5870.412958704138;5878.617889837147;14366.138031718026;14685.192314422702;14717.55847324582;14898.106113633108;15016.849831501764;2335.859848409101;2401.21960362881;2480.1071614218868;2481.392058945552;2490.414752533153;10394.844077338847;10733.83899241512;10746;11084.889151108466;11108.389038602874;1967.5376286572643;2016.6571682813958;2026.6656001759693;2032.481717162129;2043.7240972468676;81420.59289703514;84356;85137.57431212804;85439.57280213559;86448;11516.654500365003;11796.115291353182;12181;12233.632991010283;12686.619401417973;17166.914165429094;17311.220995055253;17321.3937512189;17338.653226935643;17350.47948561545;8213.79465513355;8270.751877443687;8318.8336233275;8357.414980951275;8430.45202061864;7744.922550774533;7804.921950780419;7823.726169584037;7859.567723775133;7965;4619.745913974763;4772.546608072297;5103.464136265713;5216.982688375806;5257.921131183035;2111.6199084164996;2114.4291041418733;2133.797289257518;2160.8919554022236;2197.6044312023987;1043.8277684182026;1070.8019016481976;1074.484247561175;1092;1102.0577406317643;17614.647707045788;18060.729089063676;19045.142968566448;19090.61818763617;19406.70889936652;1028.5217373921014;1034.250168627739;1063.5373612478597;1076.0530732955106;1084.728817795551;10130.24023198263;10688.839667404995;10760.892391076146;10835.241533092609;10863.239573229797;528518;537550;550272;554814;557847;95127.52436237775;96254.51872740591;97371;97547;98072.50963745135;1047.1622302858434;1063.0857462582173;1110.972225694348;1118.78743038824;1131.6491887514896;16672.16639168061;16966.151692415577;17097.48707538776;17291.82708172902;17656.205470754103;38874;39370.803145984086;39733.80133099316;41515;41713.58286417097;53444;53642.46357536375;54023.18965215446;55258.44741552533;55272.447275528335;8703.173198546128;8786.121387861163;8955.865662015205;9034.683786067457;9305.11601397866;9742;12656.493740250287;12685.492580296685;12924.224546527239;13394.129381590166;10282.280240383101;10449.05958463742;10449.373037617766;10548.472576371305;10794.946025269823;10851.294665846695;10952.890471095187;11397.772044559228;11646.126540509325;11982.760344793229;6060.6666633333825;8821.823563528822;10666.200034997251;10870.347779133277;10896.61861834832;8629;8649.783755406164;8745.518996455256;8784.604692788967;8968.820623587622;8481.575921203792;10648.520816563117;10724.410157441416;10774.946125269324;10797.352158870493 16:25:05 INFO - PROCESS | 29557 | |3;mootools.html;1403.1931639307402;1511.7581187010073;1527.0913806285257;1548.450300143449;1559.0723519505893;8488.61801218945;9193.40242884213;9542.570584323705;9551;9866.21070314375;9574.904250957488;9758.219342452605;9759.51202439877;9766.169875560587;9772.169365603932;2122.936311910645;2380.2264264114146;2382.1662418153637;2416.927492175229;2465.6917885264343;2470.814688898331;2495.1765917247267;2497.6003839385703;2500.737422570631;2502.1492692484553;2407.037185125946;2448.6327050942414;2451.767082127204;2452.9386765330833;2453.3498622864954;2028.8174064334207;2316.652502124695;2368.4078980254935;2403.291029146406;2410.4455975125643;2378.452955820154;2394.341556072077;2414.6619473273754;2418.879056047208;2419.358869899478;9508.191803696694;9526;9546.093121153479;9570.186534144606;9576.090271424204;5396.865078373033;5524.696141712204;5533.612647114704;5536.363318218416;5537.1140617501005;3450.3616830886367;3536.275063611979;3591.8742844000335;3602.0274525878126;3616.963830361689;1246.5512415530493;1257.145141303913;1267.936603169847;1306.9346532673424;1337.9531716389977;13588.59234222975;13618.250996195206;13668.794968075486;13671;13681.452741890313;4825.227963525889;4830.541098595628;4970.726610036482;4976.975115124401;4990.401151861727;11272.210945233755;11283.492242849092;11370.658880233606;11395.145364097725;11411.144164187719;5445.210444485528;5462.071447853875;5468.945310546923;5477.096279113902;5477.424870388551;4393.714408563497;4912.803487860517;5052.242163675404;5056.089903817348;5115.1559992600805;4309.1381723655395;4350.564943505624;4423.535528769497;4434.911301773946;4446.333050042519;1357.8981576381811;1395.3651088754593;1398.7692030815006;1408.60559043468;1411.301405804113;1020.336781092296;1021.7139201023725;1025.4718819807806;1027.768752030787;1046.3669479964665;6233.319672540761;6417.422431981144;6429.517786165963;6442.291347951722;6518.608883466913;475.79065211306914;476.17146165672176;477.35556998053045;482.4692837878376;489.9289603007544;4128.525219599725;4134.297169481196;4222.641075508616;4298.333758267474;4361.367601697736;11701.180917335705;12124.69688257783;12129.150959432754;12142.028637708961;12193.14647974651;11823.763524729457;12120.636380908587;12328.32194229308;12336.876631233752;12358.814617780741;8964.955175224213;9117.361784675142;9120.58957346921;9121.133492318218;9130.223930965942;6375.298717141018;6406.135171751754;6533.150690410216;6578.967105164444;6713.597184168965;10736.785264294827;10777.191710621892;10856.782864342511;10895.836562451728;11180.385078820744;10877.836832447678;11155.609553665583;11162.051225645911;11281.492332844877;11303.03924166455;7507.699692012258;8409.369297302605;8586.141385861363;8618.310535157047;8741.956290218763;9358;9399.06009399082;9430.151286384258;9466.195373393064;9487.146156845916;8198.590070496331;8269.917300826915;8290.626921788413;8446.535440550828;8495.617697203517;8167.83664326722;8219.794505137419;8236.629351679068;8264;8456.534890581077;7391.741289055072;7726.3818894487185;7732.961335193513;7743.651535680795;7746.109197442366;5160.638755287094;5176.197689358156;5299.026425591068;5335.786568537215;5500.917486237629;5890.028145355968;5900.822975310747;5901.822945311647;5910.7931222407005;5981 16:25:05 INFO - PROCESS | 29557 | |4;prototype.html;321.5161182420457;327.9721223695986;336.1210434713225;344.6570662191117;355.4792229383952;5266.499682530163;5357.785688572462;5405.675509561621;5434.972825135874;5454;5334.946650533493;5439.5920305977115;5444.292242008543;5453.945790357403;5477.75350109245;4256.340267258564;4272.65818734502;4297.763623000734;4326.6106050455455;4327.545607711192;4055.1484188320487;4123.257813593552;4214.388913607525;4300.806463709125;4310.224159651261;4141.523724771645;4184.560621134783;4185.058361868586;4214.220369231687;4214.7681877496725;4155.979220103895;4158.563350848162;4179.0328866504815;4198.320671731301;4211.283721999293;3653.872114476007;3726.6459686330027;3734.8879533614036;3771.830732472942;3776.603456637041;5107;5135.64050516464;5136.614753893473;5141.303369887928;5171.198464238049;4751.524847515256;4755.405574303212;4782.928256076127;4792.496787837262;4836.371271734652;5352.491513306269;5572.969000734836;5576;5590.86022849428;5598.468145526169;4076.653484453825;4094.6110119538894;4102.651274641659;4106.172753124861;4113.095119073799;5776.306843178803;5787.3923238059815;5811.651300921958;5853.887761325334;5865.472107510344;4091.590840915944;4168.541460439349;4191.3293872980785;4196.607933477888;4204.747715137101;4202.978985105116;4209.200251952119;4211.684123690736;4219.092895027584;4253.510846252659;4777.092352453023;4781.187198176319;4784.521547845187;4787.593054590398;4798.424189097331;5476.424975377528;5608.439156084441;5618.690971996498;5643.435656434323;5668.744906479218;1404.4452441285634;1466.992665036668;1472.4846303793802;1498.9625259368386;1508.668093019534;948.9003654616166;951.8477043672979;971;994.3288280410839;1007.596961215505;4913.754312284372;4915.311856340115;4940.629452790983;4984.476629953875;4984.675996060245;5066.392032956069;5107.518819542365;5127.897502037005;5133.640645154804;5158.535731784083;4571.954280457219;4850.611951043908;4904.209963363418;4913.263010548446;4949.925751113737;2709.9729002710114;2725.536658768014;2726.740959608834;2757.47607954488;2767.8477683727187;5442.619016668795;5459.781608735606;5473.644213126134;5489.862753431116;5504.256925315111;5147.50722290539;5187.325647665779;5591.30108736408;5997.940020599825;6001.939980600226;6124.755009799647;6128.387161283925;6149.292831324276;6166.396736848452;6192.318844927055;5551.22282880389;5827.4172582741385;5866.765329386777;5925.614835035709;5943.554233432424;5775.7400916957995;5790.073588225863;5792.102224155263;5827.446392592697;5844.386339434468;6105.786297479567;6119.908201377072;6135.141080248858;6152.107944348134;6155.6614386209185;4127.814248358891;4224.176285624274;4238.131183107517;4248.468941382327;4251.192273468031;5631.915521267102;5706.915686019643;5836.854078648068;5910.734016969161;5946.43508866657;5589.972050139886;5719.597768937139;5736;5743.483086522066;5743.741531631003;5651.602719402878;5661.264035675335;5697;5709.286339207599;5749.195112684144;5410.403930840333;5611.5230205433;5622.413689702783;5643.209950607001;5662.518685911744;5702.885942281048;5713.085906255145;5719.342275638355;5749.683767392834;5806.477417032487;5451.61838671305;5615.803446879503;5627.409122042127;5668.376478587435;5683.943160568341 16:25:05 INFO - PROCESS | 29557 | |5;yui.html;804.1476035402474;824.2828738997074;876.7808047988002;903.5166186090439;905.4657751926362;13131.21212727237;15973.920130399347;16122.596935076628;16731.916340418313;16919.577010574743;16140.192990350493;16187.595310117253;16209.108499032549;16245.593860153502;16292.42976495823;722.1803253307507;845.3828705045321;848.6562942008482;870.7387783664907;875.7547886591765;859.6948083430386;862.3877047296427;864.2351518905782;864.9351298652595;865.1910463716446;900.729781065681;908;908.8454962656365;908.8591268353383;909.3180114913814;747.6822350501043;897.8473659477904;920.2867777472445;920.4155361345561;923.8706581078654;661.8577005943697;663.240589524996;663.5985228936472;664.6045602866287;664.7939138866918;15314.081155130616;15314.846851531454;15326.310316035806;15346.155961422117;15355.078695278318;15244.161571113584;15446.613834654112;15576.454824081216;16604.916975415166;17060.402885899057;14308.518067721581;14324.64188395288;14369.712605747827;14484.855151448455;14603.561893143222;4843.685160464559;4926.679765815211;4946.752662366867;4961.603071754251;5037.874053148664;78892;80328.59835700784;80350;81053;81130;12453;12587.811182832265;12817.1028028039;12891.677708057186;12894.548690795777;23361.182358617367;23373.065077397056;23386.415339616306;23408;23422.531549368916;9179.495127767903;9212.861807072899;9272.814543709223;9339.439633622005;9525.761855953519;4993.425756038102;4994;5030.597552195889;5110.540051395392;5243.397009343898;3595.496630471736;3726.9627303727157;3731.4775931369086;3733.6453036961443;3735.458358537997;590.852286928268;635.6503922842418;666.7099811581977;689.0147089661832;698.8427603789208;681.2846511163259;763.1376544504674;774.4230548241596;803.3051410529849;815.3721634341524;10360.326578772356;10671.039606435457;11063.280886742335;11072.501737421835;11095.72260693489;668.3850857211377;679.2935347238915;720.5136532840312;737.2774680812834;758.4046523479043;4909.803607855717;5218.504242096995;5237.580993520509;5312.123499622597;5349.759260833194;108579.45710271398;108878.45560772145;110857.44571277092;114432.42783786195;116048;98954;100531;100720.496397519;100838.49580752049;101665;85525.14474855296;86509.56745216233;86665.56667216624;86720.56639716761;87042;9149.86275205859;9291.256699464162;9316.48759318217;9346.439213647203;9407.294452916196;41760.79119604383;51103.48896511136;53228.4677153239;53340;53686.46313536815;64984.675076626205;65875;72322;73997.6300118496;75236;13949.16305021702;14059;14092.647683807987;14119.082259653089;14292.42830286777;15840.128792916501;16285.430009950069;16330.101844398672;16568.088755118584;16960.830391695927;14173;14426;14579.125252484884;14996.850031499545;15413.845861541242;13982.860171398155;14188;14199.716005680035;14408.711825763636;14568.562943111723;13256.602301930958;13332.40004199794;13465.528706495226;13968.650783730489;14540.85459145395;12414.379281035732;12486.250824950532;12585.748285034066;12667.366631668565;12708.745825083262;12167.878321216675;12184.51261949512;12266.907876426285;12427.565035223724;12583.05627077991 16:25:05 INFO - PROCESS | 29557 | __end_tp_report 16:25:05 INFO - PROCESS | 29557 | __start_cc_report 16:25:05 INFO - PROCESS | 29557 | _x_x_mozilla_cycle_collect,895 16:25:05 INFO - PROCESS | 29557 | __end_cc_report 16:25:05 INFO - PROCESS | 29557 | __startTimestamp1469661905402__endTimestamp 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | ------- Summary: start ------- 16:25:05 INFO - PROCESS | 29557 | Number of tests: 6 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | [#0] dojo.html Cycles:165 Average:5478.84 Median:5667.94 stddev:609.12 (10.7%) stddev-sans-first:586.85 16:25:05 INFO - PROCESS | 29557 | Values: 3314.6 3314.7 3351.3 3378.3 3395.6 5464.6 5546.6 5555.6 5563.6 5578.3 5448.3 5542.4 5543.2 5550.3 5573.6 4845.2 4953.9 5032.3 5052.9 5101.8 4803.4 4939.0 4968.9 4983.2 4989.7 4927.8 4998.8 5041.6 5067.5 5068.2 5123.0 5124.4 5126.8 5137.0 5167.3 4798.4 4933.8 4943.7 5042.7 5051.3 5275.3 5335.3 5336.2 5368.1 5388.2 5892.0 5938.5 5966.1 5972.8 5978.7 5792.6 5836.5 5853.4 5859.4 5975.0 5488.9 5502.3 5527.1 5528.6 5540.1 5747.4 6014.7 6020.3 6027.5 6033.2 5662.2 5691.7 5729.6 5804.0 5821.2 5866.4 5867.9 5868.7 5898.9 5903.4 5602.2 5769.9 5844.6 5856.5 5860.0 5805.9 5883.5 5895.9 5896.6 5921.2 4524.3 4541.1 4541.7 4541.9 4566.2 3960.0 3963.3 4037.8 4063.2 4106.7 5640.9 5659.4 5667.9 5668.8 5673.8 5927.7 5935.5 5942.9 5946.6 5964.7 5345.6 5603.2 5653.9 5660.4 5662.0 5048.6 5048.8 5054.4 5082.5 5097.3 6140.9 6183.5 6190.9 6191.5 6196.8 5600.8 5836.2 5926.9 5930.6 5996.1 6211.7 6251.1 6253.8 6259.6 6266.1 5513.3 5873.8 5879.2 5881.0 5904.7 5794.6 5839.2 5870.9 5932.3 5939.7 5753.1 5782.7 5788.4 5794.4 5798.2 5517.4 5688.7 5748.7 5803.4 5810.4 5623.1 5701.8 5704.7 5710.8 5740.5 5797.4 5805.9 5819.4 5836.8 5837.0 5379.4 5606.1 5628.5 5655.5 5687.1 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | [#1] ext.html Cycles:180 Average:70426.80 Median:3833.93 stddev:158521.82 (4134.7%) stddev-sans-first:158879.80 16:25:05 INFO - PROCESS | 29557 | Values: 590.9 628.9 641.9 642.3 656.7 32081.0 35353.6 35388.5 35392.8 35446.5 35425.0 36417.0 51277.2 52493.7 52819.7 9012.1 9147.3 9150.3 9163.2 9171.2 9040.5 9115.9 9134.2 9165.5 9165.9 8452.4 8639.2 8639.4 10952.3 11201.7 11051.3 11105.2 11117.2 11133.4 11149.2 10868.5 10932.1 10942.1 10947.7 10948.9 35765.0 44519.3 44905.0 45054.8 48821.3 3820.7 3847.1 3873.3 3945.4 4087.6 34213.5 35044.5 35057.0 35105.6 35148.5 4656.3 4727.0 4822.7 4845.7 4859.0 369451.0 378654.0 379851.1 380152.0 381260.0 38502.8 39507.2 39562.0 41895.8 41957.4 41882.0 41956.8 42035.6 42044.2 42055.6 16004.2 16128.3 16135.8 16144.3 16150.8 13713.5 13829.4 13830.9 13863.5 13888.1 8625.8 9327.9 9395.3 9402.5 9407.2 3497.5 3608.2 3616.2 3643.1 3674.4 1025.6 1089.7 1106.1 1129.2 1182.0 33500.3 34053.5 34120.7 34141.0 34228.5 1169.7 1176.1 1231.9 1260.6 1280.6 17069.8 17707.9 17730.9 17844.3 17864.2 618001.0 626561.0 627840.0 628560.0 629228.0 56282.0 60515.7 61612.7 63467.4 65095.0 1419.5 1421.7 1425.3 1432.3 1455.5 32339.8 34514.1 34765.7 34806.8 34868.8 57291.0 57637.1 57676.4 57907.0 58044.4 472920.0 756096.0 762862.0 766442.0 770411.0 30618.4 30628.7 30725.7 30727.2 30755.8 48412.8 50229.2 50503.5 50879.7 51368.5 41492.0 42298.8 42399.2 42492.0 43297.4 41133.4 41210.0 41763.8 42373.2 43250.8 41774.2 42213.6 42251.4 42757.4 42928.4 36618.8 37140.4 37268.8 37529.0 37908.2 28157.7 28454.4 28667.7 28677.9 28727.7 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | [#2] jquery.html Cycles:180 Average:30344.28 Median:2266.73 stddev:89897.92 (3966.0%) stddev-sans-first:90122.05 16:25:05 INFO - PROCESS | 29557 | Values: 433.4 433.5 435.2 439.5 445.1 18544.1 19718.9 20197.1 21193.8 21232.6 16793.3 17570.6 17586.9 17985.4 18100.2 4966.5 5295.6 5298.4 5339.1 5345.9 5285.6 5353.4 5599.3 5952.0 5970.7 5905.6 5917.0 5924.5 5926.8 5945.6 5557.7 5836.3 5855.4 5910.9 5927.6 5647.5 5754.2 5780.1 5870.4 5878.6 14366.1 14685.2 14717.6 14898.1 15016.8 2335.9 2401.2 2480.1 2481.4 2490.4 10394.8 10733.8 10746.0 11084.9 11108.4 1967.5 2016.7 2026.7 2032.5 2043.7 81420.6 84356.0 85137.6 85439.6 86448.0 11516.7 11796.1 12181.0 12233.6 12686.6 17166.9 17311.2 17321.4 17338.7 17350.5 8213.8 8270.8 8318.8 8357.4 8430.5 7744.9 7804.9 7823.7 7859.6 7965.0 4619.7 4772.5 5103.5 5217.0 5257.9 2111.6 2114.4 2133.8 2160.9 2197.6 1043.8 1070.8 1074.5 1092.0 1102.1 17614.6 18060.7 19045.1 19090.6 19406.7 1028.5 1034.3 1063.5 1076.1 1084.7 10130.2 10688.8 10760.9 10835.2 10863.2 528518.0 537550.0 550272.0 554814.0 557847.0 95127.5 96254.5 97371.0 97547.0 98072.5 1047.2 1063.1 1111.0 1118.8 1131.6 16672.2 16966.2 17097.5 17291.8 17656.2 38874.0 39370.8 39733.8 41515.0 41713.6 53444.0 53642.5 54023.2 55258.4 55272.4 8703.2 8786.1 8955.9 9034.7 9305.1 9742.0 12656.5 12685.5 12924.2 13394.1 10282.3 10449.1 10449.4 10548.5 10794.9 10851.3 10952.9 11397.8 11646.1 11982.8 6060.7 8821.8 10666.2 10870.3 10896.6 8629.0 8649.8 8745.5 8784.6 8968.8 8481.6 10648.5 10724.4 10774.9 10797.4 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | [#3] mootools.html Cycles:180 Average:6274.28 Median:2461.06 stddev:3690.81 (150.0%) stddev-sans-first:3683.01 16:25:05 INFO - PROCESS | 29557 | Values: 1403.2 1511.8 1527.1 1548.5 1559.1 8488.6 9193.4 9542.6 9551.0 9866.2 9574.9 9758.2 9759.5 9766.2 9772.2 2122.9 2380.2 2382.2 2416.9 2465.7 2470.8 2495.2 2497.6 2500.7 2502.1 2407.0 2448.6 2451.8 2452.9 2453.3 2028.8 2316.7 2368.4 2403.3 2410.4 2378.5 2394.3 2414.7 2418.9 2419.4 9508.2 9526.0 9546.1 9570.2 9576.1 5396.9 5524.7 5533.6 5536.4 5537.1 3450.4 3536.3 3591.9 3602.0 3617.0 1246.6 1257.1 1267.9 1306.9 1338.0 13588.6 13618.3 13668.8 13671.0 13681.5 4825.2 4830.5 4970.7 4977.0 4990.4 11272.2 11283.5 11370.7 11395.1 11411.1 5445.2 5462.1 5468.9 5477.1 5477.4 4393.7 4912.8 5052.2 5056.1 5115.2 4309.1 4350.6 4423.5 4434.9 4446.3 1357.9 1395.4 1398.8 1408.6 1411.3 1020.3 1021.7 1025.5 1027.8 1046.4 6233.3 6417.4 6429.5 6442.3 6518.6 475.8 476.2 477.4 482.5 489.9 4128.5 4134.3 4222.6 4298.3 4361.4 11701.2 12124.7 12129.2 12142.0 12193.1 11823.8 12120.6 12328.3 12336.9 12358.8 8965.0 9117.4 9120.6 9121.1 9130.2 6375.3 6406.1 6533.2 6579.0 6713.6 10736.8 10777.2 10856.8 10895.8 11180.4 10877.8 11155.6 11162.1 11281.5 11303.0 7507.7 8409.4 8586.1 8618.3 8742.0 9358.0 9399.1 9430.2 9466.2 9487.1 8198.6 8269.9 8290.6 8446.5 8495.6 8167.8 8219.8 8236.6 8264.0 8456.5 7391.7 7726.4 7733.0 7743.7 7746.1 5160.6 5176.2 5299.0 5335.8 5500.9 5890.0 5900.8 5901.8 5910.8 5981.0 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | [#4] prototype.html Cycles:180 Average:4703.60 Median:5164.87 stddev:1384.54 (26.8%) stddev-sans-first:1348.79 16:25:05 INFO - PROCESS | 29557 | Values: 321.5 328.0 336.1 344.7 355.5 5266.5 5357.8 5405.7 5435.0 5454.0 5334.9 5439.6 5444.3 5453.9 5477.8 4256.3 4272.7 4297.8 4326.6 4327.5 4055.1 4123.3 4214.4 4300.8 4310.2 4141.5 4184.6 4185.1 4214.2 4214.8 4156.0 4158.6 4179.0 4198.3 4211.3 3653.9 3726.6 3734.9 3771.8 3776.6 5107.0 5135.6 5136.6 5141.3 5171.2 4751.5 4755.4 4782.9 4792.5 4836.4 5352.5 5573.0 5576.0 5590.9 5598.5 4076.7 4094.6 4102.7 4106.2 4113.1 5776.3 5787.4 5811.7 5853.9 5865.5 4091.6 4168.5 4191.3 4196.6 4204.7 4203.0 4209.2 4211.7 4219.1 4253.5 4777.1 4781.2 4784.5 4787.6 4798.4 5476.4 5608.4 5618.7 5643.4 5668.7 1404.4 1467.0 1472.5 1499.0 1508.7 948.9 951.8 971.0 994.3 1007.6 4913.8 4915.3 4940.6 4984.5 4984.7 5066.4 5107.5 5127.9 5133.6 5158.5 4572.0 4850.6 4904.2 4913.3 4949.9 2710.0 2725.5 2726.7 2757.5 2767.8 5442.6 5459.8 5473.6 5489.9 5504.3 5147.5 5187.3 5591.3 5997.9 6001.9 6124.8 6128.4 6149.3 6166.4 6192.3 5551.2 5827.4 5866.8 5925.6 5943.6 5775.7 5790.1 5792.1 5827.4 5844.4 6105.8 6119.9 6135.1 6152.1 6155.7 4127.8 4224.2 4238.1 4248.5 4251.2 5631.9 5706.9 5836.9 5910.7 5946.4 5590.0 5719.6 5736.0 5743.5 5743.7 5651.6 5661.3 5697.0 5709.3 5749.2 5410.4 5611.5 5622.4 5643.2 5662.5 5702.9 5713.1 5719.3 5749.7 5806.5 5451.6 5615.8 5627.4 5668.4 5683.9 16:25:05 INFO - PROCESS | 29557 | 16:25:05 INFO - PROCESS | 29557 | [#5] yui.html Cycles:180 Average:21325.88 Median:4876.74 stddev:29634.40 (607.7%) stddev-sans-first:29677.46 16:25:05 INFO - PROCESS | 29557 | Values: 804.1 824.3 876.8 903.5 905.5 13131.2 15973.9 16122.6 16731.9 16919.6 16140.2 16187.6 16209.1 16245.6 16292.4 722.2 845.4 848.7 870.7 875.8 859.7 862.4 864.2 864.9 865.2 900.7 908.0 908.8 908.9 909.3 747.7 897.8 920.3 920.4 923.9 661.9 663.2 663.6 664.6 664.8 15314.1 15314.8 15326.3 15346.2 15355.1 15244.2 15446.6 15576.5 16604.9 17060.4 14308.5 14324.6 14369.7 14484.9 14603.6 4843.7 4926.7 4946.8 4961.6 5037.9 78892.0 80328.6 80350.0 81053.0 81130.0 12453.0 12587.8 12817.1 12891.7 12894.5 23361.2 23373.1 23386.4 23408.0 23422.5 9179.5 9212.9 9272.8 9339.4 9525.8 4993.4 4994.0 5030.6 5110.5 5243.4 3595.5 3727.0 3731.5 3733.6 3735.5 590.9 635.7 666.7 689.0 698.8 681.3 763.1 774.4 803.3 815.4 10360.3 10671.0 11063.3 11072.5 11095.7 668.4 679.3 720.5 737.3 758.4 4909.8 5218.5 5237.6 5312.1 5349.8 108579.5 108878.5 110857.4 114432.4 116048.0 98954.0 100531.0 100720.5 100838.5 101665.0 85525.1 86509.6 86665.6 86720.6 87042.0 9149.9 9291.3 9316.5 9346.4 9407.3 41760.8 51103.5 53228.5 53340.0 53686.5 64984.7 65875.0 72322.0 73997.6 75236.0 13949.2 14059.0 14092.6 14119.1 14292.4 15840.1 16285.4 16330.1 16568.1 16960.8 14173.0 14426.0 14579.1 14996.9 15413.8 13982.9 14188.0 14199.7 14408.7 14568.6 13256.6 13332.4 13465.5 13968.7 14540.9 12414.4 12486.3 12585.7 12667.4 12708.7 12167.9 12184.5 12266.9 12427.6 12583.1 16:25:05 INFO - PROCESS | 29557 | -------- Summary: end -------- 16:25:05 INFO - PROCESS | 29557 | 16:25:06 INFO - TEST-INFO | 29557: exit 0 16:25:06 INFO - TEST-OK | dromaeo_css | took 1112827ms 16:25:06 INFO - TEST-START | kraken 16:25:06 INFO - operating with platform_type : linux_ 16:25:06 INFO - Initialising browser for kraken test... 16:25:06 INFO - TEST-INFO | started process 29753 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjRu8zO/profile http://localhost:43336/getInfo.html) 16:25:10 INFO - PROCESS | 29753 | __metrics Screen width/height:1600/1200 16:25:10 INFO - PROCESS | 29753 | colorDepth:24 16:25:10 INFO - PROCESS | 29753 | Browser inner width/height: 1024/697 16:25:10 INFO - PROCESS | 29753 | __metrics 16:25:10 INFO - PROCESS | 29753 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:25:10 ERROR - PROCESS | 29753 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:25:11 INFO - TEST-INFO | 29753: exit 0 16:25:11 INFO - Browser initialized. 16:25:11 INFO - Running cycle 1/1 for kraken test... 16:25:11 INFO - TEST-INFO | started process 29839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjRu8zO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:23 INFO - PROCESS | 29839 | RSS: Main: 145694720 16:25:23 INFO - PROCESS | 29839 | 16:25:26 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43336/tests/kraken/driver.html?testName=audio-beat-detection) 16:25:26 INFO - PROCESS | 29839 | RSS: Main: 194461696 16:25:26 INFO - PROCESS | 29839 | 16:25:29 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43336/tests/kraken/driver.html?testName=audio-dft) 16:25:30 INFO - PROCESS | 29839 | RSS: Main: 192200704 16:25:30 INFO - PROCESS | 29839 | 16:25:33 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43336/tests/kraken/driver.html?testName=audio-fft) 16:25:33 INFO - PROCESS | 29839 | RSS: Main: 278605824 16:25:33 INFO - PROCESS | 29839 | 16:25:35 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43336/tests/kraken/driver.html?testName=audio-oscillator) 16:25:36 INFO - PROCESS | 29839 | RSS: Main: 187904000 16:25:36 INFO - PROCESS | 29839 | 16:25:38 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43336/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:25:39 INFO - PROCESS | 29839 | RSS: Main: 188424192 16:25:39 INFO - PROCESS | 29839 | 16:25:42 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43336/tests/kraken/driver.html?testName=imaging-darkroom) 16:25:42 INFO - PROCESS | 29839 | RSS: Main: 222834688 16:25:42 INFO - PROCESS | 29839 | 16:25:46 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43336/tests/kraken/driver.html?testName=imaging-desaturate) 16:25:46 INFO - PROCESS | 29839 | RSS: Main: 221786112 16:25:46 INFO - PROCESS | 29839 | 16:25:49 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43336/tests/kraken/driver.html?testName=json-parse-financial) 16:25:50 INFO - PROCESS | 29839 | RSS: Main: 222457856 16:25:50 INFO - PROCESS | 29839 | 16:25:51 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43336/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:25:52 INFO - PROCESS | 29839 | RSS: Main: 200650752 16:25:52 INFO - PROCESS | 29839 | 16:25:53 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:25:54 INFO - PROCESS | 29839 | RSS: Main: 202035200 16:25:54 INFO - PROCESS | 29839 | 16:25:56 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:25:57 INFO - PROCESS | 29839 | RSS: Main: 208187392 16:25:57 INFO - PROCESS | 29839 | 16:25:59 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:26:00 INFO - PROCESS | 29839 | RSS: Main: 215687168 16:26:00 INFO - PROCESS | 29839 | 16:26:02 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:26:03 INFO - PROCESS | 29839 | RSS: Main: 215023616 16:26:03 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | Cycle 1(1): loaded http://localhost:43336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43336/tests/kraken/driver.html?testName=ai-astar) 16:26:05 INFO - PROCESS | 29839 | RSS: Main: 257638400 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | __start_tp_report 16:26:05 INFO - PROCESS | 29839 | _x_x_mozilla_page_load 16:26:05 INFO - PROCESS | 29839 | _x_x_mozilla_page_load_details 16:26:05 INFO - PROCESS | 29839 | |i|pagename|runs| 16:26:05 INFO - PROCESS | 29839 | |0;ai-astar;110;103;109;109;109;109;109;109;109;109 16:26:05 INFO - PROCESS | 29839 | |1;audio-beat-detection;146;122;127;175;137;126;152;123;165;130 16:26:05 INFO - PROCESS | 29839 | |2;audio-dft;161;161;162;160;161;160;160;159;161;158 16:26:05 INFO - PROCESS | 29839 | |3;audio-fft;73;75;75;105;75;75;75;74;111;74 16:26:05 INFO - PROCESS | 29839 | |4;audio-oscillator;85;85;114;85;86;84;113;86;86;132 16:26:05 INFO - PROCESS | 29839 | |5;imaging-gaussian-blur;117;116;116;117;117;116;116;116;116;117 16:26:05 INFO - PROCESS | 29839 | |6;imaging-darkroom;147;146;146;146;148;146;145;146;147;146 16:26:05 INFO - PROCESS | 29839 | |7;imaging-desaturate;94;94;94;94;95;95;95;95;94;95 16:26:05 INFO - PROCESS | 29839 | |8;json-parse-financial;50;49;50;51;51;51;51;51;50;51 16:26:05 INFO - PROCESS | 29839 | |9;json-stringify-tinderbox;63;62;61;61;60;60;61;62;61;61 16:26:05 INFO - PROCESS | 29839 | |10;stanford-crypto-aes;77;76;75;82;77;78;77;81;111;77 16:26:05 INFO - PROCESS | 29839 | |11;stanford-crypto-ccm;134;116;118;125;118;115;126;125;135;122 16:26:05 INFO - PROCESS | 29839 | |12;stanford-crypto-pbkdf2;164;160;158;158;160;156;158;160;159;162 16:26:05 INFO - PROCESS | 29839 | |13;stanford-crypto-sha256-iterative;63;63;66;61;61;62;62;62;62;60 16:26:05 INFO - PROCESS | 29839 | __end_tp_report 16:26:05 INFO - PROCESS | 29839 | __start_cc_report 16:26:05 INFO - PROCESS | 29839 | _x_x_mozilla_cycle_collect,1598 16:26:05 INFO - PROCESS | 29839 | __end_cc_report 16:26:05 INFO - PROCESS | 29839 | __startTimestamp1469661965356__endTimestamp 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | ------- Summary: start ------- 16:26:05 INFO - PROCESS | 29839 | Number of tests: 14 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:1.96 (1.8%) stddev-sans-first:2.00 16:26:05 INFO - PROCESS | 29839 | Values: 110.0 103.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:141.50 stddev:18.61 (13.2%) stddev-sans-first:19.62 16:26:05 INFO - PROCESS | 29839 | Values: 146.0 122.0 127.0 175.0 137.0 126.0 152.0 123.0 165.0 130.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 16:26:05 INFO - PROCESS | 29839 | Values: 161.0 161.0 162.0 160.0 161.0 160.0 160.0 159.0 161.0 158.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:14.21 (18.9%) stddev-sans-first:14.76 16:26:05 INFO - PROCESS | 29839 | Values: 73.0 75.0 75.0 105.0 75.0 75.0 75.0 74.0 111.0 74.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:85.00 stddev:17.37 (20.4%) stddev-sans-first:17.99 16:26:05 INFO - PROCESS | 29839 | Values: 85.0 85.0 114.0 85.0 86.0 84.0 113.0 86.0 86.0 132.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:26:05 INFO - PROCESS | 29839 | Values: 117.0 116.0 116.0 117.0 117.0 116.0 116.0 116.0 116.0 117.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 16:26:05 INFO - PROCESS | 29839 | Values: 147.0 146.0 146.0 146.0 148.0 146.0 145.0 146.0 147.0 146.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:26:05 INFO - PROCESS | 29839 | Values: 94.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:0.71 (1.4%) stddev-sans-first:0.73 16:26:05 INFO - PROCESS | 29839 | Values: 50.0 49.0 50.0 51.0 51.0 51.0 51.0 51.0 50.0 51.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.71 16:26:05 INFO - PROCESS | 29839 | Values: 63.0 62.0 61.0 61.0 60.0 60.0 61.0 62.0 61.0 61.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:77.00 stddev:10.72 (13.9%) stddev-sans-first:11.27 16:26:05 INFO - PROCESS | 29839 | Values: 77.0 76.0 75.0 82.0 77.0 78.0 77.0 81.0 111.0 77.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:125.00 stddev:7.03 (5.6%) stddev-sans-first:6.32 16:26:05 INFO - PROCESS | 29839 | Values: 134.0 116.0 118.0 125.0 118.0 115.0 126.0 125.0 135.0 122.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:160.00 stddev:2.27 (1.4%) stddev-sans-first:1.73 16:26:05 INFO - PROCESS | 29839 | Values: 164.0 160.0 158.0 158.0 160.0 156.0 158.0 160.0 159.0 162.0 16:26:05 INFO - PROCESS | 29839 | 16:26:05 INFO - PROCESS | 29839 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.62 (2.6%) stddev-sans-first:1.69 16:26:05 INFO - PROCESS | 29839 | Values: 63.0 63.0 66.0 61.0 61.0 62.0 62.0 62.0 62.0 60.0 16:26:05 INFO - PROCESS | 29839 | -------- Summary: end -------- 16:26:05 INFO - PROCESS | 29839 | 16:26:06 INFO - TEST-INFO | 29839: exit 0 16:26:06 INFO - TEST-OK | kraken | took 60249ms 16:26:06 INFO - SUITE-END | took 1173s 16:26:06 INFO - Completed test suite (00:19:33) 16:26:06 INFO - javascript benchmark 16:26:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.6353901070884, 3314.6685331466856, 3351.2627222011165, 3378.324335132974, 3395.62648108708, 5464.562834973202, 5546.556275497961, 5555.555555555556, 5563.582731295154, 5578.274824272849, 5448.264484294619, 5542.39033706293, 5543.223948647183, 5550.306211723535, 5573.6098473106895, 4845.200541910598, 4953.876153096166, 5032.320636714052, 5052.873678158038, 5101.795928162869, 4803.447603525587, 4938.975305123469, 4968.87577810554, 4983.202687569989, 4989.65072444929, 4927.77824997876, 4998.825041123561, 5041.621878359119, 5067.5185857343495, 5068.214426763839, 5123.026624941249, 5124.385073791169, 5126.794928202868, 5136.974315128437, 5167.3282473278605, 4798.424189097331, 4933.827316043921, 4943.728094954776, 5042.697438153723, 5051.292819005343, 5275.28783614211, 5335.306410166692, 5336.17289320156, 5368.114261146906, 5388.245645609618, 5892.0, 5938.495227905633, 5966.075258334965, 5972.790952316692, 5978.730957106897, 5792.623479473863, 5836.533077353844, 5853.443922827325, 5859.355470898197, 5975.043992961106, 5488.862778430531, 5502.284702988586, 5527.143292789584, 5528.612997090206, 5540.141278101901, 5747.36778954323, 6014.729337179838, 6020.307664618537, 6027.547933904974, 6033.155358249848, 5662.150677398341, 5691.743871525791, 5729.598928074995, 5804.0, 5821.15593238978, 5866.354700982974, 5867.9413205867395, 5868.677222752704, 5898.882022359615, 5903.439173278618, 5602.215689803431, 5769.88460230793, 5844.620099693507, 5856.472917437451, 5860.033094539353, 5805.94194058054, 5883.499902508249, 5895.852603684943, 5896.64620122794, 5921.2302400688495, 4524.298733696282, 4541.069080838421, 4541.72749635023, 4541.909161816745, 4566.223741963808, 3959.9604003960167, 3963.2667956428154, 4037.7779222143063, 4063.187362527506, 4106.7125301229225, 5640.943590564124, 5659.377468478464, 5667.943320566824, 5668.801591944345, 5673.8297851064535, 5927.673977931169, 5935.465808077293, 5942.940570594325, 5946.613470124428, 5964.671943043174, 5345.599080068932, 5603.187537807076, 5653.943460565424, 5660.433956604389, 5661.971690141605, 5048.646594738407, 5048.798048078109, 5054.393472783289, 5082.542571168538, 5097.260897169889, 6140.938590614126, 6183.536234782409, 6190.876182476415, 6191.504679625619, 6196.752129914753, 5600.831975040755, 5836.153757705024, 5926.851828704317, 5930.584859059997, 5996.070609055603, 6211.689415529296, 6251.124842521959, 6253.843653908689, 6259.593126446766, 6266.122742816101, 5513.255710479034, 5873.794417195378, 5879.176915231786, 5881.029630110984, 5904.734286957183, 5794.565407594363, 5839.240898683144, 5870.9412905870395, 5932.347441781487, 5939.67331796738, 5753.137029445449, 5782.710864456844, 5788.363280039276, 5794.449527294901, 5798.246227990334, 5517.44825517445, 5688.658680479184, 5748.6838223898085, 5803.390643982321, 5810.360860305447, 5623.128415095667, 5701.802621449542, 5704.657720536781, 5710.771569137354, 5740.483356497935, 5797.362290147996, 5805.94194058054, 5819.359870414336, 5836.824895253149, 5836.970815145897, 5379.435159308373, 5606.131049687305, 5628.521575666115, 5655.519280861173, 5687.118496633028], "value": 5439.797589309007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.8552404660857, 628.858506835962, 641.9101325814387, 642.3448082955385, 656.7077650445548, 32081.0, 35353.646463535355, 35388.469172962425, 35392.823035884816, 35446.468302975474, 35425.0, 36417.0, 51277.23084153741, 52493.737531312385, 52819.735901320535, 9012.143846334604, 9147.313951453634, 9150.313726470507, 9163.175314221719, 9171.220446262076, 9040.457572545636, 9115.908840911572, 9134.223590994774, 9165.450072995642, 9165.862512062324, 8452.408331416804, 8639.179277968615, 8639.39524233304, 10952.34285942842, 11201.66395008151, 11051.336919784839, 11105.222634415593, 11117.166212534092, 11133.443327833576, 11149.163812713998, 10868.45657717119, 10932.07077398422, 10942.124630029659, 10947.671569852917, 10948.945255273673, 35765.0, 44519.33221001687, 44905.0, 45054.77472612649, 48821.26768098481, 3820.7325487215626, 3847.1343947611845, 3873.2834425631067, 3945.447637330776, 4087.570805065485, 34213.48679769805, 35044.47433288502, 35057.0, 35105.648943510496, 35148.472772908426, 4656.324832899245, 4726.976365118187, 4822.734749588772, 4845.709257444564, 4859.048976521366, 369451.0, 378654.0, 379851.10074449726, 380152.0, 381260.0, 38502.80748596295, 39507.20985580272, 39562.0, 41895.7905210472, 41957.370639440436, 41882.0, 41956.79021604872, 42035.579644203164, 42044.15911681749, 42055.57944420578, 16004.199790010452, 16128.274227659784, 16135.838641613434, 16144.273507692182, 16150.75773863393, 13713.520026799213, 13829.446822127002, 13830.930845345709, 13863.514776982756, 13888.09727367738, 8625.827483450421, 9327.860082098774, 9395.342326037111, 9402.529873506297, 9407.153356197976, 3497.510348551154, 3608.2061946371755, 3616.2405894762396, 3643.0710168906758, 3674.412094064937, 1025.5795123999123, 1089.722120859176, 1106.0985296982933, 1129.2151954391784, 1182.0, 33500.329993399995, 34053.48919766206, 34120.65879341224, 34141.0, 34228.48657270143, 1169.6900321414832, 1176.0591526778544, 1231.949009062853, 1260.5714057220405, 1280.6478218489804, 17069.829301707072, 17707.911460442872, 17730.93614383141, 17844.28622855071, 17864.196111175028, 618001.0, 626561.0, 627840.0, 628560.0, 629228.0, 56282.0, 60515.69742151261, 61612.69193654003, 63467.36532634707, 65095.0, 1419.531554586984, 1421.694335717805, 1425.3443416028742, 1432.302721275292, 1455.548779878241, 32339.838300808344, 34514.137146571535, 34765.65234347725, 34806.82596587001, 34868.82565587257, 57291.0, 57637.13544296923, 57676.423235767106, 57907.0, 58044.4195558039, 472920.0, 756096.0, 762862.0, 766442.0, 770411.0, 30618.387632247675, 30628.693713062585, 30725.692743072283, 30727.2318192047, 30755.84709070433, 48412.757936210095, 50229.246561300875, 50503.49496505135, 50879.74560127324, 51368.48631513787, 41492.0, 42298.78850605727, 42399.152016960106, 42492.0, 43297.35053974256, 41133.382999255635, 41210.0, 41763.79118104512, 42373.1525369497, 43250.78374608107, 41774.1645167101, 42213.57786422096, 42251.3662295072, 42757.35863961981, 42928.35607465828, 36618.8169059153, 37140.44289335608, 37268.813655931546, 37529.0, 37908.241835163695, 28157.71842281551, 28454.43091138207, 28667.713322866504, 28677.85661071681, 28727.71272287334], "value": 20222.20133752463, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.37377489527626, 433.45783369372907, 435.1514546634064, 439.52970321755737, 445.1142226968334, 18544.07279636018, 19718.901405492972, 20197.091130899127, 21193.788062119373, 21232.57534849302, 16793.3206679332, 17570.560735981606, 17586.912065439654, 17985.37051203208, 18100.185491652875, 4966.528179822929, 5295.576353891699, 5298.390685071208, 5339.145736682131, 5345.866353341159, 5285.577153827685, 5353.357597088336, 5599.300087489064, 5951.970240148793, 5970.73131709072, 5905.645661260338, 5917.023691090965, 5924.526037916978, 5926.822195334147, 5945.64326140433, 5557.666540007593, 5836.270466191726, 5855.443732845394, 5910.88178236435, 5927.614705044159, 5647.548196144298, 5754.1944127822135, 5780.104083866965, 5870.412958704138, 5878.617889837147, 14366.138031718026, 14685.192314422702, 14717.55847324582, 14898.106113633108, 15016.849831501764, 2335.859848409101, 2401.21960362881, 2480.1071614218868, 2481.392058945552, 2490.414752533153, 10394.844077338847, 10733.83899241512, 10746.0, 11084.889151108466, 11108.389038602874, 1967.5376286572643, 2016.6571682813958, 2026.6656001759693, 2032.481717162129, 2043.7240972468676, 81420.59289703514, 84356.0, 85137.57431212804, 85439.57280213559, 86448.0, 11516.654500365003, 11796.115291353182, 12181.0, 12233.632991010283, 12686.619401417973, 17166.914165429094, 17311.220995055253, 17321.3937512189, 17338.653226935643, 17350.47948561545, 8213.79465513355, 8270.751877443687, 8318.8336233275, 8357.414980951275, 8430.45202061864, 7744.922550774533, 7804.921950780419, 7823.726169584037, 7859.567723775133, 7965.0, 4619.745913974763, 4772.546608072297, 5103.464136265713, 5216.982688375806, 5257.921131183035, 2111.6199084164996, 2114.4291041418733, 2133.797289257518, 2160.8919554022236, 2197.6044312023987, 1043.8277684182026, 1070.8019016481976, 1074.484247561175, 1092.0, 1102.0577406317643, 17614.647707045788, 18060.729089063676, 19045.142968566448, 19090.61818763617, 19406.70889936652, 1028.5217373921014, 1034.250168627739, 1063.5373612478597, 1076.0530732955106, 1084.728817795551, 10130.24023198263, 10688.839667404995, 10760.892391076146, 10835.241533092609, 10863.239573229797, 528518.0, 537550.0, 550272.0, 554814.0, 557847.0, 95127.52436237775, 96254.51872740591, 97371.0, 97547.0, 98072.50963745135, 1047.1622302858434, 1063.0857462582173, 1110.972225694348, 1118.78743038824, 1131.6491887514896, 16672.16639168061, 16966.151692415577, 17097.48707538776, 17291.82708172902, 17656.205470754103, 38874.0, 39370.803145984086, 39733.80133099316, 41515.0, 41713.58286417097, 53444.0, 53642.46357536375, 54023.18965215446, 55258.44741552533, 55272.447275528335, 8703.173198546128, 8786.121387861163, 8955.865662015205, 9034.683786067457, 9305.11601397866, 9742.0, 12656.493740250287, 12685.492580296685, 12924.224546527239, 13394.129381590166, 10282.280240383101, 10449.05958463742, 10449.373037617766, 10548.472576371305, 10794.946025269823, 10851.294665846695, 10952.890471095187, 11397.772044559228, 11646.126540509325, 11982.760344793229, 6060.6666633333825, 8821.823563528822, 10666.200034997251, 10870.347779133277, 10896.61861834832, 8629.0, 8649.783755406164, 8745.518996455256, 8784.604692788967, 8968.820623587622, 8481.575921203792, 10648.520816563117, 10724.410157441416, 10774.946125269324, 10797.352158870493], "value": 9195.629877379457, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.1931639307402, 1511.7581187010073, 1527.0913806285257, 1548.450300143449, 1559.0723519505893, 8488.61801218945, 9193.40242884213, 9542.570584323705, 9551.0, 9866.21070314375, 9574.904250957488, 9758.219342452605, 9759.51202439877, 9766.169875560587, 9772.169365603932, 2122.936311910645, 2380.2264264114146, 2382.1662418153637, 2416.927492175229, 2465.6917885264343, 2470.814688898331, 2495.1765917247267, 2497.6003839385703, 2500.737422570631, 2502.1492692484553, 2407.037185125946, 2448.6327050942414, 2451.767082127204, 2452.9386765330833, 2453.3498622864954, 2028.8174064334207, 2316.652502124695, 2368.4078980254935, 2403.291029146406, 2410.4455975125643, 2378.452955820154, 2394.341556072077, 2414.6619473273754, 2418.879056047208, 2419.358869899478, 9508.191803696694, 9526.0, 9546.093121153479, 9570.186534144606, 9576.090271424204, 5396.865078373033, 5524.696141712204, 5533.612647114704, 5536.363318218416, 5537.1140617501005, 3450.3616830886367, 3536.275063611979, 3591.8742844000335, 3602.0274525878126, 3616.963830361689, 1246.5512415530493, 1257.145141303913, 1267.936603169847, 1306.9346532673424, 1337.9531716389977, 13588.59234222975, 13618.250996195206, 13668.794968075486, 13671.0, 13681.452741890313, 4825.227963525889, 4830.541098595628, 4970.726610036482, 4976.975115124401, 4990.401151861727, 11272.210945233755, 11283.492242849092, 11370.658880233606, 11395.145364097725, 11411.144164187719, 5445.210444485528, 5462.071447853875, 5468.945310546923, 5477.096279113902, 5477.424870388551, 4393.714408563497, 4912.803487860517, 5052.242163675404, 5056.089903817348, 5115.1559992600805, 4309.1381723655395, 4350.564943505624, 4423.535528769497, 4434.911301773946, 4446.333050042519, 1357.8981576381811, 1395.3651088754593, 1398.7692030815006, 1408.60559043468, 1411.301405804113, 1020.336781092296, 1021.7139201023725, 1025.4718819807806, 1027.768752030787, 1046.3669479964665, 6233.319672540761, 6417.422431981144, 6429.517786165963, 6442.291347951722, 6518.608883466913, 475.79065211306914, 476.17146165672176, 477.35556998053045, 482.4692837878376, 489.9289603007544, 4128.525219599725, 4134.297169481196, 4222.641075508616, 4298.333758267474, 4361.367601697736, 11701.180917335705, 12124.69688257783, 12129.150959432754, 12142.028637708961, 12193.14647974651, 11823.763524729457, 12120.636380908587, 12328.32194229308, 12336.876631233752, 12358.814617780741, 8964.955175224213, 9117.361784675142, 9120.58957346921, 9121.133492318218, 9130.223930965942, 6375.298717141018, 6406.135171751754, 6533.150690410216, 6578.967105164444, 6713.597184168965, 10736.785264294827, 10777.191710621892, 10856.782864342511, 10895.836562451728, 11180.385078820744, 10877.836832447678, 11155.609553665583, 11162.051225645911, 11281.492332844877, 11303.03924166455, 7507.699692012258, 8409.369297302605, 8586.141385861363, 8618.310535157047, 8741.956290218763, 9358.0, 9399.06009399082, 9430.151286384258, 9466.195373393064, 9487.146156845916, 8198.590070496331, 8269.917300826915, 8290.626921788413, 8446.535440550828, 8495.617697203517, 8167.83664326722, 8219.794505137419, 8236.629351679068, 8264.0, 8456.534890581077, 7391.741289055072, 7726.3818894487185, 7732.961335193513, 7743.651535680795, 7746.109197442366, 5160.638755287094, 5176.197689358156, 5299.026425591068, 5335.786568537215, 5500.917486237629, 5890.028145355968, 5900.822975310747, 5901.822945311647, 5910.7931222407005, 5981.0], "value": 4880.344643034068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.5161182420457, 327.9721223695986, 336.1210434713225, 344.6570662191117, 355.4792229383952, 5266.499682530163, 5357.785688572462, 5405.675509561621, 5434.972825135874, 5454.0, 5334.946650533493, 5439.5920305977115, 5444.292242008543, 5453.945790357403, 5477.75350109245, 4256.340267258564, 4272.65818734502, 4297.763623000734, 4326.6106050455455, 4327.545607711192, 4055.1484188320487, 4123.257813593552, 4214.388913607525, 4300.806463709125, 4310.224159651261, 4141.523724771645, 4184.560621134783, 4185.058361868586, 4214.220369231687, 4214.7681877496725, 4155.979220103895, 4158.563350848162, 4179.0328866504815, 4198.320671731301, 4211.283721999293, 3653.872114476007, 3726.6459686330027, 3734.8879533614036, 3771.830732472942, 3776.603456637041, 5107.0, 5135.64050516464, 5136.614753893473, 5141.303369887928, 5171.198464238049, 4751.524847515256, 4755.405574303212, 4782.928256076127, 4792.496787837262, 4836.371271734652, 5352.491513306269, 5572.969000734836, 5576.0, 5590.86022849428, 5598.468145526169, 4076.653484453825, 4094.6110119538894, 4102.651274641659, 4106.172753124861, 4113.095119073799, 5776.306843178803, 5787.3923238059815, 5811.651300921958, 5853.887761325334, 5865.472107510344, 4091.590840915944, 4168.541460439349, 4191.3293872980785, 4196.607933477888, 4204.747715137101, 4202.978985105116, 4209.200251952119, 4211.684123690736, 4219.092895027584, 4253.510846252659, 4777.092352453023, 4781.187198176319, 4784.521547845187, 4787.593054590398, 4798.424189097331, 5476.424975377528, 5608.439156084441, 5618.690971996498, 5643.435656434323, 5668.744906479218, 1404.4452441285634, 1466.992665036668, 1472.4846303793802, 1498.9625259368386, 1508.668093019534, 948.9003654616166, 951.8477043672979, 971.0, 994.3288280410839, 1007.596961215505, 4913.754312284372, 4915.311856340115, 4940.629452790983, 4984.476629953875, 4984.675996060245, 5066.392032956069, 5107.518819542365, 5127.897502037005, 5133.640645154804, 5158.535731784083, 4571.954280457219, 4850.611951043908, 4904.209963363418, 4913.263010548446, 4949.925751113737, 2709.9729002710114, 2725.536658768014, 2726.740959608834, 2757.47607954488, 2767.8477683727187, 5442.619016668795, 5459.781608735606, 5473.644213126134, 5489.862753431116, 5504.256925315111, 5147.50722290539, 5187.325647665779, 5591.30108736408, 5997.940020599825, 6001.939980600226, 6124.755009799647, 6128.387161283925, 6149.292831324276, 6166.396736848452, 6192.318844927055, 5551.22282880389, 5827.4172582741385, 5866.765329386777, 5925.614835035709, 5943.554233432424, 5775.7400916957995, 5790.073588225863, 5792.102224155263, 5827.446392592697, 5844.386339434468, 6105.786297479567, 6119.908201377072, 6135.141080248858, 6152.107944348134, 6155.6614386209185, 4127.814248358891, 4224.176285624274, 4238.131183107517, 4248.468941382327, 4251.192273468031, 5631.915521267102, 5706.915686019643, 5836.854078648068, 5910.734016969161, 5946.43508866657, 5589.972050139886, 5719.597768937139, 5736.0, 5743.483086522066, 5743.741531631003, 5651.602719402878, 5661.264035675335, 5697.0, 5709.286339207599, 5749.195112684144, 5410.403930840333, 5611.5230205433, 5622.413689702783, 5643.209950607001, 5662.518685911744, 5702.885942281048, 5713.085906255145, 5719.342275638355, 5749.683767392834, 5806.477417032487, 5451.61838671305, 5615.803446879503, 5627.409122042127, 5668.376478587435, 5683.943160568341], "value": 4266.034026375404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.1476035402474, 824.2828738997074, 876.7808047988002, 903.5166186090439, 905.4657751926362, 13131.21212727237, 15973.920130399347, 16122.596935076628, 16731.916340418313, 16919.577010574743, 16140.192990350493, 16187.595310117253, 16209.108499032549, 16245.593860153502, 16292.42976495823, 722.1803253307507, 845.3828705045321, 848.6562942008482, 870.7387783664907, 875.7547886591765, 859.6948083430386, 862.3877047296427, 864.2351518905782, 864.9351298652595, 865.1910463716446, 900.729781065681, 908.0, 908.8454962656365, 908.8591268353383, 909.3180114913814, 747.6822350501043, 897.8473659477904, 920.2867777472445, 920.4155361345561, 923.8706581078654, 661.8577005943697, 663.240589524996, 663.5985228936472, 664.6045602866287, 664.7939138866918, 15314.081155130616, 15314.846851531454, 15326.310316035806, 15346.155961422117, 15355.078695278318, 15244.161571113584, 15446.613834654112, 15576.454824081216, 16604.916975415166, 17060.402885899057, 14308.518067721581, 14324.64188395288, 14369.712605747827, 14484.855151448455, 14603.561893143222, 4843.685160464559, 4926.679765815211, 4946.752662366867, 4961.603071754251, 5037.874053148664, 78892.0, 80328.59835700784, 80350.0, 81053.0, 81130.0, 12453.0, 12587.811182832265, 12817.1028028039, 12891.677708057186, 12894.548690795777, 23361.182358617367, 23373.065077397056, 23386.415339616306, 23408.0, 23422.531549368916, 9179.495127767903, 9212.861807072899, 9272.814543709223, 9339.439633622005, 9525.761855953519, 4993.425756038102, 4994.0, 5030.597552195889, 5110.540051395392, 5243.397009343898, 3595.496630471736, 3726.9627303727157, 3731.4775931369086, 3733.6453036961443, 3735.458358537997, 590.852286928268, 635.6503922842418, 666.7099811581977, 689.0147089661832, 698.8427603789208, 681.2846511163259, 763.1376544504674, 774.4230548241596, 803.3051410529849, 815.3721634341524, 10360.326578772356, 10671.039606435457, 11063.280886742335, 11072.501737421835, 11095.72260693489, 668.3850857211377, 679.2935347238915, 720.5136532840312, 737.2774680812834, 758.4046523479043, 4909.803607855717, 5218.504242096995, 5237.580993520509, 5312.123499622597, 5349.759260833194, 108579.45710271398, 108878.45560772145, 110857.44571277092, 114432.42783786195, 116048.0, 98954.0, 100531.0, 100720.496397519, 100838.49580752049, 101665.0, 85525.14474855296, 86509.56745216233, 86665.56667216624, 86720.56639716761, 87042.0, 9149.86275205859, 9291.256699464162, 9316.48759318217, 9346.439213647203, 9407.294452916196, 41760.79119604383, 51103.48896511136, 53228.4677153239, 53340.0, 53686.46313536815, 64984.675076626205, 65875.0, 72322.0, 73997.6300118496, 75236.0, 13949.16305021702, 14059.0, 14092.647683807987, 14119.082259653089, 14292.42830286777, 15840.128792916501, 16285.430009950069, 16330.101844398672, 16568.088755118584, 16960.830391695927, 14173.0, 14426.0, 14579.125252484884, 14996.850031499545, 15413.845861541242, 13982.860171398155, 14188.0, 14199.716005680035, 14408.711825763636, 14568.562943111723, 13256.602301930958, 13332.40004199794, 13465.528706495226, 13968.650783730489, 14540.85459145395, 12414.379281035732, 12486.250824950532, 12585.748285034066, 12667.366631668565, 12708.745825083262, 12167.878321216675, 12184.51261949512, 12266.907876426285, 12427.565035223724, 12583.05627077991], "value": 8057.309806923691, "unit": "score"}], "name": "dromaeo_css", "value": 7440.750755512749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 127.0, 175.0, 137.0, 126.0, 152.0, 123.0, 165.0, 130.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 161.0, 160.0, 160.0, 159.0, 161.0, 158.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 105.0, 75.0, 75.0, 75.0, 74.0, 111.0, 74.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 114.0, 85.0, 86.0, 84.0, 113.0, 86.0, 86.0, 132.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 148.0, 146.0, 145.0, 146.0, 147.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 51.0, 51.0, 51.0, 51.0, 51.0, 50.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 61.0, 60.0, 60.0, 61.0, 62.0, 61.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 82.0, 77.0, 78.0, 77.0, 81.0, 111.0, 77.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 116.0, 118.0, 125.0, 118.0, 115.0, 126.0, 125.0, 135.0, 122.0], "value": 123.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 158.0, 158.0, 160.0, 156.0, 158.0, 160.0, 159.0, 162.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 66.0, 61.0, 61.0, 62.0, 62.0, 62.0, 62.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1481.0, "alertThreshold": 2.0}]} 16:26:06 INFO - Return code: 0 16:26:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:26:06 ERROR - # TBPL SUCCESS # 16:26:06 INFO - Running post-action listener: _resource_record_post_action 16:26:06 INFO - ##### 16:26:06 INFO - ##### Finished run-tests step (success) 16:26:06 INFO - ##### 16:26:06 INFO - Running post-run listener: _resource_record_post_run 16:26:06 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 506675200; Read time: 0; Write time: 487344 16:26:06 INFO - TinderboxPrint: CPU usage
13.1% 16:26:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:26:06 INFO - TinderboxPrint: I/O write bytes / time
506,675,200 / 487,344 16:26:06 INFO - TinderboxPrint: CPU idle
8,253.3 (86.9%) 16:26:06 INFO - TinderboxPrint: CPU user
1,199.6 (12.6%) 16:26:06 INFO - TinderboxPrint: Swap in / out
0 / 0 16:26:06 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252776448; Read time: 0; Write time: 337072 16:26:06 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 247287808; Read time: 0; Write time: 147100 16:26:07 INFO - Running post-run listener: _upload_blobber_files 16:26:07 INFO - Blob upload gear active. 16:26:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:26:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:26:07 INFO - (blobuploader) - INFO - Open directory for files ... 16:26:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:26:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:08 INFO - (blobuploader) - INFO - Done attempting. 16:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:09 INFO - (blobuploader) - INFO - Done attempting. 16:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:26:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:26 INFO - (blobuploader) - INFO - Done attempting. 16:26:26 INFO - (blobuploader) - INFO - Iteration through files over. 16:26:26 INFO - Return code: 0 16:26:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:26:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:26:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8fb35d9157455115a8bca28f8950f546088240213306ce9d9f5d2b8793568687eefcd5bf272d63349378085496ad937314cd014e30968c2b1a81a876ca45b6d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9d637a3bb69382c3aed35d03bef2bc97b4ce7a8f4172b83ff2379dbfccef43aeb89485438e05e1ba30c3c4a03cbdfb946ad18f6fffaa8b2fb4c3665e22550fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78601ac4c56b4b4c1c2761115bdcf51aa8f5755f611ce34023fc93eafd49a061290a202ec00de9bcb3ac296723e48124a13bf9a1e029963d08941d0b3a0f1ca6"} 16:26:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:26:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:26:26 INFO - Contents: 16:26:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8fb35d9157455115a8bca28f8950f546088240213306ce9d9f5d2b8793568687eefcd5bf272d63349378085496ad937314cd014e30968c2b1a81a876ca45b6d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9d637a3bb69382c3aed35d03bef2bc97b4ce7a8f4172b83ff2379dbfccef43aeb89485438e05e1ba30c3c4a03cbdfb946ad18f6fffaa8b2fb4c3665e22550fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78601ac4c56b4b4c1c2761115bdcf51aa8f5755f611ce34023fc93eafd49a061290a202ec00de9bcb3ac296723e48124a13bf9a1e029963d08941d0b3a0f1ca6"} 16:26:26 INFO - Running post-run listener: copy_logs_to_upload_dir 16:26:26 INFO - Copying logs to upload dir... 16:26:26 INFO - mkdir: /builds/slave/test/build/upload/logs 16:26:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.423696 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-27 16:26:26.783709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 16:26:26.788266) ========= 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=17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8fb35d9157455115a8bca28f8950f546088240213306ce9d9f5d2b8793568687eefcd5bf272d63349378085496ad937314cd014e30968c2b1a81a876ca45b6d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9d637a3bb69382c3aed35d03bef2bc97b4ce7a8f4172b83ff2379dbfccef43aeb89485438e05e1ba30c3c4a03cbdfb946ad18f6fffaa8b2fb4c3665e22550fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78601ac4c56b4b4c1c2761115bdcf51aa8f5755f611ce34023fc93eafd49a061290a202ec00de9bcb3ac296723e48124a13bf9a1e029963d08941d0b3a0f1ca6"} build_url:https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/Iuw8M-TcSr2e2pzK5TM-3A/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8fb35d9157455115a8bca28f8950f546088240213306ce9d9f5d2b8793568687eefcd5bf272d63349378085496ad937314cd014e30968c2b1a81a876ca45b6d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9d637a3bb69382c3aed35d03bef2bc97b4ce7a8f4172b83ff2379dbfccef43aeb89485438e05e1ba30c3c4a03cbdfb946ad18f6fffaa8b2fb4c3665e22550fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78601ac4c56b4b4c1c2761115bdcf51aa8f5755f611ce34023fc93eafd49a061290a202ec00de9bcb3ac296723e48124a13bf9a1e029963d08941d0b3a0f1ca6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 16:26:26.821537) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:26:26.822151) ========= 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=17051fcd5208b27b50bedfeb000001dd-1469660739.656764-405797807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 16:26:26.904126) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 16:26:26.904566) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 16:26:26.904923) ========= ========= Total master_lag: 0.19 =========