builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1464447572.84 results: success (0) buildid: 20160528053001 builduid: 7a825b4a670b49758367e164b8f2652f revision: 199230f44725b8e4919a5014b9649a9951383355 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:32.856112) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:32.856756) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:32.857084) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:32.917065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:32.917412) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-28 07:59: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 104M=0s 2016-05-28 07:59:33 (104 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120877 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.061595) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.061927) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020515 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.115170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.116036) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 199230f44725b8e4919a5014b9649a9951383355 --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 199230f44725b8e4919a5014b9649a9951383355 --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=c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-28 07:59:33,327 truncating revision to first 12 chars 2016-05-28 07:59:33,327 Setting DEBUG logging. 2016-05-28 07:59:33,327 attempt 1/10 2016-05-28 07:59:33,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/199230f44725?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 07:59:33,641 unpacking tar archive at: fx-team-199230f44725/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644591 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.775427) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.775772) ========= script_repo_revision: 199230f44725b8e4919a5014b9649a9951383355 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.776233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.776566) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 07:59:33.786114) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-05-28 07:59:33.786423) ========= /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=c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:59:34 INFO - MultiFileLogger online at 20160528 07:59:34 in /builds/slave/test 07:59: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 07:59:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:59:34 INFO - {'append_to_log': False, 07:59:34 INFO - 'base_work_dir': '/builds/slave/test', 07:59:34 INFO - 'blob_upload_branch': 'Fx-Team', 07:59:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:59:34 INFO - 'branch': 'Fx-Team', 07:59:34 INFO - 'buildbot_json_path': 'buildprops.json', 07:59:34 INFO - 'config_files': ('talos/linux_config.py',), 07:59:34 INFO - 'default_actions': ('clobber', 07:59:34 INFO - 'read-buildbot-config', 07:59:34 INFO - 'download-and-extract', 07:59:34 INFO - 'populate-webroot', 07:59:34 INFO - 'create-virtualenv', 07:59:34 INFO - 'install', 07:59:34 INFO - 'run-tests'), 07:59:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:59:34 INFO - 'download_minidump_stackwalk': True, 07:59:34 INFO - 'download_symbols': 'ondemand', 07:59:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:59:34 INFO - 'tooltool.py': '/tools/tooltool.py', 07:59:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:59:34 INFO - '/tools/misc-python/virtualenv.py')}, 07:59:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:59:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:59:34 INFO - 'installer_path': 'installer.exe', 07:59:34 INFO - 'log_level': 'info', 07:59:34 INFO - 'log_name': 'talos', 07:59:34 INFO - 'log_to_console': True, 07:59:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:59:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:59:34 INFO - 'opt_config_files': (), 07:59:34 INFO - 'pip_index': False, 07:59:34 INFO - 'sps_profile': False, 07:59:34 INFO - 'sps_profile_interval': 0, 07:59:34 INFO - 'suite': 'dromaeojs', 07:59:34 INFO - 'system_bits': '32', 07:59:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:59:34 INFO - 'title': 'talos-linux64-ix-004', 07:59:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:59:34 INFO - 'use_talos_json': True, 07:59:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:59:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:59:34 INFO - 'work_dir': 'build'} 07:59:34 INFO - ##### 07:59:34 INFO - ##### Running clobber step. 07:59:34 INFO - ##### 07:59:34 INFO - Running pre-action listener: _resource_record_pre_action 07:59:34 INFO - Running main action method: clobber 07:59:34 INFO - rmtree: /builds/slave/test/build 07:59:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:59:34 INFO - Running post-action listener: _resource_record_post_action 07:59:34 INFO - ##### 07:59:34 INFO - ##### Finished clobber step (success) 07:59:34 INFO - ##### 07:59:34 INFO - ##### 07:59:34 INFO - ##### Running read-buildbot-config step. 07:59:34 INFO - ##### 07:59:34 INFO - Running pre-action listener: _resource_record_pre_action 07:59:34 INFO - Running main action method: read_buildbot_config 07:59:34 INFO - Using buildbot properties: 07:59:34 INFO - { 07:59:34 INFO - "project": "", 07:59:34 INFO - "product": "firefox", 07:59:34 INFO - "script_repo_revision": "production", 07:59:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 07:59:34 INFO - "repository": "", 07:59:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 07:59:34 INFO - "buildid": "20160528053001", 07:59:34 INFO - "pgo_build": "True", 07:59:34 INFO - "basedir": "/builds/slave/test", 07:59:34 INFO - "buildnumber": 731, 07:59:34 INFO - "slavename": "talos-linux64-ix-004", 07:59:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:59:34 INFO - "platform": "ubuntu64_hw", 07:59:34 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 07:59:34 INFO - "branch": "fx-team", 07:59:34 INFO - "repo_path": "integration/fx-team", 07:59:34 INFO - "revision": "199230f44725b8e4919a5014b9649a9951383355", 07:59:34 INFO - "stage_platform": "linux64-pgo", 07:59:34 INFO - "builduid": "7a825b4a670b49758367e164b8f2652f", 07:59:34 INFO - "slavebuilddir": "test" 07:59:34 INFO - } 07:59:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:59:34 INFO - Running post-action listener: _resource_record_post_action 07:59:34 INFO - ##### 07:59:34 INFO - ##### Finished read-buildbot-config step (success) 07:59:34 INFO - ##### 07:59:34 INFO - ##### 07:59:34 INFO - ##### Running download-and-extract step. 07:59:34 INFO - ##### 07:59:34 INFO - Running pre-action listener: _resource_record_pre_action 07:59:34 INFO - Running main action method: download_and_extract 07:59:34 INFO - mkdir: /builds/slave/test/build/tests 07:59:34 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')]} 07:59:34 INFO - https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:59:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:59:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:59:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:35 INFO - Downloaded 1361 bytes. 07:59:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:59:35 INFO - Using the following test package requirements: 07:59:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:59:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:59:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:59:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'jsshell-linux-x86_64.zip'], 07:59:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:59:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:59:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:59:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:59:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:59:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:59:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:59:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:59: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')]} 07:59:35 INFO - https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:59:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:59:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:59:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:36 INFO - Downloaded 24410758 bytes. 07:59:36 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'] 07:59:36 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 07:59:37 INFO - Return code: 0 07:59:37 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 07:59: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')]} 07:59:37 INFO - https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:59:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:59:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:59:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:51 INFO - Downloaded 24410758 bytes. 07:59:51 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'] 07:59:51 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 07:59:53 INFO - Return code: 0 07:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:53 INFO - https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:59:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:59:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:59:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:54 INFO - Downloaded 10834034 bytes. 07:59:54 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'] 07:59:54 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 07:59:54 INFO - Return code: 0 07:59: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')]} 07:59:54 INFO - https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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 07:59:56 INFO - Downloaded 58723181 bytes. 07:59:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:59:56 INFO - mkdir: /builds/slave/test/properties 07:59:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:59:56 INFO - Writing to file /builds/slave/test/properties/build_url 07:59:56 INFO - Contents: 07:59:56 INFO - build_url:https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:59:57 INFO - Running post-action listener: _resource_record_post_action 07:59:57 INFO - Running post-action listener: set_extra_try_arguments 07:59:57 INFO - ##### 07:59:57 INFO - ##### Finished download-and-extract step (success) 07:59:57 INFO - ##### 07:59:57 INFO - ##### 07:59:57 INFO - ##### Running populate-webroot step. 07:59:57 INFO - ##### 07:59:57 INFO - Running pre-action listener: _resource_record_pre_action 07:59:57 INFO - Running main action method: populate_webroot 07:59:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:59:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:59:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:59:57 INFO - '--tppagecycles', 07:59:57 INFO - '7'], 07:59:57 INFO - 'tests': ['tsvgm']}}, 07:59:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:59:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:59:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:59:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:59:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:59:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:59:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['damp', 'tps']}, 07:59:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'tests': ['damp', 'tps']}, 07:59:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['dromaeo_dom']}, 07:59:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:59:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['a11yr', 07:59:57 INFO - 'ts_paint', 07:59:57 INFO - 'tpaint', 07:59:57 INFO - 'sessionrestore', 07:59:57 INFO - 'sessionrestore_no_auto_restore', 07:59:57 INFO - 'tabpaint']}, 07:59:57 INFO - 'other-e10s': {'tests': ['a11yr', 07:59:57 INFO - 'ts_paint', 07:59:57 INFO - 'tpaint', 07:59:57 INFO - 'sessionrestore', 07:59:57 INFO - 'sessionrestore_no_auto_restore', 07:59:57 INFO - 'tabpaint']}, 07:59:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:59:57 INFO - 'ts_paint', 07:59:57 INFO - 'tpaint', 07:59:57 INFO - 'sessionrestore', 07:59:57 INFO - 'sessionrestore_no_auto_restore', 07:59:57 INFO - 'tabpaint']}, 07:59:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:59:57 INFO - 'ts_paint', 07:59:57 INFO - 'tpaint', 07:59:57 INFO - 'sessionrestore', 07:59:57 INFO - 'sessionrestore_no_auto_restore', 07:59:57 INFO - 'tabpaint']}, 07:59:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['a11yr', 07:59:57 INFO - 'ts_paint', 07:59:57 INFO - 'tpaint', 07:59:57 INFO - 'sessionrestore', 07:59:57 INFO - 'sessionrestore_no_auto_restore', 07:59:57 INFO - 'tabpaint']}, 07:59:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['a11yr', 07:59:57 INFO - 'ts_paint', 07:59:57 INFO - 'tpaint', 07:59:57 INFO - 'sessionrestore', 07:59:57 INFO - 'sessionrestore_no_auto_restore', 07:59:57 INFO - 'tabpaint']}, 07:59:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['tsvgx', 07:59:57 INFO - 'tsvgr_opacity', 07:59:57 INFO - 'tart', 07:59:57 INFO - 'tscrollx', 07:59:57 INFO - 'cart']}, 07:59:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:59:57 INFO - 'tsvgr_opacity', 07:59:57 INFO - 'tart', 07:59:57 INFO - 'tscrollx', 07:59:57 INFO - 'cart']}, 07:59:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'talos_options': ['--disable-e10s'], 07:59:57 INFO - 'tests': ['tp5o']}, 07:59:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'tests': ['tp5o']}, 07:59:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'talos_options': ['--disable-e10s', 07:59:57 INFO - '--xperf_path', 07:59:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:57 INFO - 'tests': ['tp5n']}, 07:59:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:57 INFO - 'talos_options': ['--xperf_path', 07:59:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:57 INFO - 'tests': ['tp5n']}}, 07:59:57 INFO - 'talos.zip': {'path': '', 07:59:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:59:57 INFO - Running post-action listener: _resource_record_post_action 07:59:57 INFO - ##### 07:59:57 INFO - ##### Finished populate-webroot step (success) 07:59:57 INFO - ##### 07:59:57 INFO - ##### 07:59:57 INFO - ##### Running create-virtualenv step. 07:59:57 INFO - ##### 07:59:57 INFO - Running pre-action listener: _resource_record_pre_action 07:59:57 INFO - Running main action method: create_virtualenv 07:59:57 INFO - Creating virtualenv /builds/slave/test/build/venv 07:59:57 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 07:59:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:59:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:59:57 INFO - Using real prefix '/usr' 07:59:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:59:57 INFO - Installing distribute.............................................................................................................................................................................................done. 07:59:59 INFO - Installing pip.................done. 07:59:59 INFO - Return code: 0 07:59:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:59: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')]} 07:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59: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')]} 07:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 07:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:59:59 INFO - Using env: {'DISPLAY': ':0', 07:59:59 INFO - 'HOME': '/home/cltbld', 07:59:59 INFO - 'LANG': 'en_US.UTF-8', 07:59:59 INFO - 'LANGUAGE': 'en_US:en', 07:59:59 INFO - 'LOGNAME': 'cltbld', 07:59:59 INFO - 'MAIL': '/var/mail/cltbld', 07:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:59 INFO - 'MOZ_NO_REMOTE': '1', 07:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:59:59 INFO - 'NO_EM_RESTART': '1', 07:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:59 INFO - 'PWD': '/builds/slave/test', 07:59:59 INFO - 'SHELL': '/bin/bash', 07:59:59 INFO - 'SHLVL': '1', 07:59:59 INFO - 'TERM': 'linux', 07:59:59 INFO - 'TMOUT': '86400', 07:59:59 INFO - 'USER': 'cltbld', 07:59:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 07:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:59 INFO - '_': '/tools/buildbot/bin/python'} 07:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:59 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 07:59:59 INFO - Cleaning up... 07:59:59 INFO - Return code: 0 07:59:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:59: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')]} 07:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59: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')]} 07:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 07:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:59:59 INFO - Using env: {'DISPLAY': ':0', 07:59:59 INFO - 'HOME': '/home/cltbld', 07:59:59 INFO - 'LANG': 'en_US.UTF-8', 07:59:59 INFO - 'LANGUAGE': 'en_US:en', 07:59:59 INFO - 'LOGNAME': 'cltbld', 07:59:59 INFO - 'MAIL': '/var/mail/cltbld', 07:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:59 INFO - 'MOZ_NO_REMOTE': '1', 07:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:59:59 INFO - 'NO_EM_RESTART': '1', 07:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:59 INFO - 'PWD': '/builds/slave/test', 07:59:59 INFO - 'SHELL': '/bin/bash', 07:59:59 INFO - 'SHLVL': '1', 07:59:59 INFO - 'TERM': 'linux', 07:59:59 INFO - 'TMOUT': '86400', 07:59:59 INFO - 'USER': 'cltbld', 07:59:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 07:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:59 INFO - '_': '/tools/buildbot/bin/python'} 08:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:00 INFO - Downloading/unpacking psutil>=3.1.1 08:00:00 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 08:00:00 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 08:00:00 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 08:00:00 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 08:00:00 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 08:00:00 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 08:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:02 INFO - Installing collected packages: psutil 08:00:02 INFO - Running setup.py install for psutil 08:00:02 INFO - building 'psutil._psutil_linux' extension 08:00:02 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 08:00:02 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 08:00:02 INFO - building 'psutil._psutil_posix' extension 08:00:02 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 08:00:02 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 08:00:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:02 INFO - Successfully installed psutil 08:00:02 INFO - Cleaning up... 08:00:02 INFO - Return code: 0 08:00:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:02 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')]} 08:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:02 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')]} 08:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:02 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:02 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 08:00:02 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 08:00:02 INFO - Using env: {'DISPLAY': ':0', 08:00:02 INFO - 'HOME': '/home/cltbld', 08:00:02 INFO - 'LANG': 'en_US.UTF-8', 08:00:02 INFO - 'LANGUAGE': 'en_US:en', 08:00:02 INFO - 'LOGNAME': 'cltbld', 08:00:02 INFO - 'MAIL': '/var/mail/cltbld', 08:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:02 INFO - 'MOZ_NO_REMOTE': '1', 08:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:02 INFO - 'NO_EM_RESTART': '1', 08:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:02 INFO - 'PWD': '/builds/slave/test', 08:00:02 INFO - 'SHELL': '/bin/bash', 08:00:02 INFO - 'SHLVL': '1', 08:00:02 INFO - 'TERM': 'linux', 08:00:02 INFO - 'TMOUT': '86400', 08:00:02 INFO - 'USER': 'cltbld', 08:00:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:02 INFO - '_': '/tools/buildbot/bin/python'} 08:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:02 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 08:00:02 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 08:00:02 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 08:00:02 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 08:00:02 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 08:00:02 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 08:00:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:00:05 INFO - Installing collected packages: mozsystemmonitor 08:00:05 INFO - Running setup.py install for mozsystemmonitor 08:00:05 INFO - Successfully installed mozsystemmonitor 08:00:05 INFO - Cleaning up... 08:00:05 INFO - Return code: 0 08:00:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:05 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:05 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 08:00:05 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 08:00:05 INFO - Using env: {'DISPLAY': ':0', 08:00:05 INFO - 'HOME': '/home/cltbld', 08:00:05 INFO - 'LANG': 'en_US.UTF-8', 08:00:05 INFO - 'LANGUAGE': 'en_US:en', 08:00:05 INFO - 'LOGNAME': 'cltbld', 08:00:05 INFO - 'MAIL': '/var/mail/cltbld', 08:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:05 INFO - 'MOZ_NO_REMOTE': '1', 08:00:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:05 INFO - 'NO_EM_RESTART': '1', 08:00:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:05 INFO - 'PWD': '/builds/slave/test', 08:00:05 INFO - 'SHELL': '/bin/bash', 08:00:05 INFO - 'SHLVL': '1', 08:00:05 INFO - 'TERM': 'linux', 08:00:05 INFO - 'TMOUT': '86400', 08:00:05 INFO - 'USER': 'cltbld', 08:00:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:05 INFO - '_': '/tools/buildbot/bin/python'} 08:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:05 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:05 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 08:00:05 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 08:00:05 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 08:00:05 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 08:00:05 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 08:00:05 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 08:00:07 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:00:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:08 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 08:00:08 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 08:00:08 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 08:00:08 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 08:00:08 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 08:00:08 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 08:00:08 INFO - Downloading docopt-0.6.1.tar.gz 08:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:08 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:08 INFO - Running setup.py install for blobuploader 08:00:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:00:08 INFO - Running setup.py install for requests 08:00:08 INFO - Running setup.py install for docopt 08:00:08 INFO - Successfully installed blobuploader requests docopt 08:00:08 INFO - Cleaning up... 08:00:08 INFO - Return code: 0 08:00:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:08 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:08 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 08:00:08 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 08:00:08 INFO - Using env: {'DISPLAY': ':0', 08:00:08 INFO - 'HOME': '/home/cltbld', 08:00:08 INFO - 'LANG': 'en_US.UTF-8', 08:00:08 INFO - 'LANGUAGE': 'en_US:en', 08:00:08 INFO - 'LOGNAME': 'cltbld', 08:00:08 INFO - 'MAIL': '/var/mail/cltbld', 08:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:08 INFO - 'MOZ_NO_REMOTE': '1', 08:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:08 INFO - 'NO_EM_RESTART': '1', 08:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:08 INFO - 'PWD': '/builds/slave/test', 08:00:08 INFO - 'SHELL': '/bin/bash', 08:00:08 INFO - 'SHLVL': '1', 08:00:08 INFO - 'TERM': 'linux', 08:00:08 INFO - 'TMOUT': '86400', 08:00:08 INFO - 'USER': 'cltbld', 08:00:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:08 INFO - '_': '/tools/buildbot/bin/python'} 08:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:09 INFO - Running setup.py (path:/tmp/pip-yx2zVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:09 INFO - Running setup.py (path:/tmp/pip-3LTh3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:09 INFO - Running setup.py (path:/tmp/pip-NT2d4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:09 INFO - Running setup.py (path:/tmp/pip-OH8GqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:09 INFO - Running setup.py (path:/tmp/pip-8tO00u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:09 INFO - Running setup.py (path:/tmp/pip-RLoBB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:09 INFO - Running setup.py (path:/tmp/pip-ZD4Hk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:09 INFO - Running setup.py (path:/tmp/pip-UmU14z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:09 INFO - Running setup.py (path:/tmp/pip-yp1tbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:09 INFO - Running setup.py (path:/tmp/pip-RMlAR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:09 INFO - Running setup.py (path:/tmp/pip-zSVWbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:09 INFO - Running setup.py (path:/tmp/pip-IBOR2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:10 INFO - Running setup.py (path:/tmp/pip-Ct5sYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:10 INFO - Running setup.py (path:/tmp/pip-B1BAte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:10 INFO - Running setup.py (path:/tmp/pip-Lcw38D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:10 INFO - Running setup.py (path:/tmp/pip-pqV0d6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:10 INFO - Running setup.py (path:/tmp/pip-eI8EOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:10 INFO - Running setup.py install for manifestparser 08:00:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:00:10 INFO - Running setup.py install for mozcrash 08:00:10 INFO - Running setup.py install for mozdebug 08:00:10 INFO - Running setup.py install for mozdevice 08:00:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:00:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:00:10 INFO - Running setup.py install for mozfile 08:00:10 INFO - Running setup.py install for mozhttpd 08:00:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:00:10 INFO - Running setup.py install for mozinfo 08:00:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Running setup.py install for mozInstall 08:00:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Running setup.py install for mozleak 08:00:11 INFO - Running setup.py install for mozlog 08:00:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Running setup.py install for moznetwork 08:00:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Running setup.py install for mozprocess 08:00:11 INFO - Running setup.py install for mozprofile 08:00:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Running setup.py install for mozrunner 08:00:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:11 INFO - Running setup.py install for mozscreenshot 08:00:11 INFO - Running setup.py install for moztest 08:00:12 INFO - Running setup.py install for mozversion 08:00:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:12 INFO - Cleaning up... 08:00:12 INFO - Return code: 0 08:00:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:12 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:12 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 08:00:12 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 08:00:12 INFO - Using env: {'DISPLAY': ':0', 08:00:12 INFO - 'HOME': '/home/cltbld', 08:00:12 INFO - 'LANG': 'en_US.UTF-8', 08:00:12 INFO - 'LANGUAGE': 'en_US:en', 08:00:12 INFO - 'LOGNAME': 'cltbld', 08:00:12 INFO - 'MAIL': '/var/mail/cltbld', 08:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:12 INFO - 'MOZ_NO_REMOTE': '1', 08:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:12 INFO - 'NO_EM_RESTART': '1', 08:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:12 INFO - 'PWD': '/builds/slave/test', 08:00:12 INFO - 'SHELL': '/bin/bash', 08:00:12 INFO - 'SHLVL': '1', 08:00:12 INFO - 'TERM': 'linux', 08:00:12 INFO - 'TMOUT': '86400', 08:00:12 INFO - 'USER': 'cltbld', 08:00:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:12 INFO - '_': '/tools/buildbot/bin/python'} 08:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:12 INFO - Running setup.py (path:/tmp/pip-f37S9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:12 INFO - Running setup.py (path:/tmp/pip-4oeae9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:12 INFO - Running setup.py (path:/tmp/pip-_kMQOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:12 INFO - Running setup.py (path:/tmp/pip-gWxfsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:12 INFO - Running setup.py (path:/tmp/pip-fUN2VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:12 INFO - Running setup.py (path:/tmp/pip-IpeuTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:12 INFO - Running setup.py (path:/tmp/pip-fVsFro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:12 INFO - Running setup.py (path:/tmp/pip-P_EDJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:12 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)) 08:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:12 INFO - Running setup.py (path:/tmp/pip-wy_8Nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:13 INFO - Running setup.py (path:/tmp/pip-HWtkk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:13 INFO - Running setup.py (path:/tmp/pip-b6wjI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:13 INFO - Running setup.py (path:/tmp/pip-yxlxzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:13 INFO - Running setup.py (path:/tmp/pip-wz85mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:13 INFO - Running setup.py (path:/tmp/pip-gCFk4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:13 INFO - Running setup.py (path:/tmp/pip-N6MFka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:13 INFO - Running setup.py (path:/tmp/pip-9bgg0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:13 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)) 08:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:13 INFO - Running setup.py (path:/tmp/pip-M3wXiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:13 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)) 08:00:13 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)) 08:00:13 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)) 08:00:13 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)) 08:00:13 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)) 08:00:13 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)) 08:00:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:13 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 08:00:13 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 08:00:13 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 08:00:13 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 08:00:13 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 08:00:13 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 08:00:15 INFO - Downloading blessings-1.6.tar.gz 08:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:15 INFO - Installing collected packages: blessings 08:00:15 INFO - Running setup.py install for blessings 08:00:15 INFO - Successfully installed blessings 08:00:15 INFO - Cleaning up... 08:00:15 INFO - Return code: 0 08:00:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:00:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:16 INFO - Reading from file tmpfile_stdout 08:00:16 INFO - Current package versions: 08:00:16 INFO - argparse == 1.2.1 08:00:16 INFO - blessings == 1.6 08:00:16 INFO - blobuploader == 1.2.4 08:00:16 INFO - docopt == 0.6.1 08:00:16 INFO - manifestparser == 1.1 08:00:16 INFO - mozInstall == 1.12 08:00:16 INFO - mozcrash == 0.17 08:00:16 INFO - mozdebug == 0.1 08:00:16 INFO - mozdevice == 0.48 08:00:16 INFO - mozfile == 1.2 08:00:16 INFO - mozhttpd == 0.7 08:00:16 INFO - mozinfo == 0.9 08:00:16 INFO - mozleak == 0.1 08:00:16 INFO - mozlog == 3.2 08:00:16 INFO - moznetwork == 0.27 08:00:16 INFO - mozprocess == 0.22 08:00:16 INFO - mozprofile == 0.28 08:00:16 INFO - mozrunner == 6.11 08:00:16 INFO - mozscreenshot == 0.1 08:00:16 INFO - mozsystemmonitor == 0.3 08:00:16 INFO - moztest == 0.7 08:00:16 INFO - mozversion == 1.4 08:00:16 INFO - psutil == 3.1.1 08:00:16 INFO - requests == 1.2.3 08:00:16 INFO - wsgiref == 0.1.2 08:00:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:16 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')]} 08:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:16 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')]} 08:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:16 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:16 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 08:00:16 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 08:00:16 INFO - Using env: {'DISPLAY': ':0', 08:00:16 INFO - 'HOME': '/home/cltbld', 08:00:16 INFO - 'LANG': 'en_US.UTF-8', 08:00:16 INFO - 'LANGUAGE': 'en_US:en', 08:00:16 INFO - 'LOGNAME': 'cltbld', 08:00:16 INFO - 'MAIL': '/var/mail/cltbld', 08:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:16 INFO - 'MOZ_NO_REMOTE': '1', 08:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:16 INFO - 'NO_EM_RESTART': '1', 08:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:16 INFO - 'PWD': '/builds/slave/test', 08:00:16 INFO - 'SHELL': '/bin/bash', 08:00:16 INFO - 'SHLVL': '1', 08:00:16 INFO - 'TERM': 'linux', 08:00:16 INFO - 'TMOUT': '86400', 08:00:16 INFO - 'USER': 'cltbld', 08:00:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:16 INFO - '_': '/tools/buildbot/bin/python'} 08:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 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)) 08:00:16 INFO - Cleaning up... 08:00:16 INFO - Return code: 0 08:00:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:00:16 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')]} 08:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:16 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')]} 08:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:16 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:16 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 08:00:16 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 08:00:16 INFO - Using env: {'DISPLAY': ':0', 08:00:16 INFO - 'HOME': '/home/cltbld', 08:00:16 INFO - 'LANG': 'en_US.UTF-8', 08:00:16 INFO - 'LANGUAGE': 'en_US:en', 08:00:16 INFO - 'LOGNAME': 'cltbld', 08:00:16 INFO - 'MAIL': '/var/mail/cltbld', 08:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:16 INFO - 'MOZ_NO_REMOTE': '1', 08:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:16 INFO - 'NO_EM_RESTART': '1', 08:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:16 INFO - 'PWD': '/builds/slave/test', 08:00:16 INFO - 'SHELL': '/bin/bash', 08:00:16 INFO - 'SHLVL': '1', 08:00:16 INFO - 'TERM': 'linux', 08:00:16 INFO - 'TMOUT': '86400', 08:00:16 INFO - 'USER': 'cltbld', 08:00:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:16 INFO - '_': '/tools/buildbot/bin/python'} 08:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:16 INFO - Downloading/unpacking jsonschema 08:00:16 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 08:00:16 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 08:00:16 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 08:00:16 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 08:00:16 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 08:00:16 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 08:00:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:18 INFO - Downloading/unpacking functools32 (from jsonschema) 08:00:18 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 08:00:18 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 08:00:18 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 08:00:18 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 08:00:18 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 08:00:18 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 08:00:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:18 INFO - warning: no files found matching '*.txt' 08:00:18 INFO - no previously-included directories found matching 'build' 08:00:18 INFO - no previously-included directories found matching 'dist' 08:00:18 INFO - no previously-included directories found matching '.git*' 08:00:18 INFO - Installing collected packages: jsonschema, functools32 08:00:18 INFO - Running setup.py install for functools32 08:00:18 INFO - warning: no files found matching '*.txt' 08:00:18 INFO - no previously-included directories found matching 'build' 08:00:18 INFO - no previously-included directories found matching 'dist' 08:00:18 INFO - no previously-included directories found matching '.git*' 08:00:18 INFO - Successfully installed jsonschema functools32 08:00:18 INFO - Cleaning up... 08:00:18 INFO - Return code: 0 08:00:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:00:18 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')]} 08:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:18 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')]} 08:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:18 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 0x7f9c0f401f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe13770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', '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 08:00:18 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 08:00:18 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 08:00:18 INFO - Using env: {'DISPLAY': ':0', 08:00:18 INFO - 'HOME': '/home/cltbld', 08:00:18 INFO - 'LANG': 'en_US.UTF-8', 08:00:18 INFO - 'LANGUAGE': 'en_US:en', 08:00:18 INFO - 'LOGNAME': 'cltbld', 08:00:18 INFO - 'MAIL': '/var/mail/cltbld', 08:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:18 INFO - 'MOZ_NO_REMOTE': '1', 08:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:18 INFO - 'NO_EM_RESTART': '1', 08:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:18 INFO - 'PWD': '/builds/slave/test', 08:00:18 INFO - 'SHELL': '/bin/bash', 08:00:18 INFO - 'SHLVL': '1', 08:00:18 INFO - 'TERM': 'linux', 08:00:18 INFO - 'TMOUT': '86400', 08:00:18 INFO - 'USER': 'cltbld', 08:00:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:18 INFO - '_': '/tools/buildbot/bin/python'} 08:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:19 INFO - Downloading/unpacking flake8 08:00:19 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 08:00:19 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 08:00:19 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 08:00:19 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 08:00:19 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 08:00:19 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 08:00:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:00:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:00:20 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 08:00:20 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 08:00:20 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 08:00:20 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 08:00:20 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 08:00:20 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 08:00:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:00:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 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 08:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:00:21 INFO - no previously-included directories found matching 'docs/_build' 08:00:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:00:21 INFO - Running setup.py install for pep8 08:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:00:21 INFO - no previously-included directories found matching 'docs/_build' 08:00:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:00:21 INFO - Cleaning up... 08:00:21 INFO - Return code: 0 08:00:21 INFO - Running post-action listener: _resource_record_post_action 08:00:21 INFO - Running post-action listener: _start_resource_monitoring 08:00:21 INFO - Starting resource monitoring. 08:00:21 INFO - ##### 08:00:21 INFO - ##### Finished create-virtualenv step (success) 08:00:21 INFO - ##### 08:00:21 INFO - ##### 08:00:21 INFO - ##### Running install step. 08:00:21 INFO - ##### 08:00:21 INFO - Running pre-action listener: _resource_record_pre_action 08:00:21 INFO - Running main action method: install 08:00:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:22 INFO - Reading from file tmpfile_stdout 08:00:22 INFO - Detecting whether we're running mozinstall >=1.0... 08:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:00:22 INFO - Reading from file tmpfile_stdout 08:00:22 INFO - Output received: 08:00:22 INFO - Usage: mozinstall [options] installer 08:00:22 INFO - Options: 08:00:22 INFO - -h, --help show this help message and exit 08:00:22 INFO - -d DEST, --destination=DEST 08:00:22 INFO - Directory to install application into. [default: 08:00:22 INFO - "/builds/slave/test"] 08:00:22 INFO - --app=APP Application being installed. [default: firefox] 08:00:22 INFO - mkdir: /builds/slave/test/build/application 08:00:22 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'] 08:00:22 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 08:00:38 INFO - Reading from file tmpfile_stdout 08:00:38 INFO - Output received: 08:00:38 INFO - /builds/slave/test/build/application/firefox/firefox 08:00:38 INFO - Running post-action listener: _resource_record_post_action 08:00:38 INFO - ##### 08:00:38 INFO - ##### Finished install step (success) 08:00:38 INFO - ##### 08:00:38 INFO - ##### 08:00:38 INFO - ##### Running run-tests step. 08:00:38 INFO - ##### 08:00:38 INFO - Running pre-action listener: _resource_record_pre_action 08:00:38 INFO - Running main action method: run_tests 08:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:00:38 INFO - Python 2.7.3 08:00:38 INFO - Return code: 0 08:00:38 INFO - grabbing minidump binary from tooltool 08:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:38 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 0xea29c0>, '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 0xea38c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea3d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:00:38 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 08:00:38 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 08:00:38 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 08:00:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:00:38 INFO - Return code: 0 08:00:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:00:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:00:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:00:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:00:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:00:39 INFO - Return code: 0 08:00:39 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/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 08:00:39 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/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 08:00:39 INFO - Using env: {'DISPLAY': ':0', 08:00:39 INFO - 'HOME': '/home/cltbld', 08:00:39 INFO - 'LANG': 'en_US.UTF-8', 08:00:39 INFO - 'LANGUAGE': 'en_US:en', 08:00:39 INFO - 'LOGNAME': 'cltbld', 08:00:39 INFO - 'MAIL': '/var/mail/cltbld', 08:00:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:00:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:39 INFO - 'MOZ_NO_REMOTE': '1', 08:00:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:39 INFO - 'NO_EM_RESTART': '1', 08:00:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:39 INFO - 'PWD': '/builds/slave/test', 08:00:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:00:39 INFO - 'SHELL': '/bin/bash', 08:00:39 INFO - 'SHLVL': '1', 08:00:39 INFO - 'TERM': 'linux', 08:00:39 INFO - 'TMOUT': '86400', 08:00:39 INFO - 'USER': 'cltbld', 08:00:39 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982', 08:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:39 INFO - '_': '/tools/buildbot/bin/python'} 08:00:39 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/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 08:00:40 INFO - mozversion application_buildid: 20160528053001 08:00:40 INFO - mozversion application_changeset: 199230f44725b8e4919a5014b9649a9951383355 08:00:40 INFO - mozversion application_display_name: Nightly 08:00:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:00:40 INFO - mozversion application_name: Firefox 08:00:40 INFO - mozversion application_remotingname: firefox 08:00:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:00:40 INFO - mozversion application_vendor: Mozilla 08:00:40 INFO - mozversion application_version: 49.0a1 08:00:40 INFO - mozversion platform_buildid: 20160528053001 08:00:40 INFO - mozversion platform_changeset: 199230f44725b8e4919a5014b9649a9951383355 08:00:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:00:40 INFO - mozversion platform_version: 49.0a1 08:00:40 INFO - using testdate: 1464447640 08:00:40 INFO - actual date: 1464447640 08:00:40 INFO - starting webserver on 'localhost:32995' 08:00:40 INFO - SUITE-START | Running 2 tests 08:00:40 INFO - TEST-START | dromaeo_css 08:00:40 INFO - operating with platform_type : linux_ 08:00:40 INFO - Initialising browser for dromaeo_css test... 08:00:40 INFO - TEST-INFO | started process 17199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYgKQnT/profile http://localhost:32995/getInfo.html) 08:00:43 INFO - PROCESS | 17199 | __metrics Screen width/height:1600/1200 08:00:43 INFO - PROCESS | 17199 | colorDepth:24 08:00:43 INFO - PROCESS | 17199 | Browser inner width/height: 1024/697 08:00:43 INFO - PROCESS | 17199 | __metrics 08:00:45 ERROR - PROCESS | 17199 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:00:45 INFO - TEST-INFO | 17199: exit 0 08:00:45 INFO - Browser initialized. 08:00:45 INFO - Running cycle 1/1 for dromaeo_css test... 08:00:45 INFO - TEST-INFO | started process 17278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYgKQnT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:00:57 INFO - PROCESS | 17278 | RSS: Main: 155226112 08:00:57 INFO - PROCESS | 17278 | 08:01:48 INFO - PROCESS | 17278 | 1464447708713 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] 08:03:56 INFO - PROCESS | 17278 | Cycle 1(1): loaded http://localhost:32995/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32995/tests/dromaeo/cssquery-ext.html) 08:03:57 INFO - PROCESS | 17278 | RSS: Main: 257486848 08:03:57 INFO - PROCESS | 17278 | 08:06:59 INFO - PROCESS | 17278 | Cycle 1(1): loaded http://localhost:32995/tests/dromaeo/cssquery-ext.html (next: http://localhost:32995/tests/dromaeo/cssquery-jquery.html) 08:07:00 INFO - PROCESS | 17278 | RSS: Main: 244359168 08:07:00 INFO - PROCESS | 17278 | 08:10:02 INFO - PROCESS | 17278 | Cycle 1(1): loaded http://localhost:32995/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32995/tests/dromaeo/cssquery-mootools.html) 08:10:03 INFO - PROCESS | 17278 | RSS: Main: 239669248 08:10:03 INFO - PROCESS | 17278 | 08:13:05 INFO - PROCESS | 17278 | Cycle 1(1): loaded http://localhost:32995/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32995/tests/dromaeo/cssquery-prototype.html) 08:13:05 INFO - PROCESS | 17278 | RSS: Main: 232415232 08:13:05 INFO - PROCESS | 17278 | 08:16:08 INFO - PROCESS | 17278 | Cycle 1(1): loaded http://localhost:32995/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32995/tests/dromaeo/cssquery-yui.html) 08:16:09 INFO - PROCESS | 17278 | RSS: Main: 203276288 08:16:09 INFO - PROCESS | 17278 | 08:19:11 INFO - PROCESS | 17278 | Cycle 1(1): loaded http://localhost:32995/tests/dromaeo/cssquery-yui.html (next: http://localhost:32995/tests/dromaeo/cssquery-dojo.html) 08:19:12 INFO - PROCESS | 17278 | RSS: Main: 269819904 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | __start_tp_report 08:19:12 INFO - PROCESS | 17278 | _x_x_mozilla_page_load 08:19:12 INFO - PROCESS | 17278 | _x_x_mozilla_page_load_details 08:19:12 INFO - PROCESS | 17278 | |i|pagename|runs| 08:19:12 INFO - PROCESS | 17278 | |0;dojo.html;3112.128603990882;3118.2048577612713;3130.6243250809907;3141.890033848815;3176.9205769855753;5193.480651934805;5271.973640131794;5272.103742363798;5312.840614781563;5316.734163291834;5277.076511610462;5296.867578310535;5322.733863306828;5333.573314134869;5336.17289320156;4740.265258884879;4838;4852.902941941159;4856.514348565151;4972.453030166679;4880.8693051540295;4881.951180488185;4894.730789806559;4942.629302802305;4959.504049595048;4724.669273150881;4747.477777444478;4747.90504189916;4754.809807607691;4794.999999999983;4755.191617425046;4763.642726795504;4810.158222311116;4818.975905120487;4851.490593487703;4666.090112428078;4773.379460670126;4783.234682450826;4788.425388953313;4816.44610869751;5130.127878260705;5162.432132465463;5185.248139019859;5262.710550919698;5270.868228294285;5678.091505359164;5692.345380281279;5695.117256825199;5712.971435142839;5721.6853073080965;5580.414056524088;5635.154726790973;5706.572007099443;5761.625494342854;5767.221425107598;5311.150215965466;5395.406505284415;5412.377576578665;5425.240466334716;5432.29380180574;5904.202932604129;5919.4968427684125;5920.467157955804;5922.555808314393;5930.733117009745;5373.811916582901;5521.365043020104;5562.499375056183;5661.150827375926;5671.432856714296;5714.314282286069;5761.049426844607;5766.913496297559;5773.162891380726;5774.566907481955;5688.573356998241;5692.174634677948;5698.6580805151825;5702.914456283159;5708.743106560215;5498.230247765316;5608.270924779753;5652.406497317723;5653.378128405872;5703.82888513337;4331.068820203672;4336.566343365701;4341.891452713708;4343.891402714958;4372.256716358227;3879.922401551953;3889.9967260930903;3904.863329783444;3934;3942.6254505821903;5274.156135018378;5282;5329.626926115212;5379.273798037293;5395.973020134874;5769.798057067983;5779.826605201851;5791.507721843691;5793.710314484343;5822.359540450547;5423.376311724202;5441.428649991773;5442.727863606804;5445.319335083114;5446.836594902159;4768.666193366431;4843.830465933675;4849.854504364875;4880.780364883589;4893.608511319086;5853.678047707417;5865.618734782225;5866.384029676908;5866.6480011199465;5867.207926929896;5541.473560011793;5670.376258611549;5688.516476099578;5697.202391665253;5700.971495142664;5808.593398462067;5867.325257595431;5876.206712093813;5936.643801371759;5936.910946335895;5638.23883775685;5645.774169033357;5646;5646.548276137982;5646.887062258814;5528.087865502147;5676.77292908279;5752.223449834219;5759.971200143972;5768.134779783066;5543.417941116286;5606.355269143937;5616.943830561642;5617.494425501724;5656.406077361818;5545.750441230235;5596.8320950369925;5601.60788744784;5608.831735047955;5610.607257491936;5300.628955973045;5362.490563396471;5401.513863752281;5461.1808228766;5515.834524964257;5669.262995810518;5673.716314184192;5677.347105082803;5681.204631351531;5683.658980461186;5512.476314750093;5513.393526711979;5514.145307477347;5521.089020311764;5526.723663816712 08:19:12 INFO - PROCESS | 17278 | |1;ext.html;572.2932178759231;610.4627927423867;611.9969400152999;621.5307442880625;621.947134493568;33156.17109572262;44239.33640995384;44572.77713611435;45001.324980125246;45090.09819803606;45685.771571142184;46087.76956115215;46159.538404615945;46187.307190392174;46210.30684539735;8028;8633.740987770378;8825.117488251155;8836.409446299664;10364.948175259113;10186.64346747864;10555.788884222311;10598.099161571276;10602;10607.363558186495;10591.682249532489;10641.78716425671;10641.893581064207;10653;10654.094401975803;10497.947510262476;10550.841737373983;10557.524911379007;10559.577616895314;10566.52450639723;10449.895501044968;10484.266101372907;10503.73740656482;10514.369137851754;10540.78918421635;41490.58509414898;42040.15919681589;42092.78953605212;42107;42118.789406052776;3225.580674512322;3537.2041290709385;3795.3927371620407;3823.9235215295816;3949.308870947573;32196.678033219603;32422.675773242438;32768;32768.50847237293;33773.15567110817;4377.255866502702;4386.385905973167;4422.115576884604;4458.821647134143;4583.518730533313;319058;319628;319912;320216;320659;56489.15266271009;56953.71523142441;57070.7146464265;57276.42723572794;57280;60287.69856150779;60531.69734151301;60628.393716063154;60646;60793.39206607877;12962.287074211008;13231.139975901535;13245.271510066847;13283.800742988862;13289.933550332187;8719.694810681596;9335.159835614828;9501.90498095024;9523.380980236263;9594.904050959402;16072.517824465285;16212.10833404174;16271;16391.098489583186;16407.343706251853;5923.289205295392;6000.279966403972;6012.669303188279;6026.5178785697035;6091.664958427329;945.3713280668278;1026.9948650256702;1059.081398824353;1068.4444089073638;1082.0477979378254;32120.678793212235;32429.35141297161;32662.67337326644;32837;33173.86271616708;1128.1200663482496;1168.7954607943745;1185.6561597136906;1208.848893888264;1239.5971309324505;17108.828911710723;17377.349839523245;17404.216810243568;17447.38934137299;17677.646447070987;491545;493476;494443;494610;496719;57400.42599574034;57410.42589574051;57419;57430.7128464355;57681.71159144177;1219.939003049844;1262.9052821038458;1351.1690310459194;1351.9391627376792;1380.5996261084174;32711.67288327086;33159;33601.15997100092;34115.488267676505;34226.657733423344;50086;50557.74721126371;50578;50614.746926265136;50647.240291396396;337365;338148;338988;339057;340231;31494.685053149176;41027.58972410357;41664.583354167284;44201.55798441974;44892.55107449014;48407;48416;48940.75529622329;49534;49646;41017.384739228335;41615.58384416117;41905.37141942934;42739;42905.78547107244;41020;41327.1734565313;41701.16597667969;41790.16419671529;42014;40328.1934361317;41433.58566414418;41565.37651935284;41840;42054.57945420629;34347.65652343544;36271.27457450889;36966.63033369632;36982.26035479329;37054.629453705114;28106.065864759457;28107.479422640474;28295.443750593917;28580.57129143106;28612.713872861004 08:19:12 INFO - PROCESS | 17278 | |2;jquery.html;386.2564563215809;386.9941950870736;392.17447273489296;401.2576733043869;411.1366131124637;14297.571072867804;15666.295016724247;18027.819721802778;19250.61498770024;19266.325678601235;13277;15571.68856622867;15579.688406231868;15891.44379946702;15952.122633255167;5520.861978450531;5702.4012478689765;5712.828615141531;5776.277965254343;5784.219130417382;5551.139573366135;5677.801276955307;5695.401982791809;5716.7141642917895;5719.170720245562;4061.6953728470485;5670.0927851543765;6099.878002439948;6391.073294372313;6391.392817682313;5279.1817268323475;6468.838279043014;6470.482361411099;6495.512836537255;6501.252355979052;5382.273393091921;5408.4591540845995;5448.972755136238;5452.745868450239;5465.234867118606;12327.44526496301;12366.690832729164;12538.561150359785;12873.163244389085;12923.289219092947;2172.8696278223356;2314.525522267931;2376.453415714378;2385.3440303916386;2839.8640543782653;9114.498702571356;9532.380395274286;9614.202390055889;9717.416954982653;11467.139964502696;1825.863060270485;1838.9506988903865;1853.1290293562004;1861.8882867027887;1864.692325766247;77216;79467.6026619869;80176.59911700462;80459.19540804635;81793.59103204445;10281.794364112677;10818.67543973682;10835.51240194193;10841.349519028725;11058.22592418539;14965.326560304813;14991.550253492413;15075;15082.24588770557;15086.471973480875;6247.375262473807;6567.770128045496;6571.080048793173;6684.665766711645;7029.015937768717;5732.971335143297;6056.515478761688;6060.151578778975;6518.934810651833;6568.573042752206;4598.586127248497;4625.676202665848;4628.976855115703;4675.649326300473;4708;1944.893030883315;1954.8924809135633;1965.9115339809744;1970.8916009619322;2021.8584699071218;951.4909523404945;1019.938803671782;1027.8950128611773;1069.385103565453;1082.7169803782542;18390.81609183891;18656.160472778756;18845.151968161466;19087.141078651493;19280.22879084821;953.9284553658504;958.4536814016084;997.4514017290606;999.8050380175796;1026.3277553202515;10314.638987635546;10577.153827693766;10624.150067994697;10704.357738535557;10760;418724;418970;437050;438551;440448;88632;89668;89985;90077.54961225283;91254.54372728094;985.0297457004845;993.4734590666958;1031.376017509411;1055.2929537210089;1061.0291583201285;16507.09210993359;16763.32946682119;16910.74633880468;16921.83078169252;17326.826731732523;39255.6074439252;40603.79698101491;41058;41640.583594164884;42052.78973605113;55417.722911385186;55514.72242638761;56068.43931560795;56296.718516407156;56470.717646411504;5588.3573389060775;7873.645685944031;7908.881366779618;8181.713640022571;8339.874901876598;9065.728028159167;12414.441350139085;12926.224426534438;13341.86658133445;13419.127756695783;10951.56193752241;11727.237729547553;11824.23142495735;11977.880221197676;12130.090243231616;11217.88782112201;11591.420428978348;11683.941580292045;11743.530258789895;12278.324692141661;11960.401979901144;12068.577599783965;12155.088368372231;12177.573784917486;12222.694432639255;8108;8147.59262036884;8168.959155204186;8284.83430331402;8303.95848020756;8589.012263589768;11151.219414640897;11702.180847340946;11765.52937882475;12120.878791211975 08:19:12 INFO - PROCESS | 17278 | |3;mootools.html;1347.9191248525096;1495.3495229575135;1511.365226604826;1662.6757782232462;1800.4868612445455;8365.330773538117;8724.08397118303;8726.040135585088;8915.68795092172;8917.420367676097;8678.652853885853;8859.15837995391;8871.112888711126;8880.555972201397;8881.555922203896;1973.1614064022804;2218.2901471529117;2265.694131292279;2281.7261928568514;2288.656701494781;2313.363824948144;2314.1206341590173;2318.014843691433;2321.292005938188;2321.3268152235833;1940.262700173939;1949.7172909928051;1983.7718662353798;1987.7714062882737;1989.542405246794;1943.2129987355145;2200.6258935980923;2253.921112761062;2258.5821622999633;2273.57938781326;2200.3838925101;2217.5675743230076;2228.7994080532667;2232.9218477353215;2235.1953296813135;8851.114888511162;8907.198352148338;8913.910860891438;8918.73243802687;8922.509261990588;5203.9219411708855;5360.758765855546;5360.785568577252;5363.115086010804;5366.248725178478;3245.594300712411;3247.8376081195847;3304.80171189727;3307.107081088093;3363.83180840957;1187.2935603316014;1200.1658847101269;1202.326697049655;1202.663751118181;1206.3244583033443;12323;12334.81497777534;12351.444185011696;12351.93824030874;12362.011039116938;4525.954740452553;4588.151192029419;4663.183942809995;4663.463701674351;4707.8587642370785;10435.478226088666;10439.634612788515;10443.477826108665;10444.425556594308;10450.425226612457;5132.12753831842;5165.612579056586;5170.8190213343105;5172.689638621619;5173;4286.271333873186;4647.186742320148;4767.404074490689;4807.95192048075;4873.975630121827;3912.5304963404574;3935.114599215211;3995.9800200998807;4049.8987525311513;4060.26915155269;1222.4315693202705;1237.4493350458959;1248.4881198708485;1252.7244006318595;1263.134752694389;820.4666966471841;908.5003248213457;915.5696822493418;919.4529255093208;998.5905778630727;5990.251218597676;6051.727672254759;6154.630722156685;6267.623942563369;6271.655058971709;982.6658935961665;998.510729742421;1023.3246057601947;1035.2132379391717;1042.223543460127;4097.487814023247;4155.314373128461;4354.804033818486;4361.214981303396;4371.890702732394;10829;11139.665810025712;11205.831912521318;11209.271397359145;11225.045871100885;11017.83473247902;11056.613018544313;11102;11116.555337786398;11142.832857507145;7729.8454030919065;8012.839743205103;8015.639296231567;8179.018517777786;8188.877166842502;6057.363976782374;6076.240469941257;6094;6302.180716506825;6306.621602703853;10141.442220677933;10212.285140040423;10237.9488102559;10372;10487.108595769445;10454.581816727246;10501.474926253504;10532.05211530966;10537.683869483928;10539.314944528887;6854.314568543105;7258.564486130849;7478.925210747823;7499.137599176164;7590;8521.82956340882;8543.829123417372;8548.957255213683;8597.828043439222;8600.569971501525;7270.309320614533;7593.696252150073;7648.426368022532;7702.4608277422285;8121.512709237465;7676.616169191407;7883.329916957123;7906.090799557895;8035.67857285702;8135.959320203361;7019.964900175466;7149.356557909813;7170.103737032871;7185.245549217257;7201.2438693938475;5011.548960593418;5113.51421614946;5211.661242019257;5366.543843773323;5371.865703357447;5146.150885104065;5171.689698618095;5223.947760522498;5290.814821481384;5497.917531236954 08:19:12 INFO - PROCESS | 17278 | |4;prototype.html;319.5494352962322;331.0812495325473;331.75615922297106;333.85143611093065;341.235632183908;5148.639595228336;5221.973890130544;5287.35632183908;5337.11937530307;5341.89316213676;5238.083335416296;5276.15581506959;5277.762500687469;5301.549368303689;5336.439673834249;4153.833846646131;4167.645750111229;4171.728837625545;4176.895577610554;4187;3969.6427321541055;4035.656969157625;4081.795910204478;4191.832326706928;4192.727472714279;4131.2357213915375;4132.958670413303;4136.193442278742;4149.294619914606;4157.397177409274;4152.3771434284945;4154.31453810124;4155.4182414462;4161.001359673687;4162.7502349858805;3590.9281814363567;3602.5316708827945;3687.8340474678703;3689.723270754704;3691.778493290411;5094.31226784383;5122.897542049138;5135.435102138762;5138.691678499302;5141.485851414866;4728.598069164125;4740.786664600101;4744.691595046311;4758.952410475886;4780.521947805227;5312.893742125175;5505.917411238835;5538.501534861882;5547.750351234205;5566.415526369754;3954.604539546051;3967.523897132334;3969.8015099245213;3971.245463361981;3982.303096958021;5747;5757.76208115253;5759.625624334447;5774.2782152230975;5788;3889.4943657324366;4069.2878746219294;4080.6125917187705;4114.156597897424;4121.475054229894;4124.958750412457;4129.132882094734;4143.399207114951;4165.396017577435;4166.64583510405;4743.407074115736;4746.572808447257;4747.667663263607;4751.904961900743;4760.285957106462;5294.497022782906;5624;5642.407547207484;5648.689322087324;5651;1324.43711422645;1351.3648585164956;1351.4121357209644;1352.2157148853642;1383.2323060701206;907.3603109807569;907.5552979040223;909.5679552212646;916.062527753796;925.4262357338494;4807.95192048082;4819.421669399694;4820.9517904820505;4823.252395878574;4855.757212139379;4847.321375007431;5005.349304590452;5006.6245031622775;5092.821751238689;5102.36220472441;4698.130845793539;4704.458987216445;4807.95192048082;4810.735409552438;4862.8784280392565;2728.01791355112;2732.5218086834725;2764.433291175304;2770.5844123381653;2772.209920172741;5043.344365232496;5172.611939179843;5180.7150606717;5188.92216616751;5281.603879709037;5056.317397151338;5127.794888204505;5166.250893620404;5168.689878607295;5196.648871293491;5007.848194915263;5195.19474481456;5218.069314353714;5248.763805628756;5267.9736601316745;5042.445331013512;5065;5079.974600127123;5082.949170508247;5117.901660319251;5002.974985125051;5009.899802003866;5019.84940451787;5024.472430394902;5093.694378337312;4810.965642386764;5226.477352264895;5244.396894357198;5282.049231138432;5301.602379821606;3647.434647629622;3667.541557305337;3755.9248815024093;3777.5844657088246;3788.4885540452788;5003.34956455673;5026.899462010812;5063.949360506348;5121.61587880903;5145.819896303761;4917.483664215349;4933.703977761345;4954.9752251238515;4973.45292017885;5172.379314482287;5390.51485366319;5452.945470545244;5622.9718851407115;5656.660600363992;5660.547066289478;5457.181422786613;5458.91811622818;5502.779888804403;5505.862353441035;5520.66875987442;5307.256984022163;5649.406812284816;5666.660000399989;5682.886342273214;5688.317401911797;5489.807856725155;5496.8076117337305;5513.3108361454815;5534.201384549957;5573.721313934206 08:19:12 INFO - PROCESS | 17278 | |5;yui.html;737.1706829816457;738.8263758016865;751.6091632351178;760.2245709376436;768.0974854545914;14607;14878.77681834772;15031.624209394771;15053.096814191156;15089.773653395194;13420.261885596288;13425.261610611413;13433;13436.462541498353;13446.529371472001;662.5991275278459;792.0574516325554;802.0455657767238;817.8446095241915;819.5451524403953;806.9354451643854;810.1250649298772;813.2843098073687;813.4062134641715;814.7107776739261;851.4210336970857;852.3990586636406;852.8891244138254;853.159637756809;853.5348235211825;700.9929900701004;838.9664413423455;864.235151890575;866.1554983890719;867.7657032601226;630.3412933484519;630.4420587779802;630.5775130662468;630.7319389259592;630.9495240380759;14050.156990580495;14121.08212966154;14145.292735363293;14145.929270353685;14179.716405671828;12744.80882786759;12763.553275635308;12772.552960646426;13031.152975056591;13033.413496392684;11356.432178391047;11405;11557.88442115585;11930.284182949052;12114.636560903187;4312.870613881589;4317.0934103838235;4318.52496225415;4331.891702707426;4332.870013899588;57194.7140264296;57195;57438;57440.13839792406;57626.423735762524;10356.689299321033;10525.157987360994;10631.734206644896;10742;11042.779144417067;18082.728759068625;18796.966166860955;18808.52978675544;18812;18813.341533046278;8108.472949258397;8134.227248411392;8346.8330633387;8416.705415310435;8475.788105297417;4550.749708765983;4558.133954548625;4581.129585378767;4592.288195329729;4753.714777113315;3179.268768183331;3275.81982990933;3276.705096541275;3282.2122690554097;3282.2614911645164;491.2434850330451;558.7178474870245;568.0853825341197;587.3744462147799;612.5589575505629;696.4324075878143;703.9612821294776;716.874546954281;722.1334398721555;732.3116270705519;10243.436610986319;10288.845667314996;10381;10403.635872744419;10417.687469375931;629.9874002520015;669.7421492725335;727.6652739739673;757.7234309477111;771.2172145272552;4574;4670.159371313196;4679.110968915895;4679.1577516046755;4729.456112547033;60502;63775;63813.680931595976;70425;72094.63952680203;58790.41209587935;59284;60164.398356016754;60726.696366517885;61765;55540.72229638826;56109;56135;56959.43040569624;56983.71508142433;9520.61917523319;9607.759806004906;9639.18066964316;9643.855342170013;9680.5643746033;29256.853715731286;34684;36719.449208261365;36797.44803827998;37374.716139195036;46848.765756171;48807.511924880295;48916;50203.49796501988;50211.74894125506;11652.708682283012;11756.23872331707;11953;11974.760504789681;12066;12815;12912.677183070122;12920.418581164058;13095.934520327337;13174.802377964146;8531.957340213508;12783.872161278268;12806.67983300425;12889.8066529004;13024.993046449807;12381.257124572554;12645.810312845131;12711.61865144047;12890.291033992846;12980.610581682564;11726.589569365031;11811.114166437379;11890.76218475643;11964.102692297938;12435.75128497443;10354.223433242387;10636.255462117577;10852.240343175901;10937.671869843918;11090.279131856403;10688.198385121303;10942.617008404668;11006.614768483065;11266.436678166223;11286.379249141377 08:19:12 INFO - PROCESS | 17278 | __end_tp_report 08:19:12 INFO - PROCESS | 17278 | __start_cc_report 08:19:12 INFO - PROCESS | 17278 | _x_x_mozilla_cycle_collect,822 08:19:12 INFO - PROCESS | 17278 | __end_cc_report 08:19:12 INFO - PROCESS | 17278 | __startTimestamp1464448752017__endTimestamp 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | ------- Summary: start ------- 08:19:12 INFO - PROCESS | 17278 | Number of tests: 6 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | [#0] dojo.html Cycles:165 Average:5290.04 Median:5513.39 stddev:603.53 (10.9%) stddev-sans-first:580.69 08:19:12 INFO - PROCESS | 17278 | Values: 3112.1 3118.2 3130.6 3141.9 3176.9 5193.5 5272.0 5272.1 5312.8 5316.7 5277.1 5296.9 5322.7 5333.6 5336.2 4740.3 4838.0 4852.9 4856.5 4972.5 4880.9 4882.0 4894.7 4942.6 4959.5 4724.7 4747.5 4747.9 4754.8 4795.0 4755.2 4763.6 4810.2 4819.0 4851.5 4666.1 4773.4 4783.2 4788.4 4816.4 5130.1 5162.4 5185.2 5262.7 5270.9 5678.1 5692.3 5695.1 5713.0 5721.7 5580.4 5635.2 5706.6 5761.6 5767.2 5311.2 5395.4 5412.4 5425.2 5432.3 5904.2 5919.5 5920.5 5922.6 5930.7 5373.8 5521.4 5562.5 5661.2 5671.4 5714.3 5761.0 5766.9 5773.2 5774.6 5688.6 5692.2 5698.7 5702.9 5708.7 5498.2 5608.3 5652.4 5653.4 5703.8 4331.1 4336.6 4341.9 4343.9 4372.3 3879.9 3890.0 3904.9 3934.0 3942.6 5274.2 5282.0 5329.6 5379.3 5396.0 5769.8 5779.8 5791.5 5793.7 5822.4 5423.4 5441.4 5442.7 5445.3 5446.8 4768.7 4843.8 4849.9 4880.8 4893.6 5853.7 5865.6 5866.4 5866.6 5867.2 5541.5 5670.4 5688.5 5697.2 5701.0 5808.6 5867.3 5876.2 5936.6 5936.9 5638.2 5645.8 5646.0 5646.5 5646.9 5528.1 5676.8 5752.2 5760.0 5768.1 5543.4 5606.4 5616.9 5617.5 5656.4 5545.8 5596.8 5601.6 5608.8 5610.6 5300.6 5362.5 5401.5 5461.2 5515.8 5669.3 5673.7 5677.3 5681.2 5683.7 5512.5 5513.4 5514.1 5521.1 5526.7 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | [#1] ext.html Cycles:180 Average:55910.48 Median:3886.62 stddev:103478.34 (2662.4%) stddev-sans-first:103685.21 08:19:12 INFO - PROCESS | 17278 | Values: 572.3 610.5 612.0 621.5 621.9 33156.2 44239.3 44572.8 45001.3 45090.1 45685.8 46087.8 46159.5 46187.3 46210.3 8028.0 8633.7 8825.1 8836.4 10364.9 10186.6 10555.8 10598.1 10602.0 10607.4 10591.7 10641.8 10641.9 10653.0 10654.1 10497.9 10550.8 10557.5 10559.6 10566.5 10449.9 10484.3 10503.7 10514.4 10540.8 41490.6 42040.2 42092.8 42107.0 42118.8 3225.6 3537.2 3795.4 3823.9 3949.3 32196.7 32422.7 32768.0 32768.5 33773.2 4377.3 4386.4 4422.1 4458.8 4583.5 319058.0 319628.0 319912.0 320216.0 320659.0 56489.2 56953.7 57070.7 57276.4 57280.0 60287.7 60531.7 60628.4 60646.0 60793.4 12962.3 13231.1 13245.3 13283.8 13289.9 8719.7 9335.2 9501.9 9523.4 9594.9 16072.5 16212.1 16271.0 16391.1 16407.3 5923.3 6000.3 6012.7 6026.5 6091.7 945.4 1027.0 1059.1 1068.4 1082.0 32120.7 32429.4 32662.7 32837.0 33173.9 1128.1 1168.8 1185.7 1208.8 1239.6 17108.8 17377.3 17404.2 17447.4 17677.6 491545.0 493476.0 494443.0 494610.0 496719.0 57400.4 57410.4 57419.0 57430.7 57681.7 1219.9 1262.9 1351.2 1351.9 1380.6 32711.7 33159.0 33601.2 34115.5 34226.7 50086.0 50557.7 50578.0 50614.7 50647.2 337365.0 338148.0 338988.0 339057.0 340231.0 31494.7 41027.6 41664.6 44201.6 44892.6 48407.0 48416.0 48940.8 49534.0 49646.0 41017.4 41615.6 41905.4 42739.0 42905.8 41020.0 41327.2 41701.2 41790.2 42014.0 40328.2 41433.6 41565.4 41840.0 42054.6 34347.7 36271.3 36966.6 36982.3 37054.6 28106.1 28107.5 28295.4 28580.6 28612.7 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | [#2] jquery.html Cycles:180 Average:26530.15 Median:19828.43 stddev:71412.18 (360.2%) stddev-sans-first:71585.53 08:19:12 INFO - PROCESS | 17278 | Values: 386.3 387.0 392.2 401.3 411.1 14297.6 15666.3 18027.8 19250.6 19266.3 13277.0 15571.7 15579.7 15891.4 15952.1 5520.9 5702.4 5712.8 5776.3 5784.2 5551.1 5677.8 5695.4 5716.7 5719.2 4061.7 5670.1 6099.9 6391.1 6391.4 5279.2 6468.8 6470.5 6495.5 6501.3 5382.3 5408.5 5449.0 5452.7 5465.2 12327.4 12366.7 12538.6 12873.2 12923.3 2172.9 2314.5 2376.5 2385.3 2839.9 9114.5 9532.4 9614.2 9717.4 11467.1 1825.9 1839.0 1853.1 1861.9 1864.7 77216.0 79467.6 80176.6 80459.2 81793.6 10281.8 10818.7 10835.5 10841.3 11058.2 14965.3 14991.6 15075.0 15082.2 15086.5 6247.4 6567.8 6571.1 6684.7 7029.0 5733.0 6056.5 6060.2 6518.9 6568.6 4598.6 4625.7 4629.0 4675.6 4708.0 1944.9 1954.9 1965.9 1970.9 2021.9 951.5 1019.9 1027.9 1069.4 1082.7 18390.8 18656.2 18845.2 19087.1 19280.2 953.9 958.5 997.5 999.8 1026.3 10314.6 10577.2 10624.2 10704.4 10760.0 418724.0 418970.0 437050.0 438551.0 440448.0 88632.0 89668.0 89985.0 90077.5 91254.5 985.0 993.5 1031.4 1055.3 1061.0 16507.1 16763.3 16910.7 16921.8 17326.8 39255.6 40603.8 41058.0 41640.6 42052.8 55417.7 55514.7 56068.4 56296.7 56470.7 5588.4 7873.6 7908.9 8181.7 8339.9 9065.7 12414.4 12926.2 13341.9 13419.1 10951.6 11727.2 11824.2 11977.9 12130.1 11217.9 11591.4 11683.9 11743.5 12278.3 11960.4 12068.6 12155.1 12177.6 12222.7 8108.0 8147.6 8169.0 8284.8 8304.0 8589.0 11151.2 11702.2 11765.5 12120.9 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | [#3] mootools.html Cycles:180 Average:5831.05 Median:4617.67 stddev:3358.91 (72.7%) stddev-sans-first:3351.43 08:19:12 INFO - PROCESS | 17278 | Values: 1347.9 1495.3 1511.4 1662.7 1800.5 8365.3 8724.1 8726.0 8915.7 8917.4 8678.7 8859.2 8871.1 8880.6 8881.6 1973.2 2218.3 2265.7 2281.7 2288.7 2313.4 2314.1 2318.0 2321.3 2321.3 1940.3 1949.7 1983.8 1987.8 1989.5 1943.2 2200.6 2253.9 2258.6 2273.6 2200.4 2217.6 2228.8 2232.9 2235.2 8851.1 8907.2 8913.9 8918.7 8922.5 5203.9 5360.8 5360.8 5363.1 5366.2 3245.6 3247.8 3304.8 3307.1 3363.8 1187.3 1200.2 1202.3 1202.7 1206.3 12323.0 12334.8 12351.4 12351.9 12362.0 4526.0 4588.2 4663.2 4663.5 4707.9 10435.5 10439.6 10443.5 10444.4 10450.4 5132.1 5165.6 5170.8 5172.7 5173.0 4286.3 4647.2 4767.4 4808.0 4874.0 3912.5 3935.1 3996.0 4049.9 4060.3 1222.4 1237.4 1248.5 1252.7 1263.1 820.5 908.5 915.6 919.5 998.6 5990.3 6051.7 6154.6 6267.6 6271.7 982.7 998.5 1023.3 1035.2 1042.2 4097.5 4155.3 4354.8 4361.2 4371.9 10829.0 11139.7 11205.8 11209.3 11225.0 11017.8 11056.6 11102.0 11116.6 11142.8 7729.8 8012.8 8015.6 8179.0 8188.9 6057.4 6076.2 6094.0 6302.2 6306.6 10141.4 10212.3 10237.9 10372.0 10487.1 10454.6 10501.5 10532.1 10537.7 10539.3 6854.3 7258.6 7478.9 7499.1 7590.0 8521.8 8543.8 8549.0 8597.8 8600.6 7270.3 7593.7 7648.4 7702.5 8121.5 7676.6 7883.3 7906.1 8035.7 8136.0 7020.0 7149.4 7170.1 7185.2 7201.2 5011.5 5113.5 5211.7 5366.5 5371.9 5146.2 5171.7 5223.9 5290.8 5497.9 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | [#4] prototype.html Cycles:180 Average:4475.13 Median:5008.87 stddev:1283.91 (25.6%) stddev-sans-first:1249.05 08:19:12 INFO - PROCESS | 17278 | Values: 319.5 331.1 331.8 333.9 341.2 5148.6 5222.0 5287.4 5337.1 5341.9 5238.1 5276.2 5277.8 5301.5 5336.4 4153.8 4167.6 4171.7 4176.9 4187.0 3969.6 4035.7 4081.8 4191.8 4192.7 4131.2 4133.0 4136.2 4149.3 4157.4 4152.4 4154.3 4155.4 4161.0 4162.8 3590.9 3602.5 3687.8 3689.7 3691.8 5094.3 5122.9 5135.4 5138.7 5141.5 4728.6 4740.8 4744.7 4759.0 4780.5 5312.9 5505.9 5538.5 5547.8 5566.4 3954.6 3967.5 3969.8 3971.2 3982.3 5747.0 5757.8 5759.6 5774.3 5788.0 3889.5 4069.3 4080.6 4114.2 4121.5 4125.0 4129.1 4143.4 4165.4 4166.6 4743.4 4746.6 4747.7 4751.9 4760.3 5294.5 5624.0 5642.4 5648.7 5651.0 1324.4 1351.4 1351.4 1352.2 1383.2 907.4 907.6 909.6 916.1 925.4 4808.0 4819.4 4821.0 4823.3 4855.8 4847.3 5005.3 5006.6 5092.8 5102.4 4698.1 4704.5 4808.0 4810.7 4862.9 2728.0 2732.5 2764.4 2770.6 2772.2 5043.3 5172.6 5180.7 5188.9 5281.6 5056.3 5127.8 5166.3 5168.7 5196.6 5007.8 5195.2 5218.1 5248.8 5268.0 5042.4 5065.0 5080.0 5082.9 5117.9 5003.0 5009.9 5019.8 5024.5 5093.7 4811.0 5226.5 5244.4 5282.0 5301.6 3647.4 3667.5 3755.9 3777.6 3788.5 5003.3 5026.9 5063.9 5121.6 5145.8 4917.5 4933.7 4955.0 4973.5 5172.4 5390.5 5452.9 5623.0 5656.7 5660.5 5457.2 5458.9 5502.8 5505.9 5520.7 5307.3 5649.4 5666.7 5682.9 5688.3 5489.8 5496.8 5513.3 5534.2 5573.7 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - PROCESS | 17278 | [#5] yui.html Cycles:180 Average:15429.01 Median:4566.07 stddev:18501.48 (405.2%) stddev-sans-first:18520.48 08:19:12 INFO - PROCESS | 17278 | Values: 737.2 738.8 751.6 760.2 768.1 14607.0 14878.8 15031.6 15053.1 15089.8 13420.3 13425.3 13433.0 13436.5 13446.5 662.6 792.1 802.0 817.8 819.5 806.9 810.1 813.3 813.4 814.7 851.4 852.4 852.9 853.2 853.5 701.0 839.0 864.2 866.2 867.8 630.3 630.4 630.6 630.7 630.9 14050.2 14121.1 14145.3 14145.9 14179.7 12744.8 12763.6 12772.6 13031.2 13033.4 11356.4 11405.0 11557.9 11930.3 12114.6 4312.9 4317.1 4318.5 4331.9 4332.9 57194.7 57195.0 57438.0 57440.1 57626.4 10356.7 10525.2 10631.7 10742.0 11042.8 18082.7 18797.0 18808.5 18812.0 18813.3 8108.5 8134.2 8346.8 8416.7 8475.8 4550.7 4558.1 4581.1 4592.3 4753.7 3179.3 3275.8 3276.7 3282.2 3282.3 491.2 558.7 568.1 587.4 612.6 696.4 704.0 716.9 722.1 732.3 10243.4 10288.8 10381.0 10403.6 10417.7 630.0 669.7 727.7 757.7 771.2 4574.0 4670.2 4679.1 4679.2 4729.5 60502.0 63775.0 63813.7 70425.0 72094.6 58790.4 59284.0 60164.4 60726.7 61765.0 55540.7 56109.0 56135.0 56959.4 56983.7 9520.6 9607.8 9639.2 9643.9 9680.6 29256.9 34684.0 36719.4 36797.4 37374.7 46848.8 48807.5 48916.0 50203.5 50211.7 11652.7 11756.2 11953.0 11974.8 12066.0 12815.0 12912.7 12920.4 13095.9 13174.8 8532.0 12783.9 12806.7 12889.8 13025.0 12381.3 12645.8 12711.6 12890.3 12980.6 11726.6 11811.1 11890.8 11964.1 12435.8 10354.2 10636.3 10852.2 10937.7 11090.3 10688.2 10942.6 11006.6 11266.4 11286.4 08:19:12 INFO - PROCESS | 17278 | -------- Summary: end -------- 08:19:12 INFO - PROCESS | 17278 | 08:19:12 INFO - TEST-INFO | 17278: exit 0 08:19:12 INFO - TEST-OK | dromaeo_css | took 1112752ms 08:19:12 INFO - TEST-START | kraken 08:19:12 INFO - operating with platform_type : linux_ 08:19:12 INFO - Initialising browser for kraken test... 08:19:12 INFO - TEST-INFO | started process 17456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3epi3L/profile http://localhost:32995/getInfo.html) 08:19:16 INFO - PROCESS | 17456 | __metrics Screen width/height:1600/1200 08:19:16 INFO - PROCESS | 17456 | colorDepth:24 08:19:16 INFO - PROCESS | 17456 | Browser inner width/height: 1024/697 08:19:16 INFO - PROCESS | 17456 | __metrics 08:19:17 ERROR - PROCESS | 17456 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:19:18 INFO - TEST-INFO | 17456: exit 0 08:19:18 INFO - Browser initialized. 08:19:18 INFO - Running cycle 1/1 for kraken test... 08:19:18 INFO - TEST-INFO | started process 17537 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3epi3L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:30 INFO - PROCESS | 17537 | RSS: Main: 155324416 08:19:30 INFO - PROCESS | 17537 | 08:19:32 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32995/tests/kraken/driver.html?testName=audio-beat-detection) 08:19:33 INFO - PROCESS | 17537 | RSS: Main: 213258240 08:19:33 INFO - PROCESS | 17537 | 08:19:36 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32995/tests/kraken/driver.html?testName=audio-dft) 08:19:36 INFO - PROCESS | 17537 | RSS: Main: 203186176 08:19:36 INFO - PROCESS | 17537 | 08:19:39 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32995/tests/kraken/driver.html?testName=audio-fft) 08:19:40 INFO - PROCESS | 17537 | RSS: Main: 267567104 08:19:40 INFO - PROCESS | 17537 | 08:19:42 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32995/tests/kraken/driver.html?testName=audio-oscillator) 08:19:42 INFO - PROCESS | 17537 | RSS: Main: 178597888 08:19:42 INFO - PROCESS | 17537 | 08:19:45 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32995/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:19:45 INFO - PROCESS | 17537 | RSS: Main: 181874688 08:19:45 INFO - PROCESS | 17537 | 08:19:48 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32995/tests/kraken/driver.html?testName=imaging-darkroom) 08:19:49 INFO - PROCESS | 17537 | RSS: Main: 218423296 08:19:49 INFO - PROCESS | 17537 | 08:19:52 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32995/tests/kraken/driver.html?testName=imaging-desaturate) 08:19:53 INFO - PROCESS | 17537 | RSS: Main: 219242496 08:19:53 INFO - PROCESS | 17537 | 08:19:56 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32995/tests/kraken/driver.html?testName=json-parse-financial) 08:19:56 INFO - PROCESS | 17537 | RSS: Main: 219971584 08:19:56 INFO - PROCESS | 17537 | 08:19:58 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32995/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:19:58 INFO - PROCESS | 17537 | RSS: Main: 206426112 08:19:58 INFO - PROCESS | 17537 | 08:20:00 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:20:01 INFO - PROCESS | 17537 | RSS: Main: 209833984 08:20:01 INFO - PROCESS | 17537 | 08:20:03 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:20:03 INFO - PROCESS | 17537 | RSS: Main: 216743936 08:20:03 INFO - PROCESS | 17537 | 08:20:06 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:20:06 INFO - PROCESS | 17537 | RSS: Main: 221286400 08:20:06 INFO - PROCESS | 17537 | 08:20:09 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:20:10 INFO - PROCESS | 17537 | RSS: Main: 221560832 08:20:10 INFO - PROCESS | 17537 | 08:20:11 INFO - PROCESS | 17537 | Cycle 1(1): loaded http://localhost:32995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32995/tests/kraken/driver.html?testName=ai-astar) 08:20:12 INFO - PROCESS | 17537 | RSS: Main: 264241152 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | __start_tp_report 08:20:12 INFO - PROCESS | 17537 | _x_x_mozilla_page_load 08:20:12 INFO - PROCESS | 17537 | _x_x_mozilla_page_load_details 08:20:12 INFO - PROCESS | 17537 | |i|pagename|runs| 08:20:12 INFO - PROCESS | 17537 | |0;ai-astar;111;106;110;108;111;110;108;108;109;109 08:20:12 INFO - PROCESS | 17537 | |1;audio-beat-detection;149;124;125;177;124;124;155;124;162;129 08:20:12 INFO - PROCESS | 17537 | |2;audio-dft;160;160;159;160;161;159;159;161;161;160 08:20:12 INFO - PROCESS | 17537 | |3;audio-fft;75;75;75;126;75;75;74;75;98;76 08:20:12 INFO - PROCESS | 17537 | |4;audio-oscillator;83;83;120;83;84;83;133;83;84;139 08:20:12 INFO - PROCESS | 17537 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 08:20:12 INFO - PROCESS | 17537 | |6;imaging-darkroom;146;146;145;146;145;146;146;146;145;145 08:20:12 INFO - PROCESS | 17537 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;96;95 08:20:12 INFO - PROCESS | 17537 | |8;json-parse-financial;51;53;53;51;52;51;52;52;52;52 08:20:12 INFO - PROCESS | 17537 | |9;json-stringify-tinderbox;61;61;61;61;61;61;61;62;62;62 08:20:12 INFO - PROCESS | 17537 | |10;stanford-crypto-aes;81;82;78;85;81;81;81;82;114;80 08:20:12 INFO - PROCESS | 17537 | |11;stanford-crypto-ccm;120;130;119;119;117;124;124;130;132;122 08:20:12 INFO - PROCESS | 17537 | |12;stanford-crypto-pbkdf2;161;161;161;159;163;159;166;158;161;159 08:20:12 INFO - PROCESS | 17537 | |13;stanford-crypto-sha256-iterative;63;63;63;61;62;61;62;64;62;63 08:20:12 INFO - PROCESS | 17537 | __end_tp_report 08:20:12 INFO - PROCESS | 17537 | __start_cc_report 08:20:12 INFO - PROCESS | 17537 | _x_x_mozilla_cycle_collect,1628 08:20:12 INFO - PROCESS | 17537 | __end_cc_report 08:20:12 INFO - PROCESS | 17537 | __startTimestamp1464448812128__endTimestamp 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | ------- Summary: start ------- 08:20:12 INFO - PROCESS | 17537 | Number of tests: 14 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#0] ai-astar Cycles:10 Average:109.00 Median:109.50 stddev:1.56 (1.4%) stddev-sans-first:1.48 08:20:12 INFO - PROCESS | 17537 | Values: 111.0 106.0 110.0 108.0 111.0 110.0 108.0 108.0 109.0 109.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:139.00 stddev:19.79 (14.2%) stddev-sans-first:20.67 08:20:12 INFO - PROCESS | 17537 | Values: 149.0 124.0 125.0 177.0 124.0 124.0 155.0 124.0 162.0 129.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 08:20:12 INFO - PROCESS | 17537 | Values: 160.0 160.0 159.0 160.0 161.0 159.0 159.0 161.0 161.0 160.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:16.95 (22.6%) stddev-sans-first:17.76 08:20:12 INFO - PROCESS | 17537 | Values: 75.0 75.0 75.0 126.0 75.0 75.0 74.0 75.0 98.0 76.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:83.00 stddev:23.34 (28.1%) stddev-sans-first:24.16 08:20:12 INFO - PROCESS | 17537 | Values: 83.0 83.0 120.0 83.0 84.0 83.0 133.0 83.0 84.0 139.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:20:12 INFO - PROCESS | 17537 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:20:12 INFO - PROCESS | 17537 | Values: 146.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:20:12 INFO - PROCESS | 17537 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.74 (1.4%) stddev-sans-first:0.71 08:20:12 INFO - PROCESS | 17537 | Values: 51.0 53.0 53.0 51.0 52.0 51.0 52.0 52.0 52.0 52.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 08:20:12 INFO - PROCESS | 17537 | Values: 61.0 61.0 61.0 61.0 61.0 61.0 61.0 62.0 62.0 62.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:81.00 stddev:10.51 (13.0%) stddev-sans-first:11.07 08:20:12 INFO - PROCESS | 17537 | Values: 81.0 82.0 78.0 85.0 81.0 81.0 81.0 82.0 114.0 80.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:124.00 stddev:5.31 (4.3%) stddev-sans-first:5.46 08:20:12 INFO - PROCESS | 17537 | Values: 120.0 130.0 119.0 119.0 117.0 124.0 124.0 130.0 132.0 122.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:161.00 stddev:2.35 (1.5%) stddev-sans-first:2.49 08:20:12 INFO - PROCESS | 17537 | Values: 161.0 161.0 161.0 159.0 163.0 159.0 166.0 158.0 161.0 159.0 08:20:12 INFO - PROCESS | 17537 | 08:20:12 INFO - PROCESS | 17537 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 08:20:12 INFO - PROCESS | 17537 | Values: 63.0 63.0 63.0 61.0 62.0 61.0 62.0 64.0 62.0 63.0 08:20:12 INFO - PROCESS | 17537 | -------- Summary: end -------- 08:20:12 INFO - PROCESS | 17537 | 08:20:13 INFO - TEST-INFO | 17537: exit 0 08:20:13 INFO - TEST-OK | kraken | took 60284ms 08:20:13 INFO - SUITE-END | took 1173s 08:20:13 INFO - Completed test suite (00:19:33) 08:20:13 INFO - javascript benchmark 08:20:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.128603990882, 3118.2048577612713, 3130.6243250809907, 3141.890033848815, 3176.9205769855753, 5193.480651934805, 5271.973640131794, 5272.103742363798, 5312.840614781563, 5316.734163291834, 5277.076511610462, 5296.867578310535, 5322.733863306828, 5333.573314134869, 5336.17289320156, 4740.265258884879, 4838.0, 4852.902941941159, 4856.514348565151, 4972.453030166679, 4880.8693051540295, 4881.951180488185, 4894.730789806559, 4942.629302802305, 4959.504049595048, 4724.669273150881, 4747.477777444478, 4747.90504189916, 4754.809807607691, 4794.999999999983, 4755.191617425046, 4763.642726795504, 4810.158222311116, 4818.975905120487, 4851.490593487703, 4666.090112428078, 4773.379460670126, 4783.234682450826, 4788.425388953313, 4816.44610869751, 5130.127878260705, 5162.432132465463, 5185.248139019859, 5262.710550919698, 5270.868228294285, 5678.091505359164, 5692.345380281279, 5695.117256825199, 5712.971435142839, 5721.6853073080965, 5580.414056524088, 5635.154726790973, 5706.572007099443, 5761.625494342854, 5767.221425107598, 5311.150215965466, 5395.406505284415, 5412.377576578665, 5425.240466334716, 5432.29380180574, 5904.202932604129, 5919.4968427684125, 5920.467157955804, 5922.555808314393, 5930.733117009745, 5373.811916582901, 5521.365043020104, 5562.499375056183, 5661.150827375926, 5671.432856714296, 5714.314282286069, 5761.049426844607, 5766.913496297559, 5773.162891380726, 5774.566907481955, 5688.573356998241, 5692.174634677948, 5698.6580805151825, 5702.914456283159, 5708.743106560215, 5498.230247765316, 5608.270924779753, 5652.406497317723, 5653.378128405872, 5703.82888513337, 4331.068820203672, 4336.566343365701, 4341.891452713708, 4343.891402714958, 4372.256716358227, 3879.922401551953, 3889.9967260930903, 3904.863329783444, 3934.0, 3942.6254505821903, 5274.156135018378, 5282.0, 5329.626926115212, 5379.273798037293, 5395.973020134874, 5769.798057067983, 5779.826605201851, 5791.507721843691, 5793.710314484343, 5822.359540450547, 5423.376311724202, 5441.428649991773, 5442.727863606804, 5445.319335083114, 5446.836594902159, 4768.666193366431, 4843.830465933675, 4849.854504364875, 4880.780364883589, 4893.608511319086, 5853.678047707417, 5865.618734782225, 5866.384029676908, 5866.6480011199465, 5867.207926929896, 5541.473560011793, 5670.376258611549, 5688.516476099578, 5697.202391665253, 5700.971495142664, 5808.593398462067, 5867.325257595431, 5876.206712093813, 5936.643801371759, 5936.910946335895, 5638.23883775685, 5645.774169033357, 5646.0, 5646.548276137982, 5646.887062258814, 5528.087865502147, 5676.77292908279, 5752.223449834219, 5759.971200143972, 5768.134779783066, 5543.417941116286, 5606.355269143937, 5616.943830561642, 5617.494425501724, 5656.406077361818, 5545.750441230235, 5596.8320950369925, 5601.60788744784, 5608.831735047955, 5610.607257491936, 5300.628955973045, 5362.490563396471, 5401.513863752281, 5461.1808228766, 5515.834524964257, 5669.262995810518, 5673.716314184192, 5677.347105082803, 5681.204631351531, 5683.658980461186, 5512.476314750093, 5513.393526711979, 5514.145307477347, 5521.089020311764, 5526.723663816712], "value": 5249.6293616031835, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.2932178759231, 610.4627927423867, 611.9969400152999, 621.5307442880625, 621.947134493568, 33156.17109572262, 44239.33640995384, 44572.77713611435, 45001.324980125246, 45090.09819803606, 45685.771571142184, 46087.76956115215, 46159.538404615945, 46187.307190392174, 46210.30684539735, 8028.0, 8633.740987770378, 8825.117488251155, 8836.409446299664, 10364.948175259113, 10186.64346747864, 10555.788884222311, 10598.099161571276, 10602.0, 10607.363558186495, 10591.682249532489, 10641.78716425671, 10641.893581064207, 10653.0, 10654.094401975803, 10497.947510262476, 10550.841737373983, 10557.524911379007, 10559.577616895314, 10566.52450639723, 10449.895501044968, 10484.266101372907, 10503.73740656482, 10514.369137851754, 10540.78918421635, 41490.58509414898, 42040.15919681589, 42092.78953605212, 42107.0, 42118.789406052776, 3225.580674512322, 3537.2041290709385, 3795.3927371620407, 3823.9235215295816, 3949.308870947573, 32196.678033219603, 32422.675773242438, 32768.0, 32768.50847237293, 33773.15567110817, 4377.255866502702, 4386.385905973167, 4422.115576884604, 4458.821647134143, 4583.518730533313, 319058.0, 319628.0, 319912.0, 320216.0, 320659.0, 56489.15266271009, 56953.71523142441, 57070.7146464265, 57276.42723572794, 57280.0, 60287.69856150779, 60531.69734151301, 60628.393716063154, 60646.0, 60793.39206607877, 12962.287074211008, 13231.139975901535, 13245.271510066847, 13283.800742988862, 13289.933550332187, 8719.694810681596, 9335.159835614828, 9501.90498095024, 9523.380980236263, 9594.904050959402, 16072.517824465285, 16212.10833404174, 16271.0, 16391.098489583186, 16407.343706251853, 5923.289205295392, 6000.279966403972, 6012.669303188279, 6026.5178785697035, 6091.664958427329, 945.3713280668278, 1026.9948650256702, 1059.081398824353, 1068.4444089073638, 1082.0477979378254, 32120.678793212235, 32429.35141297161, 32662.67337326644, 32837.0, 33173.86271616708, 1128.1200663482496, 1168.7954607943745, 1185.6561597136906, 1208.848893888264, 1239.5971309324505, 17108.828911710723, 17377.349839523245, 17404.216810243568, 17447.38934137299, 17677.646447070987, 491545.0, 493476.0, 494443.0, 494610.0, 496719.0, 57400.42599574034, 57410.42589574051, 57419.0, 57430.7128464355, 57681.71159144177, 1219.939003049844, 1262.9052821038458, 1351.1690310459194, 1351.9391627376792, 1380.5996261084174, 32711.67288327086, 33159.0, 33601.15997100092, 34115.488267676505, 34226.657733423344, 50086.0, 50557.74721126371, 50578.0, 50614.746926265136, 50647.240291396396, 337365.0, 338148.0, 338988.0, 339057.0, 340231.0, 31494.685053149176, 41027.58972410357, 41664.583354167284, 44201.55798441974, 44892.55107449014, 48407.0, 48416.0, 48940.75529622329, 49534.0, 49646.0, 41017.384739228335, 41615.58384416117, 41905.37141942934, 42739.0, 42905.78547107244, 41020.0, 41327.1734565313, 41701.16597667969, 41790.16419671529, 42014.0, 40328.1934361317, 41433.58566414418, 41565.37651935284, 41840.0, 42054.57945420629, 34347.65652343544, 36271.27457450889, 36966.63033369632, 36982.26035479329, 37054.629453705114, 28106.065864759457, 28107.479422640474, 28295.443750593917, 28580.57129143106, 28612.713872861004], "value": 20134.606050542203, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.2564563215809, 386.9941950870736, 392.17447273489296, 401.2576733043869, 411.1366131124637, 14297.571072867804, 15666.295016724247, 18027.819721802778, 19250.61498770024, 19266.325678601235, 13277.0, 15571.68856622867, 15579.688406231868, 15891.44379946702, 15952.122633255167, 5520.861978450531, 5702.4012478689765, 5712.828615141531, 5776.277965254343, 5784.219130417382, 5551.139573366135, 5677.801276955307, 5695.401982791809, 5716.7141642917895, 5719.170720245562, 4061.6953728470485, 5670.0927851543765, 6099.878002439948, 6391.073294372313, 6391.392817682313, 5279.1817268323475, 6468.838279043014, 6470.482361411099, 6495.512836537255, 6501.252355979052, 5382.273393091921, 5408.4591540845995, 5448.972755136238, 5452.745868450239, 5465.234867118606, 12327.44526496301, 12366.690832729164, 12538.561150359785, 12873.163244389085, 12923.289219092947, 2172.8696278223356, 2314.525522267931, 2376.453415714378, 2385.3440303916386, 2839.8640543782653, 9114.498702571356, 9532.380395274286, 9614.202390055889, 9717.416954982653, 11467.139964502696, 1825.863060270485, 1838.9506988903865, 1853.1290293562004, 1861.8882867027887, 1864.692325766247, 77216.0, 79467.6026619869, 80176.59911700462, 80459.19540804635, 81793.59103204445, 10281.794364112677, 10818.67543973682, 10835.51240194193, 10841.349519028725, 11058.22592418539, 14965.326560304813, 14991.550253492413, 15075.0, 15082.24588770557, 15086.471973480875, 6247.375262473807, 6567.770128045496, 6571.080048793173, 6684.665766711645, 7029.015937768717, 5732.971335143297, 6056.515478761688, 6060.151578778975, 6518.934810651833, 6568.573042752206, 4598.586127248497, 4625.676202665848, 4628.976855115703, 4675.649326300473, 4708.0, 1944.893030883315, 1954.8924809135633, 1965.9115339809744, 1970.8916009619322, 2021.8584699071218, 951.4909523404945, 1019.938803671782, 1027.8950128611773, 1069.385103565453, 1082.7169803782542, 18390.81609183891, 18656.160472778756, 18845.151968161466, 19087.141078651493, 19280.22879084821, 953.9284553658504, 958.4536814016084, 997.4514017290606, 999.8050380175796, 1026.3277553202515, 10314.638987635546, 10577.153827693766, 10624.150067994697, 10704.357738535557, 10760.0, 418724.0, 418970.0, 437050.0, 438551.0, 440448.0, 88632.0, 89668.0, 89985.0, 90077.54961225283, 91254.54372728094, 985.0297457004845, 993.4734590666958, 1031.376017509411, 1055.2929537210089, 1061.0291583201285, 16507.09210993359, 16763.32946682119, 16910.74633880468, 16921.83078169252, 17326.826731732523, 39255.6074439252, 40603.79698101491, 41058.0, 41640.583594164884, 42052.78973605113, 55417.722911385186, 55514.72242638761, 56068.43931560795, 56296.718516407156, 56470.717646411504, 5588.3573389060775, 7873.645685944031, 7908.881366779618, 8181.713640022571, 8339.874901876598, 9065.728028159167, 12414.441350139085, 12926.224426534438, 13341.86658133445, 13419.127756695783, 10951.56193752241, 11727.237729547553, 11824.23142495735, 11977.880221197676, 12130.090243231616, 11217.88782112201, 11591.420428978348, 11683.941580292045, 11743.530258789895, 12278.324692141661, 11960.401979901144, 12068.577599783965, 12155.088368372231, 12177.573784917486, 12222.694432639255, 8108.0, 8147.59262036884, 8168.959155204186, 8284.83430331402, 8303.95848020756, 8589.012263589768, 11151.219414640897, 11702.180847340946, 11765.52937882475, 12120.878791211975], "value": 8737.791689608832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.9191248525096, 1495.3495229575135, 1511.365226604826, 1662.6757782232462, 1800.4868612445455, 8365.330773538117, 8724.08397118303, 8726.040135585088, 8915.68795092172, 8917.420367676097, 8678.652853885853, 8859.15837995391, 8871.112888711126, 8880.555972201397, 8881.555922203896, 1973.1614064022804, 2218.2901471529117, 2265.694131292279, 2281.7261928568514, 2288.656701494781, 2313.363824948144, 2314.1206341590173, 2318.014843691433, 2321.292005938188, 2321.3268152235833, 1940.262700173939, 1949.7172909928051, 1983.7718662353798, 1987.7714062882737, 1989.542405246794, 1943.2129987355145, 2200.6258935980923, 2253.921112761062, 2258.5821622999633, 2273.57938781326, 2200.3838925101, 2217.5675743230076, 2228.7994080532667, 2232.9218477353215, 2235.1953296813135, 8851.114888511162, 8907.198352148338, 8913.910860891438, 8918.73243802687, 8922.509261990588, 5203.9219411708855, 5360.758765855546, 5360.785568577252, 5363.115086010804, 5366.248725178478, 3245.594300712411, 3247.8376081195847, 3304.80171189727, 3307.107081088093, 3363.83180840957, 1187.2935603316014, 1200.1658847101269, 1202.326697049655, 1202.663751118181, 1206.3244583033443, 12323.0, 12334.81497777534, 12351.444185011696, 12351.93824030874, 12362.011039116938, 4525.954740452553, 4588.151192029419, 4663.183942809995, 4663.463701674351, 4707.8587642370785, 10435.478226088666, 10439.634612788515, 10443.477826108665, 10444.425556594308, 10450.425226612457, 5132.12753831842, 5165.612579056586, 5170.8190213343105, 5172.689638621619, 5173.0, 4286.271333873186, 4647.186742320148, 4767.404074490689, 4807.95192048075, 4873.975630121827, 3912.5304963404574, 3935.114599215211, 3995.9800200998807, 4049.8987525311513, 4060.26915155269, 1222.4315693202705, 1237.4493350458959, 1248.4881198708485, 1252.7244006318595, 1263.134752694389, 820.4666966471841, 908.5003248213457, 915.5696822493418, 919.4529255093208, 998.5905778630727, 5990.251218597676, 6051.727672254759, 6154.630722156685, 6267.623942563369, 6271.655058971709, 982.6658935961665, 998.510729742421, 1023.3246057601947, 1035.2132379391717, 1042.223543460127, 4097.487814023247, 4155.314373128461, 4354.804033818486, 4361.214981303396, 4371.890702732394, 10829.0, 11139.665810025712, 11205.831912521318, 11209.271397359145, 11225.045871100885, 11017.83473247902, 11056.613018544313, 11102.0, 11116.555337786398, 11142.832857507145, 7729.8454030919065, 8012.839743205103, 8015.639296231567, 8179.018517777786, 8188.877166842502, 6057.363976782374, 6076.240469941257, 6094.0, 6302.180716506825, 6306.621602703853, 10141.442220677933, 10212.285140040423, 10237.9488102559, 10372.0, 10487.108595769445, 10454.581816727246, 10501.474926253504, 10532.05211530966, 10537.683869483928, 10539.314944528887, 6854.314568543105, 7258.564486130849, 7478.925210747823, 7499.137599176164, 7590.0, 8521.82956340882, 8543.829123417372, 8548.957255213683, 8597.828043439222, 8600.569971501525, 7270.309320614533, 7593.696252150073, 7648.426368022532, 7702.4608277422285, 8121.512709237465, 7676.616169191407, 7883.329916957123, 7906.090799557895, 8035.67857285702, 8135.959320203361, 7019.964900175466, 7149.356557909813, 7170.103737032871, 7185.245549217257, 7201.2438693938475, 5011.548960593418, 5113.51421614946, 5211.661242019257, 5366.543843773323, 5371.865703357447, 5146.150885104065, 5171.689698618095, 5223.947760522498, 5290.814821481384, 5497.917531236954], "value": 4635.847274533628, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.5494352962322, 331.0812495325473, 331.75615922297106, 333.85143611093065, 341.235632183908, 5148.639595228336, 5221.973890130544, 5287.35632183908, 5337.11937530307, 5341.89316213676, 5238.083335416296, 5276.15581506959, 5277.762500687469, 5301.549368303689, 5336.439673834249, 4153.833846646131, 4167.645750111229, 4171.728837625545, 4176.895577610554, 4187.0, 3969.6427321541055, 4035.656969157625, 4081.795910204478, 4191.832326706928, 4192.727472714279, 4131.2357213915375, 4132.958670413303, 4136.193442278742, 4149.294619914606, 4157.397177409274, 4152.3771434284945, 4154.31453810124, 4155.4182414462, 4161.001359673687, 4162.7502349858805, 3590.9281814363567, 3602.5316708827945, 3687.8340474678703, 3689.723270754704, 3691.778493290411, 5094.31226784383, 5122.897542049138, 5135.435102138762, 5138.691678499302, 5141.485851414866, 4728.598069164125, 4740.786664600101, 4744.691595046311, 4758.952410475886, 4780.521947805227, 5312.893742125175, 5505.917411238835, 5538.501534861882, 5547.750351234205, 5566.415526369754, 3954.604539546051, 3967.523897132334, 3969.8015099245213, 3971.245463361981, 3982.303096958021, 5747.0, 5757.76208115253, 5759.625624334447, 5774.2782152230975, 5788.0, 3889.4943657324366, 4069.2878746219294, 4080.6125917187705, 4114.156597897424, 4121.475054229894, 4124.958750412457, 4129.132882094734, 4143.399207114951, 4165.396017577435, 4166.64583510405, 4743.407074115736, 4746.572808447257, 4747.667663263607, 4751.904961900743, 4760.285957106462, 5294.497022782906, 5624.0, 5642.407547207484, 5648.689322087324, 5651.0, 1324.43711422645, 1351.3648585164956, 1351.4121357209644, 1352.2157148853642, 1383.2323060701206, 907.3603109807569, 907.5552979040223, 909.5679552212646, 916.062527753796, 925.4262357338494, 4807.95192048082, 4819.421669399694, 4820.9517904820505, 4823.252395878574, 4855.757212139379, 4847.321375007431, 5005.349304590452, 5006.6245031622775, 5092.821751238689, 5102.36220472441, 4698.130845793539, 4704.458987216445, 4807.95192048082, 4810.735409552438, 4862.8784280392565, 2728.01791355112, 2732.5218086834725, 2764.433291175304, 2770.5844123381653, 2772.209920172741, 5043.344365232496, 5172.611939179843, 5180.7150606717, 5188.92216616751, 5281.603879709037, 5056.317397151338, 5127.794888204505, 5166.250893620404, 5168.689878607295, 5196.648871293491, 5007.848194915263, 5195.19474481456, 5218.069314353714, 5248.763805628756, 5267.9736601316745, 5042.445331013512, 5065.0, 5079.974600127123, 5082.949170508247, 5117.901660319251, 5002.974985125051, 5009.899802003866, 5019.84940451787, 5024.472430394902, 5093.694378337312, 4810.965642386764, 5226.477352264895, 5244.396894357198, 5282.049231138432, 5301.602379821606, 3647.434647629622, 3667.541557305337, 3755.9248815024093, 3777.5844657088246, 3788.4885540452788, 5003.34956455673, 5026.899462010812, 5063.949360506348, 5121.61587880903, 5145.819896303761, 4917.483664215349, 4933.703977761345, 4954.9752251238515, 4973.45292017885, 5172.379314482287, 5390.51485366319, 5452.945470545244, 5622.9718851407115, 5656.660600363992, 5660.547066289478, 5457.181422786613, 5458.91811622818, 5502.779888804403, 5505.862353441035, 5520.66875987442, 5307.256984022163, 5649.406812284816, 5666.660000399989, 5682.886342273214, 5688.317401911797, 5489.807856725155, 5496.8076117337305, 5513.3108361454815, 5534.201384549957, 5573.721313934206], "value": 4067.623569103058, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.1706829816457, 738.8263758016865, 751.6091632351178, 760.2245709376436, 768.0974854545914, 14607.0, 14878.77681834772, 15031.624209394771, 15053.096814191156, 15089.773653395194, 13420.261885596288, 13425.261610611413, 13433.0, 13436.462541498353, 13446.529371472001, 662.5991275278459, 792.0574516325554, 802.0455657767238, 817.8446095241915, 819.5451524403953, 806.9354451643854, 810.1250649298772, 813.2843098073687, 813.4062134641715, 814.7107776739261, 851.4210336970857, 852.3990586636406, 852.8891244138254, 853.159637756809, 853.5348235211825, 700.9929900701004, 838.9664413423455, 864.235151890575, 866.1554983890719, 867.7657032601226, 630.3412933484519, 630.4420587779802, 630.5775130662468, 630.7319389259592, 630.9495240380759, 14050.156990580495, 14121.08212966154, 14145.292735363293, 14145.929270353685, 14179.716405671828, 12744.80882786759, 12763.553275635308, 12772.552960646426, 13031.152975056591, 13033.413496392684, 11356.432178391047, 11405.0, 11557.88442115585, 11930.284182949052, 12114.636560903187, 4312.870613881589, 4317.0934103838235, 4318.52496225415, 4331.891702707426, 4332.870013899588, 57194.7140264296, 57195.0, 57438.0, 57440.13839792406, 57626.423735762524, 10356.689299321033, 10525.157987360994, 10631.734206644896, 10742.0, 11042.779144417067, 18082.728759068625, 18796.966166860955, 18808.52978675544, 18812.0, 18813.341533046278, 8108.472949258397, 8134.227248411392, 8346.8330633387, 8416.705415310435, 8475.788105297417, 4550.749708765983, 4558.133954548625, 4581.129585378767, 4592.288195329729, 4753.714777113315, 3179.268768183331, 3275.81982990933, 3276.705096541275, 3282.2122690554097, 3282.2614911645164, 491.2434850330451, 558.7178474870245, 568.0853825341197, 587.3744462147799, 612.5589575505629, 696.4324075878143, 703.9612821294776, 716.874546954281, 722.1334398721555, 732.3116270705519, 10243.436610986319, 10288.845667314996, 10381.0, 10403.635872744419, 10417.687469375931, 629.9874002520015, 669.7421492725335, 727.6652739739673, 757.7234309477111, 771.2172145272552, 4574.0, 4670.159371313196, 4679.110968915895, 4679.1577516046755, 4729.456112547033, 60502.0, 63775.0, 63813.680931595976, 70425.0, 72094.63952680203, 58790.41209587935, 59284.0, 60164.398356016754, 60726.696366517885, 61765.0, 55540.72229638826, 56109.0, 56135.0, 56959.43040569624, 56983.71508142433, 9520.61917523319, 9607.759806004906, 9639.18066964316, 9643.855342170013, 9680.5643746033, 29256.853715731286, 34684.0, 36719.449208261365, 36797.44803827998, 37374.716139195036, 46848.765756171, 48807.511924880295, 48916.0, 50203.49796501988, 50211.74894125506, 11652.708682283012, 11756.23872331707, 11953.0, 11974.760504789681, 12066.0, 12815.0, 12912.677183070122, 12920.418581164058, 13095.934520327337, 13174.802377964146, 8531.957340213508, 12783.872161278268, 12806.67983300425, 12889.8066529004, 13024.993046449807, 12381.257124572554, 12645.810312845131, 12711.61865144047, 12890.291033992846, 12980.610581682564, 11726.589569365031, 11811.114166437379, 11890.76218475643, 11964.102692297938, 12435.75128497443, 10354.223433242387, 10636.255462117577, 10852.240343175901, 10937.671869843918, 11090.279131856403, 10688.198385121303, 10942.617008404668, 11006.614768483065, 11266.436678166223, 11286.379249141377], "value": 6804.375251959257, "unit": "score"}], "name": "dromaeo_css", "value": 7008.574230011208, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 110.0, 108.0, 111.0, 110.0, 108.0, 108.0, 109.0, 109.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 125.0, 177.0, 124.0, 124.0, 155.0, 124.0, 162.0, 129.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 161.0, 159.0, 159.0, 161.0, 161.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 126.0, 75.0, 75.0, 74.0, 75.0, 98.0, 76.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 120.0, 83.0, 84.0, 83.0, 133.0, 83.0, 84.0, 139.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 53.0, 51.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 78.0, 85.0, 81.0, 81.0, 81.0, 82.0, 114.0, 80.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 130.0, 119.0, 119.0, 117.0, 124.0, 124.0, 130.0, 132.0, 122.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 159.0, 163.0, 159.0, 166.0, 158.0, 161.0, 159.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 61.0, 62.0, 61.0, 62.0, 64.0, 62.0, 63.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1490.6000000000001, "alertThreshold": 2.0}]} 08:20:13 INFO - Return code: 0 08:20:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:20:13 ERROR - # TBPL SUCCESS # 08:20:13 INFO - Running post-action listener: _resource_record_post_action 08:20:13 INFO - ##### 08:20:13 INFO - ##### Finished run-tests step (success) 08:20:13 INFO - ##### 08:20:13 INFO - Running post-run listener: _resource_record_post_run 08:20:13 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 28672; Write bytes: 541929472; Read time: 232; Write time: 487844 08:20:13 INFO - TinderboxPrint: CPU usage
13.3% 08:20:13 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 232 08:20:13 INFO - TinderboxPrint: I/O write bytes / time
541,929,472 / 487,844 08:20:13 INFO - TinderboxPrint: CPU idle
8,245.6 (86.7%) 08:20:13 INFO - TinderboxPrint: CPU user
1,214.3 (12.8%) 08:20:13 INFO - TinderboxPrint: Swap in / out
0 / 0 08:20:13 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247177216; Read time: 0; Write time: 262012 08:20:13 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 28672; Write bytes: 294752256; Read time: 232; Write time: 225832 08:20:14 INFO - Running post-run listener: _upload_blobber_files 08:20:14 INFO - Blob upload gear active. 08:20:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:20:14 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 08:20:14 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'] 08:20:14 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 08:20:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:20:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:15 INFO - (blobuploader) - INFO - Done attempting. 08:20:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:20:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:16 INFO - (blobuploader) - INFO - Done attempting. 08:20:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:20:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:20:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:19 INFO - (blobuploader) - INFO - Done attempting. 08:20:19 INFO - (blobuploader) - INFO - Iteration through files over. 08:20:20 INFO - Return code: 0 08:20:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:20:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:20:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/980e1990278a3cd9eaeb52b2d67d26e80e13da1f9031d341cc8dc0e89a01d1d01ea06beaee56307df11bfd51a4cded9ae74a98705163b57f433e04e4af8b3d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f9fe4bc61734e0979cbfd81cf08f0d3776ef3dab68a1c1bb2bd9c0c0be7bc52ae4b11acf34fd9a11226725eee4dc900344a889c9f62b35a3d4f54d8da871780d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f40d8126589c73a4ffa06bfe2a9340516e46088728dde40444a7876108a008709dec9917756d1e5770256e9de5b1987d2e705715d56c4877dc8c763d70ea9084"} 08:20:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:20:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:20:20 INFO - Contents: 08:20:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/980e1990278a3cd9eaeb52b2d67d26e80e13da1f9031d341cc8dc0e89a01d1d01ea06beaee56307df11bfd51a4cded9ae74a98705163b57f433e04e4af8b3d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f9fe4bc61734e0979cbfd81cf08f0d3776ef3dab68a1c1bb2bd9c0c0be7bc52ae4b11acf34fd9a11226725eee4dc900344a889c9f62b35a3d4f54d8da871780d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f40d8126589c73a4ffa06bfe2a9340516e46088728dde40444a7876108a008709dec9917756d1e5770256e9de5b1987d2e705715d56c4877dc8c763d70ea9084"} 08:20:20 INFO - Running post-run listener: copy_logs_to_upload_dir 08:20:20 INFO - Copying logs to upload dir... 08:20:20 INFO - mkdir: /builds/slave/test/build/upload/logs 08:20:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.078569 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-05-28 08:20:19.885127) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 08:20:19.888861) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982 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/980e1990278a3cd9eaeb52b2d67d26e80e13da1f9031d341cc8dc0e89a01d1d01ea06beaee56307df11bfd51a4cded9ae74a98705163b57f433e04e4af8b3d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f9fe4bc61734e0979cbfd81cf08f0d3776ef3dab68a1c1bb2bd9c0c0be7bc52ae4b11acf34fd9a11226725eee4dc900344a889c9f62b35a3d4f54d8da871780d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f40d8126589c73a4ffa06bfe2a9340516e46088728dde40444a7876108a008709dec9917756d1e5770256e9de5b1987d2e705715d56c4877dc8c763d70ea9084"} build_url:https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011450 build_url: 'https://queue.taskcluster.net/v1/task/IxBlpjRmRnWuQ4SHPz4OTg/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/980e1990278a3cd9eaeb52b2d67d26e80e13da1f9031d341cc8dc0e89a01d1d01ea06beaee56307df11bfd51a4cded9ae74a98705163b57f433e04e4af8b3d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f9fe4bc61734e0979cbfd81cf08f0d3776ef3dab68a1c1bb2bd9c0c0be7bc52ae4b11acf34fd9a11226725eee4dc900344a889c9f62b35a3d4f54d8da871780d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f40d8126589c73a4ffa06bfe2a9340516e46088728dde40444a7876108a008709dec9917756d1e5770256e9de5b1987d2e705715d56c4877dc8c763d70ea9084"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 08:20:19.915098) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 08:20:19.915450) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464446223.197144-793014982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004398 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 08:20:20.003287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 08:20:20.004001) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 08:20:20.004640) ========= ========= Total master_lag: 0.19 =========