builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1463537888.41 results: success (0) buildid: 20160517163014 builduid: 0ab0d1d3b082424c9634698f7aa6a504 revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.413698) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.414144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.414421) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.443733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.444128) ========= 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=8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 19:18:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.9M=0s 2016-05-17 19:18:08 (35.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.179031 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.636250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.636620) ========= 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=8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019862 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.686819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:08.687169) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --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 f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --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=8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 19:18:08,692 truncating revision to first 12 chars 2016-05-17 19:18:08,693 Setting DEBUG logging. 2016-05-17 19:18:08,693 attempt 1/10 2016-05-17 19:18:08,693 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f3f2fa1d7eed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 19:18:08,954 unpacking tar archive at: mozilla-central-f3f2fa1d7eed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.536072 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:09.235636) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:09.235960) ========= script_repo_revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:09.236337) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:09.236617) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 19:18:09.243224) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-17 19:18:09.243570) ========= /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=8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:18:09 INFO - MultiFileLogger online at 20160517 19:18:09 in /builds/slave/test 19:18:09 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 19:18:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:18:09 INFO - {'append_to_log': False, 19:18:09 INFO - 'base_work_dir': '/builds/slave/test', 19:18:09 INFO - 'blob_upload_branch': 'Firefox', 19:18:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:18:09 INFO - 'branch': 'Firefox', 19:18:09 INFO - 'buildbot_json_path': 'buildprops.json', 19:18:09 INFO - 'config_files': ('talos/linux_config.py',), 19:18:09 INFO - 'default_actions': ('clobber', 19:18:09 INFO - 'read-buildbot-config', 19:18:09 INFO - 'download-and-extract', 19:18:09 INFO - 'populate-webroot', 19:18:09 INFO - 'create-virtualenv', 19:18:09 INFO - 'install', 19:18:09 INFO - 'run-tests'), 19:18:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:18:09 INFO - 'download_minidump_stackwalk': True, 19:18:09 INFO - 'download_symbols': 'ondemand', 19:18:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:18:09 INFO - 'tooltool.py': '/tools/tooltool.py', 19:18:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:18:09 INFO - '/tools/misc-python/virtualenv.py')}, 19:18:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:18:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:18:09 INFO - 'installer_path': 'installer.exe', 19:18:09 INFO - 'log_level': 'info', 19:18:09 INFO - 'log_name': 'talos', 19:18:09 INFO - 'log_to_console': True, 19:18:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:18:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:18:09 INFO - 'opt_config_files': (), 19:18:09 INFO - 'pip_index': False, 19:18:09 INFO - 'sps_profile': False, 19:18:09 INFO - 'sps_profile_interval': 0, 19:18:09 INFO - 'suite': 'dromaeojs', 19:18:09 INFO - 'system_bits': '32', 19:18:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:18:09 INFO - 'title': 'talos-linux64-ix-038', 19:18:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:18:09 INFO - 'use_talos_json': True, 19:18:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:18:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:18:09 INFO - 'work_dir': 'build'} 19:18:09 INFO - ##### 19:18:09 INFO - ##### Running clobber step. 19:18:09 INFO - ##### 19:18:09 INFO - Running pre-action listener: _resource_record_pre_action 19:18:09 INFO - Running main action method: clobber 19:18:09 INFO - rmtree: /builds/slave/test/build 19:18:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:18:10 INFO - Running post-action listener: _resource_record_post_action 19:18:10 INFO - ##### 19:18:10 INFO - ##### Finished clobber step (success) 19:18:10 INFO - ##### 19:18:10 INFO - ##### 19:18:10 INFO - ##### Running read-buildbot-config step. 19:18:10 INFO - ##### 19:18:10 INFO - Running pre-action listener: _resource_record_pre_action 19:18:10 INFO - Running main action method: read_buildbot_config 19:18:10 INFO - Using buildbot properties: 19:18:10 INFO - { 19:18:10 INFO - "project": "", 19:18:10 INFO - "product": "firefox", 19:18:10 INFO - "script_repo_revision": "production", 19:18:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:18:10 INFO - "repository": "", 19:18:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:18:10 INFO - "buildid": "20160517163014", 19:18:10 INFO - "pgo_build": "True", 19:18:10 INFO - "basedir": "/builds/slave/test", 19:18:10 INFO - "buildnumber": 290, 19:18:10 INFO - "slavename": "talos-linux64-ix-038", 19:18:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:18:10 INFO - "platform": "ubuntu64_hw", 19:18:10 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:18:10 INFO - "branch": "mozilla-central", 19:18:10 INFO - "repo_path": "mozilla-central", 19:18:10 INFO - "revision": "f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e", 19:18:10 INFO - "stage_platform": "linux64-pgo", 19:18:10 INFO - "builduid": "0ab0d1d3b082424c9634698f7aa6a504", 19:18:10 INFO - "slavebuilddir": "test" 19:18:10 INFO - } 19:18:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:18:10 INFO - Running post-action listener: _resource_record_post_action 19:18:10 INFO - ##### 19:18:10 INFO - ##### Finished read-buildbot-config step (success) 19:18:10 INFO - ##### 19:18:10 INFO - ##### 19:18:10 INFO - ##### Running download-and-extract step. 19:18:10 INFO - ##### 19:18:10 INFO - Running pre-action listener: _resource_record_pre_action 19:18:10 INFO - Running main action method: download_and_extract 19:18:10 INFO - mkdir: /builds/slave/test/build/tests 19:18:10 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')]} 19:18:10 INFO - https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:18:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:18:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:18:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:10 INFO - Downloaded 1361 bytes. 19:18:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:18:10 INFO - Using the following test package requirements: 19:18:10 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:18:10 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:18:10 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:18:10 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'jsshell-linux-x86_64.zip'], 19:18:10 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:18:10 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:18:10 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:18:10 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:18:10 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:18:10 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:18:10 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:18:10 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')]} 19:18:10 INFO - https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:18:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:18:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:18:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:11 INFO - Downloaded 24025305 bytes. 19:18:11 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'] 19:18:11 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 19:18:13 INFO - Return code: 0 19:18:13 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 19:18:13 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')]} 19:18:13 INFO - https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:18:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:18:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:18:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:14 INFO - Downloaded 24025305 bytes. 19:18:14 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'] 19:18:14 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 19:18:17 INFO - Return code: 0 19:18:17 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')]} 19:18:17 INFO - https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:18:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:18:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:18:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:19 INFO - Downloaded 10840194 bytes. 19:18:19 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'] 19:18:19 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 19:18:19 INFO - Return code: 0 19:18:19 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')]} 19:18:19 INFO - https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:18:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:18:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:18:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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 19:18:20 INFO - Downloaded 58419040 bytes. 19:18:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:18:20 INFO - mkdir: /builds/slave/test/properties 19:18:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:18:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:18:20 INFO - Contents: 19:18:20 INFO - build_url:https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:18:21 INFO - Running post-action listener: _resource_record_post_action 19:18:21 INFO - Running post-action listener: set_extra_try_arguments 19:18:21 INFO - ##### 19:18:21 INFO - ##### Finished download-and-extract step (success) 19:18:21 INFO - ##### 19:18:21 INFO - ##### 19:18:21 INFO - ##### Running populate-webroot step. 19:18:21 INFO - ##### 19:18:21 INFO - Running pre-action listener: _resource_record_pre_action 19:18:21 INFO - Running main action method: populate_webroot 19:18:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:18:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:18:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:18:21 INFO - '--tppagecycles', 19:18:21 INFO - '7'], 19:18:21 INFO - 'tests': ['tsvgm']}}, 19:18:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:18:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:18:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:18:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:18:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['damp', 'tps']}, 19:18:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'tests': ['damp', 'tps']}, 19:18:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['dromaeo_dom']}, 19:18:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:18:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['a11yr', 19:18:21 INFO - 'ts_paint', 19:18:21 INFO - 'tpaint', 19:18:21 INFO - 'sessionrestore', 19:18:21 INFO - 'sessionrestore_no_auto_restore', 19:18:21 INFO - 'tabpaint']}, 19:18:21 INFO - 'other-e10s': {'tests': ['a11yr', 19:18:21 INFO - 'ts_paint', 19:18:21 INFO - 'tpaint', 19:18:21 INFO - 'sessionrestore', 19:18:21 INFO - 'sessionrestore_no_auto_restore', 19:18:21 INFO - 'tabpaint']}, 19:18:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:18:21 INFO - 'ts_paint', 19:18:21 INFO - 'tpaint', 19:18:21 INFO - 'sessionrestore', 19:18:21 INFO - 'sessionrestore_no_auto_restore', 19:18:21 INFO - 'tabpaint']}, 19:18:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:18:21 INFO - 'ts_paint', 19:18:21 INFO - 'tpaint', 19:18:21 INFO - 'sessionrestore', 19:18:21 INFO - 'sessionrestore_no_auto_restore', 19:18:21 INFO - 'tabpaint']}, 19:18:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['a11yr', 19:18:21 INFO - 'ts_paint', 19:18:21 INFO - 'tpaint', 19:18:21 INFO - 'sessionrestore', 19:18:21 INFO - 'sessionrestore_no_auto_restore', 19:18:21 INFO - 'tabpaint']}, 19:18:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['a11yr', 19:18:21 INFO - 'ts_paint', 19:18:21 INFO - 'tpaint', 19:18:21 INFO - 'sessionrestore', 19:18:21 INFO - 'sessionrestore_no_auto_restore', 19:18:21 INFO - 'tabpaint']}, 19:18:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['tsvgx', 19:18:21 INFO - 'tsvgr_opacity', 19:18:21 INFO - 'tart', 19:18:21 INFO - 'tscrollx', 19:18:21 INFO - 'cart']}, 19:18:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:18:21 INFO - 'tsvgr_opacity', 19:18:21 INFO - 'tart', 19:18:21 INFO - 'tscrollx', 19:18:21 INFO - 'cart']}, 19:18:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'talos_options': ['--disable-e10s'], 19:18:21 INFO - 'tests': ['tp5o']}, 19:18:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'tests': ['tp5o']}, 19:18:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'talos_options': ['--disable-e10s', 19:18:21 INFO - '--xperf_path', 19:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:21 INFO - 'tests': ['tp5n']}, 19:18:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:21 INFO - 'talos_options': ['--xperf_path', 19:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:21 INFO - 'tests': ['tp5n']}}, 19:18:21 INFO - 'talos.zip': {'path': '', 19:18:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:18:21 INFO - Running post-action listener: _resource_record_post_action 19:18:21 INFO - ##### 19:18:21 INFO - ##### Finished populate-webroot step (success) 19:18:21 INFO - ##### 19:18:21 INFO - ##### 19:18:21 INFO - ##### Running create-virtualenv step. 19:18:21 INFO - ##### 19:18:21 INFO - Running pre-action listener: _resource_record_pre_action 19:18:21 INFO - Running main action method: create_virtualenv 19:18:21 INFO - Creating virtualenv /builds/slave/test/build/venv 19:18:21 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 19:18:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:18:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:18:21 INFO - Using real prefix '/usr' 19:18:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:18:22 INFO - Installing distribute.............................................................................................................................................................................................done. 19:18:31 INFO - Installing pip.................done. 19:18:31 INFO - Return code: 0 19:18:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:18:31 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')]} 19:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:31 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')]} 19:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:31 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:31 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 19:18:31 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 19:18:31 INFO - Using env: {'DISPLAY': ':0', 19:18:31 INFO - 'HOME': '/home/cltbld', 19:18:31 INFO - 'LANG': 'en_US.UTF-8', 19:18:31 INFO - 'LANGUAGE': 'en_US:en', 19:18:31 INFO - 'LOGNAME': 'cltbld', 19:18:31 INFO - 'MAIL': '/var/mail/cltbld', 19:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:31 INFO - 'MOZ_NO_REMOTE': '1', 19:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:31 INFO - 'NO_EM_RESTART': '1', 19:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:31 INFO - 'PWD': '/builds/slave/test', 19:18:31 INFO - 'SHELL': '/bin/bash', 19:18:31 INFO - 'SHLVL': '1', 19:18:31 INFO - 'TERM': 'linux', 19:18:31 INFO - 'TMOUT': '86400', 19:18:31 INFO - 'USER': 'cltbld', 19:18:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:31 INFO - '_': '/tools/buildbot/bin/python'} 19:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:31 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 19:18:31 INFO - Cleaning up... 19:18:31 INFO - Return code: 0 19:18:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:18:31 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')]} 19:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:31 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')]} 19:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:31 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:31 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 19:18:31 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 19:18:31 INFO - Using env: {'DISPLAY': ':0', 19:18:31 INFO - 'HOME': '/home/cltbld', 19:18:31 INFO - 'LANG': 'en_US.UTF-8', 19:18:31 INFO - 'LANGUAGE': 'en_US:en', 19:18:31 INFO - 'LOGNAME': 'cltbld', 19:18:31 INFO - 'MAIL': '/var/mail/cltbld', 19:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:31 INFO - 'MOZ_NO_REMOTE': '1', 19:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:31 INFO - 'NO_EM_RESTART': '1', 19:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:31 INFO - 'PWD': '/builds/slave/test', 19:18:31 INFO - 'SHELL': '/bin/bash', 19:18:31 INFO - 'SHLVL': '1', 19:18:31 INFO - 'TERM': 'linux', 19:18:31 INFO - 'TMOUT': '86400', 19:18:31 INFO - 'USER': 'cltbld', 19:18:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:31 INFO - '_': '/tools/buildbot/bin/python'} 19:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:32 INFO - Downloading/unpacking psutil>=3.1.1 19:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:18:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:18:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:18:34 INFO - Installing collected packages: psutil 19:18:34 INFO - Running setup.py install for psutil 19:18:34 INFO - building 'psutil._psutil_linux' extension 19:18:34 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 19:18:36 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 19:18:36 INFO - building 'psutil._psutil_posix' extension 19:18:36 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 19:18:37 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 19:18:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:18:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:18:37 INFO - Successfully installed psutil 19:18:37 INFO - Cleaning up... 19:18:37 INFO - Return code: 0 19:18:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:37 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:37 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 19:18:37 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 19:18:37 INFO - Using env: {'DISPLAY': ':0', 19:18:37 INFO - 'HOME': '/home/cltbld', 19:18:37 INFO - 'LANG': 'en_US.UTF-8', 19:18:37 INFO - 'LANGUAGE': 'en_US:en', 19:18:37 INFO - 'LOGNAME': 'cltbld', 19:18:37 INFO - 'MAIL': '/var/mail/cltbld', 19:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:37 INFO - 'MOZ_NO_REMOTE': '1', 19:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:37 INFO - 'NO_EM_RESTART': '1', 19:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:37 INFO - 'PWD': '/builds/slave/test', 19:18:37 INFO - 'SHELL': '/bin/bash', 19:18:37 INFO - 'SHLVL': '1', 19:18:37 INFO - 'TERM': 'linux', 19:18:37 INFO - 'TMOUT': '86400', 19:18:37 INFO - 'USER': 'cltbld', 19:18:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:37 INFO - '_': '/tools/buildbot/bin/python'} 19:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 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 19:18:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:18:39 INFO - Installing collected packages: mozsystemmonitor 19:18:39 INFO - Running setup.py install for mozsystemmonitor 19:18:39 INFO - Successfully installed mozsystemmonitor 19:18:39 INFO - Cleaning up... 19:18:39 INFO - Return code: 0 19:18:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:18: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')]} 19:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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')]} 19:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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', '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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:18: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 blobuploader==1.2.4 19:18:39 INFO - Using env: {'DISPLAY': ':0', 19:18:39 INFO - 'HOME': '/home/cltbld', 19:18:39 INFO - 'LANG': 'en_US.UTF-8', 19:18:39 INFO - 'LANGUAGE': 'en_US:en', 19:18:39 INFO - 'LOGNAME': 'cltbld', 19:18:39 INFO - 'MAIL': '/var/mail/cltbld', 19:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:39 INFO - 'MOZ_NO_REMOTE': '1', 19:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:39 INFO - 'NO_EM_RESTART': '1', 19:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:39 INFO - 'PWD': '/builds/slave/test', 19:18:39 INFO - 'SHELL': '/bin/bash', 19:18:39 INFO - 'SHLVL': '1', 19:18:39 INFO - 'TERM': 'linux', 19:18:39 INFO - 'TMOUT': '86400', 19:18:39 INFO - 'USER': 'cltbld', 19:18:39 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:39 INFO - '_': '/tools/buildbot/bin/python'} 19:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:39 INFO - Downloading/unpacking blobuploader==1.2.4 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:41 INFO - Downloading blobuploader-1.2.4.tar.gz 19:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:18:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:18:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 INFO - Downloading docopt-0.6.1.tar.gz 19:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:18:42 INFO - Installing collected packages: blobuploader, requests, docopt 19:18:42 INFO - Running setup.py install for blobuploader 19:18:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:18:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:18:42 INFO - Running setup.py install for requests 19:18:42 INFO - Running setup.py install for docopt 19:18:42 INFO - Successfully installed blobuploader requests docopt 19:18:42 INFO - Cleaning up... 19:18:43 INFO - Return code: 0 19:18:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:43 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:43 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 19:18:43 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 19:18:43 INFO - Using env: {'DISPLAY': ':0', 19:18:43 INFO - 'HOME': '/home/cltbld', 19:18:43 INFO - 'LANG': 'en_US.UTF-8', 19:18:43 INFO - 'LANGUAGE': 'en_US:en', 19:18:43 INFO - 'LOGNAME': 'cltbld', 19:18:43 INFO - 'MAIL': '/var/mail/cltbld', 19:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:43 INFO - 'MOZ_NO_REMOTE': '1', 19:18:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:43 INFO - 'NO_EM_RESTART': '1', 19:18:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:43 INFO - 'PWD': '/builds/slave/test', 19:18:43 INFO - 'SHELL': '/bin/bash', 19:18:43 INFO - 'SHLVL': '1', 19:18:43 INFO - 'TERM': 'linux', 19:18:43 INFO - 'TMOUT': '86400', 19:18:43 INFO - 'USER': 'cltbld', 19:18:43 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:43 INFO - '_': '/tools/buildbot/bin/python'} 19:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:18:43 INFO - Running setup.py (path:/tmp/pip-61wrtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:18:43 INFO - Running setup.py (path:/tmp/pip-G0aVjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:18:43 INFO - Running setup.py (path:/tmp/pip-PJ9LRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:18:43 INFO - Running setup.py (path:/tmp/pip-DN8LE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:18:43 INFO - Running setup.py (path:/tmp/pip-e4Dd0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:18:43 INFO - Running setup.py (path:/tmp/pip-WKMbXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:18:43 INFO - Running setup.py (path:/tmp/pip-X1quhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:18:43 INFO - Running setup.py (path:/tmp/pip-kzg9Jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:18:43 INFO - Running setup.py (path:/tmp/pip-7aIxck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:18:43 INFO - Running setup.py (path:/tmp/pip-Gks6mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:18:43 INFO - Running setup.py (path:/tmp/pip-ssjjuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:18:44 INFO - Running setup.py (path:/tmp/pip-EwW8bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:18:44 INFO - Running setup.py (path:/tmp/pip-KSvqOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:18:44 INFO - Running setup.py (path:/tmp/pip-rAE7bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:44 INFO - Running setup.py (path:/tmp/pip-MW_PF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:18:44 INFO - Running setup.py (path:/tmp/pip-WGXYl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:18:44 INFO - Running setup.py (path:/tmp/pip-dYCpTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:18:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:18:44 INFO - Running setup.py install for manifestparser 19:18:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:18:44 INFO - Running setup.py install for mozcrash 19:18:44 INFO - Running setup.py install for mozdebug 19:18:44 INFO - Running setup.py install for mozdevice 19:18:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:18:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:18:44 INFO - Running setup.py install for mozfile 19:18:44 INFO - Running setup.py install for mozhttpd 19:18:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for mozinfo 19:18:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for mozInstall 19:18:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for mozleak 19:18:45 INFO - Running setup.py install for mozlog 19:18:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for moznetwork 19:18:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for mozprocess 19:18:45 INFO - Running setup.py install for mozprofile 19:18:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for mozrunner 19:18:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:18:45 INFO - Running setup.py install for mozscreenshot 19:18:46 INFO - Running setup.py install for moztest 19:18:46 INFO - Running setup.py install for mozversion 19:18:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:18:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:18:46 INFO - Cleaning up... 19:18:46 INFO - Return code: 0 19:18:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:18:46 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')]} 19:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:46 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')]} 19:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:46 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:46 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 19:18:46 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 19:18:46 INFO - Using env: {'DISPLAY': ':0', 19:18:46 INFO - 'HOME': '/home/cltbld', 19:18:46 INFO - 'LANG': 'en_US.UTF-8', 19:18:46 INFO - 'LANGUAGE': 'en_US:en', 19:18:46 INFO - 'LOGNAME': 'cltbld', 19:18:46 INFO - 'MAIL': '/var/mail/cltbld', 19:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:46 INFO - 'MOZ_NO_REMOTE': '1', 19:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:46 INFO - 'NO_EM_RESTART': '1', 19:18:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:46 INFO - 'PWD': '/builds/slave/test', 19:18:46 INFO - 'SHELL': '/bin/bash', 19:18:46 INFO - 'SHLVL': '1', 19:18:46 INFO - 'TERM': 'linux', 19:18:46 INFO - 'TMOUT': '86400', 19:18:46 INFO - 'USER': 'cltbld', 19:18:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:46 INFO - '_': '/tools/buildbot/bin/python'} 19:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:18:46 INFO - Running setup.py (path:/tmp/pip-oHy_AV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:18:46 INFO - Running setup.py (path:/tmp/pip-3j3GxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:18:46 INFO - Running setup.py (path:/tmp/pip-rvRP1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:18:46 INFO - Running setup.py (path:/tmp/pip-VGyLRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:18:46 INFO - Running setup.py (path:/tmp/pip-T03tse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:18:46 INFO - Running setup.py (path:/tmp/pip-TnepoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:18:46 INFO - Running setup.py (path:/tmp/pip-aR5L2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:18:46 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)) 19:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:18:46 INFO - Running setup.py (path:/tmp/pip-lMvY68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:18:47 INFO - Running setup.py (path:/tmp/pip-7Mt5dP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:18:47 INFO - Running setup.py (path:/tmp/pip-A5bJep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:18:47 INFO - Running setup.py (path:/tmp/pip-rAI5J4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:18:47 INFO - Running setup.py (path:/tmp/pip-QNcGMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:18:47 INFO - Running setup.py (path:/tmp/pip-n_OJ2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:18:47 INFO - Running setup.py (path:/tmp/pip-gLFgw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:47 INFO - Running setup.py (path:/tmp/pip-6kPHXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:18:47 INFO - Running setup.py (path:/tmp/pip-44AQXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:18:47 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)) 19:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:18:47 INFO - Running setup.py (path:/tmp/pip-V8pmfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:18:47 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)) 19:18:47 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)) 19:18:47 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)) 19:18:47 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)) 19:18:47 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)) 19:18:47 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)) 19:18:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:18:47 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 19:18:47 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 19:18:47 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 19:18:47 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 19:18:47 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 19:18:47 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 19:18:49 INFO - Downloading blessings-1.6.tar.gz 19:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:18:49 INFO - Installing collected packages: blessings 19:18:49 INFO - Running setup.py install for blessings 19:18:49 INFO - Successfully installed blessings 19:18:49 INFO - Cleaning up... 19:18:49 INFO - Return code: 0 19:18:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:18:50 INFO - Reading from file tmpfile_stdout 19:18:50 INFO - Current package versions: 19:18:50 INFO - argparse == 1.2.1 19:18:50 INFO - blessings == 1.6 19:18:50 INFO - blobuploader == 1.2.4 19:18:50 INFO - docopt == 0.6.1 19:18:50 INFO - manifestparser == 1.1 19:18:50 INFO - mozInstall == 1.12 19:18:50 INFO - mozcrash == 0.17 19:18:50 INFO - mozdebug == 0.1 19:18:50 INFO - mozdevice == 0.48 19:18:50 INFO - mozfile == 1.2 19:18:50 INFO - mozhttpd == 0.7 19:18:50 INFO - mozinfo == 0.9 19:18:50 INFO - mozleak == 0.1 19:18:50 INFO - mozlog == 3.2 19:18:50 INFO - moznetwork == 0.27 19:18:50 INFO - mozprocess == 0.22 19:18:50 INFO - mozprofile == 0.28 19:18:50 INFO - mozrunner == 6.11 19:18:50 INFO - mozscreenshot == 0.1 19:18:50 INFO - mozsystemmonitor == 0.3 19:18:50 INFO - moztest == 0.7 19:18:50 INFO - mozversion == 1.4 19:18:50 INFO - psutil == 3.1.1 19:18:50 INFO - requests == 1.2.3 19:18:50 INFO - wsgiref == 0.1.2 19:18:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:50 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:50 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 19:18:50 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 19:18:50 INFO - Using env: {'DISPLAY': ':0', 19:18:50 INFO - 'HOME': '/home/cltbld', 19:18:50 INFO - 'LANG': 'en_US.UTF-8', 19:18:50 INFO - 'LANGUAGE': 'en_US:en', 19:18:50 INFO - 'LOGNAME': 'cltbld', 19:18:50 INFO - 'MAIL': '/var/mail/cltbld', 19:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:50 INFO - 'MOZ_NO_REMOTE': '1', 19:18:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:50 INFO - 'NO_EM_RESTART': '1', 19:18:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:50 INFO - 'PWD': '/builds/slave/test', 19:18:50 INFO - 'SHELL': '/bin/bash', 19:18:50 INFO - 'SHLVL': '1', 19:18:50 INFO - 'TERM': 'linux', 19:18:50 INFO - 'TMOUT': '86400', 19:18:50 INFO - 'USER': 'cltbld', 19:18:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:50 INFO - '_': '/tools/buildbot/bin/python'} 19:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 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)) 19:18:50 INFO - Cleaning up... 19:18:50 INFO - Return code: 0 19:18:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:50 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 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18:50 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 19:18:50 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 19:18:50 INFO - Using env: {'DISPLAY': ':0', 19:18:50 INFO - 'HOME': '/home/cltbld', 19:18:50 INFO - 'LANG': 'en_US.UTF-8', 19:18:50 INFO - 'LANGUAGE': 'en_US:en', 19:18:50 INFO - 'LOGNAME': 'cltbld', 19:18:50 INFO - 'MAIL': '/var/mail/cltbld', 19:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:50 INFO - 'MOZ_NO_REMOTE': '1', 19:18:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:50 INFO - 'NO_EM_RESTART': '1', 19:18:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:50 INFO - 'PWD': '/builds/slave/test', 19:18:50 INFO - 'SHELL': '/bin/bash', 19:18:50 INFO - 'SHLVL': '1', 19:18:50 INFO - 'TERM': 'linux', 19:18:50 INFO - 'TMOUT': '86400', 19:18:50 INFO - 'USER': 'cltbld', 19:18:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:50 INFO - '_': '/tools/buildbot/bin/python'} 19:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:50 INFO - Downloading/unpacking jsonschema 19:18:50 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 19:18:50 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 19:18:50 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 19:18:50 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 19:18:50 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 19:18:50 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 19:18:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:18:52 INFO - Downloading/unpacking functools32 (from jsonschema) 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:52 INFO - Downloading functools32-3.2.3-2.tar.gz 19:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:18:52 INFO - warning: no files found matching '*.txt' 19:18:52 INFO - no previously-included directories found matching 'build' 19:18:52 INFO - no previously-included directories found matching 'dist' 19:18:52 INFO - no previously-included directories found matching '.git*' 19:18:52 INFO - Installing collected packages: jsonschema, functools32 19:18:52 INFO - Running setup.py install for functools32 19:18:52 INFO - warning: no files found matching '*.txt' 19:18:52 INFO - no previously-included directories found matching 'build' 19:18:52 INFO - no previously-included directories found matching 'dist' 19:18:52 INFO - no previously-included directories found matching '.git*' 19:18:52 INFO - Successfully installed jsonschema functools32 19:18:52 INFO - Cleaning up... 19:18:52 INFO - Return code: 0 19:18:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:18: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')]} 19:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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')]} 19:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0517000f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2204770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, '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': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', '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 19:18: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', 'flake8'] in /builds/slave/test/build 19:18: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 flake8 19:18:52 INFO - Using env: {'DISPLAY': ':0', 19:18:52 INFO - 'HOME': '/home/cltbld', 19:18:52 INFO - 'LANG': 'en_US.UTF-8', 19:18:52 INFO - 'LANGUAGE': 'en_US:en', 19:18:52 INFO - 'LOGNAME': 'cltbld', 19:18:52 INFO - 'MAIL': '/var/mail/cltbld', 19:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:52 INFO - 'MOZ_NO_REMOTE': '1', 19:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:52 INFO - 'NO_EM_RESTART': '1', 19:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:52 INFO - 'PWD': '/builds/slave/test', 19:18:52 INFO - 'SHELL': '/bin/bash', 19:18:52 INFO - 'SHLVL': '1', 19:18:52 INFO - 'TERM': 'linux', 19:18:52 INFO - 'TMOUT': '86400', 19:18:52 INFO - 'USER': 'cltbld', 19:18:52 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:52 INFO - '_': '/tools/buildbot/bin/python'} 19:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:53 INFO - Downloading/unpacking flake8 19:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:18:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:18:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18: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 19:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:18:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:18:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:18:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:18:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:18:55 INFO - no previously-included directories found matching 'docs/_build' 19:18:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:18:55 INFO - Running setup.py install for pep8 19:18:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:18:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:18:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:18:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:18:55 INFO - no previously-included directories found matching 'docs/_build' 19:18:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:18:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:18:55 INFO - Cleaning up... 19:18:55 INFO - Return code: 0 19:18:55 INFO - Running post-action listener: _resource_record_post_action 19:18:55 INFO - Running post-action listener: _start_resource_monitoring 19:18:55 INFO - Starting resource monitoring. 19:18:55 INFO - ##### 19:18:55 INFO - ##### Finished create-virtualenv step (success) 19:18:55 INFO - ##### 19:18:55 INFO - ##### 19:18:55 INFO - ##### Running install step. 19:18:55 INFO - ##### 19:18:55 INFO - Running pre-action listener: _resource_record_pre_action 19:18:55 INFO - Running main action method: install 19:18:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:18:56 INFO - Reading from file tmpfile_stdout 19:18:56 INFO - Detecting whether we're running mozinstall >=1.0... 19:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:18:56 INFO - Reading from file tmpfile_stdout 19:18:56 INFO - Output received: 19:18:56 INFO - Usage: mozinstall [options] installer 19:18:56 INFO - Options: 19:18:56 INFO - -h, --help show this help message and exit 19:18:56 INFO - -d DEST, --destination=DEST 19:18:56 INFO - Directory to install application into. [default: 19:18:56 INFO - "/builds/slave/test"] 19:18:56 INFO - --app=APP Application being installed. [default: firefox] 19:18:56 INFO - mkdir: /builds/slave/test/build/application 19:18:56 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'] 19:18:56 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 19:19:12 INFO - Reading from file tmpfile_stdout 19:19:12 INFO - Output received: 19:19:12 INFO - /builds/slave/test/build/application/firefox/firefox 19:19:12 INFO - Running post-action listener: _resource_record_post_action 19:19:12 INFO - ##### 19:19:12 INFO - ##### Finished install step (success) 19:19:12 INFO - ##### 19:19:12 INFO - ##### 19:19:12 INFO - ##### Running run-tests step. 19:19:12 INFO - ##### 19:19:12 INFO - Running pre-action listener: _resource_record_pre_action 19:19:12 INFO - Running main action method: run_tests 19:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:19:12 INFO - Python 2.7.3 19:19:12 INFO - Return code: 0 19:19:12 INFO - grabbing minidump binary from tooltool 19:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:19:12 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 0x22939c0>, '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 0x22948c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:19:12 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 19:19:12 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 19:19:12 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 19:19:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:19:12 INFO - Return code: 0 19:19:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:19:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:19:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:19:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:19:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:19:13 INFO - Return code: 0 19:19:13 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/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 19:19:13 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/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 19:19:13 INFO - Using env: {'DISPLAY': ':0', 19:19:13 INFO - 'HOME': '/home/cltbld', 19:19:13 INFO - 'LANG': 'en_US.UTF-8', 19:19:13 INFO - 'LANGUAGE': 'en_US:en', 19:19:13 INFO - 'LOGNAME': 'cltbld', 19:19:13 INFO - 'MAIL': '/var/mail/cltbld', 19:19:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:19:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:13 INFO - 'MOZ_NO_REMOTE': '1', 19:19:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:19:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:13 INFO - 'NO_EM_RESTART': '1', 19:19:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:13 INFO - 'PWD': '/builds/slave/test', 19:19:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:19:13 INFO - 'SHELL': '/bin/bash', 19:19:13 INFO - 'SHLVL': '1', 19:19:13 INFO - 'TERM': 'linux', 19:19:13 INFO - 'TMOUT': '86400', 19:19:13 INFO - 'USER': 'cltbld', 19:19:13 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656', 19:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:13 INFO - '_': '/tools/buildbot/bin/python'} 19:19:13 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/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 19:19:14 INFO - mozversion application_buildid: 20160517163014 19:19:14 INFO - mozversion application_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 19:19:14 INFO - mozversion application_display_name: Nightly 19:19:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:19:14 INFO - mozversion application_name: Firefox 19:19:14 INFO - mozversion application_remotingname: firefox 19:19:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:19:14 INFO - mozversion application_vendor: Mozilla 19:19:14 INFO - mozversion application_version: 49.0a1 19:19:14 INFO - mozversion platform_buildid: 20160517163014 19:19:14 INFO - mozversion platform_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 19:19:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:19:14 INFO - mozversion platform_version: 49.0a1 19:19:14 INFO - using testdate: 1463537954 19:19:14 INFO - actual date: 1463537954 19:19:14 INFO - starting webserver on 'localhost:58854' 19:19:14 INFO - SUITE-START | Running 2 tests 19:19:14 INFO - TEST-START | dromaeo_css 19:19:14 INFO - operating with platform_type : linux_ 19:19:14 INFO - Initialising browser for dromaeo_css test... 19:19:14 INFO - TEST-INFO | started process 3288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTEAh2z/profile http://localhost:58854/getInfo.html) 19:19:22 INFO - PROCESS | 3288 | __metrics Screen width/height:1600/1200 19:19:22 INFO - PROCESS | 3288 | colorDepth:24 19:19:22 INFO - PROCESS | 3288 | Browser inner width/height: 1024/697 19:19:22 INFO - PROCESS | 3288 | __metrics 19:19:24 ERROR - PROCESS | 3288 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:19:25 INFO - TEST-INFO | 3288: exit 0 19:19:25 INFO - Browser initialized. 19:19:25 INFO - Running cycle 1/1 for dromaeo_css test... 19:19:25 INFO - TEST-INFO | started process 3375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTEAh2z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:36 INFO - PROCESS | 3375 | RSS: Main: 152231936 19:19:36 INFO - PROCESS | 3375 | 19:20:28 INFO - PROCESS | 3375 | 1463538028224 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] 19:22:36 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58854/tests/dromaeo/cssquery-ext.html) 19:22:37 INFO - PROCESS | 3375 | RSS: Main: 255561728 19:22:37 INFO - PROCESS | 3375 | 19:25:39 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-ext.html (next: http://localhost:58854/tests/dromaeo/cssquery-jquery.html) 19:25:40 INFO - PROCESS | 3375 | RSS: Main: 238030848 19:25:40 INFO - PROCESS | 3375 | 19:28:42 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58854/tests/dromaeo/cssquery-mootools.html) 19:28:43 INFO - PROCESS | 3375 | RSS: Main: 243617792 19:28:43 INFO - PROCESS | 3375 | 19:31:45 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58854/tests/dromaeo/cssquery-prototype.html) 19:31:45 INFO - PROCESS | 3375 | RSS: Main: 235991040 19:31:45 INFO - PROCESS | 3375 | 19:34:48 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58854/tests/dromaeo/cssquery-yui.html) 19:34:48 INFO - PROCESS | 3375 | RSS: Main: 223969280 19:34:48 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-yui.html (next: http://localhost:58854/tests/dromaeo/cssquery-dojo.html) 19:37:51 INFO - PROCESS | 3375 | RSS: Main: 265211904 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | __start_tp_report 19:37:51 INFO - PROCESS | 3375 | _x_x_mozilla_page_load 19:37:51 INFO - PROCESS | 3375 | _x_x_mozilla_page_load_details 19:37:51 INFO - PROCESS | 3375 | |i|pagename|runs| 19:37:51 INFO - PROCESS | 3375 | |0;dojo.html;3081.07567729681;3101.0076775431844;3136.372725454909;3143.5913331266934;3159.8736050557977;5313.840584782453;5397.514223719864;5458.317710286215;5469.288992430978;5483.7806487740445;5346.144616861293;5364.865878353043;5466.781328746855;5484.451554844513;5550.8057217997375;4828.613710903137;4924.753762311888;4942.18453955097;4961.454240033593;5112.207607820794;5008.624353173508;5026.673266237701;5054.646174767768;5066.290719299301;5080.77136528857;4895.11887860185;4900.166971614816;4915.336429582015;4962.4045114586115;4979.053979743837;4951.801927922878;4974.054929563371;5047.747612619354;5053.974730126326;5084.3644544431945;4880.926786098212;4991.07665081961;5007.924881126786;5037.29477873098;5071.847844564669;5255.343082114736;5297.576193904479;5317.680939143664;5375.462453754632;5394.352677678665;5844.649321040752;5867.090600956859;5877.9118313225335;5892.734826932799;5898.14476900847;5711.400302968212;5778.508826749731;5932.139839723259;5958.910616340759;5992.760289588411;5472.589555783332;5586.553075753971;5587.078132108198;5588.133839254922;5591.217229587861;6041.9382768881815;6056.848578785565;6072.180255665517;6072.696365181723;6112.663803490762;5532.474414930575;5667.291588551431;5681.886362272731;5854.151148083504;5869.236999190078;5810.273715785527;5819;5888.793892213752;5891.23413956183;5900.704964751745;5772.9711351442975;5785;5804.593678442468;5805.332386775491;5830.183774271605;5665.66006039639;5803.448672376117;5808.738606762706;5837.09525023622;5844.415558444121;4444.2222611042935;4448.844290449819;4452.9554704453185;4458.331250312479;4475.888102797456;3951.4072889066297;3956.8219430125714;3981.243563722883;3999.680025597945;4017.0760725032824;5259.15853463444;5369.221462887859;5464.18037294409;5494.945050549523;5517.4482551745305;5918.260217472816;5929.584929055011;5930.8813823723285;5932.79235226765;5953.553483488669;5476.2333273341765;5485.4788795064405;5500.669959802424;5501.037318469252;5513.751881165357;4786.760661966888;4891.755412229374;4932.1861892788;4948.628852836052;4969.229769385751;6071.696415179312;6072.483838873745;6079.544034197453;6084.30030546484;6131.957567213584;5612.186232996111;5938.910916336172;5948.672822994777;5952.255968003999;5972.5520585955355;5969.641821490724;6004.189434426471;6010.188624535632;6069.787557435645;6080.179175811209;5675.233843431084;5762.2221000164445;5763.106718458645;5763.452472015152;5765.164051212634;5659.3491748447805;5803.361630220588;5885.529157667462;5891.1457838614015;5891.675957822361;5675.120356344773;5739.598228123991;5754.56840736938;5756.971215143897;5798.478136967693;5695.8860822784145;5695.914561281501;5701.173329867063;5702.714864256854;5714.714264286852;5437.3747019091725;5535.6955367455175;5590.944090559043;5621.971890140687;5625.156226566048;5821.243238378984;5831.883362332815;5838.474537291664;5841.50347220491;5844.357120716632;5603.32760068778;5639.379668236409;5645.774169033357;5646.153077038477;5648.039833228443 19:37:51 INFO - PROCESS | 3375 | |1;ext.html;599.1851082527761;632.5098049012016;634.800037988034;637.4804534304542;650.9259721459588;33978;36567.268654626925;36854.81572592137;44468;45713;47665.7616711916;48015;48089.75955120228;48159;48187.277190842164;8828.646854125844;9012.8648070279;9019.143181397756;9019.909800901973;9024.954875225647;8851.380403371766;8919.509426981513;8933.15135062168;8944.463332200055;8952.641894324252;8954.104589541059;8990.865137022916;9003.594838232262;9010.324225683067;9013.414128081686;8945.329100317469;8984.8652270216;8988.59551320192;8988.820223595492;8995.95502022489;8765.517896515688;8842.823143537158;8847.601857916345;8853.380263381567;8860;43767;43917.78041109806;44008.119837603386;44080.338794918105;44151;3533.2756784859043;3628.183658676803;3661.578918424362;3828.8659896903755;4260.0414906646065;32195.370541535744;32463.837680811444;32514;32852.06795268708;32878.67121328804;3907.5506316773367;3915.882523524299;3943.38877473992;3962.9405558916637;3995.5604883462797;356920;359407;359427;360126.9999999974;361632;35148.64851351505;61285;61622.61075640883;61761.69119154375;61763;35919.28161436757;37789.05527361838;37917;37952;38020.04949876275;15804.60488487797;16108;16141.515754527383;16150;16153.757693634605;13520.932395338157;13547.390367433489;13578.728425431633;13581.388837502336;13598.660033499043;8594.226519613265;8610.87083693745;8613.267872230805;8693;8695.608697608623;3284.753643476749;3333.1667083229195;3355.3624811285777;3360.6639336066687;3408.9488657670154;1024.4007255755466;1027.2847629381176;1088.8584484017026;1104.5703091899697;1192.1118766518825;32306.81693402664;34374;34385.65614343875;34796.65203347985;35106;1224.9816252756216;1242.2918936206456;1246.7568824079217;1280.3918138883955;1298.9350532473338;17754.822451775315;18238.27046918135;18376.632467350577;18382.264709411742;18411;547287;548118;554195;556625;559858;61763.6911815447;62131;62748.372516275165;62819;62896;1332.9000324975473;1421.1686163594206;1459.547540262522;1468.4713503138862;1477.6527516033723;31065.223369415944;35503.46744798779;35676.82161589279;35809.46285805663;35812.28375432425;53068.20397694115;53241;53740.73129634327;53842.46157538374;53853.73073134766;413655;418750;419744;419751;421461;40696.3895541573;40990.79504602458;41314.79342603268;41718.58281417147;45187.32219016652;50255.989760409175;50724.23913641372;51490.48509514857;51792.22311665252;51865.22202166895;42364;42517.5748242526;42553.5744642562;42639.855888760714;43027.56972430361;44314.11371772611;44387;44416.11167776562;45488.31767523556;45493;43670.56329436665;43954.12091758083;44010;44888.77555612201;45222;37901.81049094737;38486;38601.80699096486;38810;39354.803225983684;29408.852955735085;29483;29834.850825746602;30005.549916750828;30241.395172096876 19:37:51 INFO - PROCESS | 3375 | |2;jquery.html;395.9564447910727;396.0771402631866;406.7905028910111;414.22333125389895;422.08197171152756;13266.867331326685;17990.82009179908;19104.044797760125;19599.902000489994;19600.31398901039;15010.699786004274;17474.21366038528;18110.185041673125;18646.720299195524;19457.805421945777;5726.942730572693;5914.112883067532;5942.524598032147;5947.940520594803;5959.821205363846;5843.79546715865;5922.3485416604135;5922.674252916088;5928.258967629046;5935.317438494585;4164.541900390955;6156.5382596305235;6426.132472116275;6452.548321617489;6584.176977877765;6520.934790652104;6526.347365263436;6531.934680653204;6563.573367731106;6612.173478315211;5666.914996275059;5794.391588883191;5814.273215848019;5830.679312637803;5836.38717934615;21606.027728752088;22456.55086898253;22897.656535151986;23235;23270;2494.9750502494926;2496.8002559795173;2548.413864811085;2657.0833062593374;3014.0505740691833;14122.50571230002;14161.291935403291;14392.784108238386;14549.854501454956;15103.773443398359;1867.906604669761;1883.256113835041;1935.6902895536787;1984.99007504963;2149.3981685128188;78938.6053069731;79032.60483697544;80011.19988800096;80312.59843700803;80938;10309.22680798928;10559.366438013743;11410.94294528522;11464.885351146382;11642.19545970376;14393.064450810663;14430.783538246935;14435.85564144366;14451.49419770324;14465.13209207451;8331;8547.273481753997;8655.523946182895;8705.47767133974;8846.823063538694;8444.493330400195;8522.48865068098;8625.741227763177;8688.826223475495;8875.822483550293;5923.437273459014;6348.333424990402;6651.63416012124;6791.694373753193;6823.215330236987;1948.7174359717762;1984.5666996096834;2005.1823799083234;2013.8590298678953;2040.2553068130026;949.4066208619613;1085.766560189563;1086.0659832544006;1113.2541197397763;1113.3041848844473;18116.799768258297;19025.238990440226;19656.606867862563;19819.405417837486;19834.60330793376;1075.634284343327;1124.7019539821954;1128.1482480727;1129.158776711339;1152.141654467427;11278.774424511465;11473.483693233824;11515.136364772676;11517.596884109184;11530.59642912494;497914;513600;522976;523312;524987;82388.9999999988;82690.58654706688;83828.58085709532;84369;84696.57651711826;1055.5144633468537;1091.579741799413;1113.732704150998;1118.6644006797928;1119.4906317625623;17385.652286954442;17395.694313234584;17609.383671571435;17691.469255922344;17863.642727145645;41489.792551037055;41574;41835;43470.13059738851;45010;53118.73440632772;57910;57947.710261448425;58054.4194558049;59104.704476477345;7628.313451789366;7753.961230193813;8416.83166336682;8780.253678437404;8800.823983520422;8975.910240897507;13259.933700331436;13490.662733431744;13526;13782.104163229358;8462.534560599226;11118.221724479208;11679;12336.259824410565;12349.814752778895;11909.464074116859;12046.638600841989;12063.698407539881;12141.453634586642;12169.878301216875;6716.1604799400075;10484.842727359248;12386.3187524687;12387.380630968235;12409.44157512896;8764.21122099014;8777.956110219407;8899.377043606886;9203.171714545722;9209.539523023956;10860.456977150952;12022.939885300868;12094.274343539415;12138.089643276613;12158.635240942785 19:37:51 INFO - PROCESS | 3375 | |3;mootools.html;1375.4498200719704;1530.341952960227;1595.0270335095593;1763.1008185825233;1897.3169658922786;8758.737237882866;9072.546372681365;9088.72733817985;9294.814103717921;9307.162355388014;9012.729618111467;9178.265738740902;9239.538023098852;9244.306676999235;9277.25781937445;2080.8139360564483;2369.2418426103654;2401.879906004697;2431.65956766052;2439.8536087834786;2426.9393265168337;2432.914847980321;2438.573249681299;2439.4145405102827;2439.426734717349;2390.1873363056557;2418.5525677749583;2424.903003879843;2430.428849220432;2431.4286142756528;1953.0430089256304;2344.22640528626;2391.6651668766385;2405.542946840112;2416.7220769611545;2361.0437772702085;2365.775251351119;2367.2898130560934;2368.171140100951;2401.327628264089;9086.182243598041;9127.452352858849;9143.177114059767;9146.2682985361;9146.634134634607;5434.864128396782;5602.523785478239;5609.158626206061;5609.80365687199;5616.747246373882;3548.077499850032;3576.8032758198287;3609.4405367168133;3661.761985470936;3682.5949145593963;1126.3467189030343;1134.863816342036;1154.1920655541155;1166.8424762657019;1167.7372591167004;12906.161099528501;12921.612351629467;12929.61211163676;12930.547430839875;12940;4866.586340161055;4903.5586797188425;4938.777755001033;4951.900961980812;5108.923366149511;10611.257211995086;10737.51681174349;10740.248182627141;10793.29843560166;10817.567297308176;4845.685030473008;5501.504864562208;5509.586780991362;5519.834404967857;5523.199136125239;4524.88687782808;4586.380838586815;4913.557779799764;5049.5960323174795;5069.239614057847;3993.5407428146136;4074.8777536673947;4145.564715704868;4154.5222299435345;4196.055887425305;1253.8181963615225;1304.8368953880765;1315.5132600937523;1327.858042083807;1349.8987575931844;911.6535716427715;1058.1534772182224;1059.1103473082649;1066.168388656849;1072.4905669806778;6090.720948600843;6418.133551970424;6431.710573024225;6472.644004579794;6486;447.27318108074377;455.62638636318565;461.86836751526323;1018.2973748113777;1066.7653116314398;4553.998120413504;4618.168729628632;4651.790669419885;4651.976740116278;4666.556677115736;11519.424028798527;11685.766284674259;11844.407779610985;11856.407179640983;11874;11751.588694395827;11787.823182652268;11803.704907377212;11856.76286474283;11906.642800715992;8424.651049219332;8531.872021919677;8625.309975201968;8648.394612377198;8659.523726194993;6427.357264273535;6468.902966455413;6484.935150648622;6566.671666416755;6584.934150658623;10585.364878107339;10786.083182929538;10887.23789334739;10910.61812836547;10911.399873007062;10715.196360272856;10837.945810270898;11023.669289921316;11093.33439993603;11126.554937802397;6958;7521.360684341674;7612.961935190288;7849.529028258323;7966.1237263902085;9149.634014639607;9155.13026262504;9197.310201734763;9209.539523023688;9235.492047937429;8088.555129467701;8260.545669988209;8275.668973241243;8333.874991875005;8358.498490090371;7890.092639346549;7969.242921922408;7980.241877021674;8007.079185893696;8073.596320184085;7343.963280183565;7347.853042939005;7364.005859208938;7457.403407727477;7536.472446928662;5512.5314348280845;5555.833325000256;5578.86052848682;5596.496315331639;5666.490015898589;5504.2018907257425;5548.805791797267;5605.495505404533;5608.635438696471;5641.294838145232 19:37:51 INFO - PROCESS | 3375 | |4;prototype.html;312.4999999999999;326.18941929305686;326.32288002395023;329.13437658956946;334.2879666310758;5322.680639161653;5331.173668081444;5479.315085614298;5504.807331743385;5505.1467022611505;5431.945680543204;5506.91739623906;5509.917351239735;5512.9173062404;5537.833864984047;4249.999999999993;4269.893252668692;4290.54949230331;4290.978545107286;4309.832273279138;3944.2703099926607;4170.812313445902;4240.300350442173;4267.338562522798;4274.273373526493;4232.407462955189;4238.173556156551;4245.405643209953;4260.595243451882;4260.659147268211;4163.542010378856;4225.204288177528;4233.470816147981;4272.850450234242;4272.87181384559;3625.274945011008;3657.908552286214;3741.9064523387133;3754.8310326035394;3755.422722456575;5159.767810448501;5195.428502864682;5197.038547868657;5226.137687281594;5261.342332208474;4802.663813533054;4835.395575553056;4853.150698627761;4873.951260487385;4875.219964805614;5339.6262261641705;5578.609497335188;5606.495415412592;5623.606347555673;5650.604457687963;4091.2022155679656;4095.52901416335;4102.569230230843;4141.068259641586;4144.316187829035;5758.107493338539;5763.279590051244;5878.706064696791;5924.20023296858;5951.821445356646;3904.0239940014994;4072.2466343726505;4090.877273681794;4135.834566617302;4146.813393397304;4146.771927544014;4178.850816025617;4208.579142085767;4214.798782347017;4214.810333534998;4725.645576581702;4731.52684731531;4754.595859351924;4755.286706993909;4810.254410566367;5499.807506737325;5527.226188333637;5612.607117501736;5625.774969001276;5645.95118635932;1336.9131006484743;1425.173399428329;1438.5109062918657;1472.4036765110147;1478.3199728125182;899.2626046641691;946.4605175050156;948.245745371058;950.8193443245899;954.556131398889;4745;4801.903961920742;4841.8547443576745;4854.393200849894;4855.393075865517;5141.280220769096;5144.6655967362;5157.252198431206;5160.612954028388;5162.5095615916425;4844.878878028007;4851.913677118802;4853.247746599282;4890.804367825247;5049.722265275372;3081.198888289016;3098.7675924305768;3134.2477805326557;3145.292309230405;3167.4298626247037;6033.582108204569;6053.789242151499;6061.8484537886025;6097.634141951497;6120.265568131853;5713.714314284269;5903.822885313361;5907.9704601476715;5920.378360272195;5923.644581325134;5974.3726908674835;5977.32634862248;5978.192943952685;5991.790287340009;5995.760169593254;5587.55299576041;5745.454181852718;5752.827415177551;5791.449812267828;5891.705414729332;5791.247137872049;5809.344336863974;5810.215620891126;5813.895359881609;5814.313848983747;5973.01445261527;6067.089936509559;6083.178770865877;6089.665068421279;6092.268927728701;3879.903002424962;4046;4074.1851629673592;4082.081531655354;4157.605027522381;5700.600957932905;5746.482816546531;5759;5859.150423188528;5889;5564.772431989517;5583.385827559046;5658.179563963285;5672.858178545569;5777.335606405317;5624.521915637217;5645.8426022664435;5659.858503537444;5670.914936276042;5776.508996735324;5350.652207606493;5565.248691426767;5565.749541270571;5579.860503487445;5593.517717804703;5679.006173919465;5706.514946229617;5738.426157384228;5777.595568310178;5810.622309549865;5424.810131645373;5526.557875370041;5564.749586268546;5575.386707462095;5592.972035139798 19:37:51 INFO - PROCESS | 3375 | |5;yui.html;803.2088392932961;815.2825513548077;842.8440738463385;868.1659249841066;890.376736284601;14987.850121498781;15871.206439678028;15951.282192301345;16031.679366412665;16057.357705691773;12406.503739850417;13261.336933153329;13308.201507909533;13410.932945335284;13620.863791362084;841.7390608911257;854.2311919272643;854.2525290370926;854.7350321400386;855.2943821347383;832.3216578488543;836.8870202522643;837.2966707965307;839.6053854688287;839.9496030238174;897.4884315939917;898.4250079948838;899.3210126354593;899.4962820820328;899.8470260055774;890.5102193793388;893.5219657483248;893.8793262909489;894.3605322194629;894.7494701483595;651.8044586624042;652.1261509577189;652.2368828470701;652.26620052441;652.9706163222667;13414.061015728903;13650.590482285548;13658.248796316198;13669.17984920898;13709.862901370958;12549.309787961658;12659.620211393672;12680.80978785319;12733.490660373574;12834.871651283462;11001.724956876062;11078.224524283303;11091.55633774648;11201.887981120248;11315;3613.241219343941;3732.029672285198;3806.238752249561;3810;3893.9415908761393;56726.432735672526;57396;57463;58253.41746582522;58560.82878342452;9822.803543929082;9920.15678667307;9931.503424828874;9957.103860652576;10212.540435680412;18511.81488185128;18518.722219166724;18540;18552.907235464005;18557.16492757829;8591.3556483264;8625.611847466878;8706.042335343107;8949.731508054769;9059.139381758723;4645.187092258841;4900;4948.975255123702;4993.675411098267;5098.362704661918;3360.22714775603;3491.389006923778;3491.4937334086417;3494.545709057806;3494.982525087358;603.9214902062791;628.1864984844638;635.4725577770439;638.7189636559899;656.6257233377024;727.1710250314645;796.4623878881731;796.6614188969664;800.415696541528;818.1981657975217;9669.178119859891;9916.206703463706;9957.352772069937;10016.899831001743;10110.69667909964;593.7654626422539;697.3410127429631;712.914450265961;775.097011981047;779.8245394786242;4632.536746325408;4654.906901862011;4717.882052948704;4760.238361862085;4761.071591039783;70715.29284707087;71050.64474677593;72491.63754181196;72536;72662;44999;56093.71953140208;58553.414465854796;63588.68205658942;68858.65570672214;54815.177772333445;55988.720056399456;56102.43897561054;56359;57417;9816.558254878404;10091.142252908292;10129.696109116738;10131.949340253252;10219.386836789816;38071;38462.42306365351;38778;39581;39748.20503589854;50667.74666126646;52252;52377;52411.73794131005;52480;12433.813492797795;12500.624981250578;12527.37363131858;12898.613041608767;13282.601521954357;13640.522581709592;14321;14714.117152970855;15016.324265407864;15204.087754734752;13716.314184290546;13758.31208439554;13815.447382104603;14310.284485775877;14624.926875365554;13058.412371443497;13251.469941202244;13797.862021379658;14252.572422827332;14555.636109097357;12187.153433444064;12312.630621081382;12422.627321180378;12592.748145036865;12610.936945315214;11164.776704466027;11215.439228038402;11445.370504622326;11522.884771152181;11568.710782230512;11044.392558409365;11092.611758588411;11383.203175777617;11511.654650360502;11737.35444550558 19:37:51 INFO - PROCESS | 3375 | __end_tp_report 19:37:51 INFO - PROCESS | 3375 | __start_cc_report 19:37:51 INFO - PROCESS | 3375 | _x_x_mozilla_cycle_collect,835 19:37:51 INFO - PROCESS | 3375 | __end_cc_report 19:37:51 INFO - PROCESS | 3375 | __startTimestamp1463539071589__endTimestamp 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | ------- Summary: start ------- 19:37:51 INFO - PROCESS | 3375 | Number of tests: 6 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | [#0] dojo.html Cycles:165 Average:5426.63 Median:5625.16 stddev:629.95 (11.2%) stddev-sans-first:604.41 19:37:51 INFO - PROCESS | 3375 | Values: 3081.1 3101.0 3136.4 3143.6 3159.9 5313.8 5397.5 5458.3 5469.3 5483.8 5346.1 5364.9 5466.8 5484.5 5550.8 4828.6 4924.8 4942.2 4961.5 5112.2 5008.6 5026.7 5054.6 5066.3 5080.8 4895.1 4900.2 4915.3 4962.4 4979.1 4951.8 4974.1 5047.7 5054.0 5084.4 4880.9 4991.1 5007.9 5037.3 5071.8 5255.3 5297.6 5317.7 5375.5 5394.4 5844.6 5867.1 5877.9 5892.7 5898.1 5711.4 5778.5 5932.1 5958.9 5992.8 5472.6 5586.6 5587.1 5588.1 5591.2 6041.9 6056.8 6072.2 6072.7 6112.7 5532.5 5667.3 5681.9 5854.2 5869.2 5810.3 5819.0 5888.8 5891.2 5900.7 5773.0 5785.0 5804.6 5805.3 5830.2 5665.7 5803.4 5808.7 5837.1 5844.4 4444.2 4448.8 4453.0 4458.3 4475.9 3951.4 3956.8 3981.2 3999.7 4017.1 5259.2 5369.2 5464.2 5494.9 5517.4 5918.3 5929.6 5930.9 5932.8 5953.6 5476.2 5485.5 5500.7 5501.0 5513.8 4786.8 4891.8 4932.2 4948.6 4969.2 6071.7 6072.5 6079.5 6084.3 6132.0 5612.2 5938.9 5948.7 5952.3 5972.6 5969.6 6004.2 6010.2 6069.8 6080.2 5675.2 5762.2 5763.1 5763.5 5765.2 5659.3 5803.4 5885.5 5891.1 5891.7 5675.1 5739.6 5754.6 5757.0 5798.5 5695.9 5695.9 5701.2 5702.7 5714.7 5437.4 5535.7 5590.9 5622.0 5625.2 5821.2 5831.9 5838.5 5841.5 5844.4 5603.3 5639.4 5645.8 5646.2 5648.0 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | [#1] ext.html Cycles:180 Average:60679.14 Median:420606.00 stddev:119665.43 (28.5%) stddev-sans-first:119916.10 19:37:51 INFO - PROCESS | 3375 | Values: 599.2 632.5 634.8 637.5 650.9 33978.0 36567.3 36854.8 44468.0 45713.0 47665.8 48015.0 48089.8 48159.0 48187.3 8828.6 9012.9 9019.1 9019.9 9025.0 8851.4 8919.5 8933.2 8944.5 8952.6 8954.1 8990.9 9003.6 9010.3 9013.4 8945.3 8984.9 8988.6 8988.8 8996.0 8765.5 8842.8 8847.6 8853.4 8860.0 43767.0 43917.8 44008.1 44080.3 44151.0 3533.3 3628.2 3661.6 3828.9 4260.0 32195.4 32463.8 32514.0 32852.1 32878.7 3907.6 3915.9 3943.4 3962.9 3995.6 356920.0 359407.0 359427.0 360127.0 361632.0 35148.6 61285.0 61622.6 61761.7 61763.0 35919.3 37789.1 37917.0 37952.0 38020.0 15804.6 16108.0 16141.5 16150.0 16153.8 13520.9 13547.4 13578.7 13581.4 13598.7 8594.2 8610.9 8613.3 8693.0 8695.6 3284.8 3333.2 3355.4 3360.7 3408.9 1024.4 1027.3 1088.9 1104.6 1192.1 32306.8 34374.0 34385.7 34796.7 35106.0 1225.0 1242.3 1246.8 1280.4 1298.9 17754.8 18238.3 18376.6 18382.3 18411.0 547287.0 548118.0 554195.0 556625.0 559858.0 61763.7 62131.0 62748.4 62819.0 62896.0 1332.9 1421.2 1459.5 1468.5 1477.7 31065.2 35503.5 35676.8 35809.5 35812.3 53068.2 53241.0 53740.7 53842.5 53853.7 413655.0 418750.0 419744.0 419751.0 421461.0 40696.4 40990.8 41314.8 41718.6 45187.3 50256.0 50724.2 51490.5 51792.2 51865.2 42364.0 42517.6 42553.6 42639.9 43027.6 44314.1 44387.0 44416.1 45488.3 45493.0 43670.6 43954.1 44010.0 44888.8 45222.0 37901.8 38486.0 38601.8 38810.0 39354.8 29408.9 29483.0 29834.9 30005.5 30241.4 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | [#2] jquery.html Cycles:180 Average:29627.07 Median:12882.49 stddev:84819.33 (658.4%) stddev-sans-first:85028.87 19:37:51 INFO - PROCESS | 3375 | Values: 396.0 396.1 406.8 414.2 422.1 13266.9 17990.8 19104.0 19599.9 19600.3 15010.7 17474.2 18110.2 18646.7 19457.8 5726.9 5914.1 5942.5 5947.9 5959.8 5843.8 5922.3 5922.7 5928.3 5935.3 4164.5 6156.5 6426.1 6452.5 6584.2 6520.9 6526.3 6531.9 6563.6 6612.2 5666.9 5794.4 5814.3 5830.7 5836.4 21606.0 22456.6 22897.7 23235.0 23270.0 2495.0 2496.8 2548.4 2657.1 3014.1 14122.5 14161.3 14392.8 14549.9 15103.8 1867.9 1883.3 1935.7 1985.0 2149.4 78938.6 79032.6 80011.2 80312.6 80938.0 10309.2 10559.4 11410.9 11464.9 11642.2 14393.1 14430.8 14435.9 14451.5 14465.1 8331.0 8547.3 8655.5 8705.5 8846.8 8444.5 8522.5 8625.7 8688.8 8875.8 5923.4 6348.3 6651.6 6791.7 6823.2 1948.7 1984.6 2005.2 2013.9 2040.3 949.4 1085.8 1086.1 1113.3 1113.3 18116.8 19025.2 19656.6 19819.4 19834.6 1075.6 1124.7 1128.1 1129.2 1152.1 11278.8 11473.5 11515.1 11517.6 11530.6 497914.0 513600.0 522976.0 523312.0 524987.0 82389.0 82690.6 83828.6 84369.0 84696.6 1055.5 1091.6 1113.7 1118.7 1119.5 17385.7 17395.7 17609.4 17691.5 17863.6 41489.8 41574.0 41835.0 43470.1 45010.0 53118.7 57910.0 57947.7 58054.4 59104.7 7628.3 7754.0 8416.8 8780.3 8800.8 8975.9 13259.9 13490.7 13526.0 13782.1 8462.5 11118.2 11679.0 12336.3 12349.8 11909.5 12046.6 12063.7 12141.5 12169.9 6716.2 10484.8 12386.3 12387.4 12409.4 8764.2 8778.0 8899.4 9203.2 9209.5 10860.5 12022.9 12094.3 12138.1 12158.6 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | [#3] mootools.html Cycles:180 Average:6119.01 Median:2524.12 stddev:3519.33 (139.4%) stddev-sans-first:3511.14 19:37:51 INFO - PROCESS | 3375 | Values: 1375.4 1530.3 1595.0 1763.1 1897.3 8758.7 9072.5 9088.7 9294.8 9307.2 9012.7 9178.3 9239.5 9244.3 9277.3 2080.8 2369.2 2401.9 2431.7 2439.9 2426.9 2432.9 2438.6 2439.4 2439.4 2390.2 2418.6 2424.9 2430.4 2431.4 1953.0 2344.2 2391.7 2405.5 2416.7 2361.0 2365.8 2367.3 2368.2 2401.3 9086.2 9127.5 9143.2 9146.3 9146.6 5434.9 5602.5 5609.2 5609.8 5616.7 3548.1 3576.8 3609.4 3661.8 3682.6 1126.3 1134.9 1154.2 1166.8 1167.7 12906.2 12921.6 12929.6 12930.5 12940.0 4866.6 4903.6 4938.8 4951.9 5108.9 10611.3 10737.5 10740.2 10793.3 10817.6 4845.7 5501.5 5509.6 5519.8 5523.2 4524.9 4586.4 4913.6 5049.6 5069.2 3993.5 4074.9 4145.6 4154.5 4196.1 1253.8 1304.8 1315.5 1327.9 1349.9 911.7 1058.2 1059.1 1066.2 1072.5 6090.7 6418.1 6431.7 6472.6 6486.0 447.3 455.6 461.9 1018.3 1066.8 4554.0 4618.2 4651.8 4652.0 4666.6 11519.4 11685.8 11844.4 11856.4 11874.0 11751.6 11787.8 11803.7 11856.8 11906.6 8424.7 8531.9 8625.3 8648.4 8659.5 6427.4 6468.9 6484.9 6566.7 6584.9 10585.4 10786.1 10887.2 10910.6 10911.4 10715.2 10837.9 11023.7 11093.3 11126.6 6958.0 7521.4 7613.0 7849.5 7966.1 9149.6 9155.1 9197.3 9209.5 9235.5 8088.6 8260.5 8275.7 8333.9 8358.5 7890.1 7969.2 7980.2 8007.1 8073.6 7344.0 7347.9 7364.0 7457.4 7536.5 5512.5 5555.8 5578.9 5596.5 5666.5 5504.2 5548.8 5605.5 5608.6 5641.3 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | [#4] prototype.html Cycles:180 Average:4721.85 Median:5326.93 stddev:1388.53 (26.1%) stddev-sans-first:1352.41 19:37:51 INFO - PROCESS | 3375 | Values: 312.5 326.2 326.3 329.1 334.3 5322.7 5331.2 5479.3 5504.8 5505.1 5431.9 5506.9 5509.9 5512.9 5537.8 4250.0 4269.9 4290.5 4291.0 4309.8 3944.3 4170.8 4240.3 4267.3 4274.3 4232.4 4238.2 4245.4 4260.6 4260.7 4163.5 4225.2 4233.5 4272.9 4272.9 3625.3 3657.9 3741.9 3754.8 3755.4 5159.8 5195.4 5197.0 5226.1 5261.3 4802.7 4835.4 4853.2 4874.0 4875.2 5339.6 5578.6 5606.5 5623.6 5650.6 4091.2 4095.5 4102.6 4141.1 4144.3 5758.1 5763.3 5878.7 5924.2 5951.8 3904.0 4072.2 4090.9 4135.8 4146.8 4146.8 4178.9 4208.6 4214.8 4214.8 4725.6 4731.5 4754.6 4755.3 4810.3 5499.8 5527.2 5612.6 5625.8 5646.0 1336.9 1425.2 1438.5 1472.4 1478.3 899.3 946.5 948.2 950.8 954.6 4745.0 4801.9 4841.9 4854.4 4855.4 5141.3 5144.7 5157.3 5160.6 5162.5 4844.9 4851.9 4853.2 4890.8 5049.7 3081.2 3098.8 3134.2 3145.3 3167.4 6033.6 6053.8 6061.8 6097.6 6120.3 5713.7 5903.8 5908.0 5920.4 5923.6 5974.4 5977.3 5978.2 5991.8 5995.8 5587.6 5745.5 5752.8 5791.4 5891.7 5791.2 5809.3 5810.2 5813.9 5814.3 5973.0 6067.1 6083.2 6089.7 6092.3 3879.9 4046.0 4074.2 4082.1 4157.6 5700.6 5746.5 5759.0 5859.2 5889.0 5564.8 5583.4 5658.2 5672.9 5777.3 5624.5 5645.8 5659.9 5670.9 5776.5 5350.7 5565.2 5565.7 5579.9 5593.5 5679.0 5706.5 5738.4 5777.6 5810.6 5424.8 5526.6 5564.7 5575.4 5593.0 19:37:51 INFO - PROCESS | 3375 | 19:37:51 INFO - PROCESS | 3375 | [#5] yui.html Cycles:180 Average:15919.31 Median:4686.39 stddev:19125.52 (408.1%) stddev-sans-first:19145.49 19:37:51 INFO - PROCESS | 3375 | Values: 803.2 815.3 842.8 868.2 890.4 14987.9 15871.2 15951.3 16031.7 16057.4 12406.5 13261.3 13308.2 13410.9 13620.9 841.7 854.2 854.3 854.7 855.3 832.3 836.9 837.3 839.6 839.9 897.5 898.4 899.3 899.5 899.8 890.5 893.5 893.9 894.4 894.7 651.8 652.1 652.2 652.3 653.0 13414.1 13650.6 13658.2 13669.2 13709.9 12549.3 12659.6 12680.8 12733.5 12834.9 11001.7 11078.2 11091.6 11201.9 11315.0 3613.2 3732.0 3806.2 3810.0 3893.9 56726.4 57396.0 57463.0 58253.4 58560.8 9822.8 9920.2 9931.5 9957.1 10212.5 18511.8 18518.7 18540.0 18552.9 18557.2 8591.4 8625.6 8706.0 8949.7 9059.1 4645.2 4900.0 4949.0 4993.7 5098.4 3360.2 3491.4 3491.5 3494.5 3495.0 603.9 628.2 635.5 638.7 656.6 727.2 796.5 796.7 800.4 818.2 9669.2 9916.2 9957.4 10016.9 10110.7 593.8 697.3 712.9 775.1 779.8 4632.5 4654.9 4717.9 4760.2 4761.1 70715.3 71050.6 72491.6 72536.0 72662.0 44999.0 56093.7 58553.4 63588.7 68858.7 54815.2 55988.7 56102.4 56359.0 57417.0 9816.6 10091.1 10129.7 10131.9 10219.4 38071.0 38462.4 38778.0 39581.0 39748.2 50667.7 52252.0 52377.0 52411.7 52480.0 12433.8 12500.6 12527.4 12898.6 13282.6 13640.5 14321.0 14714.1 15016.3 15204.1 13716.3 13758.3 13815.4 14310.3 14624.9 13058.4 13251.5 13797.9 14252.6 14555.6 12187.2 12312.6 12422.6 12592.7 12610.9 11164.8 11215.4 11445.4 11522.9 11568.7 11044.4 11092.6 11383.2 11511.7 11737.4 19:37:51 INFO - PROCESS | 3375 | -------- Summary: end -------- 19:37:51 INFO - PROCESS | 3375 | 19:37:52 INFO - TEST-INFO | 3375: exit 0 19:37:52 INFO - TEST-OK | dromaeo_css | took 1118281ms 19:37:52 INFO - TEST-START | kraken 19:37:52 INFO - operating with platform_type : linux_ 19:37:52 INFO - Initialising browser for kraken test... 19:37:52 INFO - TEST-INFO | started process 3553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDFgN9y/profile http://localhost:58854/getInfo.html) 19:37:58 INFO - PROCESS | 3553 | __metrics Screen width/height:1600/1200 19:37:58 INFO - PROCESS | 3553 | colorDepth:24 19:37:58 INFO - PROCESS | 3553 | Browser inner width/height: 1024/697 19:37:58 INFO - PROCESS | 3553 | __metrics 19:38:00 ERROR - PROCESS | 3553 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:38:01 INFO - TEST-INFO | 3553: exit 0 19:38:01 INFO - Browser initialized. 19:38:01 INFO - Running cycle 1/1 for kraken test... 19:38:01 INFO - TEST-INFO | started process 3634 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDFgN9y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:38:13 INFO - PROCESS | 3634 | RSS: Main: 152477696 19:38:13 INFO - PROCESS | 3634 | 19:38:16 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-beat-detection) 19:38:16 INFO - PROCESS | 3634 | RSS: Main: 208977920 19:38:16 INFO - PROCESS | 3634 | 19:38:19 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-dft) 19:38:20 INFO - PROCESS | 3634 | RSS: Main: 197967872 19:38:20 INFO - PROCESS | 3634 | 19:38:23 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-fft) 19:38:24 INFO - PROCESS | 3634 | RSS: Main: 265359360 19:38:24 INFO - PROCESS | 3634 | 19:38:26 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-oscillator) 19:38:26 INFO - PROCESS | 3634 | RSS: Main: 174751744 19:38:26 INFO - PROCESS | 3634 | 19:38:29 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58854/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:38:29 INFO - PROCESS | 3634 | RSS: Main: 178974720 19:38:29 INFO - PROCESS | 3634 | 19:38:32 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58854/tests/kraken/driver.html?testName=imaging-darkroom) 19:38:33 INFO - PROCESS | 3634 | RSS: Main: 215175168 19:38:33 INFO - PROCESS | 3634 | 19:38:37 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58854/tests/kraken/driver.html?testName=imaging-desaturate) 19:38:37 INFO - PROCESS | 3634 | RSS: Main: 213049344 19:38:37 INFO - PROCESS | 3634 | 19:38:40 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58854/tests/kraken/driver.html?testName=json-parse-financial) 19:38:42 INFO - PROCESS | 3634 | RSS: Main: 214417408 19:38:44 INFO - PROCESS | 3634 | 19:38:44 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58854/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:38:44 INFO - PROCESS | 3634 | RSS: Main: 204636160 19:38:44 INFO - PROCESS | 3634 | 19:38:45 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:38:46 INFO - PROCESS | 3634 | RSS: Main: 207151104 19:38:46 INFO - PROCESS | 3634 | 19:38:52 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:38:52 INFO - PROCESS | 3634 | RSS: Main: 207163392 19:38:52 INFO - PROCESS | 3634 | 19:38:55 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:38:55 INFO - PROCESS | 3634 | RSS: Main: 223375360 19:38:55 INFO - PROCESS | 3634 | 19:38:58 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:38:59 INFO - PROCESS | 3634 | RSS: Main: 219856896 19:38:59 INFO - PROCESS | 3634 | 19:39:00 INFO - PROCESS | 3634 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58854/tests/kraken/driver.html?testName=ai-astar) 19:39:01 INFO - PROCESS | 3634 | RSS: Main: 265428992 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | __start_tp_report 19:39:01 INFO - PROCESS | 3634 | _x_x_mozilla_page_load 19:39:01 INFO - PROCESS | 3634 | _x_x_mozilla_page_load_details 19:39:01 INFO - PROCESS | 3634 | |i|pagename|runs| 19:39:01 INFO - PROCESS | 3634 | |0;ai-astar;113;107;110;110;109;108;110;109;109;109 19:39:01 INFO - PROCESS | 3634 | |1;audio-beat-detection;159;129;150;209;123;149;149;125;178;126 19:39:01 INFO - PROCESS | 3634 | |2;audio-dft;161;161;160;160;161;163;160;162;162;162 19:39:01 INFO - PROCESS | 3634 | |3;audio-fft;73;74;78;140;74;75;74;74;150;75 19:39:01 INFO - PROCESS | 3634 | |4;audio-oscillator;91;91;146;91;91;92;126;91;92;116 19:39:01 INFO - PROCESS | 3634 | |5;imaging-gaussian-blur;117;117;116;116;117;118;117;116;117;117 19:39:01 INFO - PROCESS | 3634 | |6;imaging-darkroom;145;146;145;145;146;146;144;145;145;145 19:39:01 INFO - PROCESS | 3634 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 19:39:01 INFO - PROCESS | 3634 | |8;json-parse-financial;55;56;55;55;54;55;55;105;53;52 19:39:01 INFO - PROCESS | 3634 | |9;json-stringify-tinderbox;60;69;63;59;60;60;59;59;61;60 19:39:01 INFO - PROCESS | 3634 | |10;stanford-crypto-aes;78;2902;81;82;84;78;78;77;78;83 19:39:01 INFO - PROCESS | 3634 | |11;stanford-crypto-ccm;123;129;130;123;119;120;118;118;119;123 19:39:01 INFO - PROCESS | 3634 | |12;stanford-crypto-pbkdf2;167;158;158;161;163;156;158;158;158;157 19:39:01 INFO - PROCESS | 3634 | |13;stanford-crypto-sha256-iterative;63;63;62;62;62;61;61;62;62;62 19:39:01 INFO - PROCESS | 3634 | __end_tp_report 19:39:01 INFO - PROCESS | 3634 | __start_cc_report 19:39:01 INFO - PROCESS | 3634 | _x_x_mozilla_cycle_collect,1844 19:39:01 INFO - PROCESS | 3634 | __end_cc_report 19:39:01 INFO - PROCESS | 3634 | __startTimestamp1463539141138__endTimestamp 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | ------- Summary: start ------- 19:39:01 INFO - PROCESS | 3634 | Number of tests: 14 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#0] ai-astar Cycles:10 Average:109.40 Median:109.50 stddev:1.58 (1.4%) stddev-sans-first:1.00 19:39:01 INFO - PROCESS | 3634 | Values: 113.0 107.0 110.0 110.0 109.0 108.0 110.0 109.0 109.0 109.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:149.50 stddev:27.32 (18.3%) stddev-sans-first:28.77 19:39:01 INFO - PROCESS | 3634 | Values: 159.0 129.0 150.0 209.0 123.0 149.0 149.0 125.0 178.0 126.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.03 (0.6%) stddev-sans-first:1.09 19:39:01 INFO - PROCESS | 3634 | Values: 161.0 161.0 160.0 160.0 161.0 163.0 160.0 162.0 162.0 162.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#3] audio-fft Cycles:10 Average:88.70 Median:74.00 stddev:29.80 (40.3%) stddev-sans-first:31.06 19:39:01 INFO - PROCESS | 3634 | Values: 73.0 74.0 78.0 140.0 74.0 75.0 74.0 74.0 150.0 75.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:91.00 stddev:19.74 (21.7%) stddev-sans-first:20.48 19:39:01 INFO - PROCESS | 3634 | Values: 91.0 91.0 146.0 91.0 91.0 92.0 126.0 91.0 92.0 116.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 19:39:01 INFO - PROCESS | 3634 | Values: 117.0 117.0 116.0 116.0 117.0 118.0 117.0 116.0 117.0 117.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 19:39:01 INFO - PROCESS | 3634 | Values: 145.0 146.0 145.0 145.0 146.0 146.0 144.0 145.0 145.0 145.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:39:01 INFO - PROCESS | 3634 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:55.00 stddev:16.03 (29.1%) stddev-sans-first:16.92 19:39:01 INFO - PROCESS | 3634 | Values: 55.0 56.0 55.0 55.0 54.0 55.0 55.0 105.0 53.0 52.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:60.00 stddev:3.06 (5.1%) stddev-sans-first:3.22 19:39:01 INFO - PROCESS | 3634 | Values: 60.0 69.0 63.0 59.0 60.0 60.0 59.0 59.0 61.0 60.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#10] stanford-crypto-aes Cycles:10 Average:362.10 Median:79.50 stddev:892.43 (1122.6%) stddev-sans-first:940.63 19:39:01 INFO - PROCESS | 3634 | Values: 78.0 2902.0 81.0 82.0 84.0 78.0 78.0 77.0 78.0 83.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:123.00 stddev:4.34 (3.5%) stddev-sans-first:4.59 19:39:01 INFO - PROCESS | 3634 | Values: 123.0 129.0 130.0 123.0 119.0 120.0 118.0 118.0 119.0 123.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.40 Median:158.00 stddev:3.34 (2.1%) stddev-sans-first:2.13 19:39:01 INFO - PROCESS | 3634 | Values: 167.0 158.0 158.0 161.0 163.0 156.0 158.0 158.0 158.0 157.0 19:39:01 INFO - PROCESS | 3634 | 19:39:01 INFO - PROCESS | 3634 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 19:39:01 INFO - PROCESS | 3634 | Values: 63.0 63.0 62.0 62.0 62.0 61.0 61.0 62.0 62.0 62.0 19:39:01 INFO - PROCESS | 3634 | -------- Summary: end -------- 19:39:01 INFO - PROCESS | 3634 | 19:39:02 INFO - TEST-INFO | 3634: exit 0 19:39:02 INFO - TEST-OK | kraken | took 69734ms 19:39:02 INFO - SUITE-END | took 1188s 19:39:02 INFO - Completed test suite (00:19:48) 19:39:02 INFO - javascript benchmark 19:39:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3081.07567729681, 3101.0076775431844, 3136.372725454909, 3143.5913331266934, 3159.8736050557977, 5313.840584782453, 5397.514223719864, 5458.317710286215, 5469.288992430978, 5483.7806487740445, 5346.144616861293, 5364.865878353043, 5466.781328746855, 5484.451554844513, 5550.8057217997375, 4828.613710903137, 4924.753762311888, 4942.18453955097, 4961.454240033593, 5112.207607820794, 5008.624353173508, 5026.673266237701, 5054.646174767768, 5066.290719299301, 5080.77136528857, 4895.11887860185, 4900.166971614816, 4915.336429582015, 4962.4045114586115, 4979.053979743837, 4951.801927922878, 4974.054929563371, 5047.747612619354, 5053.974730126326, 5084.3644544431945, 4880.926786098212, 4991.07665081961, 5007.924881126786, 5037.29477873098, 5071.847844564669, 5255.343082114736, 5297.576193904479, 5317.680939143664, 5375.462453754632, 5394.352677678665, 5844.649321040752, 5867.090600956859, 5877.9118313225335, 5892.734826932799, 5898.14476900847, 5711.400302968212, 5778.508826749731, 5932.139839723259, 5958.910616340759, 5992.760289588411, 5472.589555783332, 5586.553075753971, 5587.078132108198, 5588.133839254922, 5591.217229587861, 6041.9382768881815, 6056.848578785565, 6072.180255665517, 6072.696365181723, 6112.663803490762, 5532.474414930575, 5667.291588551431, 5681.886362272731, 5854.151148083504, 5869.236999190078, 5810.273715785527, 5819.0, 5888.793892213752, 5891.23413956183, 5900.704964751745, 5772.9711351442975, 5785.0, 5804.593678442468, 5805.332386775491, 5830.183774271605, 5665.66006039639, 5803.448672376117, 5808.738606762706, 5837.09525023622, 5844.415558444121, 4444.2222611042935, 4448.844290449819, 4452.9554704453185, 4458.331250312479, 4475.888102797456, 3951.4072889066297, 3956.8219430125714, 3981.243563722883, 3999.680025597945, 4017.0760725032824, 5259.15853463444, 5369.221462887859, 5464.18037294409, 5494.945050549523, 5517.4482551745305, 5918.260217472816, 5929.584929055011, 5930.8813823723285, 5932.79235226765, 5953.553483488669, 5476.2333273341765, 5485.4788795064405, 5500.669959802424, 5501.037318469252, 5513.751881165357, 4786.760661966888, 4891.755412229374, 4932.1861892788, 4948.628852836052, 4969.229769385751, 6071.696415179312, 6072.483838873745, 6079.544034197453, 6084.30030546484, 6131.957567213584, 5612.186232996111, 5938.910916336172, 5948.672822994777, 5952.255968003999, 5972.5520585955355, 5969.641821490724, 6004.189434426471, 6010.188624535632, 6069.787557435645, 6080.179175811209, 5675.233843431084, 5762.2221000164445, 5763.106718458645, 5763.452472015152, 5765.164051212634, 5659.3491748447805, 5803.361630220588, 5885.529157667462, 5891.1457838614015, 5891.675957822361, 5675.120356344773, 5739.598228123991, 5754.56840736938, 5756.971215143897, 5798.478136967693, 5695.8860822784145, 5695.914561281501, 5701.173329867063, 5702.714864256854, 5714.714264286852, 5437.3747019091725, 5535.6955367455175, 5590.944090559043, 5621.971890140687, 5625.156226566048, 5821.243238378984, 5831.883362332815, 5838.474537291664, 5841.50347220491, 5844.357120716632, 5603.32760068778, 5639.379668236409, 5645.774169033357, 5646.153077038477, 5648.039833228443], "value": 5383.227819863205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.1851082527761, 632.5098049012016, 634.800037988034, 637.4804534304542, 650.9259721459588, 33978.0, 36567.268654626925, 36854.81572592137, 44468.0, 45713.0, 47665.7616711916, 48015.0, 48089.75955120228, 48159.0, 48187.277190842164, 8828.646854125844, 9012.8648070279, 9019.143181397756, 9019.909800901973, 9024.954875225647, 8851.380403371766, 8919.509426981513, 8933.15135062168, 8944.463332200055, 8952.641894324252, 8954.104589541059, 8990.865137022916, 9003.594838232262, 9010.324225683067, 9013.414128081686, 8945.329100317469, 8984.8652270216, 8988.59551320192, 8988.820223595492, 8995.95502022489, 8765.517896515688, 8842.823143537158, 8847.601857916345, 8853.380263381567, 8860.0, 43767.0, 43917.78041109806, 44008.119837603386, 44080.338794918105, 44151.0, 3533.2756784859043, 3628.183658676803, 3661.578918424362, 3828.8659896903755, 4260.0414906646065, 32195.370541535744, 32463.837680811444, 32514.0, 32852.06795268708, 32878.67121328804, 3907.5506316773367, 3915.882523524299, 3943.38877473992, 3962.9405558916637, 3995.5604883462797, 356920.0, 359407.0, 359427.0, 360126.9999999974, 361632.0, 35148.64851351505, 61285.0, 61622.61075640883, 61761.69119154375, 61763.0, 35919.28161436757, 37789.05527361838, 37917.0, 37952.0, 38020.04949876275, 15804.60488487797, 16108.0, 16141.515754527383, 16150.0, 16153.757693634605, 13520.932395338157, 13547.390367433489, 13578.728425431633, 13581.388837502336, 13598.660033499043, 8594.226519613265, 8610.87083693745, 8613.267872230805, 8693.0, 8695.608697608623, 3284.753643476749, 3333.1667083229195, 3355.3624811285777, 3360.6639336066687, 3408.9488657670154, 1024.4007255755466, 1027.2847629381176, 1088.8584484017026, 1104.5703091899697, 1192.1118766518825, 32306.81693402664, 34374.0, 34385.65614343875, 34796.65203347985, 35106.0, 1224.9816252756216, 1242.2918936206456, 1246.7568824079217, 1280.3918138883955, 1298.9350532473338, 17754.822451775315, 18238.27046918135, 18376.632467350577, 18382.264709411742, 18411.0, 547287.0, 548118.0, 554195.0, 556625.0, 559858.0, 61763.6911815447, 62131.0, 62748.372516275165, 62819.0, 62896.0, 1332.9000324975473, 1421.1686163594206, 1459.547540262522, 1468.4713503138862, 1477.6527516033723, 31065.223369415944, 35503.46744798779, 35676.82161589279, 35809.46285805663, 35812.28375432425, 53068.20397694115, 53241.0, 53740.73129634327, 53842.46157538374, 53853.73073134766, 413655.0, 418750.0, 419744.0, 419751.0, 421461.0, 40696.3895541573, 40990.79504602458, 41314.79342603268, 41718.58281417147, 45187.32219016652, 50255.989760409175, 50724.23913641372, 51490.48509514857, 51792.22311665252, 51865.22202166895, 42364.0, 42517.5748242526, 42553.5744642562, 42639.855888760714, 43027.56972430361, 44314.11371772611, 44387.0, 44416.11167776562, 45488.31767523556, 45493.0, 43670.56329436665, 43954.12091758083, 44010.0, 44888.77555612201, 45222.0, 37901.81049094737, 38486.0, 38601.80699096486, 38810.0, 39354.803225983684, 29408.852955735085, 29483.0, 29834.850825746602, 30005.549916750828, 30241.395172096876], "value": 19808.864143792787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.9564447910727, 396.0771402631866, 406.7905028910111, 414.22333125389895, 422.08197171152756, 13266.867331326685, 17990.82009179908, 19104.044797760125, 19599.902000489994, 19600.31398901039, 15010.699786004274, 17474.21366038528, 18110.185041673125, 18646.720299195524, 19457.805421945777, 5726.942730572693, 5914.112883067532, 5942.524598032147, 5947.940520594803, 5959.821205363846, 5843.79546715865, 5922.3485416604135, 5922.674252916088, 5928.258967629046, 5935.317438494585, 4164.541900390955, 6156.5382596305235, 6426.132472116275, 6452.548321617489, 6584.176977877765, 6520.934790652104, 6526.347365263436, 6531.934680653204, 6563.573367731106, 6612.173478315211, 5666.914996275059, 5794.391588883191, 5814.273215848019, 5830.679312637803, 5836.38717934615, 21606.027728752088, 22456.55086898253, 22897.656535151986, 23235.0, 23270.0, 2494.9750502494926, 2496.8002559795173, 2548.413864811085, 2657.0833062593374, 3014.0505740691833, 14122.50571230002, 14161.291935403291, 14392.784108238386, 14549.854501454956, 15103.773443398359, 1867.906604669761, 1883.256113835041, 1935.6902895536787, 1984.99007504963, 2149.3981685128188, 78938.6053069731, 79032.60483697544, 80011.19988800096, 80312.59843700803, 80938.0, 10309.22680798928, 10559.366438013743, 11410.94294528522, 11464.885351146382, 11642.19545970376, 14393.064450810663, 14430.783538246935, 14435.85564144366, 14451.49419770324, 14465.13209207451, 8331.0, 8547.273481753997, 8655.523946182895, 8705.47767133974, 8846.823063538694, 8444.493330400195, 8522.48865068098, 8625.741227763177, 8688.826223475495, 8875.822483550293, 5923.437273459014, 6348.333424990402, 6651.63416012124, 6791.694373753193, 6823.215330236987, 1948.7174359717762, 1984.5666996096834, 2005.1823799083234, 2013.8590298678953, 2040.2553068130026, 949.4066208619613, 1085.766560189563, 1086.0659832544006, 1113.2541197397763, 1113.3041848844473, 18116.799768258297, 19025.238990440226, 19656.606867862563, 19819.405417837486, 19834.60330793376, 1075.634284343327, 1124.7019539821954, 1128.1482480727, 1129.158776711339, 1152.141654467427, 11278.774424511465, 11473.483693233824, 11515.136364772676, 11517.596884109184, 11530.59642912494, 497914.0, 513600.0, 522976.0, 523312.0, 524987.0, 82388.9999999988, 82690.58654706688, 83828.58085709532, 84369.0, 84696.57651711826, 1055.5144633468537, 1091.579741799413, 1113.732704150998, 1118.6644006797928, 1119.4906317625623, 17385.652286954442, 17395.694313234584, 17609.383671571435, 17691.469255922344, 17863.642727145645, 41489.792551037055, 41574.0, 41835.0, 43470.13059738851, 45010.0, 53118.73440632772, 57910.0, 57947.710261448425, 58054.4194558049, 59104.704476477345, 7628.313451789366, 7753.961230193813, 8416.83166336682, 8780.253678437404, 8800.823983520422, 8975.910240897507, 13259.933700331436, 13490.662733431744, 13526.0, 13782.104163229358, 8462.534560599226, 11118.221724479208, 11679.0, 12336.259824410565, 12349.814752778895, 11909.464074116859, 12046.638600841989, 12063.698407539881, 12141.453634586642, 12169.878301216875, 6716.1604799400075, 10484.842727359248, 12386.3187524687, 12387.380630968235, 12409.44157512896, 8764.21122099014, 8777.956110219407, 8899.377043606886, 9203.171714545722, 9209.539523023956, 10860.456977150952, 12022.939885300868, 12094.274343539415, 12138.089643276613, 12158.635240942785], "value": 9549.250571532386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.4498200719704, 1530.341952960227, 1595.0270335095593, 1763.1008185825233, 1897.3169658922786, 8758.737237882866, 9072.546372681365, 9088.72733817985, 9294.814103717921, 9307.162355388014, 9012.729618111467, 9178.265738740902, 9239.538023098852, 9244.306676999235, 9277.25781937445, 2080.8139360564483, 2369.2418426103654, 2401.879906004697, 2431.65956766052, 2439.8536087834786, 2426.9393265168337, 2432.914847980321, 2438.573249681299, 2439.4145405102827, 2439.426734717349, 2390.1873363056557, 2418.5525677749583, 2424.903003879843, 2430.428849220432, 2431.4286142756528, 1953.0430089256304, 2344.22640528626, 2391.6651668766385, 2405.542946840112, 2416.7220769611545, 2361.0437772702085, 2365.775251351119, 2367.2898130560934, 2368.171140100951, 2401.327628264089, 9086.182243598041, 9127.452352858849, 9143.177114059767, 9146.2682985361, 9146.634134634607, 5434.864128396782, 5602.523785478239, 5609.158626206061, 5609.80365687199, 5616.747246373882, 3548.077499850032, 3576.8032758198287, 3609.4405367168133, 3661.761985470936, 3682.5949145593963, 1126.3467189030343, 1134.863816342036, 1154.1920655541155, 1166.8424762657019, 1167.7372591167004, 12906.161099528501, 12921.612351629467, 12929.61211163676, 12930.547430839875, 12940.0, 4866.586340161055, 4903.5586797188425, 4938.777755001033, 4951.900961980812, 5108.923366149511, 10611.257211995086, 10737.51681174349, 10740.248182627141, 10793.29843560166, 10817.567297308176, 4845.685030473008, 5501.504864562208, 5509.586780991362, 5519.834404967857, 5523.199136125239, 4524.88687782808, 4586.380838586815, 4913.557779799764, 5049.5960323174795, 5069.239614057847, 3993.5407428146136, 4074.8777536673947, 4145.564715704868, 4154.5222299435345, 4196.055887425305, 1253.8181963615225, 1304.8368953880765, 1315.5132600937523, 1327.858042083807, 1349.8987575931844, 911.6535716427715, 1058.1534772182224, 1059.1103473082649, 1066.168388656849, 1072.4905669806778, 6090.720948600843, 6418.133551970424, 6431.710573024225, 6472.644004579794, 6486.0, 447.27318108074377, 455.62638636318565, 461.86836751526323, 1018.2973748113777, 1066.7653116314398, 4553.998120413504, 4618.168729628632, 4651.790669419885, 4651.976740116278, 4666.556677115736, 11519.424028798527, 11685.766284674259, 11844.407779610985, 11856.407179640983, 11874.0, 11751.588694395827, 11787.823182652268, 11803.704907377212, 11856.76286474283, 11906.642800715992, 8424.651049219332, 8531.872021919677, 8625.309975201968, 8648.394612377198, 8659.523726194993, 6427.357264273535, 6468.902966455413, 6484.935150648622, 6566.671666416755, 6584.934150658623, 10585.364878107339, 10786.083182929538, 10887.23789334739, 10910.61812836547, 10911.399873007062, 10715.196360272856, 10837.945810270898, 11023.669289921316, 11093.33439993603, 11126.554937802397, 6958.0, 7521.360684341674, 7612.961935190288, 7849.529028258323, 7966.1237263902085, 9149.634014639607, 9155.13026262504, 9197.310201734763, 9209.539523023688, 9235.492047937429, 8088.555129467701, 8260.545669988209, 8275.668973241243, 8333.874991875005, 8358.498490090371, 7890.092639346549, 7969.242921922408, 7980.241877021674, 8007.079185893696, 8073.596320184085, 7343.963280183565, 7347.853042939005, 7364.005859208938, 7457.403407727477, 7536.472446928662, 5512.5314348280845, 5555.833325000256, 5578.86052848682, 5596.496315331639, 5666.490015898589, 5504.2018907257425, 5548.805791797267, 5605.495505404533, 5608.635438696471, 5641.294838145232], "value": 4834.386650905509, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.4999999999999, 326.18941929305686, 326.32288002395023, 329.13437658956946, 334.2879666310758, 5322.680639161653, 5331.173668081444, 5479.315085614298, 5504.807331743385, 5505.1467022611505, 5431.945680543204, 5506.91739623906, 5509.917351239735, 5512.9173062404, 5537.833864984047, 4249.999999999993, 4269.893252668692, 4290.54949230331, 4290.978545107286, 4309.832273279138, 3944.2703099926607, 4170.812313445902, 4240.300350442173, 4267.338562522798, 4274.273373526493, 4232.407462955189, 4238.173556156551, 4245.405643209953, 4260.595243451882, 4260.659147268211, 4163.542010378856, 4225.204288177528, 4233.470816147981, 4272.850450234242, 4272.87181384559, 3625.274945011008, 3657.908552286214, 3741.9064523387133, 3754.8310326035394, 3755.422722456575, 5159.767810448501, 5195.428502864682, 5197.038547868657, 5226.137687281594, 5261.342332208474, 4802.663813533054, 4835.395575553056, 4853.150698627761, 4873.951260487385, 4875.219964805614, 5339.6262261641705, 5578.609497335188, 5606.495415412592, 5623.606347555673, 5650.604457687963, 4091.2022155679656, 4095.52901416335, 4102.569230230843, 4141.068259641586, 4144.316187829035, 5758.107493338539, 5763.279590051244, 5878.706064696791, 5924.20023296858, 5951.821445356646, 3904.0239940014994, 4072.2466343726505, 4090.877273681794, 4135.834566617302, 4146.813393397304, 4146.771927544014, 4178.850816025617, 4208.579142085767, 4214.798782347017, 4214.810333534998, 4725.645576581702, 4731.52684731531, 4754.595859351924, 4755.286706993909, 4810.254410566367, 5499.807506737325, 5527.226188333637, 5612.607117501736, 5625.774969001276, 5645.95118635932, 1336.9131006484743, 1425.173399428329, 1438.5109062918657, 1472.4036765110147, 1478.3199728125182, 899.2626046641691, 946.4605175050156, 948.245745371058, 950.8193443245899, 954.556131398889, 4745.0, 4801.903961920742, 4841.8547443576745, 4854.393200849894, 4855.393075865517, 5141.280220769096, 5144.6655967362, 5157.252198431206, 5160.612954028388, 5162.5095615916425, 4844.878878028007, 4851.913677118802, 4853.247746599282, 4890.804367825247, 5049.722265275372, 3081.198888289016, 3098.7675924305768, 3134.2477805326557, 3145.292309230405, 3167.4298626247037, 6033.582108204569, 6053.789242151499, 6061.8484537886025, 6097.634141951497, 6120.265568131853, 5713.714314284269, 5903.822885313361, 5907.9704601476715, 5920.378360272195, 5923.644581325134, 5974.3726908674835, 5977.32634862248, 5978.192943952685, 5991.790287340009, 5995.760169593254, 5587.55299576041, 5745.454181852718, 5752.827415177551, 5791.449812267828, 5891.705414729332, 5791.247137872049, 5809.344336863974, 5810.215620891126, 5813.895359881609, 5814.313848983747, 5973.01445261527, 6067.089936509559, 6083.178770865877, 6089.665068421279, 6092.268927728701, 3879.903002424962, 4046.0, 4074.1851629673592, 4082.081531655354, 4157.605027522381, 5700.600957932905, 5746.482816546531, 5759.0, 5859.150423188528, 5889.0, 5564.772431989517, 5583.385827559046, 5658.179563963285, 5672.858178545569, 5777.335606405317, 5624.521915637217, 5645.8426022664435, 5659.858503537444, 5670.914936276042, 5776.508996735324, 5350.652207606493, 5565.248691426767, 5565.749541270571, 5579.860503487445, 5593.517717804703, 5679.006173919465, 5706.514946229617, 5738.426157384228, 5777.595568310178, 5810.622309549865, 5424.810131645373, 5526.557875370041, 5564.749586268546, 5575.386707462095, 5592.972035139798], "value": 4275.844400010789, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.2088392932961, 815.2825513548077, 842.8440738463385, 868.1659249841066, 890.376736284601, 14987.850121498781, 15871.206439678028, 15951.282192301345, 16031.679366412665, 16057.357705691773, 12406.503739850417, 13261.336933153329, 13308.201507909533, 13410.932945335284, 13620.863791362084, 841.7390608911257, 854.2311919272643, 854.2525290370926, 854.7350321400386, 855.2943821347383, 832.3216578488543, 836.8870202522643, 837.2966707965307, 839.6053854688287, 839.9496030238174, 897.4884315939917, 898.4250079948838, 899.3210126354593, 899.4962820820328, 899.8470260055774, 890.5102193793388, 893.5219657483248, 893.8793262909489, 894.3605322194629, 894.7494701483595, 651.8044586624042, 652.1261509577189, 652.2368828470701, 652.26620052441, 652.9706163222667, 13414.061015728903, 13650.590482285548, 13658.248796316198, 13669.17984920898, 13709.862901370958, 12549.309787961658, 12659.620211393672, 12680.80978785319, 12733.490660373574, 12834.871651283462, 11001.724956876062, 11078.224524283303, 11091.55633774648, 11201.887981120248, 11315.0, 3613.241219343941, 3732.029672285198, 3806.238752249561, 3810.0, 3893.9415908761393, 56726.432735672526, 57396.0, 57463.0, 58253.41746582522, 58560.82878342452, 9822.803543929082, 9920.15678667307, 9931.503424828874, 9957.103860652576, 10212.540435680412, 18511.81488185128, 18518.722219166724, 18540.0, 18552.907235464005, 18557.16492757829, 8591.3556483264, 8625.611847466878, 8706.042335343107, 8949.731508054769, 9059.139381758723, 4645.187092258841, 4900.0, 4948.975255123702, 4993.675411098267, 5098.362704661918, 3360.22714775603, 3491.389006923778, 3491.4937334086417, 3494.545709057806, 3494.982525087358, 603.9214902062791, 628.1864984844638, 635.4725577770439, 638.7189636559899, 656.6257233377024, 727.1710250314645, 796.4623878881731, 796.6614188969664, 800.415696541528, 818.1981657975217, 9669.178119859891, 9916.206703463706, 9957.352772069937, 10016.899831001743, 10110.69667909964, 593.7654626422539, 697.3410127429631, 712.914450265961, 775.097011981047, 779.8245394786242, 4632.536746325408, 4654.906901862011, 4717.882052948704, 4760.238361862085, 4761.071591039783, 70715.29284707087, 71050.64474677593, 72491.63754181196, 72536.0, 72662.0, 44999.0, 56093.71953140208, 58553.414465854796, 63588.68205658942, 68858.65570672214, 54815.177772333445, 55988.720056399456, 56102.43897561054, 56359.0, 57417.0, 9816.558254878404, 10091.142252908292, 10129.696109116738, 10131.949340253252, 10219.386836789816, 38071.0, 38462.42306365351, 38778.0, 39581.0, 39748.20503589854, 50667.74666126646, 52252.0, 52377.0, 52411.73794131005, 52480.0, 12433.813492797795, 12500.624981250578, 12527.37363131858, 12898.613041608767, 13282.601521954357, 13640.522581709592, 14321.0, 14714.117152970855, 15016.324265407864, 15204.087754734752, 13716.314184290546, 13758.31208439554, 13815.447382104603, 14310.284485775877, 14624.926875365554, 13058.412371443497, 13251.469941202244, 13797.862021379658, 14252.572422827332, 14555.636109097357, 12187.153433444064, 12312.630621081382, 12422.627321180378, 12592.748145036865, 12610.936945315214, 11164.776704466027, 11215.439228038402, 11445.370504622326, 11522.884771152181, 11568.710782230512, 11044.392558409365, 11092.611758588411, 11383.203175777617, 11511.654650360502, 11737.35444550558], "value": 7056.241641484448, "unit": "score"}], "name": "dromaeo_css", "value": 7277.38273693413, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 110.0, 110.0, 109.0, 108.0, 110.0, 109.0, 109.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 129.0, 150.0, 209.0, 123.0, 149.0, 149.0, 125.0, 178.0, 126.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 161.0, 163.0, 160.0, 162.0, 162.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 78.0, 140.0, 74.0, 75.0, 74.0, 74.0, 150.0, 75.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 146.0, 91.0, 91.0, 92.0, 126.0, 91.0, 92.0, 116.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 144.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 55.0, 54.0, 55.0, 55.0, 105.0, 53.0, 52.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 69.0, 63.0, 59.0, 60.0, 60.0, 59.0, 59.0, 61.0, 60.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 2902.0, 81.0, 82.0, 84.0, 78.0, 78.0, 77.0, 78.0, 83.0], "value": 362.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 129.0, 130.0, 123.0, 119.0, 120.0, 118.0, 118.0, 119.0, 123.0], "value": 122.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 158.0, 158.0, 161.0, 163.0, 156.0, 158.0, 158.0, 158.0, 157.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 62.0, 62.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1794.8, "alertThreshold": 2.0}]} 19:39:02 INFO - Return code: 0 19:39:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:39:02 ERROR - # TBPL SUCCESS # 19:39:02 INFO - Running post-action listener: _resource_record_post_action 19:39:02 INFO - ##### 19:39:02 INFO - ##### Finished run-tests step (success) 19:39:02 INFO - ##### 19:39:02 INFO - Running post-run listener: _resource_record_post_run 19:39:02 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 8015872; Write bytes: 418324480; Read time: 2760; Write time: 1195868 19:39:02 INFO - TinderboxPrint: CPU usage
13.3% 19:39:02 INFO - TinderboxPrint: I/O read bytes / time
8,015,872 / 2,760 19:39:02 INFO - TinderboxPrint: I/O write bytes / time
418,324,480 / 1,195,868 19:39:02 INFO - TinderboxPrint: CPU idle
8,349.7 (86.7%) 19:39:02 INFO - TinderboxPrint: CPU user
1,205.3 (12.5%) 19:39:02 INFO - TinderboxPrint: Swap in / out
0 / 0 19:39:02 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 83873792; Read time: 0; Write time: 176856 19:39:02 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 6279168; Write bytes: 334450688; Read time: 2692; Write time: 1019012 19:39:03 INFO - Running post-run listener: _upload_blobber_files 19:39:03 INFO - Blob upload gear active. 19:39:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:39:03 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 19:39:03 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'] 19:39:03 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 19:39:03 INFO - (blobuploader) - INFO - Open directory for files ... 19:39:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:39:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:04 INFO - (blobuploader) - INFO - Done attempting. 19:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:09 INFO - (blobuploader) - INFO - Done attempting. 19:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:09 INFO - (blobuploader) - INFO - Done attempting. 19:39:09 INFO - (blobuploader) - INFO - Iteration through files over. 19:39:10 INFO - Return code: 0 19:39:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:39:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:39:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b8003963b8284d1374b299ce6c84b0d9fba5ac3e59d495803798ec5f97f082c0ca4fb1b5e7643d2c4ec3d2da0e1279a1f2da4780ccaadd5fcaa9b06d129d601", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43cd0247abdcfa4834cc7323ce5057947f7fe0dba62a7772bfad9945e162702df1aa5f6a9a73d13a9187b041401c5c0933ca9df648249005ba1931a61cca39c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be53fe06f487072ee8ceff6c227fcb0b0eb0dd0781bbe512576ce18c6f084159ec605283b88435cfa4ac6f293e2368eb32884104b4c9637102d8181ba001792e"} 19:39:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:39:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:39:10 INFO - Contents: 19:39:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b8003963b8284d1374b299ce6c84b0d9fba5ac3e59d495803798ec5f97f082c0ca4fb1b5e7643d2c4ec3d2da0e1279a1f2da4780ccaadd5fcaa9b06d129d601", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43cd0247abdcfa4834cc7323ce5057947f7fe0dba62a7772bfad9945e162702df1aa5f6a9a73d13a9187b041401c5c0933ca9df648249005ba1931a61cca39c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be53fe06f487072ee8ceff6c227fcb0b0eb0dd0781bbe512576ce18c6f084159ec605283b88435cfa4ac6f293e2368eb32884104b4c9637102d8181ba001792e"} 19:39:10 INFO - Running post-run listener: copy_logs_to_upload_dir 19:39:10 INFO - Copying logs to upload dir... 19:39:10 INFO - mkdir: /builds/slave/test/build/upload/logs 19:39:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.830855 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-17 19:39:10.000021) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 19:39:10.003995) ========= 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=8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656 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/3b8003963b8284d1374b299ce6c84b0d9fba5ac3e59d495803798ec5f97f082c0ca4fb1b5e7643d2c4ec3d2da0e1279a1f2da4780ccaadd5fcaa9b06d129d601", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43cd0247abdcfa4834cc7323ce5057947f7fe0dba62a7772bfad9945e162702df1aa5f6a9a73d13a9187b041401c5c0933ca9df648249005ba1931a61cca39c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be53fe06f487072ee8ceff6c227fcb0b0eb0dd0781bbe512576ce18c6f084159ec605283b88435cfa4ac6f293e2368eb32884104b4c9637102d8181ba001792e"} build_url:https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011167 build_url: 'https://queue.taskcluster.net/v1/task/YbUmE1gCQ1GCcNMeNMaWCA/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/3b8003963b8284d1374b299ce6c84b0d9fba5ac3e59d495803798ec5f97f082c0ca4fb1b5e7643d2c4ec3d2da0e1279a1f2da4780ccaadd5fcaa9b06d129d601", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43cd0247abdcfa4834cc7323ce5057947f7fe0dba62a7772bfad9945e162702df1aa5f6a9a73d13a9187b041401c5c0933ca9df648249005ba1931a61cca39c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be53fe06f487072ee8ceff6c227fcb0b0eb0dd0781bbe512576ce18c6f084159ec605283b88435cfa4ac6f293e2368eb32884104b4c9637102d8181ba001792e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 19:39:10.029860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:39:10.030232) ========= 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=8783db0840cc980e80bb0bd400000236-1463537887.129653-265565656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 19:39:10.114481) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 19:39:10.114905) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 19:39:10.115259) ========= ========= Total master_lag: 0.08 =========