builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1463464832.95 results: success (0) buildid: 20160516203022 builduid: efc89e00935144db8a7ba6e2355dcf1f revision: c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:32.947256) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:32.947742) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:32.948020) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.007057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.007317) ========= 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=920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-16 23:00:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2016-05-16 23:00:33 (228 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.188197 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.218781) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.219112) ========= 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=920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021046 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.272406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.272733) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c --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=920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-16 23:00:33,939 truncating revision to first 12 chars 2016-05-16 23:00:33,939 Setting DEBUG logging. 2016-05-16 23:00:33,939 attempt 1/10 2016-05-16 23:00:33,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c5cfd82c36e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 23:00:34,190 unpacking tar archive at: fx-team-c5cfd82c36e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.548217 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.834644) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.834989) ========= script_repo_revision: c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.835508) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.835839) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 23:00:33.853010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-16 23:00:33.853472) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:00:34 INFO - MultiFileLogger online at 20160516 23:00:34 in /builds/slave/test 23:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:00:34 INFO - {'append_to_log': False, 23:00:34 INFO - 'base_work_dir': '/builds/slave/test', 23:00:34 INFO - 'blob_upload_branch': 'Fx-Team', 23:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:00:34 INFO - 'branch': 'Fx-Team', 23:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 23:00:34 INFO - 'config_files': ('talos/linux_config.py',), 23:00:34 INFO - 'default_actions': ('clobber', 23:00:34 INFO - 'read-buildbot-config', 23:00:34 INFO - 'download-and-extract', 23:00:34 INFO - 'populate-webroot', 23:00:34 INFO - 'create-virtualenv', 23:00:34 INFO - 'install', 23:00:34 INFO - 'run-tests'), 23:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:00:34 INFO - 'download_minidump_stackwalk': True, 23:00:34 INFO - 'download_symbols': 'ondemand', 23:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 23:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 23:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:00:34 INFO - 'installer_path': 'installer.exe', 23:00:34 INFO - 'log_level': 'info', 23:00:34 INFO - 'log_name': 'talos', 23:00:34 INFO - 'log_to_console': True, 23:00:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:00:34 INFO - 'opt_config_files': (), 23:00:34 INFO - 'pip_index': False, 23:00:34 INFO - 'sps_profile': False, 23:00:34 INFO - 'sps_profile_interval': 0, 23:00:34 INFO - 'suite': 'dromaeojs', 23:00:34 INFO - 'system_bits': '32', 23:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:00:34 INFO - 'title': 'talos-linux64-ix-053', 23:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:00:34 INFO - 'use_talos_json': True, 23:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:00:34 INFO - 'work_dir': 'build'} 23:00:34 INFO - ##### 23:00:34 INFO - ##### Running clobber step. 23:00:34 INFO - ##### 23:00:34 INFO - Running pre-action listener: _resource_record_pre_action 23:00:34 INFO - Running main action method: clobber 23:00:34 INFO - rmtree: /builds/slave/test/build 23:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:00:35 INFO - Running post-action listener: _resource_record_post_action 23:00:35 INFO - ##### 23:00:35 INFO - ##### Finished clobber step (success) 23:00:35 INFO - ##### 23:00:35 INFO - ##### 23:00:35 INFO - ##### Running read-buildbot-config step. 23:00:35 INFO - ##### 23:00:35 INFO - Running pre-action listener: _resource_record_pre_action 23:00:35 INFO - Running main action method: read_buildbot_config 23:00:35 INFO - Using buildbot properties: 23:00:35 INFO - { 23:00:35 INFO - "project": "", 23:00:35 INFO - "product": "firefox", 23:00:35 INFO - "script_repo_revision": "production", 23:00:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:00:35 INFO - "repository": "", 23:00:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:00:35 INFO - "buildid": "20160516203022", 23:00:35 INFO - "pgo_build": "True", 23:00:35 INFO - "basedir": "/builds/slave/test", 23:00:35 INFO - "buildnumber": 687, 23:00:35 INFO - "slavename": "talos-linux64-ix-053", 23:00:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:00:35 INFO - "platform": "ubuntu64_hw", 23:00:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:00:35 INFO - "branch": "fx-team", 23:00:35 INFO - "repo_path": "integration/fx-team", 23:00:35 INFO - "revision": "c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c", 23:00:35 INFO - "stage_platform": "linux64-pgo", 23:00:35 INFO - "builduid": "efc89e00935144db8a7ba6e2355dcf1f", 23:00:35 INFO - "slavebuilddir": "test" 23:00:35 INFO - } 23:00:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:00:35 INFO - Running post-action listener: _resource_record_post_action 23:00:35 INFO - ##### 23:00:35 INFO - ##### Finished read-buildbot-config step (success) 23:00:35 INFO - ##### 23:00:35 INFO - ##### 23:00:35 INFO - ##### Running download-and-extract step. 23:00:35 INFO - ##### 23:00:35 INFO - Running pre-action listener: _resource_record_pre_action 23:00:35 INFO - Running main action method: download_and_extract 23:00:35 INFO - mkdir: /builds/slave/test/build/tests 23:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:35 INFO - https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:37 INFO - Downloaded 1361 bytes. 23:00:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:00:37 INFO - Using the following test package requirements: 23:00:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:00:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:00:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:00:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'jsshell-linux-x86_64.zip'], 23:00:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:00:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:00:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:00:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:00:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:00:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:00:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:00: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')]} 23:00:37 INFO - https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:00:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:00:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:00:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:39 INFO - Downloaded 24023930 bytes. 23:00:39 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'] 23:00:39 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 23:00:40 INFO - Return code: 0 23:00:40 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 23:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:40 INFO - https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:00:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:00:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:00:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:43 INFO - Downloaded 24023930 bytes. 23:00:43 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'] 23:00:43 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 23:00:44 INFO - Return code: 0 23:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:44 INFO - https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:45 INFO - Downloaded 10840194 bytes. 23:00:45 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'] 23:00:45 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 23:00:46 INFO - Return code: 0 23:00: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')]} 23:00:46 INFO - https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:00:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:00:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:00:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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 23:00:47 INFO - Downloaded 58419539 bytes. 23:00:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:00:47 INFO - mkdir: /builds/slave/test/properties 23:00:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:00:47 INFO - Writing to file /builds/slave/test/properties/build_url 23:00:47 INFO - Contents: 23:00:47 INFO - build_url:https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:00:48 INFO - Running post-action listener: _resource_record_post_action 23:00:48 INFO - Running post-action listener: set_extra_try_arguments 23:00:48 INFO - ##### 23:00:48 INFO - ##### Finished download-and-extract step (success) 23:00:48 INFO - ##### 23:00:48 INFO - ##### 23:00:48 INFO - ##### Running populate-webroot step. 23:00:48 INFO - ##### 23:00:48 INFO - Running pre-action listener: _resource_record_pre_action 23:00:48 INFO - Running main action method: populate_webroot 23:00:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:00:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:00:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:00:48 INFO - '--tppagecycles', 23:00:48 INFO - '7'], 23:00:48 INFO - 'tests': ['tsvgm']}}, 23:00:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:00:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:00:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:00:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:00:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:00:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:00:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['damp', 'tps']}, 23:00:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'tests': ['damp', 'tps']}, 23:00:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['dromaeo_dom']}, 23:00:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:00:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['a11yr', 23:00:48 INFO - 'ts_paint', 23:00:48 INFO - 'tpaint', 23:00:48 INFO - 'sessionrestore', 23:00:48 INFO - 'sessionrestore_no_auto_restore', 23:00:48 INFO - 'tabpaint']}, 23:00:48 INFO - 'other-e10s': {'tests': ['a11yr', 23:00:48 INFO - 'ts_paint', 23:00:48 INFO - 'tpaint', 23:00:48 INFO - 'sessionrestore', 23:00:48 INFO - 'sessionrestore_no_auto_restore', 23:00:48 INFO - 'tabpaint']}, 23:00:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:00:48 INFO - 'ts_paint', 23:00:48 INFO - 'tpaint', 23:00:48 INFO - 'sessionrestore', 23:00:48 INFO - 'sessionrestore_no_auto_restore', 23:00:48 INFO - 'tabpaint']}, 23:00:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:00:48 INFO - 'ts_paint', 23:00:48 INFO - 'tpaint', 23:00:48 INFO - 'sessionrestore', 23:00:48 INFO - 'sessionrestore_no_auto_restore', 23:00:48 INFO - 'tabpaint']}, 23:00:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['a11yr', 23:00:48 INFO - 'ts_paint', 23:00:48 INFO - 'tpaint', 23:00:48 INFO - 'sessionrestore', 23:00:48 INFO - 'sessionrestore_no_auto_restore', 23:00:48 INFO - 'tabpaint']}, 23:00:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['a11yr', 23:00:48 INFO - 'ts_paint', 23:00:48 INFO - 'tpaint', 23:00:48 INFO - 'sessionrestore', 23:00:48 INFO - 'sessionrestore_no_auto_restore', 23:00:48 INFO - 'tabpaint']}, 23:00:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['tsvgx', 23:00:48 INFO - 'tsvgr_opacity', 23:00:48 INFO - 'tart', 23:00:48 INFO - 'tscrollx', 23:00:48 INFO - 'cart']}, 23:00:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:00:48 INFO - 'tsvgr_opacity', 23:00:48 INFO - 'tart', 23:00:48 INFO - 'tscrollx', 23:00:48 INFO - 'cart']}, 23:00:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'talos_options': ['--disable-e10s'], 23:00:48 INFO - 'tests': ['tp5o']}, 23:00:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'tests': ['tp5o']}, 23:00:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'talos_options': ['--disable-e10s', 23:00:48 INFO - '--xperf_path', 23:00:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:00:48 INFO - 'tests': ['tp5n']}, 23:00:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:00:48 INFO - 'talos_options': ['--xperf_path', 23:00:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:00:48 INFO - 'tests': ['tp5n']}}, 23:00:48 INFO - 'talos.zip': {'path': '', 23:00:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:00:48 INFO - Running post-action listener: _resource_record_post_action 23:00:48 INFO - ##### 23:00:48 INFO - ##### Finished populate-webroot step (success) 23:00:48 INFO - ##### 23:00:48 INFO - ##### 23:00:48 INFO - ##### Running create-virtualenv step. 23:00:48 INFO - ##### 23:00:48 INFO - Running pre-action listener: _resource_record_pre_action 23:00:48 INFO - Running main action method: create_virtualenv 23:00:48 INFO - Creating virtualenv /builds/slave/test/build/venv 23:00:48 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 23:00:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:00:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:00:48 INFO - Using real prefix '/usr' 23:00:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:00:49 INFO - Installing distribute.............................................................................................................................................................................................done. 23:00:51 INFO - Installing pip.................done. 23:00:51 INFO - Return code: 0 23:00:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:00:51 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')]} 23:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:51 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')]} 23:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:51 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:00:51 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 23:00:51 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 23:00:51 INFO - Using env: {'DISPLAY': ':0', 23:00:51 INFO - 'HOME': '/home/cltbld', 23:00:51 INFO - 'LANG': 'en_US.UTF-8', 23:00:51 INFO - 'LANGUAGE': 'en_US:en', 23:00:51 INFO - 'LOGNAME': 'cltbld', 23:00:51 INFO - 'MAIL': '/var/mail/cltbld', 23:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:51 INFO - 'MOZ_NO_REMOTE': '1', 23:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:51 INFO - 'NO_EM_RESTART': '1', 23:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:51 INFO - 'PWD': '/builds/slave/test', 23:00:51 INFO - 'SHELL': '/bin/bash', 23:00:51 INFO - 'SHLVL': '1', 23:00:51 INFO - 'TERM': 'linux', 23:00:51 INFO - 'TMOUT': '86400', 23:00:51 INFO - 'USER': 'cltbld', 23:00:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:51 INFO - '_': '/tools/buildbot/bin/python'} 23:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:51 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 23:00:51 INFO - Cleaning up... 23:00:51 INFO - Return code: 0 23:00:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:00:51 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')]} 23:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:51 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')]} 23:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:51 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:00:51 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 23:00:51 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 23:00:51 INFO - Using env: {'DISPLAY': ':0', 23:00:51 INFO - 'HOME': '/home/cltbld', 23:00:51 INFO - 'LANG': 'en_US.UTF-8', 23:00:51 INFO - 'LANGUAGE': 'en_US:en', 23:00:51 INFO - 'LOGNAME': 'cltbld', 23:00:51 INFO - 'MAIL': '/var/mail/cltbld', 23:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:51 INFO - 'MOZ_NO_REMOTE': '1', 23:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:51 INFO - 'NO_EM_RESTART': '1', 23:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:51 INFO - 'PWD': '/builds/slave/test', 23:00:51 INFO - 'SHELL': '/bin/bash', 23:00:51 INFO - 'SHLVL': '1', 23:00:51 INFO - 'TERM': 'linux', 23:00:51 INFO - 'TMOUT': '86400', 23:00:51 INFO - 'USER': 'cltbld', 23:00:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:51 INFO - '_': '/tools/buildbot/bin/python'} 23:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:51 INFO - Downloading/unpacking psutil>=3.1.1 23:00:51 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 23:00:51 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 23:00:51 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 23:00:51 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 23:00:51 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 23:00:51 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 23:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:00:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:00:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:00:53 INFO - Installing collected packages: psutil 23:00:53 INFO - Running setup.py install for psutil 23:00:53 INFO - building 'psutil._psutil_linux' extension 23:00:53 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 23:00:53 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 23:00:53 INFO - building 'psutil._psutil_posix' extension 23:00:53 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 23:00:53 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 23:00:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:00:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:00:53 INFO - Successfully installed psutil 23:00:53 INFO - Cleaning up... 23:00:54 INFO - Return code: 0 23:00:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:00:54 INFO - Using env: {'DISPLAY': ':0', 23:00:54 INFO - 'HOME': '/home/cltbld', 23:00:54 INFO - 'LANG': 'en_US.UTF-8', 23:00:54 INFO - 'LANGUAGE': 'en_US:en', 23:00:54 INFO - 'LOGNAME': 'cltbld', 23:00:54 INFO - 'MAIL': '/var/mail/cltbld', 23:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:54 INFO - 'MOZ_NO_REMOTE': '1', 23:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:54 INFO - 'NO_EM_RESTART': '1', 23:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:54 INFO - 'PWD': '/builds/slave/test', 23:00:54 INFO - 'SHELL': '/bin/bash', 23:00:54 INFO - 'SHLVL': '1', 23:00:54 INFO - 'TERM': 'linux', 23:00:54 INFO - 'TMOUT': '86400', 23:00:54 INFO - 'USER': 'cltbld', 23:00:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:54 INFO - '_': '/tools/buildbot/bin/python'} 23:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:00:56 INFO - Installing collected packages: mozsystemmonitor 23:00:56 INFO - Running setup.py install for mozsystemmonitor 23:00:56 INFO - Successfully installed mozsystemmonitor 23:00:56 INFO - Cleaning up... 23:00:56 INFO - Return code: 0 23:00:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:00:56 INFO - Using env: {'DISPLAY': ':0', 23:00:56 INFO - 'HOME': '/home/cltbld', 23:00:56 INFO - 'LANG': 'en_US.UTF-8', 23:00:56 INFO - 'LANGUAGE': 'en_US:en', 23:00:56 INFO - 'LOGNAME': 'cltbld', 23:00:56 INFO - 'MAIL': '/var/mail/cltbld', 23:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:56 INFO - 'MOZ_NO_REMOTE': '1', 23:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:56 INFO - 'NO_EM_RESTART': '1', 23:00:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:56 INFO - 'PWD': '/builds/slave/test', 23:00:56 INFO - 'SHELL': '/bin/bash', 23:00:56 INFO - 'SHLVL': '1', 23:00:56 INFO - 'TERM': 'linux', 23:00:56 INFO - 'TMOUT': '86400', 23:00:56 INFO - 'USER': 'cltbld', 23:00:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:56 INFO - '_': '/tools/buildbot/bin/python'} 23:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:56 INFO - Downloading/unpacking blobuploader==1.2.4 23:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:58 INFO - Downloading blobuploader-1.2.4.tar.gz 23:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:00:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:00:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 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 23:00:58 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 23:00:59 INFO - Downloading docopt-0.6.1.tar.gz 23:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:00:59 INFO - Installing collected packages: blobuploader, requests, docopt 23:00:59 INFO - Running setup.py install for blobuploader 23:00:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:00:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:00:59 INFO - Running setup.py install for requests 23:00:59 INFO - Running setup.py install for docopt 23:00:59 INFO - Successfully installed blobuploader requests docopt 23:00:59 INFO - Cleaning up... 23:00:59 INFO - Return code: 0 23:00:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:59 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:00:59 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 23:00:59 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 23:00:59 INFO - Using env: {'DISPLAY': ':0', 23:00:59 INFO - 'HOME': '/home/cltbld', 23:00:59 INFO - 'LANG': 'en_US.UTF-8', 23:00:59 INFO - 'LANGUAGE': 'en_US:en', 23:00:59 INFO - 'LOGNAME': 'cltbld', 23:00:59 INFO - 'MAIL': '/var/mail/cltbld', 23:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:59 INFO - 'MOZ_NO_REMOTE': '1', 23:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:59 INFO - 'NO_EM_RESTART': '1', 23:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:59 INFO - 'PWD': '/builds/slave/test', 23:00:59 INFO - 'SHELL': '/bin/bash', 23:00:59 INFO - 'SHLVL': '1', 23:00:59 INFO - 'TERM': 'linux', 23:00:59 INFO - 'TMOUT': '86400', 23:00:59 INFO - 'USER': 'cltbld', 23:00:59 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:59 INFO - '_': '/tools/buildbot/bin/python'} 23:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:00:59 INFO - Running setup.py (path:/tmp/pip-4jh_Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:01:00 INFO - Running setup.py (path:/tmp/pip-4ZHv4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:01:00 INFO - Running setup.py (path:/tmp/pip-HvPkLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:01:00 INFO - Running setup.py (path:/tmp/pip-piB3xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:01:00 INFO - Running setup.py (path:/tmp/pip-LotkjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:01:00 INFO - Running setup.py (path:/tmp/pip-gkptbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:01:00 INFO - Running setup.py (path:/tmp/pip-etaoez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:01:00 INFO - Running setup.py (path:/tmp/pip-_QU0Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:01:00 INFO - Running setup.py (path:/tmp/pip-dco691-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:01:00 INFO - Running setup.py (path:/tmp/pip-cinMyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:01:00 INFO - Running setup.py (path:/tmp/pip-rI1eKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:01:00 INFO - Running setup.py (path:/tmp/pip-frbjmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:01:00 INFO - Running setup.py (path:/tmp/pip-HUwRFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:01:00 INFO - Running setup.py (path:/tmp/pip-1rfv4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:01:00 INFO - Running setup.py (path:/tmp/pip-XEsa8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:01:01 INFO - Running setup.py (path:/tmp/pip-wL89Et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:01:01 INFO - Running setup.py (path:/tmp/pip-0aKLk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:01:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:01:01 INFO - Running setup.py install for manifestparser 23:01:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Running setup.py install for mozcrash 23:01:01 INFO - Running setup.py install for mozdebug 23:01:01 INFO - Running setup.py install for mozdevice 23:01:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Running setup.py install for mozfile 23:01:01 INFO - Running setup.py install for mozhttpd 23:01:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Running setup.py install for mozinfo 23:01:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Running setup.py install for mozInstall 23:01:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:01:01 INFO - Running setup.py install for mozleak 23:01:02 INFO - Running setup.py install for mozlog 23:01:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:01:02 INFO - Running setup.py install for moznetwork 23:01:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:01:02 INFO - Running setup.py install for mozprocess 23:01:02 INFO - Running setup.py install for mozprofile 23:01:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:01:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:01:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:01:02 INFO - Running setup.py install for mozrunner 23:01:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:01:02 INFO - Running setup.py install for mozscreenshot 23:01:02 INFO - Running setup.py install for moztest 23:01:02 INFO - Running setup.py install for mozversion 23:01:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:01:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:01:03 INFO - Cleaning up... 23:01:03 INFO - Return code: 0 23:01:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:01:03 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')]} 23:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:03 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')]} 23:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:03 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:01:03 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 23:01:03 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 23:01:03 INFO - Using env: {'DISPLAY': ':0', 23:01:03 INFO - 'HOME': '/home/cltbld', 23:01:03 INFO - 'LANG': 'en_US.UTF-8', 23:01:03 INFO - 'LANGUAGE': 'en_US:en', 23:01:03 INFO - 'LOGNAME': 'cltbld', 23:01:03 INFO - 'MAIL': '/var/mail/cltbld', 23:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:03 INFO - 'MOZ_NO_REMOTE': '1', 23:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:01:03 INFO - 'NO_EM_RESTART': '1', 23:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:03 INFO - 'PWD': '/builds/slave/test', 23:01:03 INFO - 'SHELL': '/bin/bash', 23:01:03 INFO - 'SHLVL': '1', 23:01:03 INFO - 'TERM': 'linux', 23:01:03 INFO - 'TMOUT': '86400', 23:01:03 INFO - 'USER': 'cltbld', 23:01:03 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:03 INFO - '_': '/tools/buildbot/bin/python'} 23:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:01:03 INFO - Running setup.py (path:/tmp/pip-yPX64s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:01:03 INFO - Running setup.py (path:/tmp/pip-fWwhs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:01:03 INFO - Running setup.py (path:/tmp/pip-6AKAeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:01:03 INFO - Running setup.py (path:/tmp/pip-e3b9Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:01:03 INFO - Running setup.py (path:/tmp/pip-fuhg7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:01:03 INFO - Running setup.py (path:/tmp/pip-PpwjkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:01:03 INFO - Running setup.py (path:/tmp/pip-fgp9DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:01:03 INFO - Running setup.py (path:/tmp/pip-tSP_IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:01:03 INFO - Running setup.py (path:/tmp/pip-7U73bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:01:03 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)) 23:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:01:03 INFO - Running setup.py (path:/tmp/pip-u55XZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:01:04 INFO - Running setup.py (path:/tmp/pip-jdIKIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:01:04 INFO - Running setup.py (path:/tmp/pip-6NaXr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:01:04 INFO - Running setup.py (path:/tmp/pip-oqkR3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:01:04 INFO - Running setup.py (path:/tmp/pip-tp7A0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:01:04 INFO - Running setup.py (path:/tmp/pip-eDLFuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:01:04 INFO - Running setup.py (path:/tmp/pip-UeWMXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:01:04 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)) 23:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:01:04 INFO - Running setup.py (path:/tmp/pip-OqUopM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:01:04 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)) 23:01:04 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)) 23:01:04 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)) 23:01:04 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)) 23:01:04 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)) 23:01:04 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)) 23:01:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:06 INFO - Downloading blessings-1.6.tar.gz 23:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:01:06 INFO - Installing collected packages: blessings 23:01:06 INFO - Running setup.py install for blessings 23:01:06 INFO - Successfully installed blessings 23:01:06 INFO - Cleaning up... 23:01:06 INFO - Return code: 0 23:01:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:01:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:01:06 INFO - Reading from file tmpfile_stdout 23:01:06 INFO - Current package versions: 23:01:06 INFO - argparse == 1.2.1 23:01:06 INFO - blessings == 1.6 23:01:06 INFO - blobuploader == 1.2.4 23:01:06 INFO - docopt == 0.6.1 23:01:06 INFO - manifestparser == 1.1 23:01:06 INFO - mozInstall == 1.12 23:01:06 INFO - mozcrash == 0.17 23:01:06 INFO - mozdebug == 0.1 23:01:06 INFO - mozdevice == 0.48 23:01:06 INFO - mozfile == 1.2 23:01:06 INFO - mozhttpd == 0.7 23:01:06 INFO - mozinfo == 0.9 23:01:06 INFO - mozleak == 0.1 23:01:06 INFO - mozlog == 3.2 23:01:06 INFO - moznetwork == 0.27 23:01:06 INFO - mozprocess == 0.22 23:01:06 INFO - mozprofile == 0.28 23:01:06 INFO - mozrunner == 6.11 23:01:06 INFO - mozscreenshot == 0.1 23:01:06 INFO - mozsystemmonitor == 0.3 23:01:06 INFO - moztest == 0.7 23:01:06 INFO - mozversion == 1.4 23:01:06 INFO - psutil == 3.1.1 23:01:06 INFO - requests == 1.2.3 23:01:06 INFO - wsgiref == 0.1.2 23:01:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:01:06 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')]} 23:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:06 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')]} 23:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:06 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:01:06 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 23:01:06 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 23:01:06 INFO - Using env: {'DISPLAY': ':0', 23:01:06 INFO - 'HOME': '/home/cltbld', 23:01:06 INFO - 'LANG': 'en_US.UTF-8', 23:01:06 INFO - 'LANGUAGE': 'en_US:en', 23:01:06 INFO - 'LOGNAME': 'cltbld', 23:01:06 INFO - 'MAIL': '/var/mail/cltbld', 23:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:06 INFO - 'MOZ_NO_REMOTE': '1', 23:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:01:06 INFO - 'NO_EM_RESTART': '1', 23:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:06 INFO - 'PWD': '/builds/slave/test', 23:01:06 INFO - 'SHELL': '/bin/bash', 23:01:06 INFO - 'SHLVL': '1', 23:01:06 INFO - 'TERM': 'linux', 23:01:06 INFO - 'TMOUT': '86400', 23:01:06 INFO - 'USER': 'cltbld', 23:01:06 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:06 INFO - '_': '/tools/buildbot/bin/python'} 23:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 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)) 23:01:07 INFO - Cleaning up... 23:01:07 INFO - Return code: 0 23:01:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:01:07 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')]} 23:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:07 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')]} 23:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:07 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:01:07 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 23:01:07 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 23:01:07 INFO - Using env: {'DISPLAY': ':0', 23:01:07 INFO - 'HOME': '/home/cltbld', 23:01:07 INFO - 'LANG': 'en_US.UTF-8', 23:01:07 INFO - 'LANGUAGE': 'en_US:en', 23:01:07 INFO - 'LOGNAME': 'cltbld', 23:01:07 INFO - 'MAIL': '/var/mail/cltbld', 23:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:07 INFO - 'MOZ_NO_REMOTE': '1', 23:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:01:07 INFO - 'NO_EM_RESTART': '1', 23:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:07 INFO - 'PWD': '/builds/slave/test', 23:01:07 INFO - 'SHELL': '/bin/bash', 23:01:07 INFO - 'SHLVL': '1', 23:01:07 INFO - 'TERM': 'linux', 23:01:07 INFO - 'TMOUT': '86400', 23:01:07 INFO - 'USER': 'cltbld', 23:01:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:07 INFO - '_': '/tools/buildbot/bin/python'} 23:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:01:07 INFO - Downloading/unpacking jsonschema 23:01:07 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 23:01:07 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 23:01:07 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 23:01:07 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 23:01:07 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 23:01:07 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 23:01:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:01:09 INFO - Downloading/unpacking functools32 (from jsonschema) 23:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:09 INFO - Downloading functools32-3.2.3-2.tar.gz 23:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:01:09 INFO - warning: no files found matching '*.txt' 23:01:09 INFO - no previously-included directories found matching 'build' 23:01:09 INFO - no previously-included directories found matching 'dist' 23:01:09 INFO - no previously-included directories found matching '.git*' 23:01:09 INFO - Installing collected packages: jsonschema, functools32 23:01:09 INFO - Running setup.py install for functools32 23:01:09 INFO - warning: no files found matching '*.txt' 23:01:09 INFO - no previously-included directories found matching 'build' 23:01:09 INFO - no previously-included directories found matching 'dist' 23:01:09 INFO - no previously-included directories found matching '.git*' 23:01:09 INFO - Successfully installed jsonschema functools32 23:01:09 INFO - Cleaning up... 23:01:09 INFO - Return code: 0 23:01:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:09 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 0x7f9996b64f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2272770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, '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': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', '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 23:01:09 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 23:01:09 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 23:01:09 INFO - Using env: {'DISPLAY': ':0', 23:01:09 INFO - 'HOME': '/home/cltbld', 23:01:09 INFO - 'LANG': 'en_US.UTF-8', 23:01:09 INFO - 'LANGUAGE': 'en_US:en', 23:01:09 INFO - 'LOGNAME': 'cltbld', 23:01:09 INFO - 'MAIL': '/var/mail/cltbld', 23:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:09 INFO - 'MOZ_NO_REMOTE': '1', 23:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:01:09 INFO - 'NO_EM_RESTART': '1', 23:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:09 INFO - 'PWD': '/builds/slave/test', 23:01:09 INFO - 'SHELL': '/bin/bash', 23:01:09 INFO - 'SHLVL': '1', 23:01:09 INFO - 'TERM': 'linux', 23:01:09 INFO - 'TMOUT': '86400', 23:01:09 INFO - 'USER': 'cltbld', 23:01:09 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:09 INFO - '_': '/tools/buildbot/bin/python'} 23:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:01:09 INFO - Downloading/unpacking flake8 23:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:01:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:01:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 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 23:01:11 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 23:01:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:01:12 INFO - no previously-included directories found matching 'docs/_build' 23:01:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:01:12 INFO - Running setup.py install for pep8 23:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:01:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:01:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:01:12 INFO - no previously-included directories found matching 'docs/_build' 23:01:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:01:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:01:12 INFO - Cleaning up... 23:01:12 INFO - Return code: 0 23:01:12 INFO - Running post-action listener: _resource_record_post_action 23:01:12 INFO - Running post-action listener: _start_resource_monitoring 23:01:12 INFO - Starting resource monitoring. 23:01:12 INFO - ##### 23:01:12 INFO - ##### Finished create-virtualenv step (success) 23:01:12 INFO - ##### 23:01:12 INFO - ##### 23:01:12 INFO - ##### Running install step. 23:01:12 INFO - ##### 23:01:12 INFO - Running pre-action listener: _resource_record_pre_action 23:01:12 INFO - Running main action method: install 23:01:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:01:12 INFO - Reading from file tmpfile_stdout 23:01:12 INFO - Detecting whether we're running mozinstall >=1.0... 23:01:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:01:13 INFO - Reading from file tmpfile_stdout 23:01:13 INFO - Output received: 23:01:13 INFO - Usage: mozinstall [options] installer 23:01:13 INFO - Options: 23:01:13 INFO - -h, --help show this help message and exit 23:01:13 INFO - -d DEST, --destination=DEST 23:01:13 INFO - Directory to install application into. [default: 23:01:13 INFO - "/builds/slave/test"] 23:01:13 INFO - --app=APP Application being installed. [default: firefox] 23:01:13 INFO - mkdir: /builds/slave/test/build/application 23:01:13 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'] 23:01:13 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 23:01:29 INFO - Reading from file tmpfile_stdout 23:01:29 INFO - Output received: 23:01:29 INFO - /builds/slave/test/build/application/firefox/firefox 23:01:29 INFO - Running post-action listener: _resource_record_post_action 23:01:29 INFO - ##### 23:01:29 INFO - ##### Finished install step (success) 23:01:29 INFO - ##### 23:01:29 INFO - ##### 23:01:29 INFO - ##### Running run-tests step. 23:01:29 INFO - ##### 23:01:29 INFO - Running pre-action listener: _resource_record_pre_action 23:01:29 INFO - Running main action method: run_tests 23:01:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:01:29 INFO - Python 2.7.3 23:01:29 INFO - Return code: 0 23:01:29 INFO - grabbing minidump binary from tooltool 23:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:29 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 0x23019c0>, '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 0x23028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2302d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:01:29 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 23:01:29 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 23:01:29 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 23:01:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:01:29 INFO - Return code: 0 23:01:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:01:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:01:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:01:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:01:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:01:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:01:30 INFO - Return code: 0 23:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 23:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 23:01:30 INFO - Using env: {'DISPLAY': ':0', 23:01:30 INFO - 'HOME': '/home/cltbld', 23:01:30 INFO - 'LANG': 'en_US.UTF-8', 23:01:30 INFO - 'LANGUAGE': 'en_US:en', 23:01:30 INFO - 'LOGNAME': 'cltbld', 23:01:30 INFO - 'MAIL': '/var/mail/cltbld', 23:01:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:01:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:30 INFO - 'MOZ_NO_REMOTE': '1', 23:01:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:01:30 INFO - 'NO_EM_RESTART': '1', 23:01:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:30 INFO - 'PWD': '/builds/slave/test', 23:01:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:01:30 INFO - 'SHELL': '/bin/bash', 23:01:30 INFO - 'SHLVL': '1', 23:01:30 INFO - 'TERM': 'linux', 23:01:30 INFO - 'TMOUT': '86400', 23:01:30 INFO - 'USER': 'cltbld', 23:01:30 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737', 23:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:30 INFO - '_': '/tools/buildbot/bin/python'} 23:01:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 23:01:30 INFO - mozversion application_buildid: 20160516203022 23:01:30 INFO - mozversion application_changeset: c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c 23:01:30 INFO - mozversion application_display_name: Nightly 23:01:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:01:30 INFO - mozversion application_name: Firefox 23:01:30 INFO - mozversion application_remotingname: firefox 23:01:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:01:30 INFO - mozversion application_vendor: Mozilla 23:01:30 INFO - mozversion application_version: 49.0a1 23:01:30 INFO - mozversion platform_buildid: 20160516203022 23:01:30 INFO - mozversion platform_changeset: c5cfd82c36e54ca7e027e6bf82de7fcbc6863e7c 23:01:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:01:30 INFO - mozversion platform_version: 49.0a1 23:01:30 INFO - using testdate: 1463464890 23:01:30 INFO - actual date: 1463464890 23:01:30 INFO - starting webserver on 'localhost:56017' 23:01:30 INFO - SUITE-START | Running 2 tests 23:01:30 INFO - TEST-START | dromaeo_css 23:01:30 INFO - operating with platform_type : linux_ 23:01:30 INFO - Initialising browser for dromaeo_css test... 23:01:30 INFO - TEST-INFO | started process 12194 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIYRVwt/profile http://localhost:56017/getInfo.html) 23:01:34 INFO - PROCESS | 12194 | __metrics Screen width/height:1600/1200 23:01:34 INFO - PROCESS | 12194 | colorDepth:24 23:01:34 INFO - PROCESS | 12194 | Browser inner width/height: 1024/697 23:01:34 INFO - PROCESS | 12194 | __metrics 23:01:36 ERROR - PROCESS | 12194 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:01:36 INFO - TEST-INFO | 12194: exit 0 23:01:36 INFO - Browser initialized. 23:01:36 INFO - Running cycle 1/1 for dromaeo_css test... 23:01:36 INFO - TEST-INFO | started process 12274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIYRVwt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:01:48 INFO - PROCESS | 12274 | RSS: Main: 152317952 23:01:48 INFO - PROCESS | 12274 | 23:02:39 INFO - PROCESS | 12274 | 1463464959894 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] 23:04:47 INFO - PROCESS | 12274 | Cycle 1(1): loaded http://localhost:56017/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56017/tests/dromaeo/cssquery-ext.html) 23:04:48 INFO - PROCESS | 12274 | RSS: Main: 258101248 23:04:48 INFO - PROCESS | 12274 | 23:07:51 INFO - PROCESS | 12274 | Cycle 1(1): loaded http://localhost:56017/tests/dromaeo/cssquery-ext.html (next: http://localhost:56017/tests/dromaeo/cssquery-jquery.html) 23:07:51 INFO - PROCESS | 12274 | RSS: Main: 232542208 23:07:51 INFO - PROCESS | 12274 | 23:10:53 INFO - PROCESS | 12274 | Cycle 1(1): loaded http://localhost:56017/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56017/tests/dromaeo/cssquery-mootools.html) 23:10:54 INFO - PROCESS | 12274 | RSS: Main: 241938432 23:10:54 INFO - PROCESS | 12274 | 23:13:56 INFO - PROCESS | 12274 | Cycle 1(1): loaded http://localhost:56017/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56017/tests/dromaeo/cssquery-prototype.html) 23:13:56 INFO - PROCESS | 12274 | RSS: Main: 236064768 23:13:56 INFO - PROCESS | 12274 | 23:16:59 INFO - PROCESS | 12274 | Cycle 1(1): loaded http://localhost:56017/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56017/tests/dromaeo/cssquery-yui.html) 23:17:00 INFO - PROCESS | 12274 | RSS: Main: 209629184 23:17:00 INFO - PROCESS | 12274 | 23:20:02 INFO - PROCESS | 12274 | Cycle 1(1): loaded http://localhost:56017/tests/dromaeo/cssquery-yui.html (next: http://localhost:56017/tests/dromaeo/cssquery-dojo.html) 23:20:03 INFO - PROCESS | 12274 | RSS: Main: 270831616 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | __start_tp_report 23:20:03 INFO - PROCESS | 12274 | _x_x_mozilla_page_load 23:20:03 INFO - PROCESS | 12274 | _x_x_mozilla_page_load_details 23:20:03 INFO - PROCESS | 12274 | |i|pagename|runs| 23:20:03 INFO - PROCESS | 12274 | |0;dojo.html;3141.670124636913;3150.3069324748553;3191.569138166347;3191.824449655269;3230.5477233187357;5186.636935414523;5217.713025783581;5283.788648454062;5371.43599922008;5394.703291318976;5284.656497327671;5287;5298.867528311795;5351.170568561879;5352.785888564453;4734.171519984006;4817.349657796188;4828.951710482886;4837.467878533358;4975.552200301972;4892.5596696297325;4899.828506002291;4902.338184345122;4931.753412329387;4974.10466116099;4735.834245801398;4752.334673145745;4783.473817880031;4820.276958456259;4824.8552543423575;4799.9760001199775;4803.687760295605;4864.1974074277705;4871.585915197213;4896.46138924718;4719.622430205575;4835.879103022417;4858.5384388483035;4859.73271470069;4905.803767849282;5132.40977287613;5160.509751573594;5196.532312091892;5266.947330526684;5277.920831187535;5578.804741834017;5610.803621873256;5652.378238393773;5684.261046063985;5686.687232202227;5630.605857589971;5716.857078573069;5731.914021289684;5746.425357464262;5754.597178197528;5391.541718953894;5395.0558652236095;5416.485433883775;5419.5393391561765;5419.5393391561765;5889.4993925515955;5896.233489646404;5898.262717160355;5902.2032025676845;5905.114232865105;5428.0500912340185;5571.359293681197;5581.2186293918885;5624.0439125348785;5684.175794509855;5741;5747.224124743189;5753.884922301614;5798.33319168301;5804.709764511757;5702;5719.227904232958;5721.885562288814;5722.513586345207;5726.713664316768;5527.695976721238;5622.606417550732;5626.887462250814;5685.118806584986;5705.74324155414;4373.190959672471;4375.059362237134;4384.129471147697;4389.62688171509;4424.911501769947;3845.288621605012;3914.0606254498716;3926.5877082906454;3945.3913405701896;3964.8215830287704;5311.52196302327;5337.0393329201115;5346.599005074557;5370.167624018283;5370.785168593213;5647.802326918538;5685.147227915846;5685.744141513642;5686.14707793826;5693.231413759172;5205.245239440335;5339.713872688285;5362.436944120811;5367.78528858849;5419.593530485229;4935.383077115361;4975.601951843843;4995.550400463976;4996.275540046673;5031.899362012739;6022.156898034279;6039.66781826995;6040.184575082396;6044.184035155197;6045.153678485016;5463.07127788269;5579.051561234519;5640.746166422438;5737.885242295214;5759.366469688414;5925.318588362394;5948.137520059654;5950.315713692808;5951.851203719942;5994.9808532548695;5545.972270138624;5573.108302671553;5575.498205161555;5581.6371935824045;5582.720863956867;5496.6701997880255;5591.944080559142;5593.049181639212;5640.125780504029;5646.463585959327;5502.862428439321;5508.283923089973;5516.668999860021;5518.696471694096;5519.668819870821;5412.458754124556;5465;5512.310961129859;5514.972425137848;5569.721513924206;5242.056429842512;5355.384130825005;5370.167624018127;5405.621606487665;5436.266104075899;5545.584081194007;5553.527950124285;5600.215969764317;5600.4959553640365;5603.075492543848;5328.3605967284175;5422.105352616774;5425.186222066722;5425.701586412627;5469.15228139639 23:20:03 INFO - PROCESS | 12274 | |1;ext.html;615.3323643846427;654.1986067067843;658.4205898809047;667.3893387550391;675.560885424474;32934.50598241025;35802.46296305557;35930.82034589827;35993.640063599356;47071.52928470714;45692.771536142354;46075;46197.30704039442;46204;46223.53776462234;8818.07410221927;8949.463032218055;8959.955200224022;8960.417572857776;8963.238124759371;8801.823963520726;8894.777630559256;8907.73276801697;8908.554572271392;8909.73270801877;8997.460152390844;9035.277177825757;9039.638414463414;9040.276777857756;9040.77398065047;8907.777305567348;8937.910620893805;8956.641734330618;8958.37291389603;8960.955195224014;8814.735557933273;8862.911370886273;8867.911320886773;8875.73372798817;8880.8667869982;41879.37180942288;42318.78840605777;42340.78829605863;42354.57645423537;42418;3498.6326435724136;3583.7491375603718;3672.2839046385966;3777.7544459610035;4007.579204183577;32412.6758732412;32527.186820329443;32767.50848737271;32780.18049548756;34224.315513689835;4135.421041054255;4148.522919864224;4199.559046300125;4335.848245311399;4393.912121757547;340677.9999999975;341871;342941;343039;344429;51878.74060629673;55586.27516661048;56399;56597.15104273439;57684.134737978966;62241.377586223556;62380;62483;62603.6869815648;62634.373656263764;15219.391224351124;15470.5358839235;15479.380824767108;15486.612834678997;15492.690146197237;10844.945775271073;11082.05802506796;11412.258203216765;11423.828642570368;11432.428378581038;16517.174141292886;16633.0851803152;16655.91672041632;16686.33254669824;16687.582810429838;5968.9701551492835;5999.460318412158;6187.845303867385;6191.473724733448;6221.751129954751;962.2638681408728;993.8906720260766;1059.6344261229863;1066.4294602387686;1093.5133865429807;32206.677933220835;32475.51286730701;32973.83513082419;33119;33565.49651755226;1136.4374634555948;1138.732397886496;1198.3469009389903;1212.7816992941355;1257.5180408147107;17237.56906077333;17242.913785430992;17498.387556435464;17562.209700563504;17624.91187544054;149498;151124;151306;151505;152432;59237.40762592319;59301.40698593045;59554.70222648859;59639;59694;1394.1426022995988;1420.5880294714623;1425.5153247895764;1440.625437386287;1446.2624061728384;31284.530732039493;31536.526952096196;32054.839725801223;32115.518267225543;32256.838715806272;51588.48411515987;52048.21927671012;52056.47943520516;52134.78652134756;52178.73910630422;432362;440021;678687;694601;708825;39302.6069739299;39657.40513892352;39702;40119.598804012756;40206.59793402028;46856.765716172566;47015;47033.529664704285;47223.29165062458;47623.28565071458;38806.61193388143;39045;39434;39903.80048099741;40767.796161019;39070.21859562736;39747.60252397555;39848.60151398449;39925.60074399219;40329.395059074726;39379.6062039376;40139.397909030806;40654.1869162621;40832;40882.9779255521;33148.834255828566;34353.484697730055;34647;35494.29011419706;35608.46587301244;27055;27159.456810864067;27380.452390951672;27431.86284068567;27593.492288910802 23:20:03 INFO - PROCESS | 12274 | |2;jquery.html;400.93585026395783;406.6705968165785;409.3246143862626;412.31967254030843;426.95730426957306;13354.399052042658;17924.910375448122;19078.523036924085;19541.316053938113;19547.413577592677;14566.126032438062;16035.438759643415;17126;18254.269829206816;19219.807801921976;5549.639273447213;5726.427357264261;5741.626794258381;5773.595848290622;5792.884142317151;5670.206171136044;5677.48902598766;5712.800051998181;5713.885722285552;5740.425957404247;3964.2071585682834;4177.791110444481;5904.084866845646;6226.408491193352;6235.8129256122165;6225.408586184305;6341.492680585542;6347.682615869188;6355.396237357444;6364.904526432108;5489.682476205722;5590.133529302964;5610.410906854762;5634.3520495143175;5634.943650563524;21953.890230548906;22174.11303547856;22421.213383312548;22745.886270568542;22775.658365124535;2454.705435347752;2459.9016039358403;2573.8455692658313;2576.716561178269;2977.5980242667397;13784.931075344559;14370.640733981734;14380.712385752226;14380.712385752331;15148.318325675371;1938.4281636917144;2004.4487765864503;2050.4771283322807;2109.6624540073512;2113.5984163009134;81325;83880;84692.57653711754;84881.15118848794;85177.57411212966;10345.741356465998;10673.932606739263;10797.570546627103;10887.945560272148;11024.724381890517;14979.251037448084;15050.172240526657;15095.169765662993;15095.622609434633;15095.773563396559;7868.370530357558;7888.290053895063;8120.187981201951;8187.918120818835;8223;7694.153643099255;7749.767506974687;7795.415343849234;7795.454318197785;7930.762077137695;5948.553858460633;6581.769638062645;6699;6704.463642908556;6737.427318677869;1766.9726795886272;1943.4752616793387;1959.4709428454237;1989.761228652542;1990.6317331293758;872.4547158026234;994.3387647214521;1013.5084484025242;1032.6850310655173;1033.245730616654;18381.81618183828;18390.63218735618;18645.440636780917;19335.226590936487;19604.215831366586;967.5355829202023;974.4007435427303;997.6757553795046;1021.0861279155115;1033.4367769565604;10568.788624227473;11114.333140011464;11146.21976461656;11334.829977550344;11355.432228388712;495008;501608;508067;514247;519921;81639.5918020418;84756;84778.5761071203;85057.57471212729;85095.57452212699;1047.6699839550517;1069.5775168808277;1089.624079692505;1099.2085698297212;1111.4831603304501;16575.502734917973;16878.156092195095;17131.571710706834;17168.313267469162;17515.912420437817;27852;37741;38363.616363836;38594.42108368433;40253.39619905762;57127.42872571221;58119.231728610626;58786;59467;59622.403775961684;8561.914380856111;8794.868076978722;8851.247643950337;9062.048484909254;9141.908580914105;7959.442839001446;9419.529023548932;9554.713358599252;9722.513874306114;9958.203343732339;5817.301923769006;8667.566621669017;8774.38579299443;8844.95577522134;9210.769730756516;8856.158664926821;9111.134442227978;9126;9143.817123657622;9167.49578773174;8944.86582701247;9198.26413886874;9258;9369.016253293306;9540.189083927944;12721.618351449471;13265.270410127536;13389.531366402129;13464.663383415493;13468.46126154982;12259.877401225873;12398;12423.75152496927;12489.625311240678;12578.622641320775 23:20:03 INFO - PROCESS | 12274 | |3;mootools.html;1356.3218390804598;1538.476887926648;1549.0782984124446;1731.3161301285993;1849.4700844475024;8322.167783221676;8605.139486051392;8631.136886311373;8645.56772161391;8883.866741998876;8392.07687154414;8623.956880215606;8837.690680826172;8850.955745221265;8870.645174193025;2077.802608752166;2140.186729042969;2278.8176945844334;2281.0989659084735;2326.3835083702834;2356.02225076593;2377.6433534969806;2378.369732021016;2379.4884099918513;2380.8452450590744;2344.5662552427857;2364.798992085675;2366.2428023032635;2367.4554852383962;2369.312899259213;1981.3263490413256;2210.933671989827;2261.378121016717;2263.5586060718165;2297.023764899919;2278.396225000376;2298.7241531016384;2299.8965046572944;2302.378357843373;2305.296884450242;8617.052124266325;8764.1674040966;8767.123287671246;8768.386212965095;8769.561521923943;5189.974050129726;5305.310309659758;5310.389305229909;5310.495502927216;5311.601629877736;3435.6736110069755;3484.52958850557;3549.982250088733;3566.054995426214;3583.928321433583;1074.8334008228737;1087.7117563845586;1094.7646256054907;1113.9498722557423;1174.2778191412306;11969;12095.092868034933;12113.636590902199;12114.454849531792;12136.453859576428;4867.415910090741;4893.559579637778;4914.140025495595;4975.701457912537;4991.700497970133;10600.681979540626;10625.362478251329;10635.946820266005;10664.840027399596;10686.679399618024;5565.526930210977;5571.693556854412;5576.609637325349;5582.0789569721355;5582.91625625616;4459.866204013885;4861.781219845045;4986.675866068694;5008.499150084962;5057.494250574913;4175.498940127204;4191.077962848169;4255.936015996001;4261.424707664426;4346.326319420495;1321.5242512695613;1359.0758284366527;1364.624728199753;1371.0265711345057;1373.2241283674891;884.9380543362032;964.2816102004052;999.9850002249972;1005.1506477026901;1013.6198925402974;6067.666278354644;6110.908336374958;6373.74505019794;6378.904316435257;6560.967195164089;479.6234955559869;479.8344571122957;484.8787803049238;488.96332775042015;490.6026118843748;4254.234237837157;4332.588404101605;4333.090051089243;4374.628156606724;4477.3731677565165;10887.346759194315;11144.996950274513;11228.214025018333;11250.7187320316;11256.774864502664;10923.289986150874;11060.668179954615;11100.777984440267;11103.111751060058;11173.944130279458;8056.476329038711;8065.677372905136;8140.185981401813;8150.959245203855;8161.632726527202;5923.526117910471;6059.151718759289;6141.324454309933;6156.26124865019;6220;9921.603135874773;10252.538635761557;10291.639792607522;10339.069483746198;10415.895841041493;10406.635767748092;10414.895851041392;10513.316634419045;10519.21105917044;10522.526486308288;7774.455788094779;7933.8413231736195;8067.193280672121;8069.6772129116525;8084.312833408991;8596.91403085961;8620.78448038804;8655.783605409662;8680.78298042554;8682.43564168327;7797.259260370256;7830.373570114263;7877.724279650185;7958.243966823143;7999.4400391972;7592.620368981639;7630.198829123085;7639.427042971688;7663.27198916102;7863.488873223222;7284.3808276294985;7295.671694773641;7334.633268336455;7372.078490188726;7382.26177382279;5201.895962080813;5242.266082748342;5333.679979201261;5349.4650534946195;5372.489613486712;5342.1986701993455;5490.972545137249;5508.91736623959;5635.380108188014;5760.971195143998 23:20:03 INFO - PROCESS | 12274 | |4;prototype.html;333.71571592449686;339.26379755929634;342.9228423604688;344.5486412799232;347.92519608284215;5266.157414813631;5299.867503312419;5308.177232528955;5318.521333080022;5346.89306213876;5182.740862956846;5206.9218961715505;5210.478952104788;5241.3186285782795;5255.605829562779;3914.3149948758996;4176.164767046587;4177.770222637769;4181.268278051344;4181.519125300599;3994.760314381132;4136.834526618932;4153.210889930919;4173.833046678129;4182.53992060873;4106.630403263705;4122.732022418548;4137.296659567881;4140.0270936329935;4143.523494798092;3907.179492306619;4046.291898917678;4152.356384760367;4170.416141740144;4171.0823618803815;3553.614090504705;3619.7285203609836;3669.8165091745573;3674.5590529136675;3695.9260814783825;5063.569596584295;5066.82266120684;5077.923831142536;5126.231065340229;5184;4687.6249900007915;4726.763661816895;4756.690815097042;4790.952090479121;4806.452580132535;5446.537044351235;5457.563394928436;5486.31421072366;5548.833534993957;5552.805651802168;4055.837766489337;4059.7158198926086;4064.512258528966;4070.3894415837863;4087.7751723655188;5488.9725551372385;5665.631733937322;5736.3403208631125;5747.2241247431475;5759.1937128802;4105.838504351692;4133.710640255214;4136.503619565611;4143.135588984908;4162.91674166515;4169.937450938239;4205.116925445691;4217.536071032184;4220.282551966173;4222.282212023963;4742.103158736533;4752.334673145762;4762.452317983407;4777.761111944389;4793.592544633675;5328.17413300939;5485.561155107582;5520.475554822286;5524.701695101648;5526.778928842801;1425.180521200314;1441.84139744628;1445;1448.7323591857125;1454.0548643381676;916.3219217778928;935.9797820375823;942.7037822993384;955.5222388805597;963.5856581669809;4740.976295118502;4786.99999999993;5175.741212939338;5265.446693225507;5271.6309858310315;5491.560675145979;5519.862003449946;5532.869845109662;5686.431356864363;5689.260396148474;5390.733177703237;5393.379761327419;5399.163129714901;5407.378151512547;5424.321959755031;2863.083813179804;3000.5799188113683;3004.053723077224;3017.200441882903;3019.5621634862823;5861.765529378862;5878.47093761555;5894.410558944071;5894.852628684232;5923.08192230205;5458.344998600193;5713.914291285634;5714.628549144292;5735.541156707453;5782.715827248214;5849.005669036274;5919.940800592025;5964.343922168558;5970.62675584618;5992.8501787453915;5683.811732483106;5714.285714285715;5736.311642602915;5741.770329186786;5760.711964401679;5435.23906653061;5646.774129034792;5649.68926709035;5655.066913959151;5681.545476361798;5934.851628709317;5954.940450595439;5955.91066134017;5957.434043765836;5983.042713165873;3894.4742459767567;4122.175564886975;4124.608162224584;4125.525564559994;4127.607877251657;5679.914801278067;5685.51673107774;5695.971520142372;5702.971485142548;5707.258056452634;5517.475839795213;5519.420460851713;5534.861628459321;5543.611947163821;5544.473275038865;5276.525112739719;5484.698341591251;5490.846922146394;5502.284702988746;5537.587915081789;5415.675059496442;5418.051840927743;5453.291072160594;5454.509094181542;5466.671999680032;5312.9734351328;5435.211894275229;5437.776500287404;5448.482394172547;5531.917021244765;5125.79496820123;5359.812406565751;5373.650712703662;5386.622936394415;5409.945060713123 23:20:03 INFO - PROCESS | 12274 | |5;yui.html;763.496092578898;782.679101568357;812.6424373275754;835.9791005224869;842.1410161635131;13720.313984300783;15010.099394036339;15131;15334.309956051977;15614.531564053097;13887.722245555084;13956.651083722887;13974.301284935738;13977.440902363893;14034.298285085755;702.9683664235122;829.4732844643635;842.9494230346183;855.9614817333235;856.6616186606318;844.0546587821648;846.4921047371589;847.9788005299855;850.9446885952424;851.3104385447776;891.4651209274416;891.6388862510694;892.5581836990699;893.066745251214;893.0712059458156;732.5348403763621;755.7732680195919;756.130449982519;861.388414225894;892.7098692924825;645.3159650770199;646.9061986012049;647.3009150117862;647.3106141958799;647.4334956912701;14634.926825365912;14635.560933172022;14671.413143474248;14690.55928322152;14751.483698070624;13145.211287322696;13154.802677959839;13392.464301427932;13725.519606813814;13895.94289256362;10870.456477176189;10915.399653019082;11059.447027648584;11649.59226427071;11685.357305348203;4431.202383570956;4517.638588912879;4533.252013417782;4543.613792827614;4630.722156670577;61849.69075154595;61993.38006619921;62070.68964655193;64031.359686403004;64205.35794642041;10952.014318711195;10953.56185752561;11102.333859968428;11105.611303604335;11144.721381965353;19187.32844350441;19363.32228372;19401.611967760567;19406.8059319405;19493;8031.317338026333;8511.446755960966;8558.871616925751;8645.26515246211;8733.301335893113;4716.929246061243;4721.622270218374;4754.003894607142;4765.237561990134;4813.927791083137;3229.8869539566003;3299.5215693724754;3305.752068594865;3306.6032076151014;3306.6197387300285;663.0683889135772;664.9767258146038;671.701093013614;672.5930811858852;684.750066225824;729.8102493351661;765.4603504529293;778.2723153851167;798.5607915646372;809.1544336168718;10046.397216167054;10066.647667331608;10113.848292275621;10187.08316251541;10208.693739187836;684.5174152222671;684.6337209592843;699.9055127557715;704.1550139832119;714.3570786292274;4417;4578;4583.427071616048;4655.813767449264;4712.128256272601;65794.67102664456;70686.29313706797;70858;71386;71943.64028179826;63313;63761;64085;64302.67848660727;64439.67780161069;55141.897162056535;55954;56172.43827561754;57020.714896425256;57154;8658.220760131619;8791.5164665944;8799.428037177562;8814.074522175079;8991.640334386551;38680.41979370368;38986.415203772536;39018.41472377974;39070;39299;50134.49865501444;51755.741221293654;52518.47481525136;52853.47146528639;53273;11586.362750048827;11636.243644163102;11766;11829.52681892749;12086.576969806014;13939.721205576034;14273.928630356782;14488.492902748774;14542.127472351693;15226.46707365237;13098;13364.665883352995;13388.397522111334;13439.193648381128;13620.795688064885;10840.566377344818;13504;13621.455141794217;13645.590632281048;13739.313034348042;13519.101123595505;13681.247531385869;13727.382267798173;13883;14225.928870355581;10703.678889633324;10977.341359518454;11377.374244416305;11579.421028948687;11599.884001160219;11159.386233756897;11273.7745245093;11418.486168122288;11488.94255528717;11769.705757356134 23:20:03 INFO - PROCESS | 12274 | __end_tp_report 23:20:03 INFO - PROCESS | 12274 | __start_cc_report 23:20:03 INFO - PROCESS | 12274 | _x_x_mozilla_cycle_collect,866 23:20:03 INFO - PROCESS | 12274 | __end_cc_report 23:20:03 INFO - PROCESS | 12274 | __startTimestamp1463466003138__endTimestamp 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | ------- Summary: start ------- 23:20:03 INFO - PROCESS | 12274 | Number of tests: 6 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | [#0] dojo.html Cycles:165 Average:5284.95 Median:5425.19 stddev:585.95 (10.8%) stddev-sans-first:563.10 23:20:03 INFO - PROCESS | 12274 | Values: 3141.7 3150.3 3191.6 3191.8 3230.5 5186.6 5217.7 5283.8 5371.4 5394.7 5284.7 5287.0 5298.9 5351.2 5352.8 4734.2 4817.3 4829.0 4837.5 4975.6 4892.6 4899.8 4902.3 4931.8 4974.1 4735.8 4752.3 4783.5 4820.3 4824.9 4800.0 4803.7 4864.2 4871.6 4896.5 4719.6 4835.9 4858.5 4859.7 4905.8 5132.4 5160.5 5196.5 5266.9 5277.9 5578.8 5610.8 5652.4 5684.3 5686.7 5630.6 5716.9 5731.9 5746.4 5754.6 5391.5 5395.1 5416.5 5419.5 5419.5 5889.5 5896.2 5898.3 5902.2 5905.1 5428.1 5571.4 5581.2 5624.0 5684.2 5741.0 5747.2 5753.9 5798.3 5804.7 5702.0 5719.2 5721.9 5722.5 5726.7 5527.7 5622.6 5626.9 5685.1 5705.7 4373.2 4375.1 4384.1 4389.6 4424.9 3845.3 3914.1 3926.6 3945.4 3964.8 5311.5 5337.0 5346.6 5370.2 5370.8 5647.8 5685.1 5685.7 5686.1 5693.2 5205.2 5339.7 5362.4 5367.8 5419.6 4935.4 4975.6 4995.6 4996.3 5031.9 6022.2 6039.7 6040.2 6044.2 6045.2 5463.1 5579.1 5640.7 5737.9 5759.4 5925.3 5948.1 5950.3 5951.9 5995.0 5546.0 5573.1 5575.5 5581.6 5582.7 5496.7 5591.9 5593.0 5640.1 5646.5 5502.9 5508.3 5516.7 5518.7 5519.7 5412.5 5465.0 5512.3 5515.0 5569.7 5242.1 5355.4 5370.2 5405.6 5436.3 5545.6 5553.5 5600.2 5600.5 5603.1 5328.4 5422.1 5425.2 5425.7 5469.2 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | [#1] ext.html Cycles:180 Average:53617.44 Median:40173.00 stddev:110315.48 (274.6%) stddev-sans-first:110553.17 23:20:03 INFO - PROCESS | 12274 | Values: 615.3 654.2 658.4 667.4 675.6 32934.5 35802.5 35930.8 35993.6 47071.5 45692.8 46075.0 46197.3 46204.0 46223.5 8818.1 8949.5 8960.0 8960.4 8963.2 8801.8 8894.8 8907.7 8908.6 8909.7 8997.5 9035.3 9039.6 9040.3 9040.8 8907.8 8937.9 8956.6 8958.4 8961.0 8814.7 8862.9 8867.9 8875.7 8880.9 41879.4 42318.8 42340.8 42354.6 42418.0 3498.6 3583.7 3672.3 3777.8 4007.6 32412.7 32527.2 32767.5 32780.2 34224.3 4135.4 4148.5 4199.6 4335.8 4393.9 340678.0 341871.0 342941.0 343039.0 344429.0 51878.7 55586.3 56399.0 56597.2 57684.1 62241.4 62380.0 62483.0 62603.7 62634.4 15219.4 15470.5 15479.4 15486.6 15492.7 10844.9 11082.1 11412.3 11423.8 11432.4 16517.2 16633.1 16655.9 16686.3 16687.6 5969.0 5999.5 6187.8 6191.5 6221.8 962.3 993.9 1059.6 1066.4 1093.5 32206.7 32475.5 32973.8 33119.0 33565.5 1136.4 1138.7 1198.3 1212.8 1257.5 17237.6 17242.9 17498.4 17562.2 17624.9 149498.0 151124.0 151306.0 151505.0 152432.0 59237.4 59301.4 59554.7 59639.0 59694.0 1394.1 1420.6 1425.5 1440.6 1446.3 31284.5 31536.5 32054.8 32115.5 32256.8 51588.5 52048.2 52056.5 52134.8 52178.7 432362.0 440021.0 678687.0 694601.0 708825.0 39302.6 39657.4 39702.0 40119.6 40206.6 46856.8 47015.0 47033.5 47223.3 47623.3 38806.6 39045.0 39434.0 39903.8 40767.8 39070.2 39747.6 39848.6 39925.6 40329.4 39379.6 40139.4 40654.2 40832.0 40883.0 33148.8 34353.5 34647.0 35494.3 35608.5 27055.0 27159.5 27380.5 27431.9 27593.5 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | [#2] jquery.html Cycles:180 Average:29094.46 Median:2302.37 stddev:83551.96 (3628.9%) stddev-sans-first:83758.57 23:20:03 INFO - PROCESS | 12274 | Values: 400.9 406.7 409.3 412.3 427.0 13354.4 17924.9 19078.5 19541.3 19547.4 14566.1 16035.4 17126.0 18254.3 19219.8 5549.6 5726.4 5741.6 5773.6 5792.9 5670.2 5677.5 5712.8 5713.9 5740.4 3964.2 4177.8 5904.1 6226.4 6235.8 6225.4 6341.5 6347.7 6355.4 6364.9 5489.7 5590.1 5610.4 5634.4 5634.9 21953.9 22174.1 22421.2 22745.9 22775.7 2454.7 2459.9 2573.8 2576.7 2977.6 13784.9 14370.6 14380.7 14380.7 15148.3 1938.4 2004.4 2050.5 2109.7 2113.6 81325.0 83880.0 84692.6 84881.2 85177.6 10345.7 10673.9 10797.6 10887.9 11024.7 14979.3 15050.2 15095.2 15095.6 15095.8 7868.4 7888.3 8120.2 8187.9 8223.0 7694.2 7749.8 7795.4 7795.5 7930.8 5948.6 6581.8 6699.0 6704.5 6737.4 1767.0 1943.5 1959.5 1989.8 1990.6 872.5 994.3 1013.5 1032.7 1033.2 18381.8 18390.6 18645.4 19335.2 19604.2 967.5 974.4 997.7 1021.1 1033.4 10568.8 11114.3 11146.2 11334.8 11355.4 495008.0 501608.0 508067.0 514247.0 519921.0 81639.6 84756.0 84778.6 85057.6 85095.6 1047.7 1069.6 1089.6 1099.2 1111.5 16575.5 16878.2 17131.6 17168.3 17515.9 27852.0 37741.0 38363.6 38594.4 40253.4 57127.4 58119.2 58786.0 59467.0 59622.4 8561.9 8794.9 8851.2 9062.0 9141.9 7959.4 9419.5 9554.7 9722.5 9958.2 5817.3 8667.6 8774.4 8845.0 9210.8 8856.2 9111.1 9126.0 9143.8 9167.5 8944.9 9198.3 9258.0 9369.0 9540.2 12721.6 13265.3 13389.5 13464.7 13468.5 12259.9 12398.0 12423.8 12489.6 12578.6 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | [#3] mootools.html Cycles:180 Average:5900.54 Median:4864.60 stddev:3339.73 (68.7%) stddev-sans-first:3331.64 23:20:03 INFO - PROCESS | 12274 | Values: 1356.3 1538.5 1549.1 1731.3 1849.5 8322.2 8605.1 8631.1 8645.6 8883.9 8392.1 8624.0 8837.7 8851.0 8870.6 2077.8 2140.2 2278.8 2281.1 2326.4 2356.0 2377.6 2378.4 2379.5 2380.8 2344.6 2364.8 2366.2 2367.5 2369.3 1981.3 2210.9 2261.4 2263.6 2297.0 2278.4 2298.7 2299.9 2302.4 2305.3 8617.1 8764.2 8767.1 8768.4 8769.6 5190.0 5305.3 5310.4 5310.5 5311.6 3435.7 3484.5 3550.0 3566.1 3583.9 1074.8 1087.7 1094.8 1113.9 1174.3 11969.0 12095.1 12113.6 12114.5 12136.5 4867.4 4893.6 4914.1 4975.7 4991.7 10600.7 10625.4 10635.9 10664.8 10686.7 5565.5 5571.7 5576.6 5582.1 5582.9 4459.9 4861.8 4986.7 5008.5 5057.5 4175.5 4191.1 4255.9 4261.4 4346.3 1321.5 1359.1 1364.6 1371.0 1373.2 884.9 964.3 1000.0 1005.2 1013.6 6067.7 6110.9 6373.7 6378.9 6561.0 479.6 479.8 484.9 489.0 490.6 4254.2 4332.6 4333.1 4374.6 4477.4 10887.3 11145.0 11228.2 11250.7 11256.8 10923.3 11060.7 11100.8 11103.1 11173.9 8056.5 8065.7 8140.2 8151.0 8161.6 5923.5 6059.2 6141.3 6156.3 6220.0 9921.6 10252.5 10291.6 10339.1 10415.9 10406.6 10414.9 10513.3 10519.2 10522.5 7774.5 7933.8 8067.2 8069.7 8084.3 8596.9 8620.8 8655.8 8680.8 8682.4 7797.3 7830.4 7877.7 7958.2 7999.4 7592.6 7630.2 7639.4 7663.3 7863.5 7284.4 7295.7 7334.6 7372.1 7382.3 5201.9 5242.3 5333.7 5349.5 5372.5 5342.2 5491.0 5508.9 5635.4 5761.0 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | [#4] prototype.html Cycles:180 Average:4662.65 Median:5315.75 stddev:1359.40 (25.6%) stddev-sans-first:1323.82 23:20:03 INFO - PROCESS | 12274 | Values: 333.7 339.3 342.9 344.5 347.9 5266.2 5299.9 5308.2 5318.5 5346.9 5182.7 5206.9 5210.5 5241.3 5255.6 3914.3 4176.2 4177.8 4181.3 4181.5 3994.8 4136.8 4153.2 4173.8 4182.5 4106.6 4122.7 4137.3 4140.0 4143.5 3907.2 4046.3 4152.4 4170.4 4171.1 3553.6 3619.7 3669.8 3674.6 3695.9 5063.6 5066.8 5077.9 5126.2 5184.0 4687.6 4726.8 4756.7 4791.0 4806.5 5446.5 5457.6 5486.3 5548.8 5552.8 4055.8 4059.7 4064.5 4070.4 4087.8 5489.0 5665.6 5736.3 5747.2 5759.2 4105.8 4133.7 4136.5 4143.1 4162.9 4169.9 4205.1 4217.5 4220.3 4222.3 4742.1 4752.3 4762.5 4777.8 4793.6 5328.2 5485.6 5520.5 5524.7 5526.8 1425.2 1441.8 1445.0 1448.7 1454.1 916.3 936.0 942.7 955.5 963.6 4741.0 4787.0 5175.7 5265.4 5271.6 5491.6 5519.9 5532.9 5686.4 5689.3 5390.7 5393.4 5399.2 5407.4 5424.3 2863.1 3000.6 3004.1 3017.2 3019.6 5861.8 5878.5 5894.4 5894.9 5923.1 5458.3 5713.9 5714.6 5735.5 5782.7 5849.0 5919.9 5964.3 5970.6 5992.9 5683.8 5714.3 5736.3 5741.8 5760.7 5435.2 5646.8 5649.7 5655.1 5681.5 5934.9 5954.9 5955.9 5957.4 5983.0 3894.5 4122.2 4124.6 4125.5 4127.6 5679.9 5685.5 5696.0 5703.0 5707.3 5517.5 5519.4 5534.9 5543.6 5544.5 5276.5 5484.7 5490.8 5502.3 5537.6 5415.7 5418.1 5453.3 5454.5 5466.7 5313.0 5435.2 5437.8 5448.5 5531.9 5125.8 5359.8 5373.7 5386.6 5409.9 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - PROCESS | 12274 | [#5] yui.html Cycles:180 Average:16217.62 Median:4580.71 stddev:19651.74 (429.0%) stddev-sans-first:19672.61 23:20:03 INFO - PROCESS | 12274 | Values: 763.5 782.7 812.6 836.0 842.1 13720.3 15010.1 15131.0 15334.3 15614.5 13887.7 13956.7 13974.3 13977.4 14034.3 703.0 829.5 842.9 856.0 856.7 844.1 846.5 848.0 850.9 851.3 891.5 891.6 892.6 893.1 893.1 732.5 755.8 756.1 861.4 892.7 645.3 646.9 647.3 647.3 647.4 14634.9 14635.6 14671.4 14690.6 14751.5 13145.2 13154.8 13392.5 13725.5 13895.9 10870.5 10915.4 11059.4 11649.6 11685.4 4431.2 4517.6 4533.3 4543.6 4630.7 61849.7 61993.4 62070.7 64031.4 64205.4 10952.0 10953.6 11102.3 11105.6 11144.7 19187.3 19363.3 19401.6 19406.8 19493.0 8031.3 8511.4 8558.9 8645.3 8733.3 4716.9 4721.6 4754.0 4765.2 4813.9 3229.9 3299.5 3305.8 3306.6 3306.6 663.1 665.0 671.7 672.6 684.8 729.8 765.5 778.3 798.6 809.2 10046.4 10066.6 10113.8 10187.1 10208.7 684.5 684.6 699.9 704.2 714.4 4417.0 4578.0 4583.4 4655.8 4712.1 65794.7 70686.3 70858.0 71386.0 71943.6 63313.0 63761.0 64085.0 64302.7 64439.7 55141.9 55954.0 56172.4 57020.7 57154.0 8658.2 8791.5 8799.4 8814.1 8991.6 38680.4 38986.4 39018.4 39070.0 39299.0 50134.5 51755.7 52518.5 52853.5 53273.0 11586.4 11636.2 11766.0 11829.5 12086.6 13939.7 14273.9 14488.5 14542.1 15226.5 13098.0 13364.7 13388.4 13439.2 13620.8 10840.6 13504.0 13621.5 13645.6 13739.3 13519.1 13681.2 13727.4 13883.0 14225.9 10703.7 10977.3 11377.4 11579.4 11599.9 11159.4 11273.8 11418.5 11488.9 11769.7 23:20:03 INFO - PROCESS | 12274 | -------- Summary: end -------- 23:20:03 INFO - PROCESS | 12274 | 23:20:03 INFO - TEST-INFO | 12274: exit 0 23:20:03 INFO - TEST-OK | dromaeo_css | took 1113004ms 23:20:03 INFO - TEST-START | kraken 23:20:03 INFO - operating with platform_type : linux_ 23:20:03 INFO - Initialising browser for kraken test... 23:20:03 INFO - TEST-INFO | started process 12455 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJ9Oas/profile http://localhost:56017/getInfo.html) 23:20:07 INFO - PROCESS | 12455 | __metrics Screen width/height:1600/1200 23:20:07 INFO - PROCESS | 12455 | colorDepth:24 23:20:07 INFO - PROCESS | 12455 | Browser inner width/height: 1024/697 23:20:07 INFO - PROCESS | 12455 | __metrics 23:20:09 ERROR - PROCESS | 12455 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:20:09 INFO - TEST-INFO | 12455: exit 0 23:20:09 INFO - Browser initialized. 23:20:09 INFO - Running cycle 1/1 for kraken test... 23:20:09 INFO - TEST-INFO | started process 12540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJ9Oas/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:20:21 INFO - PROCESS | 12540 | RSS: Main: 152346624 23:20:21 INFO - PROCESS | 12540 | 23:20:24 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56017/tests/kraken/driver.html?testName=audio-beat-detection) 23:20:25 INFO - PROCESS | 12540 | RSS: Main: 208699392 23:20:25 INFO - PROCESS | 12540 | 23:20:27 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56017/tests/kraken/driver.html?testName=audio-dft) 23:20:28 INFO - PROCESS | 12540 | RSS: Main: 196931584 23:20:28 INFO - PROCESS | 12540 | 23:20:31 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56017/tests/kraken/driver.html?testName=audio-fft) 23:20:32 INFO - PROCESS | 12540 | RSS: Main: 265220096 23:20:32 INFO - PROCESS | 12540 | 23:20:34 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56017/tests/kraken/driver.html?testName=audio-oscillator) 23:20:35 INFO - PROCESS | 12540 | RSS: Main: 174452736 23:20:35 INFO - PROCESS | 12540 | 23:20:37 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56017/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:20:37 INFO - PROCESS | 12540 | RSS: Main: 178311168 23:20:37 INFO - PROCESS | 12540 | 23:20:41 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56017/tests/kraken/driver.html?testName=imaging-darkroom) 23:20:41 INFO - PROCESS | 12540 | RSS: Main: 214454272 23:20:41 INFO - PROCESS | 12540 | 23:20:45 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56017/tests/kraken/driver.html?testName=imaging-desaturate) 23:20:45 INFO - PROCESS | 12540 | RSS: Main: 212373504 23:20:45 INFO - PROCESS | 12540 | 23:20:48 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56017/tests/kraken/driver.html?testName=json-parse-financial) 23:20:49 INFO - PROCESS | 12540 | RSS: Main: 213508096 23:20:49 INFO - PROCESS | 12540 | 23:20:51 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56017/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:20:51 INFO - PROCESS | 12540 | RSS: Main: 202608640 23:20:51 INFO - PROCESS | 12540 | 23:20:53 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:20:54 INFO - PROCESS | 12540 | RSS: Main: 208650240 23:20:54 INFO - PROCESS | 12540 | 23:20:56 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:20:56 INFO - PROCESS | 12540 | RSS: Main: 216592384 23:20:56 INFO - PROCESS | 12540 | 23:20:59 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:21:00 INFO - PROCESS | 12540 | RSS: Main: 223436800 23:21:00 INFO - PROCESS | 12540 | 23:21:02 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:21:03 INFO - PROCESS | 12540 | RSS: Main: 218910720 23:21:03 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | Cycle 1(1): loaded http://localhost:56017/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56017/tests/kraken/driver.html?testName=ai-astar) 23:21:05 INFO - PROCESS | 12540 | RSS: Main: 264749056 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | __start_tp_report 23:21:05 INFO - PROCESS | 12540 | _x_x_mozilla_page_load 23:21:05 INFO - PROCESS | 12540 | _x_x_mozilla_page_load_details 23:21:05 INFO - PROCESS | 12540 | |i|pagename|runs| 23:21:05 INFO - PROCESS | 12540 | |0;ai-astar;112;110;111;110;110;109;110;110;109;109 23:21:05 INFO - PROCESS | 12540 | |1;audio-beat-detection;157;124;156;219;131;154;156;128;193;126 23:21:05 INFO - PROCESS | 12540 | |2;audio-dft;161;161;160;161;161;161;160;160;162;160 23:21:05 INFO - PROCESS | 12540 | |3;audio-fft;75;76;76;156;74;73;75;74;142;75 23:21:05 INFO - PROCESS | 12540 | |4;audio-oscillator;91;91;144;93;92;91;140;93;91;130 23:21:05 INFO - PROCESS | 12540 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;116;117;116 23:21:05 INFO - PROCESS | 12540 | |6;imaging-darkroom;145;145;145;145;145;146;145;145;146;145 23:21:05 INFO - PROCESS | 12540 | |7;imaging-desaturate;94;95;95;95;95;94;95;95;95;95 23:21:05 INFO - PROCESS | 12540 | |8;json-parse-financial;54;56;55;55;54;55;54;109;55;54 23:21:05 INFO - PROCESS | 12540 | |9;json-stringify-tinderbox;59;60;59;59;60;59;58;60;59;64 23:21:05 INFO - PROCESS | 12540 | |10;stanford-crypto-aes;79;78;79;84;77;86;82;80;79;80 23:21:05 INFO - PROCESS | 12540 | |11;stanford-crypto-ccm;122;120;135;120;116;123;132;120;199;129 23:21:05 INFO - PROCESS | 12540 | |12;stanford-crypto-pbkdf2;163;166;159;160;159;160;163;161;159;158 23:21:05 INFO - PROCESS | 12540 | |13;stanford-crypto-sha256-iterative;64;63;64;62;64;63;62;63;62;61 23:21:05 INFO - PROCESS | 12540 | __end_tp_report 23:21:05 INFO - PROCESS | 12540 | __start_cc_report 23:21:05 INFO - PROCESS | 12540 | _x_x_mozilla_cycle_collect,1863 23:21:05 INFO - PROCESS | 12540 | __end_cc_report 23:21:05 INFO - PROCESS | 12540 | __startTimestamp1463466065542__endTimestamp 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | ------- Summary: start ------- 23:21:05 INFO - PROCESS | 12540 | Number of tests: 14 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:0.94 (0.9%) stddev-sans-first:0.67 23:21:05 INFO - PROCESS | 12540 | Values: 112.0 110.0 111.0 110.0 110.0 109.0 110.0 110.0 109.0 109.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#1] audio-beat-detection Cycles:10 Average:154.40 Median:156.00 stddev:31.00 (19.9%) stddev-sans-first:32.87 23:21:05 INFO - PROCESS | 12540 | Values: 157.0 124.0 156.0 219.0 131.0 154.0 156.0 128.0 193.0 126.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 23:21:05 INFO - PROCESS | 12540 | Values: 161.0 161.0 160.0 161.0 161.0 161.0 160.0 160.0 162.0 160.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#3] audio-fft Cycles:10 Average:89.60 Median:75.00 stddev:31.49 (42.0%) stddev-sans-first:32.96 23:21:05 INFO - PROCESS | 12540 | Values: 75.0 76.0 76.0 156.0 74.0 73.0 75.0 74.0 142.0 75.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:91.00 stddev:22.63 (24.9%) stddev-sans-first:23.38 23:21:05 INFO - PROCESS | 12540 | Values: 91.0 91.0 144.0 93.0 92.0 91.0 140.0 93.0 91.0 130.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 23:21:05 INFO - PROCESS | 12540 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 23:21:05 INFO - PROCESS | 12540 | Values: 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 145.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 23:21:05 INFO - PROCESS | 12540 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:55.00 stddev:17.19 (31.3%) stddev-sans-first:18.10 23:21:05 INFO - PROCESS | 12540 | Values: 54.0 56.0 55.0 55.0 54.0 55.0 54.0 109.0 55.0 54.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.50 stddev:1.64 (2.8%) stddev-sans-first:1.72 23:21:05 INFO - PROCESS | 12540 | Values: 59.0 60.0 59.0 59.0 60.0 59.0 58.0 60.0 59.0 64.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.00 stddev:2.80 (3.5%) stddev-sans-first:2.92 23:21:05 INFO - PROCESS | 12540 | Values: 79.0 78.0 79.0 84.0 77.0 86.0 82.0 80.0 79.0 80.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#11] stanford-crypto-ccm Cycles:10 Average:131.60 Median:126.00 stddev:24.44 (19.4%) stddev-sans-first:25.67 23:21:05 INFO - PROCESS | 12540 | Values: 122.0 120.0 135.0 120.0 116.0 123.0 132.0 120.0 199.0 129.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:160.50 stddev:2.49 (1.5%) stddev-sans-first:2.51 23:21:05 INFO - PROCESS | 12540 | Values: 163.0 166.0 159.0 160.0 159.0 160.0 163.0 161.0 159.0 158.0 23:21:05 INFO - PROCESS | 12540 | 23:21:05 INFO - PROCESS | 12540 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.03 (1.6%) stddev-sans-first:1.00 23:21:05 INFO - PROCESS | 12540 | Values: 64.0 63.0 64.0 62.0 64.0 63.0 62.0 63.0 62.0 61.0 23:21:05 INFO - PROCESS | 12540 | -------- Summary: end -------- 23:21:05 INFO - PROCESS | 12540 | 23:21:06 INFO - TEST-INFO | 12540: exit 0 23:21:06 INFO - TEST-OK | kraken | took 62711ms 23:21:06 INFO - SUITE-END | took 1175s 23:21:06 INFO - Completed test suite (00:19:35) 23:21:06 INFO - javascript benchmark 23:21:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.670124636913, 3150.3069324748553, 3191.569138166347, 3191.824449655269, 3230.5477233187357, 5186.636935414523, 5217.713025783581, 5283.788648454062, 5371.43599922008, 5394.703291318976, 5284.656497327671, 5287.0, 5298.867528311795, 5351.170568561879, 5352.785888564453, 4734.171519984006, 4817.349657796188, 4828.951710482886, 4837.467878533358, 4975.552200301972, 4892.5596696297325, 4899.828506002291, 4902.338184345122, 4931.753412329387, 4974.10466116099, 4735.834245801398, 4752.334673145745, 4783.473817880031, 4820.276958456259, 4824.8552543423575, 4799.9760001199775, 4803.687760295605, 4864.1974074277705, 4871.585915197213, 4896.46138924718, 4719.622430205575, 4835.879103022417, 4858.5384388483035, 4859.73271470069, 4905.803767849282, 5132.40977287613, 5160.509751573594, 5196.532312091892, 5266.947330526684, 5277.920831187535, 5578.804741834017, 5610.803621873256, 5652.378238393773, 5684.261046063985, 5686.687232202227, 5630.605857589971, 5716.857078573069, 5731.914021289684, 5746.425357464262, 5754.597178197528, 5391.541718953894, 5395.0558652236095, 5416.485433883775, 5419.5393391561765, 5419.5393391561765, 5889.4993925515955, 5896.233489646404, 5898.262717160355, 5902.2032025676845, 5905.114232865105, 5428.0500912340185, 5571.359293681197, 5581.2186293918885, 5624.0439125348785, 5684.175794509855, 5741.0, 5747.224124743189, 5753.884922301614, 5798.33319168301, 5804.709764511757, 5702.0, 5719.227904232958, 5721.885562288814, 5722.513586345207, 5726.713664316768, 5527.695976721238, 5622.606417550732, 5626.887462250814, 5685.118806584986, 5705.74324155414, 4373.190959672471, 4375.059362237134, 4384.129471147697, 4389.62688171509, 4424.911501769947, 3845.288621605012, 3914.0606254498716, 3926.5877082906454, 3945.3913405701896, 3964.8215830287704, 5311.52196302327, 5337.0393329201115, 5346.599005074557, 5370.167624018283, 5370.785168593213, 5647.802326918538, 5685.147227915846, 5685.744141513642, 5686.14707793826, 5693.231413759172, 5205.245239440335, 5339.713872688285, 5362.436944120811, 5367.78528858849, 5419.593530485229, 4935.383077115361, 4975.601951843843, 4995.550400463976, 4996.275540046673, 5031.899362012739, 6022.156898034279, 6039.66781826995, 6040.184575082396, 6044.184035155197, 6045.153678485016, 5463.07127788269, 5579.051561234519, 5640.746166422438, 5737.885242295214, 5759.366469688414, 5925.318588362394, 5948.137520059654, 5950.315713692808, 5951.851203719942, 5994.9808532548695, 5545.972270138624, 5573.108302671553, 5575.498205161555, 5581.6371935824045, 5582.720863956867, 5496.6701997880255, 5591.944080559142, 5593.049181639212, 5640.125780504029, 5646.463585959327, 5502.862428439321, 5508.283923089973, 5516.668999860021, 5518.696471694096, 5519.668819870821, 5412.458754124556, 5465.0, 5512.310961129859, 5514.972425137848, 5569.721513924206, 5242.056429842512, 5355.384130825005, 5370.167624018127, 5405.621606487665, 5436.266104075899, 5545.584081194007, 5553.527950124285, 5600.215969764317, 5600.4959553640365, 5603.075492543848, 5328.3605967284175, 5422.105352616774, 5425.186222066722, 5425.701586412627, 5469.15228139639], "value": 5247.064402894071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.3323643846427, 654.1986067067843, 658.4205898809047, 667.3893387550391, 675.560885424474, 32934.50598241025, 35802.46296305557, 35930.82034589827, 35993.640063599356, 47071.52928470714, 45692.771536142354, 46075.0, 46197.30704039442, 46204.0, 46223.53776462234, 8818.07410221927, 8949.463032218055, 8959.955200224022, 8960.417572857776, 8963.238124759371, 8801.823963520726, 8894.777630559256, 8907.73276801697, 8908.554572271392, 8909.73270801877, 8997.460152390844, 9035.277177825757, 9039.638414463414, 9040.276777857756, 9040.77398065047, 8907.777305567348, 8937.910620893805, 8956.641734330618, 8958.37291389603, 8960.955195224014, 8814.735557933273, 8862.911370886273, 8867.911320886773, 8875.73372798817, 8880.8667869982, 41879.37180942288, 42318.78840605777, 42340.78829605863, 42354.57645423537, 42418.0, 3498.6326435724136, 3583.7491375603718, 3672.2839046385966, 3777.7544459610035, 4007.579204183577, 32412.6758732412, 32527.186820329443, 32767.50848737271, 32780.18049548756, 34224.315513689835, 4135.421041054255, 4148.522919864224, 4199.559046300125, 4335.848245311399, 4393.912121757547, 340677.9999999975, 341871.0, 342941.0, 343039.0, 344429.0, 51878.74060629673, 55586.27516661048, 56399.0, 56597.15104273439, 57684.134737978966, 62241.377586223556, 62380.0, 62483.0, 62603.6869815648, 62634.373656263764, 15219.391224351124, 15470.5358839235, 15479.380824767108, 15486.612834678997, 15492.690146197237, 10844.945775271073, 11082.05802506796, 11412.258203216765, 11423.828642570368, 11432.428378581038, 16517.174141292886, 16633.0851803152, 16655.91672041632, 16686.33254669824, 16687.582810429838, 5968.9701551492835, 5999.460318412158, 6187.845303867385, 6191.473724733448, 6221.751129954751, 962.2638681408728, 993.8906720260766, 1059.6344261229863, 1066.4294602387686, 1093.5133865429807, 32206.677933220835, 32475.51286730701, 32973.83513082419, 33119.0, 33565.49651755226, 1136.4374634555948, 1138.732397886496, 1198.3469009389903, 1212.7816992941355, 1257.5180408147107, 17237.56906077333, 17242.913785430992, 17498.387556435464, 17562.209700563504, 17624.91187544054, 149498.0, 151124.0, 151306.0, 151505.0, 152432.0, 59237.40762592319, 59301.40698593045, 59554.70222648859, 59639.0, 59694.0, 1394.1426022995988, 1420.5880294714623, 1425.5153247895764, 1440.625437386287, 1446.2624061728384, 31284.530732039493, 31536.526952096196, 32054.839725801223, 32115.518267225543, 32256.838715806272, 51588.48411515987, 52048.21927671012, 52056.47943520516, 52134.78652134756, 52178.73910630422, 432362.0, 440021.0, 678687.0, 694601.0, 708825.0, 39302.6069739299, 39657.40513892352, 39702.0, 40119.598804012756, 40206.59793402028, 46856.765716172566, 47015.0, 47033.529664704285, 47223.29165062458, 47623.28565071458, 38806.61193388143, 39045.0, 39434.0, 39903.80048099741, 40767.796161019, 39070.21859562736, 39747.60252397555, 39848.60151398449, 39925.60074399219, 40329.395059074726, 39379.6062039376, 40139.397909030806, 40654.1869162621, 40832.0, 40882.9779255521, 33148.834255828566, 34353.484697730055, 34647.0, 35494.29011419706, 35608.46587301244, 27055.0, 27159.456810864067, 27380.452390951672, 27431.86284068567, 27593.492288910802], "value": 19538.67094734485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.93585026395783, 406.6705968165785, 409.3246143862626, 412.31967254030843, 426.95730426957306, 13354.399052042658, 17924.910375448122, 19078.523036924085, 19541.316053938113, 19547.413577592677, 14566.126032438062, 16035.438759643415, 17126.0, 18254.269829206816, 19219.807801921976, 5549.639273447213, 5726.427357264261, 5741.626794258381, 5773.595848290622, 5792.884142317151, 5670.206171136044, 5677.48902598766, 5712.800051998181, 5713.885722285552, 5740.425957404247, 3964.2071585682834, 4177.791110444481, 5904.084866845646, 6226.408491193352, 6235.8129256122165, 6225.408586184305, 6341.492680585542, 6347.682615869188, 6355.396237357444, 6364.904526432108, 5489.682476205722, 5590.133529302964, 5610.410906854762, 5634.3520495143175, 5634.943650563524, 21953.890230548906, 22174.11303547856, 22421.213383312548, 22745.886270568542, 22775.658365124535, 2454.705435347752, 2459.9016039358403, 2573.8455692658313, 2576.716561178269, 2977.5980242667397, 13784.931075344559, 14370.640733981734, 14380.712385752226, 14380.712385752331, 15148.318325675371, 1938.4281636917144, 2004.4487765864503, 2050.4771283322807, 2109.6624540073512, 2113.5984163009134, 81325.0, 83880.0, 84692.57653711754, 84881.15118848794, 85177.57411212966, 10345.741356465998, 10673.932606739263, 10797.570546627103, 10887.945560272148, 11024.724381890517, 14979.251037448084, 15050.172240526657, 15095.169765662993, 15095.622609434633, 15095.773563396559, 7868.370530357558, 7888.290053895063, 8120.187981201951, 8187.918120818835, 8223.0, 7694.153643099255, 7749.767506974687, 7795.415343849234, 7795.454318197785, 7930.762077137695, 5948.553858460633, 6581.769638062645, 6699.0, 6704.463642908556, 6737.427318677869, 1766.9726795886272, 1943.4752616793387, 1959.4709428454237, 1989.761228652542, 1990.6317331293758, 872.4547158026234, 994.3387647214521, 1013.5084484025242, 1032.6850310655173, 1033.245730616654, 18381.81618183828, 18390.63218735618, 18645.440636780917, 19335.226590936487, 19604.215831366586, 967.5355829202023, 974.4007435427303, 997.6757553795046, 1021.0861279155115, 1033.4367769565604, 10568.788624227473, 11114.333140011464, 11146.21976461656, 11334.829977550344, 11355.432228388712, 495008.0, 501608.0, 508067.0, 514247.0, 519921.0, 81639.5918020418, 84756.0, 84778.5761071203, 85057.57471212729, 85095.57452212699, 1047.6699839550517, 1069.5775168808277, 1089.624079692505, 1099.2085698297212, 1111.4831603304501, 16575.502734917973, 16878.156092195095, 17131.571710706834, 17168.313267469162, 17515.912420437817, 27852.0, 37741.0, 38363.616363836, 38594.42108368433, 40253.39619905762, 57127.42872571221, 58119.231728610626, 58786.0, 59467.0, 59622.403775961684, 8561.914380856111, 8794.868076978722, 8851.247643950337, 9062.048484909254, 9141.908580914105, 7959.442839001446, 9419.529023548932, 9554.713358599252, 9722.513874306114, 9958.203343732339, 5817.301923769006, 8667.566621669017, 8774.38579299443, 8844.95577522134, 9210.769730756516, 8856.158664926821, 9111.134442227978, 9126.0, 9143.817123657622, 9167.49578773174, 8944.86582701247, 9198.26413886874, 9258.0, 9369.016253293306, 9540.189083927944, 12721.618351449471, 13265.270410127536, 13389.531366402129, 13464.663383415493, 13468.46126154982, 12259.877401225873, 12398.0, 12423.75152496927, 12489.625311240678, 12578.622641320775], "value": 9188.819018810054, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.3218390804598, 1538.476887926648, 1549.0782984124446, 1731.3161301285993, 1849.4700844475024, 8322.167783221676, 8605.139486051392, 8631.136886311373, 8645.56772161391, 8883.866741998876, 8392.07687154414, 8623.956880215606, 8837.690680826172, 8850.955745221265, 8870.645174193025, 2077.802608752166, 2140.186729042969, 2278.8176945844334, 2281.0989659084735, 2326.3835083702834, 2356.02225076593, 2377.6433534969806, 2378.369732021016, 2379.4884099918513, 2380.8452450590744, 2344.5662552427857, 2364.798992085675, 2366.2428023032635, 2367.4554852383962, 2369.312899259213, 1981.3263490413256, 2210.933671989827, 2261.378121016717, 2263.5586060718165, 2297.023764899919, 2278.396225000376, 2298.7241531016384, 2299.8965046572944, 2302.378357843373, 2305.296884450242, 8617.052124266325, 8764.1674040966, 8767.123287671246, 8768.386212965095, 8769.561521923943, 5189.974050129726, 5305.310309659758, 5310.389305229909, 5310.495502927216, 5311.601629877736, 3435.6736110069755, 3484.52958850557, 3549.982250088733, 3566.054995426214, 3583.928321433583, 1074.8334008228737, 1087.7117563845586, 1094.7646256054907, 1113.9498722557423, 1174.2778191412306, 11969.0, 12095.092868034933, 12113.636590902199, 12114.454849531792, 12136.453859576428, 4867.415910090741, 4893.559579637778, 4914.140025495595, 4975.701457912537, 4991.700497970133, 10600.681979540626, 10625.362478251329, 10635.946820266005, 10664.840027399596, 10686.679399618024, 5565.526930210977, 5571.693556854412, 5576.609637325349, 5582.0789569721355, 5582.91625625616, 4459.866204013885, 4861.781219845045, 4986.675866068694, 5008.499150084962, 5057.494250574913, 4175.498940127204, 4191.077962848169, 4255.936015996001, 4261.424707664426, 4346.326319420495, 1321.5242512695613, 1359.0758284366527, 1364.624728199753, 1371.0265711345057, 1373.2241283674891, 884.9380543362032, 964.2816102004052, 999.9850002249972, 1005.1506477026901, 1013.6198925402974, 6067.666278354644, 6110.908336374958, 6373.74505019794, 6378.904316435257, 6560.967195164089, 479.6234955559869, 479.8344571122957, 484.8787803049238, 488.96332775042015, 490.6026118843748, 4254.234237837157, 4332.588404101605, 4333.090051089243, 4374.628156606724, 4477.3731677565165, 10887.346759194315, 11144.996950274513, 11228.214025018333, 11250.7187320316, 11256.774864502664, 10923.289986150874, 11060.668179954615, 11100.777984440267, 11103.111751060058, 11173.944130279458, 8056.476329038711, 8065.677372905136, 8140.185981401813, 8150.959245203855, 8161.632726527202, 5923.526117910471, 6059.151718759289, 6141.324454309933, 6156.26124865019, 6220.0, 9921.603135874773, 10252.538635761557, 10291.639792607522, 10339.069483746198, 10415.895841041493, 10406.635767748092, 10414.895851041392, 10513.316634419045, 10519.21105917044, 10522.526486308288, 7774.455788094779, 7933.8413231736195, 8067.193280672121, 8069.6772129116525, 8084.312833408991, 8596.91403085961, 8620.78448038804, 8655.783605409662, 8680.78298042554, 8682.43564168327, 7797.259260370256, 7830.373570114263, 7877.724279650185, 7958.243966823143, 7999.4400391972, 7592.620368981639, 7630.198829123085, 7639.427042971688, 7663.27198916102, 7863.488873223222, 7284.3808276294985, 7295.671694773641, 7334.633268336455, 7372.078490188726, 7382.26177382279, 5201.895962080813, 5242.266082748342, 5333.679979201261, 5349.4650534946195, 5372.489613486712, 5342.1986701993455, 5490.972545137249, 5508.91736623959, 5635.380108188014, 5760.971195143998], "value": 4647.621435975282, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.71571592449686, 339.26379755929634, 342.9228423604688, 344.5486412799232, 347.92519608284215, 5266.157414813631, 5299.867503312419, 5308.177232528955, 5318.521333080022, 5346.89306213876, 5182.740862956846, 5206.9218961715505, 5210.478952104788, 5241.3186285782795, 5255.605829562779, 3914.3149948758996, 4176.164767046587, 4177.770222637769, 4181.268278051344, 4181.519125300599, 3994.760314381132, 4136.834526618932, 4153.210889930919, 4173.833046678129, 4182.53992060873, 4106.630403263705, 4122.732022418548, 4137.296659567881, 4140.0270936329935, 4143.523494798092, 3907.179492306619, 4046.291898917678, 4152.356384760367, 4170.416141740144, 4171.0823618803815, 3553.614090504705, 3619.7285203609836, 3669.8165091745573, 3674.5590529136675, 3695.9260814783825, 5063.569596584295, 5066.82266120684, 5077.923831142536, 5126.231065340229, 5184.0, 4687.6249900007915, 4726.763661816895, 4756.690815097042, 4790.952090479121, 4806.452580132535, 5446.537044351235, 5457.563394928436, 5486.31421072366, 5548.833534993957, 5552.805651802168, 4055.837766489337, 4059.7158198926086, 4064.512258528966, 4070.3894415837863, 4087.7751723655188, 5488.9725551372385, 5665.631733937322, 5736.3403208631125, 5747.2241247431475, 5759.1937128802, 4105.838504351692, 4133.710640255214, 4136.503619565611, 4143.135588984908, 4162.91674166515, 4169.937450938239, 4205.116925445691, 4217.536071032184, 4220.282551966173, 4222.282212023963, 4742.103158736533, 4752.334673145762, 4762.452317983407, 4777.761111944389, 4793.592544633675, 5328.17413300939, 5485.561155107582, 5520.475554822286, 5524.701695101648, 5526.778928842801, 1425.180521200314, 1441.84139744628, 1445.0, 1448.7323591857125, 1454.0548643381676, 916.3219217778928, 935.9797820375823, 942.7037822993384, 955.5222388805597, 963.5856581669809, 4740.976295118502, 4786.99999999993, 5175.741212939338, 5265.446693225507, 5271.6309858310315, 5491.560675145979, 5519.862003449946, 5532.869845109662, 5686.431356864363, 5689.260396148474, 5390.733177703237, 5393.379761327419, 5399.163129714901, 5407.378151512547, 5424.321959755031, 2863.083813179804, 3000.5799188113683, 3004.053723077224, 3017.200441882903, 3019.5621634862823, 5861.765529378862, 5878.47093761555, 5894.410558944071, 5894.852628684232, 5923.08192230205, 5458.344998600193, 5713.914291285634, 5714.628549144292, 5735.541156707453, 5782.715827248214, 5849.005669036274, 5919.940800592025, 5964.343922168558, 5970.62675584618, 5992.8501787453915, 5683.811732483106, 5714.285714285715, 5736.311642602915, 5741.770329186786, 5760.711964401679, 5435.23906653061, 5646.774129034792, 5649.68926709035, 5655.066913959151, 5681.545476361798, 5934.851628709317, 5954.940450595439, 5955.91066134017, 5957.434043765836, 5983.042713165873, 3894.4742459767567, 4122.175564886975, 4124.608162224584, 4125.525564559994, 4127.607877251657, 5679.914801278067, 5685.51673107774, 5695.971520142372, 5702.971485142548, 5707.258056452634, 5517.475839795213, 5519.420460851713, 5534.861628459321, 5543.611947163821, 5544.473275038865, 5276.525112739719, 5484.698341591251, 5490.846922146394, 5502.284702988746, 5537.587915081789, 5415.675059496442, 5418.051840927743, 5453.291072160594, 5454.509094181542, 5466.671999680032, 5312.9734351328, 5435.211894275229, 5437.776500287404, 5448.482394172547, 5531.917021244765, 5125.79496820123, 5359.812406565751, 5373.650712703662, 5386.622936394415, 5409.945060713123], "value": 4232.837770279014, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.496092578898, 782.679101568357, 812.6424373275754, 835.9791005224869, 842.1410161635131, 13720.313984300783, 15010.099394036339, 15131.0, 15334.309956051977, 15614.531564053097, 13887.722245555084, 13956.651083722887, 13974.301284935738, 13977.440902363893, 14034.298285085755, 702.9683664235122, 829.4732844643635, 842.9494230346183, 855.9614817333235, 856.6616186606318, 844.0546587821648, 846.4921047371589, 847.9788005299855, 850.9446885952424, 851.3104385447776, 891.4651209274416, 891.6388862510694, 892.5581836990699, 893.066745251214, 893.0712059458156, 732.5348403763621, 755.7732680195919, 756.130449982519, 861.388414225894, 892.7098692924825, 645.3159650770199, 646.9061986012049, 647.3009150117862, 647.3106141958799, 647.4334956912701, 14634.926825365912, 14635.560933172022, 14671.413143474248, 14690.55928322152, 14751.483698070624, 13145.211287322696, 13154.802677959839, 13392.464301427932, 13725.519606813814, 13895.94289256362, 10870.456477176189, 10915.399653019082, 11059.447027648584, 11649.59226427071, 11685.357305348203, 4431.202383570956, 4517.638588912879, 4533.252013417782, 4543.613792827614, 4630.722156670577, 61849.69075154595, 61993.38006619921, 62070.68964655193, 64031.359686403004, 64205.35794642041, 10952.014318711195, 10953.56185752561, 11102.333859968428, 11105.611303604335, 11144.721381965353, 19187.32844350441, 19363.32228372, 19401.611967760567, 19406.8059319405, 19493.0, 8031.317338026333, 8511.446755960966, 8558.871616925751, 8645.26515246211, 8733.301335893113, 4716.929246061243, 4721.622270218374, 4754.003894607142, 4765.237561990134, 4813.927791083137, 3229.8869539566003, 3299.5215693724754, 3305.752068594865, 3306.6032076151014, 3306.6197387300285, 663.0683889135772, 664.9767258146038, 671.701093013614, 672.5930811858852, 684.750066225824, 729.8102493351661, 765.4603504529293, 778.2723153851167, 798.5607915646372, 809.1544336168718, 10046.397216167054, 10066.647667331608, 10113.848292275621, 10187.08316251541, 10208.693739187836, 684.5174152222671, 684.6337209592843, 699.9055127557715, 704.1550139832119, 714.3570786292274, 4417.0, 4578.0, 4583.427071616048, 4655.813767449264, 4712.128256272601, 65794.67102664456, 70686.29313706797, 70858.0, 71386.0, 71943.64028179826, 63313.0, 63761.0, 64085.0, 64302.67848660727, 64439.67780161069, 55141.897162056535, 55954.0, 56172.43827561754, 57020.714896425256, 57154.0, 8658.220760131619, 8791.5164665944, 8799.428037177562, 8814.074522175079, 8991.640334386551, 38680.41979370368, 38986.415203772536, 39018.41472377974, 39070.0, 39299.0, 50134.49865501444, 51755.741221293654, 52518.47481525136, 52853.47146528639, 53273.0, 11586.362750048827, 11636.243644163102, 11766.0, 11829.52681892749, 12086.576969806014, 13939.721205576034, 14273.928630356782, 14488.492902748774, 14542.127472351693, 15226.46707365237, 13098.0, 13364.665883352995, 13388.397522111334, 13439.193648381128, 13620.795688064885, 10840.566377344818, 13504.0, 13621.455141794217, 13645.590632281048, 13739.313034348042, 13519.101123595505, 13681.247531385869, 13727.382267798173, 13883.0, 14225.928870355581, 10703.678889633324, 10977.341359518454, 11377.374244416305, 11579.421028948687, 11599.884001160219, 11159.386233756897, 11273.7745245093, 11418.486168122288, 11488.94255528717, 11769.705757356134], "value": 7078.76294400067, "unit": "score"}], "name": "dromaeo_css", "value": 7128.352336980999, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 111.0, 110.0, 110.0, 109.0, 110.0, 110.0, 109.0, 109.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 156.0, 219.0, 131.0, 154.0, 156.0, 128.0, 193.0, 126.0], "value": 154.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 160.0, 160.0, 162.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 156.0, 74.0, 73.0, 75.0, 74.0, 142.0, 75.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 144.0, 93.0, 92.0, 91.0, 140.0, 93.0, 91.0, 130.0], "value": 105.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 55.0, 55.0, 54.0, 55.0, 54.0, 109.0, 55.0, 54.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 58.0, 60.0, 59.0, 64.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 84.0, 77.0, 86.0, 82.0, 80.0, 79.0, 80.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 135.0, 120.0, 116.0, 123.0, 132.0, 120.0, 199.0, 129.0], "value": 131.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 159.0, 160.0, 159.0, 160.0, 163.0, 161.0, 159.0, 158.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 62.0, 64.0, 63.0, 62.0, 63.0, 62.0, 61.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1532.1999999999998, "alertThreshold": 2.0}]} 23:21:06 INFO - Return code: 0 23:21:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:21:06 ERROR - # TBPL SUCCESS # 23:21:06 INFO - Running post-action listener: _resource_record_post_action 23:21:06 INFO - ##### 23:21:06 INFO - ##### Finished run-tests step (success) 23:21:06 INFO - ##### 23:21:06 INFO - Running post-run listener: _resource_record_post_run 23:21:06 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 535724032; Read time: 76; Write time: 530464 23:21:06 INFO - TinderboxPrint: CPU usage
13.1% 23:21:06 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 76 23:21:06 INFO - TinderboxPrint: I/O write bytes / time
535,724,032 / 530,464 23:21:06 INFO - TinderboxPrint: CPU idle
8,283.1 (86.9%) 23:21:06 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 23:21:06 INFO - TinderboxPrint: Swap in / out
0 / 0 23:21:06 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 245743616; Read time: 8; Write time: 297604 23:21:06 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289366016; Read time: 68; Write time: 232860 23:21:07 INFO - Running post-run listener: _upload_blobber_files 23:21:07 INFO - Blob upload gear active. 23:21:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:21:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:21:07 INFO - (blobuploader) - INFO - Open directory for files ... 23:21:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:21:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:21:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:21:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:21:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:21:08 INFO - (blobuploader) - INFO - Done attempting. 23:21:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:21:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:21:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:21:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:21:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:21:14 INFO - (blobuploader) - INFO - Done attempting. 23:21:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:21:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:21:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:21:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:21:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:21:15 INFO - (blobuploader) - INFO - Done attempting. 23:21:15 INFO - (blobuploader) - INFO - Iteration through files over. 23:21:15 INFO - Return code: 0 23:21:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:21:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:21:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1fba68155f146839e014551e485a25218efcb17bd34d74c8ca89f95ecde3895209e2e5623949d7c01979bd34be8b6deea46d8d60e4f592b6756aba39cd62c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b2d0243ab634496b89829848f986c2d15c3c3c8571964d19cd4394a37e41c52fff8f778172eb38af4621d68d74a085f2e88b92733da793452417de825eef17e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbbebca29d3802ee14449abcf046e98d14d834ba64acce067014c4e190f47e9d729a49b302001410b50da27415ddfb84b8e5f3f3f9583b8bd5759761c2c37df9"} 23:21:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:21:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:21:15 INFO - Contents: 23:21:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1fba68155f146839e014551e485a25218efcb17bd34d74c8ca89f95ecde3895209e2e5623949d7c01979bd34be8b6deea46d8d60e4f592b6756aba39cd62c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b2d0243ab634496b89829848f986c2d15c3c3c8571964d19cd4394a37e41c52fff8f778172eb38af4621d68d74a085f2e88b92733da793452417de825eef17e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbbebca29d3802ee14449abcf046e98d14d834ba64acce067014c4e190f47e9d729a49b302001410b50da27415ddfb84b8e5f3f3f9583b8bd5759761c2c37df9"} 23:21:15 INFO - Running post-run listener: copy_logs_to_upload_dir 23:21:15 INFO - Copying logs to upload dir... 23:21:15 INFO - mkdir: /builds/slave/test/build/upload/logs 23:21:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.958338 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-16 23:21:14.837878) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 23:21:14.842902) ========= 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=920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1fba68155f146839e014551e485a25218efcb17bd34d74c8ca89f95ecde3895209e2e5623949d7c01979bd34be8b6deea46d8d60e4f592b6756aba39cd62c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b2d0243ab634496b89829848f986c2d15c3c3c8571964d19cd4394a37e41c52fff8f778172eb38af4621d68d74a085f2e88b92733da793452417de825eef17e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbbebca29d3802ee14449abcf046e98d14d834ba64acce067014c4e190f47e9d729a49b302001410b50da27415ddfb84b8e5f3f3f9583b8bd5759761c2c37df9"} build_url:https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011472 build_url: 'https://queue.taskcluster.net/v1/task/L8mhixN-T7mZRG5kmg-7Ww/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/Fx-Team/sha512/d1fba68155f146839e014551e485a25218efcb17bd34d74c8ca89f95ecde3895209e2e5623949d7c01979bd34be8b6deea46d8d60e4f592b6756aba39cd62c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b2d0243ab634496b89829848f986c2d15c3c3c8571964d19cd4394a37e41c52fff8f778172eb38af4621d68d74a085f2e88b92733da793452417de825eef17e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbbebca29d3802ee14449abcf046e98d14d834ba64acce067014c4e190f47e9d729a49b302001410b50da27415ddfb84b8e5f3f3f9583b8bd5759761c2c37df9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 23:21:14.869571) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:21:14.870122) ========= 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=920470bf432669ad7e3a5387000001ca-1463464796.209437-1360747737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004541 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 23:21:14.921880) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 23:21:14.922836) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 23:21:14.923575) ========= ========= Total master_lag: 0.16 =========