builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1464325337.72 results: success (0) buildid: 20160526193103 builduid: 6e7662d3806c4cc0af0fee815afb24a9 revision: 4d63dde701b47b8661ab7990f197b6b60e543839 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.718228) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.719023) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.719451) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.738477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.738896) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-26 22:02:18-- 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% 20.8M=0.001s 2016-05-26 22:02:18 (20.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117900 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.872690) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.873118) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014385 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:17.922979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 22:02:17.923305) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4d63dde701b47b8661ab7990f197b6b60e543839 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4d63dde701b47b8661ab7990f197b6b60e543839 --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=82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-26 22:02:18,713 truncating revision to first 12 chars 2016-05-26 22:02:18,713 Setting DEBUG logging. 2016-05-26 22:02:18,713 attempt 1/10 2016-05-26 22:02:18,713 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4d63dde701b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 22:02:19,453 unpacking tar archive at: mozilla-central-4d63dde701b4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.081218 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 22:02:19.017864) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:19.018189) ========= script_repo_revision: 4d63dde701b47b8661ab7990f197b6b60e543839 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:19.018604) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:19.018873) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 22:02:19.027993) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-26 22:02:19.028286) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:02:19 INFO - MultiFileLogger online at 20160526 22:02:19 in /builds/slave/test 22:02:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:02:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:02:19 INFO - {'append_to_log': False, 22:02:19 INFO - 'base_work_dir': '/builds/slave/test', 22:02:19 INFO - 'blob_upload_branch': 'Firefox', 22:02:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:02:19 INFO - 'branch': 'Firefox', 22:02:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:02:19 INFO - 'config_files': ('talos/linux_config.py',), 22:02:19 INFO - 'default_actions': ('clobber', 22:02:19 INFO - 'read-buildbot-config', 22:02:19 INFO - 'download-and-extract', 22:02:19 INFO - 'populate-webroot', 22:02:19 INFO - 'create-virtualenv', 22:02:19 INFO - 'install', 22:02:19 INFO - 'run-tests'), 22:02:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:02:19 INFO - 'download_minidump_stackwalk': True, 22:02:19 INFO - 'download_symbols': 'ondemand', 22:02:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:02:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:02:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:02:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:02:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:02:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:02:19 INFO - 'installer_path': 'installer.exe', 22:02:19 INFO - 'log_level': 'info', 22:02:19 INFO - 'log_name': 'talos', 22:02:19 INFO - 'log_to_console': True, 22:02:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:02:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:02:19 INFO - 'opt_config_files': (), 22:02:19 INFO - 'pip_index': False, 22:02:19 INFO - 'sps_profile': False, 22:02:19 INFO - 'sps_profile_interval': 0, 22:02:19 INFO - 'suite': 'dromaeojs', 22:02:19 INFO - 'system_bits': '32', 22:02:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:02:19 INFO - 'title': 'talos-linux64-ix-066', 22:02:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:02:19 INFO - 'use_talos_json': True, 22:02:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:02:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:02:19 INFO - 'work_dir': 'build'} 22:02:19 INFO - ##### 22:02:19 INFO - ##### Running clobber step. 22:02:19 INFO - ##### 22:02:19 INFO - Running pre-action listener: _resource_record_pre_action 22:02:19 INFO - Running main action method: clobber 22:02:19 INFO - rmtree: /builds/slave/test/build 22:02:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:02:20 INFO - Running post-action listener: _resource_record_post_action 22:02:20 INFO - ##### 22:02:20 INFO - ##### Finished clobber step (success) 22:02:20 INFO - ##### 22:02:20 INFO - ##### 22:02:20 INFO - ##### Running read-buildbot-config step. 22:02:20 INFO - ##### 22:02:20 INFO - Running pre-action listener: _resource_record_pre_action 22:02:20 INFO - Running main action method: read_buildbot_config 22:02:20 INFO - Using buildbot properties: 22:02:20 INFO - { 22:02:20 INFO - "project": "", 22:02:20 INFO - "product": "firefox", 22:02:20 INFO - "script_repo_revision": "production", 22:02:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:02:20 INFO - "repository": "", 22:02:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:02:20 INFO - "buildid": "20160526193103", 22:02:20 INFO - "pgo_build": "True", 22:02:20 INFO - "basedir": "/builds/slave/test", 22:02:20 INFO - "buildnumber": 307, 22:02:20 INFO - "slavename": "talos-linux64-ix-066", 22:02:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:02:20 INFO - "platform": "ubuntu64_hw", 22:02:20 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:02:20 INFO - "branch": "mozilla-central", 22:02:20 INFO - "repo_path": "mozilla-central", 22:02:20 INFO - "revision": "4d63dde701b47b8661ab7990f197b6b60e543839", 22:02:20 INFO - "stage_platform": "linux64-pgo", 22:02:20 INFO - "builduid": "6e7662d3806c4cc0af0fee815afb24a9", 22:02:20 INFO - "slavebuilddir": "test" 22:02:20 INFO - } 22:02:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:02:20 INFO - Running post-action listener: _resource_record_post_action 22:02:20 INFO - ##### 22:02:20 INFO - ##### Finished read-buildbot-config step (success) 22:02:20 INFO - ##### 22:02:20 INFO - ##### 22:02:20 INFO - ##### Running download-and-extract step. 22:02:20 INFO - ##### 22:02:20 INFO - Running pre-action listener: _resource_record_pre_action 22:02:20 INFO - Running main action method: download_and_extract 22:02:20 INFO - mkdir: /builds/slave/test/build/tests 22:02:20 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')]} 22:02:20 INFO - https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:02:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:02:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:02:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:02:23 INFO - Downloaded 1361 bytes. 22:02:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:02:23 INFO - Using the following test package requirements: 22:02:23 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:02:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:02:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:02:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'jsshell-linux-x86_64.zip'], 22:02:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:02:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:02:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:02:23 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:02:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:02:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:02:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:02:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:02:23 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')]} 22:02:23 INFO - https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:02:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:02:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:02:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:02:24 INFO - Downloaded 24410682 bytes. 22:02:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:02:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:02:25 INFO - Return code: 0 22:02:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:25 INFO - https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:02:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:02:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:02:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:02:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:02:27 INFO - Downloaded 24410682 bytes. 22:02:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:02:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:02:29 INFO - Return code: 0 22:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:29 INFO - https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:02:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:02:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:02:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:02:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:02:30 INFO - Downloaded 10834034 bytes. 22:02:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:02:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:02:30 INFO - Return code: 0 22:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:30 INFO - https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:02:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:02:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:02:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:02:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:02:32 INFO - Downloaded 58689416 bytes. 22:02:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:02:32 INFO - mkdir: /builds/slave/test/properties 22:02:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:02:32 INFO - Writing to file /builds/slave/test/properties/build_url 22:02:32 INFO - Contents: 22:02:32 INFO - build_url:https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:02:33 INFO - Running post-action listener: _resource_record_post_action 22:02:33 INFO - Running post-action listener: set_extra_try_arguments 22:02:33 INFO - ##### 22:02:33 INFO - ##### Finished download-and-extract step (success) 22:02:33 INFO - ##### 22:02:33 INFO - ##### 22:02:33 INFO - ##### Running populate-webroot step. 22:02:33 INFO - ##### 22:02:33 INFO - Running pre-action listener: _resource_record_pre_action 22:02:33 INFO - Running main action method: populate_webroot 22:02:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:02:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:02:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:02:33 INFO - '--tppagecycles', 22:02:33 INFO - '7'], 22:02:33 INFO - 'tests': ['tsvgm']}}, 22:02:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:02:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:02:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:02:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:02:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:02:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:02:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['damp', 'tps']}, 22:02:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'tests': ['damp', 'tps']}, 22:02:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['dromaeo_dom']}, 22:02:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:02:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['a11yr', 22:02:33 INFO - 'ts_paint', 22:02:33 INFO - 'tpaint', 22:02:33 INFO - 'sessionrestore', 22:02:33 INFO - 'sessionrestore_no_auto_restore', 22:02:33 INFO - 'tabpaint']}, 22:02:33 INFO - 'other-e10s': {'tests': ['a11yr', 22:02:33 INFO - 'ts_paint', 22:02:33 INFO - 'tpaint', 22:02:33 INFO - 'sessionrestore', 22:02:33 INFO - 'sessionrestore_no_auto_restore', 22:02:33 INFO - 'tabpaint']}, 22:02:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:02:33 INFO - 'ts_paint', 22:02:33 INFO - 'tpaint', 22:02:33 INFO - 'sessionrestore', 22:02:33 INFO - 'sessionrestore_no_auto_restore', 22:02:33 INFO - 'tabpaint']}, 22:02:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:02:33 INFO - 'ts_paint', 22:02:33 INFO - 'tpaint', 22:02:33 INFO - 'sessionrestore', 22:02:33 INFO - 'sessionrestore_no_auto_restore', 22:02:33 INFO - 'tabpaint']}, 22:02:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['a11yr', 22:02:33 INFO - 'ts_paint', 22:02:33 INFO - 'tpaint', 22:02:33 INFO - 'sessionrestore', 22:02:33 INFO - 'sessionrestore_no_auto_restore', 22:02:33 INFO - 'tabpaint']}, 22:02:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['a11yr', 22:02:33 INFO - 'ts_paint', 22:02:33 INFO - 'tpaint', 22:02:33 INFO - 'sessionrestore', 22:02:33 INFO - 'sessionrestore_no_auto_restore', 22:02:33 INFO - 'tabpaint']}, 22:02:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['tsvgx', 22:02:33 INFO - 'tsvgr_opacity', 22:02:33 INFO - 'tart', 22:02:33 INFO - 'tscrollx', 22:02:33 INFO - 'cart']}, 22:02:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:02:33 INFO - 'tsvgr_opacity', 22:02:33 INFO - 'tart', 22:02:33 INFO - 'tscrollx', 22:02:33 INFO - 'cart']}, 22:02:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'talos_options': ['--disable-e10s'], 22:02:33 INFO - 'tests': ['tp5o']}, 22:02:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'tests': ['tp5o']}, 22:02:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'talos_options': ['--disable-e10s', 22:02:33 INFO - '--xperf_path', 22:02:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:02:33 INFO - 'tests': ['tp5n']}, 22:02:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:33 INFO - 'talos_options': ['--xperf_path', 22:02:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:02:33 INFO - 'tests': ['tp5n']}}, 22:02:33 INFO - 'talos.zip': {'path': '', 22:02:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:02:33 INFO - Running post-action listener: _resource_record_post_action 22:02:33 INFO - ##### 22:02:33 INFO - ##### Finished populate-webroot step (success) 22:02:33 INFO - ##### 22:02:33 INFO - ##### 22:02:33 INFO - ##### Running create-virtualenv step. 22:02:33 INFO - ##### 22:02:33 INFO - Running pre-action listener: _resource_record_pre_action 22:02:33 INFO - Running main action method: create_virtualenv 22:02:33 INFO - Creating virtualenv /builds/slave/test/build/venv 22:02:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:02:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:02:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:02:33 INFO - Using real prefix '/usr' 22:02:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:02:34 INFO - Installing distribute.............................................................................................................................................................................................done. 22:02:36 INFO - Installing pip.................done. 22:02:36 INFO - Return code: 0 22:02:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:02:36 INFO - Using env: {'DISPLAY': ':0', 22:02:36 INFO - 'HOME': '/home/cltbld', 22:02:36 INFO - 'LANG': 'en_US.UTF-8', 22:02:36 INFO - 'LANGUAGE': 'en_US:en', 22:02:36 INFO - 'LOGNAME': 'cltbld', 22:02:36 INFO - 'MAIL': '/var/mail/cltbld', 22:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:36 INFO - 'MOZ_NO_REMOTE': '1', 22:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:36 INFO - 'NO_EM_RESTART': '1', 22:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:36 INFO - 'PWD': '/builds/slave/test', 22:02:36 INFO - 'SHELL': '/bin/bash', 22:02:36 INFO - 'SHLVL': '1', 22:02:36 INFO - 'TERM': 'linux', 22:02:36 INFO - 'TMOUT': '86400', 22:02:36 INFO - 'USER': 'cltbld', 22:02:36 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:36 INFO - '_': '/tools/buildbot/bin/python'} 22:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:02:36 INFO - Cleaning up... 22:02:36 INFO - Return code: 0 22:02:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:02:36 INFO - Using env: {'DISPLAY': ':0', 22:02:36 INFO - 'HOME': '/home/cltbld', 22:02:36 INFO - 'LANG': 'en_US.UTF-8', 22:02:36 INFO - 'LANGUAGE': 'en_US:en', 22:02:36 INFO - 'LOGNAME': 'cltbld', 22:02:36 INFO - 'MAIL': '/var/mail/cltbld', 22:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:36 INFO - 'MOZ_NO_REMOTE': '1', 22:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:36 INFO - 'NO_EM_RESTART': '1', 22:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:36 INFO - 'PWD': '/builds/slave/test', 22:02:36 INFO - 'SHELL': '/bin/bash', 22:02:36 INFO - 'SHLVL': '1', 22:02:36 INFO - 'TERM': 'linux', 22:02:36 INFO - 'TMOUT': '86400', 22:02:36 INFO - 'USER': 'cltbld', 22:02:36 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:36 INFO - '_': '/tools/buildbot/bin/python'} 22:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:36 INFO - Downloading/unpacking psutil>=3.1.1 22:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:02:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:02:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:02:38 INFO - Installing collected packages: psutil 22:02:38 INFO - Running setup.py install for psutil 22:02:38 INFO - building 'psutil._psutil_linux' extension 22:02:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:02:39 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 22:02:39 INFO - building 'psutil._psutil_posix' extension 22:02:39 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 22:02:39 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 22:02:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:02:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:02:39 INFO - Successfully installed psutil 22:02:39 INFO - Cleaning up... 22:02:39 INFO - Return code: 0 22:02:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:02:39 INFO - Using env: {'DISPLAY': ':0', 22:02:39 INFO - 'HOME': '/home/cltbld', 22:02:39 INFO - 'LANG': 'en_US.UTF-8', 22:02:39 INFO - 'LANGUAGE': 'en_US:en', 22:02:39 INFO - 'LOGNAME': 'cltbld', 22:02:39 INFO - 'MAIL': '/var/mail/cltbld', 22:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:39 INFO - 'MOZ_NO_REMOTE': '1', 22:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:39 INFO - 'NO_EM_RESTART': '1', 22:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:39 INFO - 'PWD': '/builds/slave/test', 22:02:39 INFO - 'SHELL': '/bin/bash', 22:02:39 INFO - 'SHLVL': '1', 22:02:39 INFO - 'TERM': 'linux', 22:02:39 INFO - 'TMOUT': '86400', 22:02:39 INFO - 'USER': 'cltbld', 22:02:39 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:39 INFO - '_': '/tools/buildbot/bin/python'} 22:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:02:41 INFO - Installing collected packages: mozsystemmonitor 22:02:41 INFO - Running setup.py install for mozsystemmonitor 22:02:41 INFO - Successfully installed mozsystemmonitor 22:02:41 INFO - Cleaning up... 22:02:41 INFO - Return code: 0 22:02:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:02:41 INFO - Using env: {'DISPLAY': ':0', 22:02:41 INFO - 'HOME': '/home/cltbld', 22:02:41 INFO - 'LANG': 'en_US.UTF-8', 22:02:41 INFO - 'LANGUAGE': 'en_US:en', 22:02:41 INFO - 'LOGNAME': 'cltbld', 22:02:41 INFO - 'MAIL': '/var/mail/cltbld', 22:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:41 INFO - 'MOZ_NO_REMOTE': '1', 22:02:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:41 INFO - 'NO_EM_RESTART': '1', 22:02:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:41 INFO - 'PWD': '/builds/slave/test', 22:02:41 INFO - 'SHELL': '/bin/bash', 22:02:41 INFO - 'SHLVL': '1', 22:02:41 INFO - 'TERM': 'linux', 22:02:41 INFO - 'TMOUT': '86400', 22:02:41 INFO - 'USER': 'cltbld', 22:02:41 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:41 INFO - '_': '/tools/buildbot/bin/python'} 22:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:41 INFO - Downloading/unpacking blobuploader==1.2.4 22:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:43 INFO - Downloading blobuploader-1.2.4.tar.gz 22:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:02:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:02:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:44 INFO - Downloading docopt-0.6.1.tar.gz 22:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:02:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:02:44 INFO - Running setup.py install for blobuploader 22:02:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:02:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:02:44 INFO - Running setup.py install for requests 22:02:44 INFO - Running setup.py install for docopt 22:02:45 INFO - Successfully installed blobuploader requests docopt 22:02:45 INFO - Cleaning up... 22:02:45 INFO - Return code: 0 22:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:02: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')]} 22:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02: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')]} 22:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:45 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 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:45 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 22:02:45 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 22:02:45 INFO - Using env: {'DISPLAY': ':0', 22:02:45 INFO - 'HOME': '/home/cltbld', 22:02:45 INFO - 'LANG': 'en_US.UTF-8', 22:02:45 INFO - 'LANGUAGE': 'en_US:en', 22:02:45 INFO - 'LOGNAME': 'cltbld', 22:02:45 INFO - 'MAIL': '/var/mail/cltbld', 22:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:45 INFO - 'MOZ_NO_REMOTE': '1', 22:02:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:45 INFO - 'NO_EM_RESTART': '1', 22:02:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:45 INFO - 'PWD': '/builds/slave/test', 22:02:45 INFO - 'SHELL': '/bin/bash', 22:02:45 INFO - 'SHLVL': '1', 22:02:45 INFO - 'TERM': 'linux', 22:02:45 INFO - 'TMOUT': '86400', 22:02:45 INFO - 'USER': 'cltbld', 22:02:45 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:45 INFO - '_': '/tools/buildbot/bin/python'} 22:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:02:45 INFO - Running setup.py (path:/tmp/pip-Cjqw2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:02:45 INFO - Running setup.py (path:/tmp/pip-goFcLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:02:45 INFO - Running setup.py (path:/tmp/pip-doRt6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:02:45 INFO - Running setup.py (path:/tmp/pip-iUjmNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:02:45 INFO - Running setup.py (path:/tmp/pip-W3R9XI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:02:45 INFO - Running setup.py (path:/tmp/pip-l7W3YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:02:45 INFO - Running setup.py (path:/tmp/pip-ONTZul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:02:45 INFO - Running setup.py (path:/tmp/pip-JI0H1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:02:45 INFO - Running setup.py (path:/tmp/pip-roIs5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:02:45 INFO - Running setup.py (path:/tmp/pip-KFGSEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:02:46 INFO - Running setup.py (path:/tmp/pip-SwXBcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:02:46 INFO - Running setup.py (path:/tmp/pip-Mo4qT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:02:46 INFO - Running setup.py (path:/tmp/pip-7YxQP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:02:46 INFO - Running setup.py (path:/tmp/pip-xOBMCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:46 INFO - Running setup.py (path:/tmp/pip-pc3aUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:02:46 INFO - Running setup.py (path:/tmp/pip-cla3Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:02:46 INFO - Running setup.py (path:/tmp/pip-5qkj9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:02:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:02:46 INFO - Running setup.py install for manifestparser 22:02:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:02:46 INFO - Running setup.py install for mozcrash 22:02:46 INFO - Running setup.py install for mozdebug 22:02:46 INFO - Running setup.py install for mozdevice 22:02:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:02:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:02:46 INFO - Running setup.py install for mozfile 22:02:47 INFO - Running setup.py install for mozhttpd 22:02:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for mozinfo 22:02:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for mozInstall 22:02:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for mozleak 22:02:47 INFO - Running setup.py install for mozlog 22:02:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for moznetwork 22:02:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for mozprocess 22:02:47 INFO - Running setup.py install for mozprofile 22:02:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for mozrunner 22:02:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:02:47 INFO - Running setup.py install for mozscreenshot 22:02:48 INFO - Running setup.py install for moztest 22:02:48 INFO - Running setup.py install for mozversion 22:02:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:02:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:02:48 INFO - Cleaning up... 22:02:48 INFO - Return code: 0 22:02:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:02: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')]} 22:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02: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')]} 22:02:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:48 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 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:48 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 22:02:48 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 22:02:48 INFO - Using env: {'DISPLAY': ':0', 22:02:48 INFO - 'HOME': '/home/cltbld', 22:02:48 INFO - 'LANG': 'en_US.UTF-8', 22:02:48 INFO - 'LANGUAGE': 'en_US:en', 22:02:48 INFO - 'LOGNAME': 'cltbld', 22:02:48 INFO - 'MAIL': '/var/mail/cltbld', 22:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:48 INFO - 'MOZ_NO_REMOTE': '1', 22:02:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:48 INFO - 'NO_EM_RESTART': '1', 22:02:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:48 INFO - 'PWD': '/builds/slave/test', 22:02:48 INFO - 'SHELL': '/bin/bash', 22:02:48 INFO - 'SHLVL': '1', 22:02:48 INFO - 'TERM': 'linux', 22:02:48 INFO - 'TMOUT': '86400', 22:02:48 INFO - 'USER': 'cltbld', 22:02:48 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:48 INFO - '_': '/tools/buildbot/bin/python'} 22:02:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:02:48 INFO - Running setup.py (path:/tmp/pip-tmnt3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:02:48 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)) 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:02:48 INFO - Running setup.py (path:/tmp/pip-EAtlUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:02:48 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)) 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:02:48 INFO - Running setup.py (path:/tmp/pip-xWMKgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:02:48 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)) 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:02:48 INFO - Running setup.py (path:/tmp/pip-yiOwli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:02:48 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)) 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:02:48 INFO - Running setup.py (path:/tmp/pip-wlI4EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:02:48 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)) 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:02:48 INFO - Running setup.py (path:/tmp/pip-PLcv9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:02:48 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)) 22:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:02:48 INFO - Running setup.py (path:/tmp/pip-vaKBsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:02:49 INFO - Running setup.py (path:/tmp/pip-KAv5JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:02:49 INFO - Running setup.py (path:/tmp/pip-r3WGEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:02:49 INFO - Running setup.py (path:/tmp/pip-zhAXG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:02:49 INFO - Running setup.py (path:/tmp/pip-PsVKCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:02:49 INFO - Running setup.py (path:/tmp/pip-Bam_hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:02:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:02:49 INFO - Running setup.py (path:/tmp/pip-_5lhz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:02:49 INFO - Running setup.py (path:/tmp/pip-sDgWnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:02:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:49 INFO - Running setup.py (path:/tmp/pip-OFm9oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:02:49 INFO - Running setup.py (path:/tmp/pip-7VmJ2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:02:49 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)) 22:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:02:49 INFO - Running setup.py (path:/tmp/pip-DPZI8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:02:49 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)) 22:02:49 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)) 22:02:49 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)) 22:02:49 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)) 22:02:49 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)) 22:02:49 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)) 22:02:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:02:49 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 22:02:49 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 22:02:49 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 22:02:49 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 22:02:49 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 22:02:49 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 22:02:51 INFO - Downloading blessings-1.6.tar.gz 22:02:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:02:51 INFO - Installing collected packages: blessings 22:02:51 INFO - Running setup.py install for blessings 22:02:51 INFO - Successfully installed blessings 22:02:51 INFO - Cleaning up... 22:02:51 INFO - Return code: 0 22:02:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:02:52 INFO - Reading from file tmpfile_stdout 22:02:52 INFO - Current package versions: 22:02:52 INFO - argparse == 1.2.1 22:02:52 INFO - blessings == 1.6 22:02:52 INFO - blobuploader == 1.2.4 22:02:52 INFO - docopt == 0.6.1 22:02:52 INFO - manifestparser == 1.1 22:02:52 INFO - mozInstall == 1.12 22:02:52 INFO - mozcrash == 0.17 22:02:52 INFO - mozdebug == 0.1 22:02:52 INFO - mozdevice == 0.48 22:02:52 INFO - mozfile == 1.2 22:02:52 INFO - mozhttpd == 0.7 22:02:52 INFO - mozinfo == 0.9 22:02:52 INFO - mozleak == 0.1 22:02:52 INFO - mozlog == 3.2 22:02:52 INFO - moznetwork == 0.27 22:02:52 INFO - mozprocess == 0.22 22:02:52 INFO - mozprofile == 0.28 22:02:52 INFO - mozrunner == 6.11 22:02:52 INFO - mozscreenshot == 0.1 22:02:52 INFO - mozsystemmonitor == 0.3 22:02:52 INFO - moztest == 0.7 22:02:52 INFO - mozversion == 1.4 22:02:52 INFO - psutil == 3.1.1 22:02:52 INFO - requests == 1.2.3 22:02:52 INFO - wsgiref == 0.1.2 22:02:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:02:52 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')]} 22:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:52 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')]} 22:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:52 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 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:52 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 22:02:52 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 22:02:52 INFO - Using env: {'DISPLAY': ':0', 22:02:52 INFO - 'HOME': '/home/cltbld', 22:02:52 INFO - 'LANG': 'en_US.UTF-8', 22:02:52 INFO - 'LANGUAGE': 'en_US:en', 22:02:52 INFO - 'LOGNAME': 'cltbld', 22:02:52 INFO - 'MAIL': '/var/mail/cltbld', 22:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:52 INFO - 'MOZ_NO_REMOTE': '1', 22:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:52 INFO - 'NO_EM_RESTART': '1', 22:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:52 INFO - 'PWD': '/builds/slave/test', 22:02:52 INFO - 'SHELL': '/bin/bash', 22:02:52 INFO - 'SHLVL': '1', 22:02:52 INFO - 'TERM': 'linux', 22:02:52 INFO - 'TMOUT': '86400', 22:02:52 INFO - 'USER': 'cltbld', 22:02:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:52 INFO - '_': '/tools/buildbot/bin/python'} 22:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 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)) 22:02:52 INFO - Cleaning up... 22:02:52 INFO - Return code: 0 22:02:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:02:52 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')]} 22:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:52 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')]} 22:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:52 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 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:52 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 22:02:52 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 22:02:52 INFO - Using env: {'DISPLAY': ':0', 22:02:52 INFO - 'HOME': '/home/cltbld', 22:02:52 INFO - 'LANG': 'en_US.UTF-8', 22:02:52 INFO - 'LANGUAGE': 'en_US:en', 22:02:52 INFO - 'LOGNAME': 'cltbld', 22:02:52 INFO - 'MAIL': '/var/mail/cltbld', 22:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:52 INFO - 'MOZ_NO_REMOTE': '1', 22:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:52 INFO - 'NO_EM_RESTART': '1', 22:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:52 INFO - 'PWD': '/builds/slave/test', 22:02:52 INFO - 'SHELL': '/bin/bash', 22:02:52 INFO - 'SHLVL': '1', 22:02:52 INFO - 'TERM': 'linux', 22:02:52 INFO - 'TMOUT': '86400', 22:02:52 INFO - 'USER': 'cltbld', 22:02:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:52 INFO - '_': '/tools/buildbot/bin/python'} 22:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:52 INFO - Downloading/unpacking jsonschema 22:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:02:54 INFO - Downloading/unpacking functools32 (from jsonschema) 22:02:54 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 22:02:54 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 22:02:54 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 22:02:54 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 22:02:54 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 22:02:54 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 22:02:54 INFO - Downloading functools32-3.2.3-2.tar.gz 22:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:02:54 INFO - warning: no files found matching '*.txt' 22:02:54 INFO - no previously-included directories found matching 'build' 22:02:54 INFO - no previously-included directories found matching 'dist' 22:02:54 INFO - no previously-included directories found matching '.git*' 22:02:54 INFO - Installing collected packages: jsonschema, functools32 22:02:54 INFO - Running setup.py install for functools32 22:02:54 INFO - warning: no files found matching '*.txt' 22:02:54 INFO - no previously-included directories found matching 'build' 22:02:54 INFO - no previously-included directories found matching 'dist' 22:02:54 INFO - no previously-included directories found matching '.git*' 22:02:54 INFO - Successfully installed jsonschema functools32 22:02:54 INFO - Cleaning up... 22:02:54 INFO - Return code: 0 22:02:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3848eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2832770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', '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 22:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:02:54 INFO - Using env: {'DISPLAY': ':0', 22:02:54 INFO - 'HOME': '/home/cltbld', 22:02:54 INFO - 'LANG': 'en_US.UTF-8', 22:02:54 INFO - 'LANGUAGE': 'en_US:en', 22:02:54 INFO - 'LOGNAME': 'cltbld', 22:02:54 INFO - 'MAIL': '/var/mail/cltbld', 22:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:54 INFO - 'MOZ_NO_REMOTE': '1', 22:02:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:54 INFO - 'NO_EM_RESTART': '1', 22:02:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:54 INFO - 'PWD': '/builds/slave/test', 22:02:54 INFO - 'SHELL': '/bin/bash', 22:02:54 INFO - 'SHLVL': '1', 22:02:54 INFO - 'TERM': 'linux', 22:02:54 INFO - 'TMOUT': '86400', 22:02:54 INFO - 'USER': 'cltbld', 22:02:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:54 INFO - '_': '/tools/buildbot/bin/python'} 22:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:55 INFO - Downloading/unpacking flake8 22:02:55 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 22:02:55 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 22:02:55 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 22:02:55 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 22:02:55 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 22:02:55 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 22:02:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:02:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:02:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 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 22:02:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:02:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:02:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:02:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:02:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:02:57 INFO - no previously-included directories found matching 'docs/_build' 22:02:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:02:57 INFO - Running setup.py install for pep8 22:02:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:02:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:02:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:02:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:02:57 INFO - no previously-included directories found matching 'docs/_build' 22:02:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:02:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:02:57 INFO - Cleaning up... 22:02:58 INFO - Return code: 0 22:02:58 INFO - Running post-action listener: _resource_record_post_action 22:02:58 INFO - Running post-action listener: _start_resource_monitoring 22:02:58 INFO - Starting resource monitoring. 22:02:58 INFO - ##### 22:02:58 INFO - ##### Finished create-virtualenv step (success) 22:02:58 INFO - ##### 22:02:58 INFO - ##### 22:02:58 INFO - ##### Running install step. 22:02:58 INFO - ##### 22:02:58 INFO - Running pre-action listener: _resource_record_pre_action 22:02:58 INFO - Running main action method: install 22:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:02:58 INFO - Reading from file tmpfile_stdout 22:02:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:02:58 INFO - Reading from file tmpfile_stdout 22:02:58 INFO - Output received: 22:02:58 INFO - Usage: mozinstall [options] installer 22:02:58 INFO - Options: 22:02:58 INFO - -h, --help show this help message and exit 22:02:58 INFO - -d DEST, --destination=DEST 22:02:58 INFO - Directory to install application into. [default: 22:02:58 INFO - "/builds/slave/test"] 22:02:58 INFO - --app=APP Application being installed. [default: firefox] 22:02:58 INFO - mkdir: /builds/slave/test/build/application 22:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:03:14 INFO - Reading from file tmpfile_stdout 22:03:14 INFO - Output received: 22:03:14 INFO - /builds/slave/test/build/application/firefox/firefox 22:03:14 INFO - Running post-action listener: _resource_record_post_action 22:03:14 INFO - ##### 22:03:14 INFO - ##### Finished install step (success) 22:03:14 INFO - ##### 22:03:14 INFO - ##### 22:03:14 INFO - ##### Running run-tests step. 22:03:14 INFO - ##### 22:03:14 INFO - Running pre-action listener: _resource_record_pre_action 22:03:14 INFO - Running main action method: run_tests 22:03:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:03:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:03:14 INFO - Python 2.7.3 22:03:14 INFO - Return code: 0 22:03:14 INFO - grabbing minidump binary from tooltool 22:03:14 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')]} 22:03:14 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 0x28c19c0>, '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 0x28c28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:03:14 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 22:03:14 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 22:03:14 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 22:03:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:03:15 INFO - Return code: 0 22:03:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:03:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:03:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:03:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:03:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:03:15 INFO - Return code: 0 22:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 22:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 22:03:15 INFO - Using env: {'DISPLAY': ':0', 22:03:15 INFO - 'HOME': '/home/cltbld', 22:03:15 INFO - 'LANG': 'en_US.UTF-8', 22:03:15 INFO - 'LANGUAGE': 'en_US:en', 22:03:15 INFO - 'LOGNAME': 'cltbld', 22:03:15 INFO - 'MAIL': '/var/mail/cltbld', 22:03:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:03:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:15 INFO - 'MOZ_NO_REMOTE': '1', 22:03:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:03:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:15 INFO - 'NO_EM_RESTART': '1', 22:03:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:15 INFO - 'PWD': '/builds/slave/test', 22:03:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:03:15 INFO - 'SHELL': '/bin/bash', 22:03:15 INFO - 'SHLVL': '1', 22:03:15 INFO - 'TERM': 'linux', 22:03:15 INFO - 'TMOUT': '86400', 22:03:15 INFO - 'USER': 'cltbld', 22:03:15 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016', 22:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:15 INFO - '_': '/tools/buildbot/bin/python'} 22:03:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 22:03:16 INFO - mozversion application_buildid: 20160526193103 22:03:16 INFO - mozversion application_changeset: 4d63dde701b47b8661ab7990f197b6b60e543839 22:03:16 INFO - mozversion application_display_name: Nightly 22:03:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:03:16 INFO - mozversion application_name: Firefox 22:03:16 INFO - mozversion application_remotingname: firefox 22:03:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:03:16 INFO - mozversion application_vendor: Mozilla 22:03:16 INFO - mozversion application_version: 49.0a1 22:03:16 INFO - mozversion platform_buildid: 20160526193103 22:03:16 INFO - mozversion platform_changeset: 4d63dde701b47b8661ab7990f197b6b60e543839 22:03:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:03:16 INFO - mozversion platform_version: 49.0a1 22:03:16 INFO - using testdate: 1464325396 22:03:16 INFO - actual date: 1464325396 22:03:16 INFO - starting webserver on 'localhost:38014' 22:03:16 INFO - SUITE-START | Running 2 tests 22:03:16 INFO - TEST-START | dromaeo_css 22:03:16 INFO - operating with platform_type : linux_ 22:03:16 INFO - Initialising browser for dromaeo_css test... 22:03:16 INFO - TEST-INFO | started process 11956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCcwkd4/profile http://localhost:38014/getInfo.html) 22:03:20 INFO - PROCESS | 11956 | __metrics Screen width/height:1600/1200 22:03:20 INFO - PROCESS | 11956 | colorDepth:24 22:03:20 INFO - PROCESS | 11956 | Browser inner width/height: 1024/697 22:03:20 INFO - PROCESS | 11956 | __metrics 22:03:21 ERROR - PROCESS | 11956 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:03:22 INFO - TEST-INFO | 11956: exit 0 22:03:22 INFO - Browser initialized. 22:03:22 INFO - Running cycle 1/1 for dromaeo_css test... 22:03:22 INFO - TEST-INFO | started process 12037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCcwkd4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:03:34 INFO - PROCESS | 12037 | RSS: Main: 155185152 22:03:34 INFO - PROCESS | 12037 | 22:04:25 INFO - PROCESS | 12037 | 1464325465370 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] 22:06:33 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:38014/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38014/tests/dromaeo/cssquery-ext.html) 22:06:34 INFO - PROCESS | 12037 | RSS: Main: 259530752 22:06:34 INFO - PROCESS | 12037 | 22:09:36 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:38014/tests/dromaeo/cssquery-ext.html (next: http://localhost:38014/tests/dromaeo/cssquery-jquery.html) 22:09:37 INFO - PROCESS | 12037 | RSS: Main: 232824832 22:09:37 INFO - PROCESS | 12037 | 22:12:39 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:38014/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38014/tests/dromaeo/cssquery-mootools.html) 22:12:40 INFO - PROCESS | 12037 | RSS: Main: 247717888 22:12:40 INFO - PROCESS | 12037 | 22:15:42 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:38014/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38014/tests/dromaeo/cssquery-prototype.html) 22:15:42 INFO - PROCESS | 12037 | RSS: Main: 236736512 22:15:42 INFO - PROCESS | 12037 | 22:18:45 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:38014/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38014/tests/dromaeo/cssquery-yui.html) 22:18:45 INFO - PROCESS | 12037 | RSS: Main: 204992512 22:18:45 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:38014/tests/dromaeo/cssquery-yui.html (next: http://localhost:38014/tests/dromaeo/cssquery-dojo.html) 22:21:48 INFO - PROCESS | 12037 | RSS: Main: 272240640 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | __start_tp_report 22:21:48 INFO - PROCESS | 12037 | _x_x_mozilla_page_load 22:21:48 INFO - PROCESS | 12037 | _x_x_mozilla_page_load_details 22:21:48 INFO - PROCESS | 12037 | |i|pagename|runs| 22:21:48 INFO - PROCESS | 12037 | |0;dojo.html;3107.8601462934166;3109.113902537777;3126.4528707476193;3136.7961082529646;3172.143521249263;5325.094733895237;5376.408595054546;5406.54044406226;5475.151351540512;5480.945190548094;5471.151971444422;5499.972500137504;5501.14732216506;5524.364698059724;5567.3040869891265;4908.190148625481;4968.701877887338;5002.049610573979;5012.624053196007;5137.743112844361;5038.697678139323;5053.5451809337155;5107.412647545524;5108.974455127719;5119.308893299412;4839.758012099399;4916.557509824133;4917.901641967141;4963.4292056413615;5001.374828146481;4901.289313049588;4914.877128071791;4996.850094497135;4999.8000079996755;5042.722650254234;4828.830990915301;4945.8268960586565;4949.82675606352;4968.056069346812;5015.122353588144;5384.838454846361;5433.864153396157;5449.427810079964;5525.392206857243;5531.280933478622;5839.386864379221;5900.026495628216;5928.584999050068;5931.169636250928;5943.3165186003735;5702.629329093637;5819.505342045931;5852;5860.648361098348;5960.910586341209;5510.035743744828;5530.806421775219;5537.58468114893;5558.333000040021;5560.805371812007;6042.83857654493;6054.03577520406;6087.452129308295;6102.664353460514;6104.359042300494;5564.4713752193475;5622.943770562242;5654.6324488908085;5679.091345384719;5854.443827836349;5801;5811.622244554091;5859.824205273849;5862.296524417012;5862.443067908542;5764.682942438206;5771.711414429262;5773.855653608609;5776.595638305278;5776.913346299809;5612.719364031782;5733.598648094677;5781.45076217767;5798.391168927202;5838.095095260242;4419.756913369732;4421;4424.247877860771;4428.535003824616;4462.152191083684;3910.061585219527;3929.5677475477673;3948.624880636335;3969.325214713448;3999.3800960851113;5430.864228394243;5450.645708029045;5486.835394938158;5541.445855414507;5579.972100139474;5868.3251426086545;5878.500327472213;5880.558958078162;5890.116482527569;5932;5528.391876893538;5534.280543529316;5536.529395001389;5543.722813859291;5546.639468434539;4925.926111108336;4987.12725273083;5044.646874718735;5049.040682270357;5071.137906555894;6080.6047606906295;6084.6349219047;6086.604370715978;6087.269527656709;6099.329073801879;5735.512481439125;5892.499137573269;5897.911531326948;5897.970510147422;5898.292204935436;6063.72713227897;6097.040762675721;6098.553255670888;6207.224096987877;6211.472024877936;5832.562557808266;5875.148103525051;5877.735501902339;5885.17607534954;5934.7922822701;5643;5823.126531020381;5935.940640593539;5942.197803296673;5954.047352423591;5730.541556675539;5786.479216870502;5790.884182316414;5797;5845.970770146122;5758;5771.8557036074435;5773.653580785166;5773.942260577341;5776.595638305446;5414.945850541601;5584.525315348241;5662.462066103713;5672.829815105553;5678.574106941914;5845.064789633639;5875.882482350415;5879.735411906389;5889.440503152194;5894.381089985662;5695.829125126087;5701.486866182064;5702.429757024264;5705.429457054262;5740.540756739368 22:21:48 INFO - PROCESS | 12037 | |1;ext.html;587.5652017507043;637.9808605741829;647.6340867409913;651.4104735214632;661.9536632435728;35186.47220291694;35930.64069359306;42118.99999999996;43837;43885.7805710971;45747.77126114373;46507;46572;46581;46611.30083048757;8621.39650224483;8796.73609791704;8798.780030499225;8802.427842190269;8805.867911981326;8637.179467950535;8759.912400876005;8769.605367758435;8784.736457906241;8793.824123517526;8743.868841967344;8765.474071555695;8811.867821982676;8817.559122043904;8825.779355516099;8745.256653184488;8806.955965220197;8807.383483156182;8810.955945220265;8811.955940220258;8691.521966291852;8778.824423511558;8782.429142105742;8796.296296296281;8800.031996480382;42503;42913.57086429127;43016;43021.35467967983;43066;3531.276088401897;3559.75081744278;3586.3544561978833;3605.4952306677087;3892.104815892332;33247;33285.50071748926;33772.49341259883;34573.827130864185;35484.46773298403;4169.958300416987;4232.449781528412;4356.302991521341;4364.192624364503;4485;361442;364497;365744;367563;369220;35604.821975890474;65031.67484162549;65982.34017659858;65990.67004664947;66034;36703.632963670025;38528.183977002875;38539.22921541554;38566.614333856305;38579;15710.842891570937;15811.130387828549;15817.446389376317;15832.76250856238;15833.920830395775;13229.206247625174;13276.137051091648;13331.266780326981;13337.599872003855;13345.466181352831;8476.576171191417;8514.361422893184;8582.184692454332;8612.138786121337;8640.870386944202;3280.2291461506525;3305.0910999475336;3309.4373956427467;3335.7331413486863;3401.880934167342;1020.4744556553233;1030.160419258302;1046.6284469013542;1065.7815147894662;1074.2265568790458;32884.342313153604;33010.83494582512;33101.17247068843;33133.502997455056;33400.66599334024;1123.1407972900738;1123.3372310336777;1165.7551914098135;1184.3722826901756;1200.5077918053555;16989.320427182774;17400.129993500275;17485.863418877732;17505.82494175042;17587;561590;566334;568565;569508;569835;57981;58432.70783646055;58535;58549.70725146347;58572.707136464895;1242.186367929008;1354.2483921423704;1385.625881012121;1416.390951890697;1416.7603347071313;32346.676533234368;34093.488597670555;34096.71751457476;34154;34465.65534344625;50124.248136278715;50392;50610;50700.74649626728;50797;416672;418258;420128;420600;421208;25003.249902502954;27697.723022770322;28582.14253572313;28706.282342940758;28778.136655900358;48606.27090593573;48850.51149488602;49009.2648610264;50093.49906501034;50151;39696.801515992236;40961.1807763849;41005.589944100175;41159.58840411557;41512.58487415208;39090.80454597709;40298.59701402948;40489;40601;40724.796376017934;40793.59206408016;41427;42115.57884421239;42135.578644213165;42315.3652695216;33281;35841.103972400895;36055.27889442144;36407.635923640424;36438.453423198145;28142.577861332506;28214.294642634097;28485.857570712014;28496.287592810346;28528.42943141084 22:21:48 INFO - PROCESS | 12037 | |2;jquery.html;381.53833861028136;391.80997216350045;399.49064942198686;403.8566308960319;410.9910170531346;18674.719879201806;19592.11835467404;19935.800641993577;20057.799422005795;20061.398158055246;17835;18035.90982045088;18135.274589016455;18689.81310186898;18894.433167004976;5501.14732216505;5584.357798853143;5649.435056494359;5674.120511320732;5683.4600712932415;5576.9163462547895;5725.770969161229;5727.198192253088;5728.398518155596;5769.682667453289;5610.354809196954;5625.353084395306;5704.971475142639;5710.7715691372505;5716.799912003081;5694.914576281359;5698.458646428603;5700.543956483492;5705.286839145107;5714.599978001542;4966.776495057731;4986.800527978877;5042.1176294148745;5045.4702256262935;5051.494850514956;22079.558408831734;22295.44261393478;22457.10171593134;22561.097556097735;22748.77251227483;2160.12514931453;2504.9980007996764;2759.268793769653;2782.5408807546733;2848.84331361775;10025.749356266078;10181.949090254575;10277.677965425028;14039.157650540898;14080.92959535206;1889.3765057530982;1938.5832046110013;1940.8835469871854;1949.39568733693;1969.2319995201883;80435.19564804336;84919.57540212259;85325.57337213274;86336.56831715802;87831.56084219602;10103.19174466041;10363.481825908675;10980.945095274472;11038.834417483744;11168.553257869757;15257.542273731806;15314.387424503118;15358.692826143415;15364.923175384052;15390.384384624489;7598.848023039619;7761.4178936578855;7815.25755053269;7872.173421790744;7876.881846772303;7877.842443151105;7929.167437419103;7971.880421793678;8043.592371334993;8184.427090103635;4489.304157855538;4602.631789456902;4733.834315798999;4763.976180119077;5038.118329292359;1904.5714714189196;1940.1754254442087;1943.0084163732288;1948.2304489726478;1985.8212760851593;910.8132832769267;984.1683777208248;1005.1958433253368;1050.1546255264586;1108.8336749487642;18749.75074775673;19117.61764764697;19185.61628767417;19363.51591210203;19674.60650786976;468.995310046902;481.3597914773413;491.7713263332496;496.7044608457962;502.47742347958444;6199.101130336076;6332.145160403286;6435.74257029723;6437.774677886251;6631.602103873783;195185;197039;255428;513875;518076;67292;83883;86593;99692;99984;478.08446824331713;479.41990191867535;485.6940127719514;486.7104073076514;488.060483569128;10931.672049838518;11355.37545435009;11461.426928653367;11483.770324593628;11501.252418592765;42140.78929605332;42475.15049698927;42684;42781.78609106935;44531.55468445274;62018.37981620126;62387.37612623816;62423.375766243575;63194.684026579576;64633.67683161742;8598.140185981352;8604.225619694258;8604.311655067704;8605.096464871098;8647.610857511301;9443.716688499357;12703.682407939876;13059.934700326437;13100.737985240432;13176.47294108225;11414.600488982845;11471.94264028708;11722.882771172179;11923.403829808301;11950.760984780081;10674.306170098918;10812.891871081503;11053.171012173958;11337.489812958602;11827.290362578273;11367.772644546898;11436.485358158736;11588.88411115878;11688.47401866901;11896.048316134862;8365.498070115813;8520.829583408173;8552.230299273095;8595.570221488775;8617.310615150898;11141.72145696364;11170.888291116984;11199.384033877888;11296.209265351346;11310.434478276218 22:21:48 INFO - PROCESS | 12037 | |3;mootools.html;1347.7574036673407;1516.643588756642;1544.5752418085026;1703.471923703652;1849.7225416187562;8524.232819046285;8869.29045676346;8883.244924181452;9100.180983711482;9110.453372797636;8837.513936733492;9036.50299233542;9040;9042.412243204188;9043.95478022609;2080.8023237792386;2374.323317854412;2419.830611857175;2450.546648869956;2459.434330104068;2456.7297597264287;2480.1567467061195;2481.255623313008;2488.1042824583137;2491;2396.712394512661;2419.3346829621905;2423.9515209695796;2426.4297889995837;2427.453822889853;2091.435312465633;2324.360800779782;2387.4747555537756;2406.338256979327;2408.7350391456926;2348.4246359641998;2356.752540983189;2358.5400846834873;2386.7613238676167;2423.236680445654;8990.100989901022;9114.908850911472;9118;9120.954395228047;9129.634814607407;5384.542313903323;5514.558835293167;5516.172574113875;5524.392316845144;5525.502704756591;3162.845561370106;3262.6411094779555;3305.93388132236;3334.249793796401;3372.443546814797;1053.0838170791385;1090.0462095666292;1097.253867370188;1103.2994048779083;1108.3903852880883;12528.74942501154;12558.937205313914;12561.183523070971;12561.371931403484;12570.434330455151;4790.7844147012775;4810.6632535722165;4919.631027672939;4932.506749325039;5005.774740136702;10265.897341026643;10316.690499285034;10331.690049298533;10380.636677716244;10396.48017599132;4769.856904292877;5197.038547868657;5306.336707911511;5310.628256022119;5313.282706834605;4858.271259311132;4965.975170124127;4993.151164302078;5023.120953833065;5030.3963524377305;4046.352583586612;4130.710850240454;4142.420061191436;4180.895477613084;4342.34864770287;1325.2379881567945;1327.5287273018132;1327.807467917166;1335.1588499245606;1342.4831439895515;1004.8141093897652;1013.5135135135176;1020.1838529176778;1026.58423338548;1029.2075102171286;6036.275646922398;6064.332923378425;6067.696615169224;6098.573099882966;6135.171751813537;458.76602932504665;466.1376453560953;469.1976719809165;471.83221526721775;476.09304275355504;4267.4238977737605;4307.160103779733;4308.3968244445805;4311.504177019683;4324.394584758136;10999.790023097454;11333.263337883176;11351.94324028391;11385.259958102697;11392.601258955896;10839.458027098613;11170;11279.097672186206;11305.71735706614;11308.660740177807;8212.096669366365;8401.663933442594;8565.271951884035;8569.657213711507;8576.099509551408;5895.9115613266695;6239.688015599293;6265.968670156803;6291.056341548804;6315.747370105144;10119.139873110867;10537.209709271989;10566.366018038942;10587.258891877802;10590.258681892194;10370.170386369224;10562.419066951392;10580.788384232426;10696.03735663763;10806.297590656583;7043;7236.674349654173;7249.56502609824;7974.720884769005;8102.959485202537;7867.291943725092;8014.158513356247;8024.759257222293;8037.919620803717;8107.918920810952;7140;7222.241664625139;7227.385672217921;7353.374963128194;7379.741709040158;7179.461540384387;7202.747903823341;7204.063471748639;7209.35115839577;7220.8916866246;6595.208574971034;6727.798166055026;6730;6733.764318249034;6736.595804251565;5062.746862656779;5067.41724701664;5084.211947148198;5170.27616133734;5225.817096401608;5128.923066153936;5168.53483186515;5190.97404512975;5246.632735708464;5278.604104692086 22:21:48 INFO - PROCESS | 12037 | |4;prototype.html;306.2787136294029;323.3855674218984;325.9217787730945;333.6913355146489;339.52975129445724;5253.816116435925;5370.489803468664;5455.809046683362;5492.434656123006;5492.642978206419;5331.520163185312;5383.246345511631;5439.347278326606;5455;5501.587380946435;4245.681573881948;4252.659787217015;4274.187904298174;4275.358696195564;4290.077633308838;4127.174565086994;4232.470941132358;4253.723507971988;4273.764942928137;4301.376300436435;4157.230912281238;4157.97921010396;4199.706020578561;4219.303814870543;4227.7463352198965;4190.664746820263;4192.308269135619;4212.368144778277;4222.852200172979;4231.196072746168;3654.6528079832374;3747.850085996557;3750;3765.190484045916;3765.3787125124322;5171.6036670098865;5193.169092945111;5216.139337009389;5219.791208351661;5239.423663397023;4803.975980120077;4821.855344339676;4836.516348365135;4848.951510484885;4861.2222044473065;5304.973475132639;5570.944290557083;5581.962583656936;5582.162675598652;5585.385607583163;3990.4014397840265;3996.820937823349;4006.5376137709527;4007.1184339445276;4014.3978403239457;5796.797112101056;5828.388019258002;5834.533237341003;5843.766249350021;5895.997680394344;4007.6192761687594;4076.5108187017154;4097.323941549671;4105.712600117963;4106.301928672073;4151.647109995623;4151.709380343347;4169.291220492523;4181.581841815794;4189.329707246826;4738.218193997952;4770.928436073462;4779.378680771478;4799.256115302133;4813.374261346073;5340.412554618989;5632.746526406321;5670.971645141748;5686.6116231183005;5710.600257981984;1345.9394327255297;1416.5750274917484;1417.149710173908;1438.633298366556;1442.4302400551724;899.4603238057114;901.8106197698556;913.7624217703312;917.4128557722929;920.7513971227731;4833.709977401367;4887.266909963463;4900.338454308623;4965.833029238126;4972.06648077636;5233.267342572043;5241.475852414728;5251.737413129328;5254.556631166526;5257.0811653746105;4772.331873537708;4961.627877909171;5001.124578283144;5020.736521261209;5100.668456550312;3070.9385812284077;3111.797733147338;3114.5172498262805;3130.514770210621;3149.165471150147;6031.577789554689;6045.183900173421;6047.365026672224;6078.635281883101;6121.510279177655;5740.6268592541355;6035.66803825785;6046.455818976313;6070.027241146645;6087.726052327655;6056.515478761688;6075.48358389533;6098.969505152446;6114.755409783559;6144.815655530341;5615.5507559395965;5868.853278668067;5892.528597712087;5933.169356290036;5980.401959804159;5792.304923409218;5807.593468457168;5810.4189581041555;5826.41735826414;5826.504747096375;6023.30731965812;6038.9396106038375;6057.727402266997;6074.726637301421;6099.688566958125;4036.535798383106;4084.795760211918;4085.89785255371;4208.957910420857;4232.195882782262;5794.7102644866745;5812.33158186797;5859.275436921852;5859.882802344015;5862.208601838697;5681.659100453986;5700.201971724045;5705.343885453226;5717.342505611908;5725.541956643542;5457.481539253765;5726.570507212059;5758.740856661544;5761.625494342854;5776.653400795965;5492.505674489315;5612.663240205601;5647.3505546861;5650.971745141248;5671.631343962629;5620.690862002629;5669.8943705976935;5772.7690892363835;5776;5780.277465316835;5625.8593535161945;5633.63381380209;5639.407862174577;5647.576431767716;5667 22:21:48 INFO - PROCESS | 12037 | |5;yui.html;706.2231545300167;719.7049209823972;799.4164260090134;855.1448551448548;860.7374750701038;14855.182964936925;15437.691246175069;15629.140397278145;15643.296051677673;15674.764878526817;13949.30253487324;14116;14173.433062677506;14179.574612761608;14180.645483862909;658.5028303630752;789.9763007109796;802.8554860125175;806.9636866341;824.2499325613693;811.736185739634;819.3445243804932;819.4878201124297;820.6840366458897;822.9711960081398;850.9234168924795;857.6097875466679;858.2061593026457;858.4505916213628;858.9656413743443;701.9964900175491;838;857.69123115678;858.5063588436611;861.5089399042548;628.937106289372;629.0187307799847;629.128656810321;629.7008920762647;629.8425393651587;13009.414576344087;13046.804297935538;13133.737325253536;13141.27722975236;13179;12519.499220031188;12610.432530536147;12616.369181540886;12748.872511274862;13128.277944713132;11873.940630296793;12184.365142250605;12225.388730563436;12365.134440589163;12442.875571244262;3774.2451509697894;3927.9410808837893;4226.344916537942;4286.657067434598;4326.610605045561;61242.69378653123;61355.693221533606;61888.38111618871;63423.68288158529;63443;10858.375728102164;10879.238453308351;11096.889031109748;11211.103111751203;11537.48081336342;19091.42725718203;19147.329843455413;19147.712784308245;19164.329248476515;19173;7750.341220996278;7785.649645765954;7813.218678132255;7835.138134805167;8062.274395304451;4619.514950930171;4752.334673145762;4932.753362331869;4936.802527898915;5005.6996580205305;3427.1260828489076;3506.1585219547123;3512.7892326460496;3514.68367846895;3516.8593256269974;609.3540846702509;622.0979579609584;624.8594066335038;625.9123722678829;644.3266786208329;670.8289386206486;685.7874059041714;699.8565294114796;702.3082263969985;739.1942782367138;8933.642654293755;8967.910320896708;9125.45247285165;9235.676751313671;9380.296477764196;684.6218433120267;686.4336922039338;706.8114511352419;722.6278466589711;727.040306795036;4231.61915427613;4351.325544540601;4355.324924636687;4369.584889435499;4413.558644135561;68368;73085;73715.26284737191;73925.26074739291;74363.62818185874;61919.38080619226;61934;61953.38046619566;62889;63341;56794.43205567974;58662.70668646629;58835.939687237806;59046.40953590409;59926.70036649789;8413.747587572383;8445.055517777173;8533.786655333666;8589.355798315026;8862;38114.80942595269;38425.42361864518;38659.806700966314;38768.80615597017;39012.2197556053;48469.5153048465;49927;51114;53070.73464632652;54516.45483545272;10586.8411973822;10920.836187457353;11018.614348497764;11020.724481888017;11432.771344572895;12038;12146.757064858477;13201.679832016722;13648.93175534116;14151.363188656329;13396.866031339561;13591.45634174622;14078.436862525385;14411.495597654031;14506.564803055926;13125.118606486258;13284.309568115448;13427.731445370842;13475.797863031867;13551.390187441391;12953.676158096123;13148.145370550883;13523.59429217125;13818;14187.716245675236;10700.83948740754;10808.837867431837;11108.555657773599;11438.313701177956;11633.6509904703;11034.503447344729;11382.260153090023;11480.474295654942;11503.424828758696;11620.883791162318 22:21:48 INFO - PROCESS | 12037 | __end_tp_report 22:21:48 INFO - PROCESS | 12037 | __start_cc_report 22:21:48 INFO - PROCESS | 12037 | _x_x_mozilla_cycle_collect,896 22:21:48 INFO - PROCESS | 12037 | __end_cc_report 22:21:48 INFO - PROCESS | 12037 | __startTimestamp1464326508907__endTimestamp 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | ------- Summary: start ------- 22:21:48 INFO - PROCESS | 12037 | Number of tests: 6 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | [#0] dojo.html Cycles:165 Average:5447.29 Median:5662.46 stddev:636.81 (11.2%) stddev-sans-first:611.74 22:21:48 INFO - PROCESS | 12037 | Values: 3107.9 3109.1 3126.5 3136.8 3172.1 5325.1 5376.4 5406.5 5475.2 5480.9 5471.2 5500.0 5501.1 5524.4 5567.3 4908.2 4968.7 5002.0 5012.6 5137.7 5038.7 5053.5 5107.4 5109.0 5119.3 4839.8 4916.6 4917.9 4963.4 5001.4 4901.3 4914.9 4996.9 4999.8 5042.7 4828.8 4945.8 4949.8 4968.1 5015.1 5384.8 5433.9 5449.4 5525.4 5531.3 5839.4 5900.0 5928.6 5931.2 5943.3 5702.6 5819.5 5852.0 5860.6 5960.9 5510.0 5530.8 5537.6 5558.3 5560.8 6042.8 6054.0 6087.5 6102.7 6104.4 5564.5 5622.9 5654.6 5679.1 5854.4 5801.0 5811.6 5859.8 5862.3 5862.4 5764.7 5771.7 5773.9 5776.6 5776.9 5612.7 5733.6 5781.5 5798.4 5838.1 4419.8 4421.0 4424.2 4428.5 4462.2 3910.1 3929.6 3948.6 3969.3 3999.4 5430.9 5450.6 5486.8 5541.4 5580.0 5868.3 5878.5 5880.6 5890.1 5932.0 5528.4 5534.3 5536.5 5543.7 5546.6 4925.9 4987.1 5044.6 5049.0 5071.1 6080.6 6084.6 6086.6 6087.3 6099.3 5735.5 5892.5 5897.9 5898.0 5898.3 6063.7 6097.0 6098.6 6207.2 6211.5 5832.6 5875.1 5877.7 5885.2 5934.8 5643.0 5823.1 5935.9 5942.2 5954.0 5730.5 5786.5 5790.9 5797.0 5846.0 5758.0 5771.9 5773.7 5773.9 5776.6 5414.9 5584.5 5662.5 5672.8 5678.6 5845.1 5875.9 5879.7 5889.4 5894.4 5695.8 5701.5 5702.4 5705.4 5740.5 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | [#1] ext.html Cycles:180 Average:60221.12 Median:40983.39 stddev:121955.70 (297.6%) stddev-sans-first:122215.63 22:21:48 INFO - PROCESS | 12037 | Values: 587.6 638.0 647.6 651.4 662.0 35186.5 35930.6 42119.0 43837.0 43885.8 45747.8 46507.0 46572.0 46581.0 46611.3 8621.4 8796.7 8798.8 8802.4 8805.9 8637.2 8759.9 8769.6 8784.7 8793.8 8743.9 8765.5 8811.9 8817.6 8825.8 8745.3 8807.0 8807.4 8811.0 8812.0 8691.5 8778.8 8782.4 8796.3 8800.0 42503.0 42913.6 43016.0 43021.4 43066.0 3531.3 3559.8 3586.4 3605.5 3892.1 33247.0 33285.5 33772.5 34573.8 35484.5 4170.0 4232.4 4356.3 4364.2 4485.0 361442.0 364497.0 365744.0 367563.0 369220.0 35604.8 65031.7 65982.3 65990.7 66034.0 36703.6 38528.2 38539.2 38566.6 38579.0 15710.8 15811.1 15817.4 15832.8 15833.9 13229.2 13276.1 13331.3 13337.6 13345.5 8476.6 8514.4 8582.2 8612.1 8640.9 3280.2 3305.1 3309.4 3335.7 3401.9 1020.5 1030.2 1046.6 1065.8 1074.2 32884.3 33010.8 33101.2 33133.5 33400.7 1123.1 1123.3 1165.8 1184.4 1200.5 16989.3 17400.1 17485.9 17505.8 17587.0 561590.0 566334.0 568565.0 569508.0 569835.0 57981.0 58432.7 58535.0 58549.7 58572.7 1242.2 1354.2 1385.6 1416.4 1416.8 32346.7 34093.5 34096.7 34154.0 34465.7 50124.2 50392.0 50610.0 50700.7 50797.0 416672.0 418258.0 420128.0 420600.0 421208.0 25003.2 27697.7 28582.1 28706.3 28778.1 48606.3 48850.5 49009.3 50093.5 50151.0 39696.8 40961.2 41005.6 41159.6 41512.6 39090.8 40298.6 40489.0 40601.0 40724.8 40793.6 41427.0 42115.6 42135.6 42315.4 33281.0 35841.1 36055.3 36407.6 36438.5 28142.6 28214.3 28485.9 28496.3 28528.4 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | [#2] jquery.html Cycles:180 Average:24489.08 Median:42307.97 stddev:61988.21 (146.5%) stddev-sans-first:62135.68 22:21:48 INFO - PROCESS | 12037 | Values: 381.5 391.8 399.5 403.9 411.0 18674.7 19592.1 19935.8 20057.8 20061.4 17835.0 18035.9 18135.3 18689.8 18894.4 5501.1 5584.4 5649.4 5674.1 5683.5 5576.9 5725.8 5727.2 5728.4 5769.7 5610.4 5625.4 5705.0 5710.8 5716.8 5694.9 5698.5 5700.5 5705.3 5714.6 4966.8 4986.8 5042.1 5045.5 5051.5 22079.6 22295.4 22457.1 22561.1 22748.8 2160.1 2505.0 2759.3 2782.5 2848.8 10025.7 10181.9 10277.7 14039.2 14080.9 1889.4 1938.6 1940.9 1949.4 1969.2 80435.2 84919.6 85325.6 86336.6 87831.6 10103.2 10363.5 10980.9 11038.8 11168.6 15257.5 15314.4 15358.7 15364.9 15390.4 7598.8 7761.4 7815.3 7872.2 7876.9 7877.8 7929.2 7971.9 8043.6 8184.4 4489.3 4602.6 4733.8 4764.0 5038.1 1904.6 1940.2 1943.0 1948.2 1985.8 910.8 984.2 1005.2 1050.2 1108.8 18749.8 19117.6 19185.6 19363.5 19674.6 469.0 481.4 491.8 496.7 502.5 6199.1 6332.1 6435.7 6437.8 6631.6 195185.0 197039.0 255428.0 513875.0 518076.0 67292.0 83883.0 86593.0 99692.0 99984.0 478.1 479.4 485.7 486.7 488.1 10931.7 11355.4 11461.4 11483.8 11501.3 42140.8 42475.2 42684.0 42781.8 44531.6 62018.4 62387.4 62423.4 63194.7 64633.7 8598.1 8604.2 8604.3 8605.1 8647.6 9443.7 12703.7 13059.9 13100.7 13176.5 11414.6 11471.9 11722.9 11923.4 11950.8 10674.3 10812.9 11053.2 11337.5 11827.3 11367.8 11436.5 11588.9 11688.5 11896.0 8365.5 8520.8 8552.2 8595.6 8617.3 11141.7 11170.9 11199.4 11296.2 11310.4 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | [#3] mootools.html Cycles:180 Average:5860.36 Median:462.45 stddev:3362.85 (727.2%) stddev-sans-first:3355.18 22:21:48 INFO - PROCESS | 12037 | Values: 1347.8 1516.6 1544.6 1703.5 1849.7 8524.2 8869.3 8883.2 9100.2 9110.5 8837.5 9036.5 9040.0 9042.4 9044.0 2080.8 2374.3 2419.8 2450.5 2459.4 2456.7 2480.2 2481.3 2488.1 2491.0 2396.7 2419.3 2424.0 2426.4 2427.5 2091.4 2324.4 2387.5 2406.3 2408.7 2348.4 2356.8 2358.5 2386.8 2423.2 8990.1 9114.9 9118.0 9121.0 9129.6 5384.5 5514.6 5516.2 5524.4 5525.5 3162.8 3262.6 3305.9 3334.2 3372.4 1053.1 1090.0 1097.3 1103.3 1108.4 12528.7 12558.9 12561.2 12561.4 12570.4 4790.8 4810.7 4919.6 4932.5 5005.8 10265.9 10316.7 10331.7 10380.6 10396.5 4769.9 5197.0 5306.3 5310.6 5313.3 4858.3 4966.0 4993.2 5023.1 5030.4 4046.4 4130.7 4142.4 4180.9 4342.3 1325.2 1327.5 1327.8 1335.2 1342.5 1004.8 1013.5 1020.2 1026.6 1029.2 6036.3 6064.3 6067.7 6098.6 6135.2 458.8 466.1 469.2 471.8 476.1 4267.4 4307.2 4308.4 4311.5 4324.4 10999.8 11333.3 11351.9 11385.3 11392.6 10839.5 11170.0 11279.1 11305.7 11308.7 8212.1 8401.7 8565.3 8569.7 8576.1 5895.9 6239.7 6266.0 6291.1 6315.7 10119.1 10537.2 10566.4 10587.3 10590.3 10370.2 10562.4 10580.8 10696.0 10806.3 7043.0 7236.7 7249.6 7974.7 8103.0 7867.3 8014.2 8024.8 8037.9 8107.9 7140.0 7222.2 7227.4 7353.4 7379.7 7179.5 7202.7 7204.1 7209.4 7220.9 6595.2 6727.8 6730.0 6733.8 6736.6 5062.7 5067.4 5084.2 5170.3 5225.8 5128.9 5168.5 5191.0 5246.6 5278.6 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | [#4] prototype.html Cycles:180 Average:4740.57 Median:5281.03 stddev:1405.59 (26.6%) stddev-sans-first:1369.56 22:21:48 INFO - PROCESS | 12037 | Values: 306.3 323.4 325.9 333.7 339.5 5253.8 5370.5 5455.8 5492.4 5492.6 5331.5 5383.2 5439.3 5455.0 5501.6 4245.7 4252.7 4274.2 4275.4 4290.1 4127.2 4232.5 4253.7 4273.8 4301.4 4157.2 4158.0 4199.7 4219.3 4227.7 4190.7 4192.3 4212.4 4222.9 4231.2 3654.7 3747.9 3750.0 3765.2 3765.4 5171.6 5193.2 5216.1 5219.8 5239.4 4804.0 4821.9 4836.5 4849.0 4861.2 5305.0 5570.9 5582.0 5582.2 5585.4 3990.4 3996.8 4006.5 4007.1 4014.4 5796.8 5828.4 5834.5 5843.8 5896.0 4007.6 4076.5 4097.3 4105.7 4106.3 4151.6 4151.7 4169.3 4181.6 4189.3 4738.2 4770.9 4779.4 4799.3 4813.4 5340.4 5632.7 5671.0 5686.6 5710.6 1345.9 1416.6 1417.1 1438.6 1442.4 899.5 901.8 913.8 917.4 920.8 4833.7 4887.3 4900.3 4965.8 4972.1 5233.3 5241.5 5251.7 5254.6 5257.1 4772.3 4961.6 5001.1 5020.7 5100.7 3070.9 3111.8 3114.5 3130.5 3149.2 6031.6 6045.2 6047.4 6078.6 6121.5 5740.6 6035.7 6046.5 6070.0 6087.7 6056.5 6075.5 6099.0 6114.8 6144.8 5615.6 5868.9 5892.5 5933.2 5980.4 5792.3 5807.6 5810.4 5826.4 5826.5 6023.3 6038.9 6057.7 6074.7 6099.7 4036.5 4084.8 4085.9 4209.0 4232.2 5794.7 5812.3 5859.3 5859.9 5862.2 5681.7 5700.2 5705.3 5717.3 5725.5 5457.5 5726.6 5758.7 5761.6 5776.7 5492.5 5612.7 5647.4 5651.0 5671.6 5620.7 5669.9 5772.8 5776.0 5780.3 5625.9 5633.6 5639.4 5647.6 5667.0 22:21:48 INFO - PROCESS | 12037 | 22:21:48 INFO - PROCESS | 12037 | [#5] yui.html Cycles:180 Average:16123.68 Median:4685.92 stddev:19841.20 (423.4%) stddev-sans-first:19863.08 22:21:48 INFO - PROCESS | 12037 | Values: 706.2 719.7 799.4 855.1 860.7 14855.2 15437.7 15629.1 15643.3 15674.8 13949.3 14116.0 14173.4 14179.6 14180.6 658.5 790.0 802.9 807.0 824.2 811.7 819.3 819.5 820.7 823.0 850.9 857.6 858.2 858.5 859.0 702.0 838.0 857.7 858.5 861.5 628.9 629.0 629.1 629.7 629.8 13009.4 13046.8 13133.7 13141.3 13179.0 12519.5 12610.4 12616.4 12748.9 13128.3 11873.9 12184.4 12225.4 12365.1 12442.9 3774.2 3927.9 4226.3 4286.7 4326.6 61242.7 61355.7 61888.4 63423.7 63443.0 10858.4 10879.2 11096.9 11211.1 11537.5 19091.4 19147.3 19147.7 19164.3 19173.0 7750.3 7785.6 7813.2 7835.1 8062.3 4619.5 4752.3 4932.8 4936.8 5005.7 3427.1 3506.2 3512.8 3514.7 3516.9 609.4 622.1 624.9 625.9 644.3 670.8 685.8 699.9 702.3 739.2 8933.6 8967.9 9125.5 9235.7 9380.3 684.6 686.4 706.8 722.6 727.0 4231.6 4351.3 4355.3 4369.6 4413.6 68368.0 73085.0 73715.3 73925.3 74363.6 61919.4 61934.0 61953.4 62889.0 63341.0 56794.4 58662.7 58835.9 59046.4 59926.7 8413.7 8445.1 8533.8 8589.4 8862.0 38114.8 38425.4 38659.8 38768.8 39012.2 48469.5 49927.0 51114.0 53070.7 54516.5 10586.8 10920.8 11018.6 11020.7 11432.8 12038.0 12146.8 13201.7 13648.9 14151.4 13396.9 13591.5 14078.4 14411.5 14506.6 13125.1 13284.3 13427.7 13475.8 13551.4 12953.7 13148.1 13523.6 13818.0 14187.7 10700.8 10808.8 11108.6 11438.3 11633.7 11034.5 11382.3 11480.5 11503.4 11620.9 22:21:48 INFO - PROCESS | 12037 | -------- Summary: end -------- 22:21:48 INFO - PROCESS | 12037 | 22:21:49 INFO - TEST-INFO | 12037: exit 0 22:21:49 INFO - TEST-OK | dromaeo_css | took 1113458ms 22:21:49 INFO - TEST-START | kraken 22:21:49 INFO - operating with platform_type : linux_ 22:21:49 INFO - Initialising browser for kraken test... 22:21:49 INFO - TEST-INFO | started process 12223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7jsOz5/profile http://localhost:38014/getInfo.html) 22:21:53 INFO - PROCESS | 12223 | __metrics Screen width/height:1600/1200 22:21:53 INFO - PROCESS | 12223 | colorDepth:24 22:21:53 INFO - PROCESS | 12223 | Browser inner width/height: 1024/697 22:21:53 INFO - PROCESS | 12223 | __metrics 22:21:55 ERROR - PROCESS | 12223 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:21:55 INFO - TEST-INFO | 12223: exit 0 22:21:55 INFO - Browser initialized. 22:21:55 INFO - Running cycle 1/1 for kraken test... 22:21:55 INFO - TEST-INFO | started process 12309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7jsOz5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:07 INFO - PROCESS | 12309 | RSS: Main: 155783168 22:22:07 INFO - PROCESS | 12309 | 22:22:10 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38014/tests/kraken/driver.html?testName=audio-beat-detection) 22:22:11 INFO - PROCESS | 12309 | RSS: Main: 212742144 22:22:11 INFO - PROCESS | 12309 | 22:22:13 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38014/tests/kraken/driver.html?testName=audio-dft) 22:22:14 INFO - PROCESS | 12309 | RSS: Main: 197787648 22:22:14 INFO - PROCESS | 12309 | 22:22:17 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38014/tests/kraken/driver.html?testName=audio-fft) 22:22:18 INFO - PROCESS | 12309 | RSS: Main: 268853248 22:22:18 INFO - PROCESS | 12309 | 22:22:20 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38014/tests/kraken/driver.html?testName=audio-oscillator) 22:22:20 INFO - PROCESS | 12309 | RSS: Main: 179986432 22:22:20 INFO - PROCESS | 12309 | 22:22:22 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38014/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:22:23 INFO - PROCESS | 12309 | RSS: Main: 183373824 22:22:23 INFO - PROCESS | 12309 | 22:22:26 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38014/tests/kraken/driver.html?testName=imaging-darkroom) 22:22:27 INFO - PROCESS | 12309 | RSS: Main: 217673728 22:22:27 INFO - PROCESS | 12309 | 22:22:30 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38014/tests/kraken/driver.html?testName=imaging-desaturate) 22:22:31 INFO - PROCESS | 12309 | RSS: Main: 220237824 22:22:31 INFO - PROCESS | 12309 | 22:22:33 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38014/tests/kraken/driver.html?testName=json-parse-financial) 22:22:34 INFO - PROCESS | 12309 | RSS: Main: 220536832 22:22:34 INFO - PROCESS | 12309 | 22:22:36 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38014/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:22:36 INFO - PROCESS | 12309 | RSS: Main: 208961536 22:22:36 INFO - PROCESS | 12309 | 22:22:38 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:22:39 INFO - PROCESS | 12309 | RSS: Main: 211369984 22:22:39 INFO - PROCESS | 12309 | 22:22:41 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:22:41 INFO - PROCESS | 12309 | RSS: Main: 218554368 22:22:41 INFO - PROCESS | 12309 | 22:22:44 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:22:44 INFO - PROCESS | 12309 | RSS: Main: 223080448 22:22:44 INFO - PROCESS | 12309 | 22:22:47 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:22:48 INFO - PROCESS | 12309 | RSS: Main: 222990336 22:22:48 INFO - PROCESS | 12309 | 22:22:49 INFO - PROCESS | 12309 | Cycle 1(1): loaded http://localhost:38014/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38014/tests/kraken/driver.html?testName=ai-astar) 22:22:50 INFO - PROCESS | 12309 | RSS: Main: 264761344 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | __start_tp_report 22:22:50 INFO - PROCESS | 12309 | _x_x_mozilla_page_load 22:22:50 INFO - PROCESS | 12309 | _x_x_mozilla_page_load_details 22:22:50 INFO - PROCESS | 12309 | |i|pagename|runs| 22:22:50 INFO - PROCESS | 12309 | |0;ai-astar;113;106;111;113;112;111;111;111;111;112 22:22:50 INFO - PROCESS | 12309 | |1;audio-beat-detection;153;124;128;177;125;126;153;124;181;124 22:22:50 INFO - PROCESS | 12309 | |2;audio-dft;161;160;161;160;160;160;162;162;161;160 22:22:50 INFO - PROCESS | 12309 | |3;audio-fft;75;74;74;112;76;76;75;75;97;75 22:22:50 INFO - PROCESS | 12309 | |4;audio-oscillator;83;83;119;83;82;84;119;84;83;128 22:22:50 INFO - PROCESS | 12309 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;117;116 22:22:50 INFO - PROCESS | 12309 | |6;imaging-darkroom;145;146;145;146;146;146;145;145;145;145 22:22:50 INFO - PROCESS | 12309 | |7;imaging-desaturate;95;94;94;95;95;95;95;94;94;95 22:22:50 INFO - PROCESS | 12309 | |8;json-parse-financial;55;53;51;52;53;53;54;53;53;55 22:22:50 INFO - PROCESS | 12309 | |9;json-stringify-tinderbox;63;62;62;63;64;63;65;64;62;66 22:22:50 INFO - PROCESS | 12309 | |10;stanford-crypto-aes;80;79;79;79;79;80;80;81;120;83 22:22:50 INFO - PROCESS | 12309 | |11;stanford-crypto-ccm;121;120;123;118;121;118;127;129;131;123 22:22:50 INFO - PROCESS | 12309 | |12;stanford-crypto-pbkdf2;164;161;162;158;157;157;160;161;160;160 22:22:50 INFO - PROCESS | 12309 | |13;stanford-crypto-sha256-iterative;62;64;65;64;61;62;61;65;62;62 22:22:50 INFO - PROCESS | 12309 | __end_tp_report 22:22:50 INFO - PROCESS | 12309 | __start_cc_report 22:22:50 INFO - PROCESS | 12309 | _x_x_mozilla_cycle_collect,1625 22:22:50 INFO - PROCESS | 12309 | __end_cc_report 22:22:50 INFO - PROCESS | 12309 | __startTimestamp1464326570133__endTimestamp 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | ------- Summary: start ------- 22:22:50 INFO - PROCESS | 12309 | Number of tests: 14 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:1.97 (1.8%) stddev-sans-first:1.96 22:22:50 INFO - PROCESS | 12309 | Values: 113.0 106.0 111.0 113.0 112.0 111.0 111.0 111.0 111.0 112.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:140.50 stddev:22.85 (16.3%) stddev-sans-first:23.85 22:22:50 INFO - PROCESS | 12309 | Values: 153.0 124.0 128.0 177.0 125.0 126.0 153.0 124.0 181.0 124.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 22:22:50 INFO - PROCESS | 12309 | Values: 161.0 160.0 161.0 160.0 160.0 160.0 162.0 162.0 161.0 160.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:12.95 (17.3%) stddev-sans-first:13.56 22:22:50 INFO - PROCESS | 12309 | Values: 75.0 74.0 74.0 112.0 76.0 76.0 75.0 75.0 97.0 75.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:83.00 stddev:18.94 (22.8%) stddev-sans-first:19.60 22:22:50 INFO - PROCESS | 12309 | Values: 83.0 83.0 119.0 83.0 82.0 84.0 119.0 84.0 83.0 128.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:22:50 INFO - PROCESS | 12309 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:22:50 INFO - PROCESS | 12309 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:22:50 INFO - PROCESS | 12309 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:1.23 (2.3%) stddev-sans-first:1.12 22:22:50 INFO - PROCESS | 12309 | Values: 55.0 53.0 51.0 52.0 53.0 53.0 54.0 53.0 53.0 55.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:63.50 stddev:1.35 (2.1%) stddev-sans-first:1.42 22:22:50 INFO - PROCESS | 12309 | Values: 63.0 62.0 62.0 63.0 64.0 63.0 65.0 64.0 62.0 66.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:80.00 stddev:12.71 (15.9%) stddev-sans-first:13.40 22:22:50 INFO - PROCESS | 12309 | Values: 80.0 79.0 79.0 79.0 79.0 80.0 80.0 81.0 120.0 83.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:123.00 stddev:4.51 (3.7%) stddev-sans-first:4.72 22:22:50 INFO - PROCESS | 12309 | Values: 121.0 120.0 123.0 118.0 121.0 118.0 127.0 129.0 131.0 123.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:160.50 stddev:2.21 (1.4%) stddev-sans-first:1.81 22:22:50 INFO - PROCESS | 12309 | Values: 164.0 161.0 162.0 158.0 157.0 157.0 160.0 161.0 160.0 160.0 22:22:50 INFO - PROCESS | 12309 | 22:22:50 INFO - PROCESS | 12309 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.55 (2.5%) stddev-sans-first:1.62 22:22:50 INFO - PROCESS | 12309 | Values: 62.0 64.0 65.0 64.0 61.0 62.0 61.0 65.0 62.0 62.0 22:22:50 INFO - PROCESS | 12309 | -------- Summary: end -------- 22:22:50 INFO - PROCESS | 12309 | 22:22:51 INFO - TEST-INFO | 12309: exit 0 22:22:51 INFO - TEST-OK | kraken | took 61545ms 22:22:51 INFO - SUITE-END | took 1175s 22:22:51 INFO - Completed test suite (00:19:35) 22:22:51 INFO - javascript benchmark 22:22:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.8601462934166, 3109.113902537777, 3126.4528707476193, 3136.7961082529646, 3172.143521249263, 5325.094733895237, 5376.408595054546, 5406.54044406226, 5475.151351540512, 5480.945190548094, 5471.151971444422, 5499.972500137504, 5501.14732216506, 5524.364698059724, 5567.3040869891265, 4908.190148625481, 4968.701877887338, 5002.049610573979, 5012.624053196007, 5137.743112844361, 5038.697678139323, 5053.5451809337155, 5107.412647545524, 5108.974455127719, 5119.308893299412, 4839.758012099399, 4916.557509824133, 4917.901641967141, 4963.4292056413615, 5001.374828146481, 4901.289313049588, 4914.877128071791, 4996.850094497135, 4999.8000079996755, 5042.722650254234, 4828.830990915301, 4945.8268960586565, 4949.82675606352, 4968.056069346812, 5015.122353588144, 5384.838454846361, 5433.864153396157, 5449.427810079964, 5525.392206857243, 5531.280933478622, 5839.386864379221, 5900.026495628216, 5928.584999050068, 5931.169636250928, 5943.3165186003735, 5702.629329093637, 5819.505342045931, 5852.0, 5860.648361098348, 5960.910586341209, 5510.035743744828, 5530.806421775219, 5537.58468114893, 5558.333000040021, 5560.805371812007, 6042.83857654493, 6054.03577520406, 6087.452129308295, 6102.664353460514, 6104.359042300494, 5564.4713752193475, 5622.943770562242, 5654.6324488908085, 5679.091345384719, 5854.443827836349, 5801.0, 5811.622244554091, 5859.824205273849, 5862.296524417012, 5862.443067908542, 5764.682942438206, 5771.711414429262, 5773.855653608609, 5776.595638305278, 5776.913346299809, 5612.719364031782, 5733.598648094677, 5781.45076217767, 5798.391168927202, 5838.095095260242, 4419.756913369732, 4421.0, 4424.247877860771, 4428.535003824616, 4462.152191083684, 3910.061585219527, 3929.5677475477673, 3948.624880636335, 3969.325214713448, 3999.3800960851113, 5430.864228394243, 5450.645708029045, 5486.835394938158, 5541.445855414507, 5579.972100139474, 5868.3251426086545, 5878.500327472213, 5880.558958078162, 5890.116482527569, 5932.0, 5528.391876893538, 5534.280543529316, 5536.529395001389, 5543.722813859291, 5546.639468434539, 4925.926111108336, 4987.12725273083, 5044.646874718735, 5049.040682270357, 5071.137906555894, 6080.6047606906295, 6084.6349219047, 6086.604370715978, 6087.269527656709, 6099.329073801879, 5735.512481439125, 5892.499137573269, 5897.911531326948, 5897.970510147422, 5898.292204935436, 6063.72713227897, 6097.040762675721, 6098.553255670888, 6207.224096987877, 6211.472024877936, 5832.562557808266, 5875.148103525051, 5877.735501902339, 5885.17607534954, 5934.7922822701, 5643.0, 5823.126531020381, 5935.940640593539, 5942.197803296673, 5954.047352423591, 5730.541556675539, 5786.479216870502, 5790.884182316414, 5797.0, 5845.970770146122, 5758.0, 5771.8557036074435, 5773.653580785166, 5773.942260577341, 5776.595638305446, 5414.945850541601, 5584.525315348241, 5662.462066103713, 5672.829815105553, 5678.574106941914, 5845.064789633639, 5875.882482350415, 5879.735411906389, 5889.440503152194, 5894.381089985662, 5695.829125126087, 5701.486866182064, 5702.429757024264, 5705.429457054262, 5740.540756739368], "value": 5402.884242120028, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.5652017507043, 637.9808605741829, 647.6340867409913, 651.4104735214632, 661.9536632435728, 35186.47220291694, 35930.64069359306, 42118.99999999996, 43837.0, 43885.7805710971, 45747.77126114373, 46507.0, 46572.0, 46581.0, 46611.30083048757, 8621.39650224483, 8796.73609791704, 8798.780030499225, 8802.427842190269, 8805.867911981326, 8637.179467950535, 8759.912400876005, 8769.605367758435, 8784.736457906241, 8793.824123517526, 8743.868841967344, 8765.474071555695, 8811.867821982676, 8817.559122043904, 8825.779355516099, 8745.256653184488, 8806.955965220197, 8807.383483156182, 8810.955945220265, 8811.955940220258, 8691.521966291852, 8778.824423511558, 8782.429142105742, 8796.296296296281, 8800.031996480382, 42503.0, 42913.57086429127, 43016.0, 43021.35467967983, 43066.0, 3531.276088401897, 3559.75081744278, 3586.3544561978833, 3605.4952306677087, 3892.104815892332, 33247.0, 33285.50071748926, 33772.49341259883, 34573.827130864185, 35484.46773298403, 4169.958300416987, 4232.449781528412, 4356.302991521341, 4364.192624364503, 4485.0, 361442.0, 364497.0, 365744.0, 367563.0, 369220.0, 35604.821975890474, 65031.67484162549, 65982.34017659858, 65990.67004664947, 66034.0, 36703.632963670025, 38528.183977002875, 38539.22921541554, 38566.614333856305, 38579.0, 15710.842891570937, 15811.130387828549, 15817.446389376317, 15832.76250856238, 15833.920830395775, 13229.206247625174, 13276.137051091648, 13331.266780326981, 13337.599872003855, 13345.466181352831, 8476.576171191417, 8514.361422893184, 8582.184692454332, 8612.138786121337, 8640.870386944202, 3280.2291461506525, 3305.0910999475336, 3309.4373956427467, 3335.7331413486863, 3401.880934167342, 1020.4744556553233, 1030.160419258302, 1046.6284469013542, 1065.7815147894662, 1074.2265568790458, 32884.342313153604, 33010.83494582512, 33101.17247068843, 33133.502997455056, 33400.66599334024, 1123.1407972900738, 1123.3372310336777, 1165.7551914098135, 1184.3722826901756, 1200.5077918053555, 16989.320427182774, 17400.129993500275, 17485.863418877732, 17505.82494175042, 17587.0, 561590.0, 566334.0, 568565.0, 569508.0, 569835.0, 57981.0, 58432.70783646055, 58535.0, 58549.70725146347, 58572.707136464895, 1242.186367929008, 1354.2483921423704, 1385.625881012121, 1416.390951890697, 1416.7603347071313, 32346.676533234368, 34093.488597670555, 34096.71751457476, 34154.0, 34465.65534344625, 50124.248136278715, 50392.0, 50610.0, 50700.74649626728, 50797.0, 416672.0, 418258.0, 420128.0, 420600.0, 421208.0, 25003.249902502954, 27697.723022770322, 28582.14253572313, 28706.282342940758, 28778.136655900358, 48606.27090593573, 48850.51149488602, 49009.2648610264, 50093.49906501034, 50151.0, 39696.801515992236, 40961.1807763849, 41005.589944100175, 41159.58840411557, 41512.58487415208, 39090.80454597709, 40298.59701402948, 40489.0, 40601.0, 40724.796376017934, 40793.59206408016, 41427.0, 42115.57884421239, 42135.578644213165, 42315.3652695216, 33281.0, 35841.103972400895, 36055.27889442144, 36407.635923640424, 36438.453423198145, 28142.577861332506, 28214.294642634097, 28485.857570712014, 28496.287592810346, 28528.42943141084], "value": 19183.340724612844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.53833861028136, 391.80997216350045, 399.49064942198686, 403.8566308960319, 410.9910170531346, 18674.719879201806, 19592.11835467404, 19935.800641993577, 20057.799422005795, 20061.398158055246, 17835.0, 18035.90982045088, 18135.274589016455, 18689.81310186898, 18894.433167004976, 5501.14732216505, 5584.357798853143, 5649.435056494359, 5674.120511320732, 5683.4600712932415, 5576.9163462547895, 5725.770969161229, 5727.198192253088, 5728.398518155596, 5769.682667453289, 5610.354809196954, 5625.353084395306, 5704.971475142639, 5710.7715691372505, 5716.799912003081, 5694.914576281359, 5698.458646428603, 5700.543956483492, 5705.286839145107, 5714.599978001542, 4966.776495057731, 4986.800527978877, 5042.1176294148745, 5045.4702256262935, 5051.494850514956, 22079.558408831734, 22295.44261393478, 22457.10171593134, 22561.097556097735, 22748.77251227483, 2160.12514931453, 2504.9980007996764, 2759.268793769653, 2782.5408807546733, 2848.84331361775, 10025.749356266078, 10181.949090254575, 10277.677965425028, 14039.157650540898, 14080.92959535206, 1889.3765057530982, 1938.5832046110013, 1940.8835469871854, 1949.39568733693, 1969.2319995201883, 80435.19564804336, 84919.57540212259, 85325.57337213274, 86336.56831715802, 87831.56084219602, 10103.19174466041, 10363.481825908675, 10980.945095274472, 11038.834417483744, 11168.553257869757, 15257.542273731806, 15314.387424503118, 15358.692826143415, 15364.923175384052, 15390.384384624489, 7598.848023039619, 7761.4178936578855, 7815.25755053269, 7872.173421790744, 7876.881846772303, 7877.842443151105, 7929.167437419103, 7971.880421793678, 8043.592371334993, 8184.427090103635, 4489.304157855538, 4602.631789456902, 4733.834315798999, 4763.976180119077, 5038.118329292359, 1904.5714714189196, 1940.1754254442087, 1943.0084163732288, 1948.2304489726478, 1985.8212760851593, 910.8132832769267, 984.1683777208248, 1005.1958433253368, 1050.1546255264586, 1108.8336749487642, 18749.75074775673, 19117.61764764697, 19185.61628767417, 19363.51591210203, 19674.60650786976, 468.995310046902, 481.3597914773413, 491.7713263332496, 496.7044608457962, 502.47742347958444, 6199.101130336076, 6332.145160403286, 6435.74257029723, 6437.774677886251, 6631.602103873783, 195185.0, 197039.0, 255428.0, 513875.0, 518076.0, 67292.0, 83883.0, 86593.0, 99692.0, 99984.0, 478.08446824331713, 479.41990191867535, 485.6940127719514, 486.7104073076514, 488.060483569128, 10931.672049838518, 11355.37545435009, 11461.426928653367, 11483.770324593628, 11501.252418592765, 42140.78929605332, 42475.15049698927, 42684.0, 42781.78609106935, 44531.55468445274, 62018.37981620126, 62387.37612623816, 62423.375766243575, 63194.684026579576, 64633.67683161742, 8598.140185981352, 8604.225619694258, 8604.311655067704, 8605.096464871098, 8647.610857511301, 9443.716688499357, 12703.682407939876, 13059.934700326437, 13100.737985240432, 13176.47294108225, 11414.600488982845, 11471.94264028708, 11722.882771172179, 11923.403829808301, 11950.760984780081, 10674.306170098918, 10812.891871081503, 11053.171012173958, 11337.489812958602, 11827.290362578273, 11367.772644546898, 11436.485358158736, 11588.88411115878, 11688.47401866901, 11896.048316134862, 8365.498070115813, 8520.829583408173, 8552.230299273095, 8595.570221488775, 8617.310615150898, 11141.72145696364, 11170.888291116984, 11199.384033877888, 11296.209265351346, 11310.434478276218], "value": 8528.457819056859, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.7574036673407, 1516.643588756642, 1544.5752418085026, 1703.471923703652, 1849.7225416187562, 8524.232819046285, 8869.29045676346, 8883.244924181452, 9100.180983711482, 9110.453372797636, 8837.513936733492, 9036.50299233542, 9040.0, 9042.412243204188, 9043.95478022609, 2080.8023237792386, 2374.323317854412, 2419.830611857175, 2450.546648869956, 2459.434330104068, 2456.7297597264287, 2480.1567467061195, 2481.255623313008, 2488.1042824583137, 2491.0, 2396.712394512661, 2419.3346829621905, 2423.9515209695796, 2426.4297889995837, 2427.453822889853, 2091.435312465633, 2324.360800779782, 2387.4747555537756, 2406.338256979327, 2408.7350391456926, 2348.4246359641998, 2356.752540983189, 2358.5400846834873, 2386.7613238676167, 2423.236680445654, 8990.100989901022, 9114.908850911472, 9118.0, 9120.954395228047, 9129.634814607407, 5384.542313903323, 5514.558835293167, 5516.172574113875, 5524.392316845144, 5525.502704756591, 3162.845561370106, 3262.6411094779555, 3305.93388132236, 3334.249793796401, 3372.443546814797, 1053.0838170791385, 1090.0462095666292, 1097.253867370188, 1103.2994048779083, 1108.3903852880883, 12528.74942501154, 12558.937205313914, 12561.183523070971, 12561.371931403484, 12570.434330455151, 4790.7844147012775, 4810.6632535722165, 4919.631027672939, 4932.506749325039, 5005.774740136702, 10265.897341026643, 10316.690499285034, 10331.690049298533, 10380.636677716244, 10396.48017599132, 4769.856904292877, 5197.038547868657, 5306.336707911511, 5310.628256022119, 5313.282706834605, 4858.271259311132, 4965.975170124127, 4993.151164302078, 5023.120953833065, 5030.3963524377305, 4046.352583586612, 4130.710850240454, 4142.420061191436, 4180.895477613084, 4342.34864770287, 1325.2379881567945, 1327.5287273018132, 1327.807467917166, 1335.1588499245606, 1342.4831439895515, 1004.8141093897652, 1013.5135135135176, 1020.1838529176778, 1026.58423338548, 1029.2075102171286, 6036.275646922398, 6064.332923378425, 6067.696615169224, 6098.573099882966, 6135.171751813537, 458.76602932504665, 466.1376453560953, 469.1976719809165, 471.83221526721775, 476.09304275355504, 4267.4238977737605, 4307.160103779733, 4308.3968244445805, 4311.504177019683, 4324.394584758136, 10999.790023097454, 11333.263337883176, 11351.94324028391, 11385.259958102697, 11392.601258955896, 10839.458027098613, 11170.0, 11279.097672186206, 11305.71735706614, 11308.660740177807, 8212.096669366365, 8401.663933442594, 8565.271951884035, 8569.657213711507, 8576.099509551408, 5895.9115613266695, 6239.688015599293, 6265.968670156803, 6291.056341548804, 6315.747370105144, 10119.139873110867, 10537.209709271989, 10566.366018038942, 10587.258891877802, 10590.258681892194, 10370.170386369224, 10562.419066951392, 10580.788384232426, 10696.03735663763, 10806.297590656583, 7043.0, 7236.674349654173, 7249.56502609824, 7974.720884769005, 8102.959485202537, 7867.291943725092, 8014.158513356247, 8024.759257222293, 8037.919620803717, 8107.918920810952, 7140.0, 7222.241664625139, 7227.385672217921, 7353.374963128194, 7379.741709040158, 7179.461540384387, 7202.747903823341, 7204.063471748639, 7209.35115839577, 7220.8916866246, 6595.208574971034, 6727.798166055026, 6730.0, 6733.764318249034, 6736.595804251565, 5062.746862656779, 5067.41724701664, 5084.211947148198, 5170.27616133734, 5225.817096401608, 5128.923066153936, 5168.53483186515, 5190.97404512975, 5246.632735708464, 5278.604104692086], "value": 4619.3615928367635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2787136294029, 323.3855674218984, 325.9217787730945, 333.6913355146489, 339.52975129445724, 5253.816116435925, 5370.489803468664, 5455.809046683362, 5492.434656123006, 5492.642978206419, 5331.520163185312, 5383.246345511631, 5439.347278326606, 5455.0, 5501.587380946435, 4245.681573881948, 4252.659787217015, 4274.187904298174, 4275.358696195564, 4290.077633308838, 4127.174565086994, 4232.470941132358, 4253.723507971988, 4273.764942928137, 4301.376300436435, 4157.230912281238, 4157.97921010396, 4199.706020578561, 4219.303814870543, 4227.7463352198965, 4190.664746820263, 4192.308269135619, 4212.368144778277, 4222.852200172979, 4231.196072746168, 3654.6528079832374, 3747.850085996557, 3750.0, 3765.190484045916, 3765.3787125124322, 5171.6036670098865, 5193.169092945111, 5216.139337009389, 5219.791208351661, 5239.423663397023, 4803.975980120077, 4821.855344339676, 4836.516348365135, 4848.951510484885, 4861.2222044473065, 5304.973475132639, 5570.944290557083, 5581.962583656936, 5582.162675598652, 5585.385607583163, 3990.4014397840265, 3996.820937823349, 4006.5376137709527, 4007.1184339445276, 4014.3978403239457, 5796.797112101056, 5828.388019258002, 5834.533237341003, 5843.766249350021, 5895.997680394344, 4007.6192761687594, 4076.5108187017154, 4097.323941549671, 4105.712600117963, 4106.301928672073, 4151.647109995623, 4151.709380343347, 4169.291220492523, 4181.581841815794, 4189.329707246826, 4738.218193997952, 4770.928436073462, 4779.378680771478, 4799.256115302133, 4813.374261346073, 5340.412554618989, 5632.746526406321, 5670.971645141748, 5686.6116231183005, 5710.600257981984, 1345.9394327255297, 1416.5750274917484, 1417.149710173908, 1438.633298366556, 1442.4302400551724, 899.4603238057114, 901.8106197698556, 913.7624217703312, 917.4128557722929, 920.7513971227731, 4833.709977401367, 4887.266909963463, 4900.338454308623, 4965.833029238126, 4972.06648077636, 5233.267342572043, 5241.475852414728, 5251.737413129328, 5254.556631166526, 5257.0811653746105, 4772.331873537708, 4961.627877909171, 5001.124578283144, 5020.736521261209, 5100.668456550312, 3070.9385812284077, 3111.797733147338, 3114.5172498262805, 3130.514770210621, 3149.165471150147, 6031.577789554689, 6045.183900173421, 6047.365026672224, 6078.635281883101, 6121.510279177655, 5740.6268592541355, 6035.66803825785, 6046.455818976313, 6070.027241146645, 6087.726052327655, 6056.515478761688, 6075.48358389533, 6098.969505152446, 6114.755409783559, 6144.815655530341, 5615.5507559395965, 5868.853278668067, 5892.528597712087, 5933.169356290036, 5980.401959804159, 5792.304923409218, 5807.593468457168, 5810.4189581041555, 5826.41735826414, 5826.504747096375, 6023.30731965812, 6038.9396106038375, 6057.727402266997, 6074.726637301421, 6099.688566958125, 4036.535798383106, 4084.795760211918, 4085.89785255371, 4208.957910420857, 4232.195882782262, 5794.7102644866745, 5812.33158186797, 5859.275436921852, 5859.882802344015, 5862.208601838697, 5681.659100453986, 5700.201971724045, 5705.343885453226, 5717.342505611908, 5725.541956643542, 5457.481539253765, 5726.570507212059, 5758.740856661544, 5761.625494342854, 5776.653400795965, 5492.505674489315, 5612.663240205601, 5647.3505546861, 5650.971745141248, 5671.631343962629, 5620.690862002629, 5669.8943705976935, 5772.7690892363835, 5776.0, 5780.277465316835, 5625.8593535161945, 5633.63381380209, 5639.407862174577, 5647.576431767716, 5667.0], "value": 4285.702033007221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.2231545300167, 719.7049209823972, 799.4164260090134, 855.1448551448548, 860.7374750701038, 14855.182964936925, 15437.691246175069, 15629.140397278145, 15643.296051677673, 15674.764878526817, 13949.30253487324, 14116.0, 14173.433062677506, 14179.574612761608, 14180.645483862909, 658.5028303630752, 789.9763007109796, 802.8554860125175, 806.9636866341, 824.2499325613693, 811.736185739634, 819.3445243804932, 819.4878201124297, 820.6840366458897, 822.9711960081398, 850.9234168924795, 857.6097875466679, 858.2061593026457, 858.4505916213628, 858.9656413743443, 701.9964900175491, 838.0, 857.69123115678, 858.5063588436611, 861.5089399042548, 628.937106289372, 629.0187307799847, 629.128656810321, 629.7008920762647, 629.8425393651587, 13009.414576344087, 13046.804297935538, 13133.737325253536, 13141.27722975236, 13179.0, 12519.499220031188, 12610.432530536147, 12616.369181540886, 12748.872511274862, 13128.277944713132, 11873.940630296793, 12184.365142250605, 12225.388730563436, 12365.134440589163, 12442.875571244262, 3774.2451509697894, 3927.9410808837893, 4226.344916537942, 4286.657067434598, 4326.610605045561, 61242.69378653123, 61355.693221533606, 61888.38111618871, 63423.68288158529, 63443.0, 10858.375728102164, 10879.238453308351, 11096.889031109748, 11211.103111751203, 11537.48081336342, 19091.42725718203, 19147.329843455413, 19147.712784308245, 19164.329248476515, 19173.0, 7750.341220996278, 7785.649645765954, 7813.218678132255, 7835.138134805167, 8062.274395304451, 4619.514950930171, 4752.334673145762, 4932.753362331869, 4936.802527898915, 5005.6996580205305, 3427.1260828489076, 3506.1585219547123, 3512.7892326460496, 3514.68367846895, 3516.8593256269974, 609.3540846702509, 622.0979579609584, 624.8594066335038, 625.9123722678829, 644.3266786208329, 670.8289386206486, 685.7874059041714, 699.8565294114796, 702.3082263969985, 739.1942782367138, 8933.642654293755, 8967.910320896708, 9125.45247285165, 9235.676751313671, 9380.296477764196, 684.6218433120267, 686.4336922039338, 706.8114511352419, 722.6278466589711, 727.040306795036, 4231.61915427613, 4351.325544540601, 4355.324924636687, 4369.584889435499, 4413.558644135561, 68368.0, 73085.0, 73715.26284737191, 73925.26074739291, 74363.62818185874, 61919.38080619226, 61934.0, 61953.38046619566, 62889.0, 63341.0, 56794.43205567974, 58662.70668646629, 58835.939687237806, 59046.40953590409, 59926.70036649789, 8413.747587572383, 8445.055517777173, 8533.786655333666, 8589.355798315026, 8862.0, 38114.80942595269, 38425.42361864518, 38659.806700966314, 38768.80615597017, 39012.2197556053, 48469.5153048465, 49927.0, 51114.0, 53070.73464632652, 54516.45483545272, 10586.8411973822, 10920.836187457353, 11018.614348497764, 11020.724481888017, 11432.771344572895, 12038.0, 12146.757064858477, 13201.679832016722, 13648.93175534116, 14151.363188656329, 13396.866031339561, 13591.45634174622, 14078.436862525385, 14411.495597654031, 14506.564803055926, 13125.118606486258, 13284.309568115448, 13427.731445370842, 13475.797863031867, 13551.390187441391, 12953.676158096123, 13148.145370550883, 13523.59429217125, 13818.0, 14187.716245675236, 10700.83948740754, 10808.837867431837, 11108.555657773599, 11438.313701177956, 11633.6509904703, 11034.503447344729, 11382.260153090023, 11480.474295654942, 11503.424828758696, 11620.883791162318], "value": 6930.00306416176, "unit": "score"}], "name": "dromaeo_css", "value": 7035.579777382367, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 111.0, 113.0, 112.0, 111.0, 111.0, 111.0, 111.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 128.0, 177.0, 125.0, 126.0, 153.0, 124.0, 181.0, 124.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 162.0, 162.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 112.0, 76.0, 76.0, 75.0, 75.0, 97.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 119.0, 83.0, 82.0, 84.0, 119.0, 84.0, 83.0, 128.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 51.0, 52.0, 53.0, 53.0, 54.0, 53.0, 53.0, 55.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 63.0, 64.0, 63.0, 65.0, 64.0, 62.0, 66.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 79.0, 79.0, 80.0, 80.0, 81.0, 120.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 123.0, 118.0, 121.0, 118.0, 127.0, 129.0, 131.0, 123.0], "value": 123.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 158.0, 157.0, 157.0, 160.0, 161.0, 160.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 65.0, 64.0, 61.0, 62.0, 61.0, 65.0, 62.0, 62.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1492.1999999999998, "alertThreshold": 2.0}]} 22:22:51 INFO - Return code: 0 22:22:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:22:51 ERROR - # TBPL SUCCESS # 22:22:51 INFO - Running post-action listener: _resource_record_post_action 22:22:51 INFO - ##### 22:22:51 INFO - ##### Finished run-tests step (success) 22:22:51 INFO - ##### 22:22:51 INFO - Running post-run listener: _resource_record_post_run 22:22:51 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 551419904; Read time: 160; Write time: 593388 22:22:51 INFO - TinderboxPrint: CPU usage
13.2% 22:22:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 160 22:22:51 INFO - TinderboxPrint: I/O write bytes / time
551,419,904 / 593,388 22:22:51 INFO - TinderboxPrint: CPU idle
8,266.0 (86.8%) 22:22:51 INFO - TinderboxPrint: CPU user
1,208.8 (12.7%) 22:22:51 INFO - TinderboxPrint: Swap in / out
0 / 0 22:22:51 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 221646848; Read time: 0; Write time: 326840 22:22:51 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285241344; Read time: 160; Write time: 248252 22:22:52 INFO - Running post-run listener: _upload_blobber_files 22:22:52 INFO - Blob upload gear active. 22:22:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:22:52 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 22:22:52 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:22:52 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:22:52 INFO - (blobuploader) - INFO - Open directory for files ... 22:22:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:22:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:22:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:56 INFO - (blobuploader) - INFO - Done attempting. 22:22:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:22:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:22:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:57 INFO - (blobuploader) - INFO - Done attempting. 22:22:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:22:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:22:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:58 INFO - (blobuploader) - INFO - Done attempting. 22:22:58 INFO - (blobuploader) - INFO - Iteration through files over. 22:22:58 INFO - Return code: 0 22:22:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:22:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:22:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03c7677c6321d1f1ae684983387921093e31d41277aa98f1d156cdcdb4ed49aa03551c2bb94a3583e563bf8ff61b68461da45ed6fe6d073f5ea034687a8477", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5107edf5c44737d64f1faa399acc4b4780967fdef6523bcdfd49a2f11a08a627fce9f051d9acef31b5ad877e37ba45ebb8aa522df27bed0ff13d3848df8e8049", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dedf034933f03bcd104f2164cada90e24670dd1914b6fa0bdb05dd5793e158954bb702da441ff49de903d8ab0974276dad22843aa2c4016ce662d690eeb9b1fa"} 22:22:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:22:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:22:58 INFO - Contents: 22:22:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03c7677c6321d1f1ae684983387921093e31d41277aa98f1d156cdcdb4ed49aa03551c2bb94a3583e563bf8ff61b68461da45ed6fe6d073f5ea034687a8477", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5107edf5c44737d64f1faa399acc4b4780967fdef6523bcdfd49a2f11a08a627fce9f051d9acef31b5ad877e37ba45ebb8aa522df27bed0ff13d3848df8e8049", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dedf034933f03bcd104f2164cada90e24670dd1914b6fa0bdb05dd5793e158954bb702da441ff49de903d8ab0974276dad22843aa2c4016ce662d690eeb9b1fa"} 22:22:58 INFO - Running post-run listener: copy_logs_to_upload_dir 22:22:58 INFO - Copying logs to upload dir... 22:22:58 INFO - mkdir: /builds/slave/test/build/upload/logs 22:22:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.577722 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-26 22:22:57.599863) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 22:22:57.602947) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03c7677c6321d1f1ae684983387921093e31d41277aa98f1d156cdcdb4ed49aa03551c2bb94a3583e563bf8ff61b68461da45ed6fe6d073f5ea034687a8477", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5107edf5c44737d64f1faa399acc4b4780967fdef6523bcdfd49a2f11a08a627fce9f051d9acef31b5ad877e37ba45ebb8aa522df27bed0ff13d3848df8e8049", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dedf034933f03bcd104f2164cada90e24670dd1914b6fa0bdb05dd5793e158954bb702da441ff49de903d8ab0974276dad22843aa2c4016ce662d690eeb9b1fa"} build_url:https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011535 build_url: 'https://queue.taskcluster.net/v1/task/UPFihe7ZRxOpqZ_wLReUCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03c7677c6321d1f1ae684983387921093e31d41277aa98f1d156cdcdb4ed49aa03551c2bb94a3583e563bf8ff61b68461da45ed6fe6d073f5ea034687a8477", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5107edf5c44737d64f1faa399acc4b4780967fdef6523bcdfd49a2f11a08a627fce9f051d9acef31b5ad877e37ba45ebb8aa522df27bed0ff13d3848df8e8049", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dedf034933f03bcd104f2164cada90e24670dd1914b6fa0bdb05dd5793e158954bb702da441ff49de903d8ab0974276dad22843aa2c4016ce662d690eeb9b1fa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 22:22:57.628507) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:22:57.628820) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464325121.811568-1702378016 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:22:57.711138) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 22:22:57.711444) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 22:22:57.711742) ========= ========= Total master_lag: 0.15 =========