builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1464382287.16 results: success (0) buildid: 20160527123448 builduid: e02cbfd8cfaa4bb082c19528eabb611a revision: 82636afc630ccb7a81d18eedd3886178f341e8c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.162068) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.162677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.163110) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.240669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.241063) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 13:51:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-05-27 13:51:28 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118227 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.377672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.378523) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015102 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.431740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:27.432339) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 82636afc630ccb7a81d18eedd3886178f341e8c6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 82636afc630ccb7a81d18eedd3886178f341e8c6 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 13:51:28,548 truncating revision to first 12 chars 2016-05-27 13:51:28,548 Setting DEBUG logging. 2016-05-27 13:51:28,548 attempt 1/10 2016-05-27 13:51:28,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/82636afc630c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 13:51:28,903 unpacking tar archive at: larch-82636afc630c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.676986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:28.125910) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:28.126223) ========= script_repo_revision: 82636afc630ccb7a81d18eedd3886178f341e8c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:28.126645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:28.126921) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 13:51:28.138059) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-27 13:51:28.138455) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:51:29 INFO - MultiFileLogger online at 20160527 13:51:29 in /builds/slave/test 13:51:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 13:51:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:51:29 INFO - {'append_to_log': False, 13:51:29 INFO - 'base_work_dir': '/builds/slave/test', 13:51:29 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 13:51:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:51:29 INFO - 'branch': 'Larch-Non-PGO', 13:51:29 INFO - 'buildbot_json_path': 'buildprops.json', 13:51:29 INFO - 'config_files': ('talos/linux_config.py',), 13:51:29 INFO - 'default_actions': ('clobber', 13:51:29 INFO - 'read-buildbot-config', 13:51:29 INFO - 'download-and-extract', 13:51:29 INFO - 'populate-webroot', 13:51:29 INFO - 'create-virtualenv', 13:51:29 INFO - 'install', 13:51:29 INFO - 'run-tests'), 13:51:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:51:29 INFO - 'download_minidump_stackwalk': True, 13:51:29 INFO - 'download_symbols': 'ondemand', 13:51:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:51:29 INFO - 'tooltool.py': '/tools/tooltool.py', 13:51:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:51:29 INFO - '/tools/misc-python/virtualenv.py')}, 13:51:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:51:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:51:29 INFO - 'installer_path': 'installer.exe', 13:51:29 INFO - 'log_level': 'info', 13:51:29 INFO - 'log_name': 'talos', 13:51:29 INFO - 'log_to_console': True, 13:51:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:51:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:51:29 INFO - 'opt_config_files': (), 13:51:29 INFO - 'pip_index': False, 13:51:29 INFO - 'sps_profile': False, 13:51:29 INFO - 'sps_profile_interval': 0, 13:51:29 INFO - 'suite': 'dromaeojs', 13:51:29 INFO - 'system_bits': '32', 13:51:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:51:29 INFO - 'title': 'talos-linux64-ix-053', 13:51:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:51:29 INFO - 'use_talos_json': True, 13:51:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:51:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:51:29 INFO - 'work_dir': 'build'} 13:51:29 INFO - ##### 13:51:29 INFO - ##### Running clobber step. 13:51:29 INFO - ##### 13:51:29 INFO - Running pre-action listener: _resource_record_pre_action 13:51:29 INFO - Running main action method: clobber 13:51:29 INFO - rmtree: /builds/slave/test/build 13:51:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:51:30 INFO - Running post-action listener: _resource_record_post_action 13:51:30 INFO - ##### 13:51:30 INFO - ##### Finished clobber step (success) 13:51:30 INFO - ##### 13:51:30 INFO - ##### 13:51:30 INFO - ##### Running read-buildbot-config step. 13:51:30 INFO - ##### 13:51:30 INFO - Running pre-action listener: _resource_record_pre_action 13:51:30 INFO - Running main action method: read_buildbot_config 13:51:30 INFO - Using buildbot properties: 13:51:30 INFO - { 13:51:30 INFO - "project": "", 13:51:30 INFO - "product": "firefox", 13:51:30 INFO - "script_repo_revision": "production", 13:51:30 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 13:51:30 INFO - "repository": "", 13:51:30 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 13:51:30 INFO - "buildid": "20160527123448", 13:51:30 INFO - "pgo_build": "False", 13:51:30 INFO - "basedir": "/builds/slave/test", 13:51:30 INFO - "buildnumber": 1, 13:51:30 INFO - "slavename": "talos-linux64-ix-053", 13:51:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:51:30 INFO - "platform": "ubuntu64_hw", 13:51:30 INFO - "scheduler": "tests-larch-linux64-talos", 13:51:30 INFO - "branch": "larch", 13:51:30 INFO - "repo_path": "projects/larch", 13:51:30 INFO - "revision": "82636afc630ccb7a81d18eedd3886178f341e8c6", 13:51:30 INFO - "stage_platform": "linux64", 13:51:30 INFO - "builduid": "e02cbfd8cfaa4bb082c19528eabb611a", 13:51:30 INFO - "slavebuilddir": "test" 13:51:30 INFO - } 13:51:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 13:51:30 INFO - Running post-action listener: _resource_record_post_action 13:51:30 INFO - ##### 13:51:30 INFO - ##### Finished read-buildbot-config step (success) 13:51:30 INFO - ##### 13:51:30 INFO - ##### 13:51:30 INFO - ##### Running download-and-extract step. 13:51:30 INFO - ##### 13:51:30 INFO - Running pre-action listener: _resource_record_pre_action 13:51:30 INFO - Running main action method: download_and_extract 13:51:30 INFO - mkdir: /builds/slave/test/build/tests 13:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:30 INFO - https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:51:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 13:51:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 13:51:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:35 INFO - Downloaded 1361 bytes. 13:51:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 13:51:35 INFO - Using the following test package requirements: 13:51:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 13:51:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:51:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:51:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'jsshell-linux-x86_64.zip'], 13:51:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:51:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 13:51:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:51:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:51:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:51:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 13:51:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:51:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:51: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')]} 13:51:35 INFO - https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:51:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:51:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:51:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:39 INFO - Downloaded 22848162 bytes. 13:51:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:51:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:51:40 INFO - Return code: 0 13:51:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:40 INFO - https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:51:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:51:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 13:51:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:42 INFO - Downloaded 22848162 bytes. 13:51:42 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'] 13:51:42 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 13:51:43 INFO - Return code: 0 13:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:43 INFO - https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:51:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 13:51:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 13:51:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:44 INFO - Downloaded 10834042 bytes. 13:51:44 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'] 13:51:44 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 13:51:44 INFO - Return code: 0 13:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:44 INFO - https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:51:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:51:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:51:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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 13:51:45 INFO - Downloaded 54910521 bytes. 13:51:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:51:45 INFO - mkdir: /builds/slave/test/properties 13:51:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:51:45 INFO - Writing to file /builds/slave/test/properties/build_url 13:51:45 INFO - Contents: 13:51:45 INFO - build_url:https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 13:51:46 INFO - Running post-action listener: _resource_record_post_action 13:51:46 INFO - Running post-action listener: set_extra_try_arguments 13:51:46 INFO - ##### 13:51:46 INFO - ##### Finished download-and-extract step (success) 13:51:46 INFO - ##### 13:51:46 INFO - ##### 13:51:46 INFO - ##### Running populate-webroot step. 13:51:46 INFO - ##### 13:51:46 INFO - Running pre-action listener: _resource_record_pre_action 13:51:46 INFO - Running main action method: populate_webroot 13:51:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:51:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:51:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:51:46 INFO - '--tppagecycles', 13:51:46 INFO - '7'], 13:51:46 INFO - 'tests': ['tsvgm']}}, 13:51:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:51:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:51:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:51:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:51:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:51:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:51:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['damp', 'tps']}, 13:51:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'tests': ['damp', 'tps']}, 13:51:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['dromaeo_dom']}, 13:51:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:51:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['a11yr', 13:51:46 INFO - 'ts_paint', 13:51:46 INFO - 'tpaint', 13:51:46 INFO - 'sessionrestore', 13:51:46 INFO - 'sessionrestore_no_auto_restore', 13:51:46 INFO - 'tabpaint']}, 13:51:46 INFO - 'other-e10s': {'tests': ['a11yr', 13:51:46 INFO - 'ts_paint', 13:51:46 INFO - 'tpaint', 13:51:46 INFO - 'sessionrestore', 13:51:46 INFO - 'sessionrestore_no_auto_restore', 13:51:46 INFO - 'tabpaint']}, 13:51:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:51:46 INFO - 'ts_paint', 13:51:46 INFO - 'tpaint', 13:51:46 INFO - 'sessionrestore', 13:51:46 INFO - 'sessionrestore_no_auto_restore', 13:51:46 INFO - 'tabpaint']}, 13:51:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:51:46 INFO - 'ts_paint', 13:51:46 INFO - 'tpaint', 13:51:46 INFO - 'sessionrestore', 13:51:46 INFO - 'sessionrestore_no_auto_restore', 13:51:46 INFO - 'tabpaint']}, 13:51:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['a11yr', 13:51:46 INFO - 'ts_paint', 13:51:46 INFO - 'tpaint', 13:51:46 INFO - 'sessionrestore', 13:51:46 INFO - 'sessionrestore_no_auto_restore', 13:51:46 INFO - 'tabpaint']}, 13:51:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['a11yr', 13:51:46 INFO - 'ts_paint', 13:51:46 INFO - 'tpaint', 13:51:46 INFO - 'sessionrestore', 13:51:46 INFO - 'sessionrestore_no_auto_restore', 13:51:46 INFO - 'tabpaint']}, 13:51:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['tsvgx', 13:51:46 INFO - 'tsvgr_opacity', 13:51:46 INFO - 'tart', 13:51:46 INFO - 'tscrollx', 13:51:46 INFO - 'cart']}, 13:51:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:51:46 INFO - 'tsvgr_opacity', 13:51:46 INFO - 'tart', 13:51:46 INFO - 'tscrollx', 13:51:46 INFO - 'cart']}, 13:51:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'talos_options': ['--disable-e10s'], 13:51:46 INFO - 'tests': ['tp5o']}, 13:51:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'tests': ['tp5o']}, 13:51:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'talos_options': ['--disable-e10s', 13:51:46 INFO - '--xperf_path', 13:51:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:51:46 INFO - 'tests': ['tp5n']}, 13:51:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:46 INFO - 'talos_options': ['--xperf_path', 13:51:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:51:46 INFO - 'tests': ['tp5n']}}, 13:51:46 INFO - 'talos.zip': {'path': '', 13:51:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:51:46 INFO - Running post-action listener: _resource_record_post_action 13:51:46 INFO - ##### 13:51:46 INFO - ##### Finished populate-webroot step (success) 13:51:46 INFO - ##### 13:51:46 INFO - ##### 13:51:46 INFO - ##### Running create-virtualenv step. 13:51:46 INFO - ##### 13:51:46 INFO - Running pre-action listener: _resource_record_pre_action 13:51:46 INFO - Running main action method: create_virtualenv 13:51:46 INFO - Creating virtualenv /builds/slave/test/build/venv 13:51:46 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 13:51:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:51:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:51:46 INFO - Using real prefix '/usr' 13:51:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:51:47 INFO - Installing distribute.............................................................................................................................................................................................done. 13:51:49 INFO - Installing pip.................done. 13:51:49 INFO - Return code: 0 13:51:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:51:49 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')]} 13:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 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')]} 13:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 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 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:51:49 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 13:51:49 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 13:51:49 INFO - Using env: {'DISPLAY': ':0', 13:51:49 INFO - 'HOME': '/home/cltbld', 13:51:49 INFO - 'LANG': 'en_US.UTF-8', 13:51:49 INFO - 'LANGUAGE': 'en_US:en', 13:51:49 INFO - 'LOGNAME': 'cltbld', 13:51:49 INFO - 'MAIL': '/var/mail/cltbld', 13:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:49 INFO - 'MOZ_NO_REMOTE': '1', 13:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:51:49 INFO - 'NO_EM_RESTART': '1', 13:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:49 INFO - 'PWD': '/builds/slave/test', 13:51:49 INFO - 'SHELL': '/bin/bash', 13:51:49 INFO - 'SHLVL': '1', 13:51:49 INFO - 'TERM': 'linux', 13:51:49 INFO - 'TMOUT': '86400', 13:51:49 INFO - 'USER': 'cltbld', 13:51:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:49 INFO - '_': '/tools/buildbot/bin/python'} 13:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:49 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 13:51:49 INFO - Cleaning up... 13:51:49 INFO - Return code: 0 13:51:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:51:49 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')]} 13:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 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')]} 13:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 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 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:51:49 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 13:51:49 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 13:51:49 INFO - Using env: {'DISPLAY': ':0', 13:51:49 INFO - 'HOME': '/home/cltbld', 13:51:49 INFO - 'LANG': 'en_US.UTF-8', 13:51:49 INFO - 'LANGUAGE': 'en_US:en', 13:51:49 INFO - 'LOGNAME': 'cltbld', 13:51:49 INFO - 'MAIL': '/var/mail/cltbld', 13:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:49 INFO - 'MOZ_NO_REMOTE': '1', 13:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:51:49 INFO - 'NO_EM_RESTART': '1', 13:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:49 INFO - 'PWD': '/builds/slave/test', 13:51:49 INFO - 'SHELL': '/bin/bash', 13:51:49 INFO - 'SHLVL': '1', 13:51:49 INFO - 'TERM': 'linux', 13:51:49 INFO - 'TMOUT': '86400', 13:51:49 INFO - 'USER': 'cltbld', 13:51:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:49 INFO - '_': '/tools/buildbot/bin/python'} 13:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:49 INFO - Downloading/unpacking psutil>=3.1.1 13:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:51:51 INFO - Installing collected packages: psutil 13:51:51 INFO - Running setup.py install for psutil 13:51:51 INFO - building 'psutil._psutil_linux' extension 13:51:51 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 13:51:51 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 13:51:51 INFO - building 'psutil._psutil_posix' extension 13:51:51 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 13:51:52 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 13:51:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:51:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:51:52 INFO - Successfully installed psutil 13:51:52 INFO - Cleaning up... 13:51:52 INFO - Return code: 0 13:51:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:51:52 INFO - Using env: {'DISPLAY': ':0', 13:51:52 INFO - 'HOME': '/home/cltbld', 13:51:52 INFO - 'LANG': 'en_US.UTF-8', 13:51:52 INFO - 'LANGUAGE': 'en_US:en', 13:51:52 INFO - 'LOGNAME': 'cltbld', 13:51:52 INFO - 'MAIL': '/var/mail/cltbld', 13:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:52 INFO - 'MOZ_NO_REMOTE': '1', 13:51:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:51:52 INFO - 'NO_EM_RESTART': '1', 13:51:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:52 INFO - 'PWD': '/builds/slave/test', 13:51:52 INFO - 'SHELL': '/bin/bash', 13:51:52 INFO - 'SHLVL': '1', 13:51:52 INFO - 'TERM': 'linux', 13:51:52 INFO - 'TMOUT': '86400', 13:51:52 INFO - 'USER': 'cltbld', 13:51:52 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:52 INFO - '_': '/tools/buildbot/bin/python'} 13:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:51:54 INFO - Installing collected packages: mozsystemmonitor 13:51:54 INFO - Running setup.py install for mozsystemmonitor 13:51:54 INFO - Successfully installed mozsystemmonitor 13:51:54 INFO - Cleaning up... 13:51:54 INFO - Return code: 0 13:51:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:51: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')]} 13:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51: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')]} 13:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:51:54 INFO - Using env: {'DISPLAY': ':0', 13:51:54 INFO - 'HOME': '/home/cltbld', 13:51:54 INFO - 'LANG': 'en_US.UTF-8', 13:51:54 INFO - 'LANGUAGE': 'en_US:en', 13:51:54 INFO - 'LOGNAME': 'cltbld', 13:51:54 INFO - 'MAIL': '/var/mail/cltbld', 13:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:54 INFO - 'MOZ_NO_REMOTE': '1', 13:51:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:51:54 INFO - 'NO_EM_RESTART': '1', 13:51:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:54 INFO - 'PWD': '/builds/slave/test', 13:51:54 INFO - 'SHELL': '/bin/bash', 13:51:54 INFO - 'SHLVL': '1', 13:51:54 INFO - 'TERM': 'linux', 13:51:54 INFO - 'TMOUT': '86400', 13:51:54 INFO - 'USER': 'cltbld', 13:51:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:54 INFO - '_': '/tools/buildbot/bin/python'} 13:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:54 INFO - Downloading/unpacking blobuploader==1.2.4 13:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:56 INFO - Downloading blobuploader-1.2.4.tar.gz 13:51:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:51:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:51:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:57 INFO - Downloading docopt-0.6.1.tar.gz 13:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:51:57 INFO - Installing collected packages: blobuploader, requests, docopt 13:51:57 INFO - Running setup.py install for blobuploader 13:51:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:51:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:51:57 INFO - Running setup.py install for requests 13:51:57 INFO - Running setup.py install for docopt 13:51:58 INFO - Successfully installed blobuploader requests docopt 13:51:58 INFO - Cleaning up... 13:51:58 INFO - Return code: 0 13:51:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:51:58 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')]} 13:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:58 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')]} 13:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:58 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 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:51:58 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 13:51:58 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 13:51:58 INFO - Using env: {'DISPLAY': ':0', 13:51:58 INFO - 'HOME': '/home/cltbld', 13:51:58 INFO - 'LANG': 'en_US.UTF-8', 13:51:58 INFO - 'LANGUAGE': 'en_US:en', 13:51:58 INFO - 'LOGNAME': 'cltbld', 13:51:58 INFO - 'MAIL': '/var/mail/cltbld', 13:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:58 INFO - 'MOZ_NO_REMOTE': '1', 13:51:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:51:58 INFO - 'NO_EM_RESTART': '1', 13:51:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:58 INFO - 'PWD': '/builds/slave/test', 13:51:58 INFO - 'SHELL': '/bin/bash', 13:51:58 INFO - 'SHLVL': '1', 13:51:58 INFO - 'TERM': 'linux', 13:51:58 INFO - 'TMOUT': '86400', 13:51:58 INFO - 'USER': 'cltbld', 13:51:58 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:58 INFO - '_': '/tools/buildbot/bin/python'} 13:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:51:58 INFO - Running setup.py (path:/tmp/pip-Zf96I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:51:58 INFO - Running setup.py (path:/tmp/pip-xxibEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:51:58 INFO - Running setup.py (path:/tmp/pip-Vn99Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:51:58 INFO - Running setup.py (path:/tmp/pip-iXq9uZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:51:58 INFO - Running setup.py (path:/tmp/pip-nsc3mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:51:58 INFO - Running setup.py (path:/tmp/pip-dyG1Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:51:58 INFO - Running setup.py (path:/tmp/pip-FnbDY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:51:58 INFO - Running setup.py (path:/tmp/pip-ZYoiu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:51:58 INFO - Running setup.py (path:/tmp/pip-SsBLSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:51:58 INFO - Running setup.py (path:/tmp/pip-gVJ3NS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:51:59 INFO - Running setup.py (path:/tmp/pip-4pwXUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:51:59 INFO - Running setup.py (path:/tmp/pip-JdKIeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:51:59 INFO - Running setup.py (path:/tmp/pip-pPQR37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:51:59 INFO - Running setup.py (path:/tmp/pip-78otE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:51:59 INFO - Running setup.py (path:/tmp/pip-YqPDFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:51:59 INFO - Running setup.py (path:/tmp/pip-xT0_w4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:51:59 INFO - Running setup.py (path:/tmp/pip-TVI0t5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:51:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:51:59 INFO - Running setup.py install for manifestparser 13:51:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:51:59 INFO - Running setup.py install for mozcrash 13:51:59 INFO - Running setup.py install for mozdebug 13:51:59 INFO - Running setup.py install for mozdevice 13:51:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:51:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:51:59 INFO - Running setup.py install for mozfile 13:52:00 INFO - Running setup.py install for mozhttpd 13:52:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for mozinfo 13:52:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for mozInstall 13:52:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for mozleak 13:52:00 INFO - Running setup.py install for mozlog 13:52:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for moznetwork 13:52:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for mozprocess 13:52:00 INFO - Running setup.py install for mozprofile 13:52:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for mozrunner 13:52:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:52:00 INFO - Running setup.py install for mozscreenshot 13:52:01 INFO - Running setup.py install for moztest 13:52:01 INFO - Running setup.py install for mozversion 13:52:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:52:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:52:01 INFO - Cleaning up... 13:52:01 INFO - Return code: 0 13:52:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52:01 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 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:52:01 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 13:52:01 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 13:52:01 INFO - Using env: {'DISPLAY': ':0', 13:52:01 INFO - 'HOME': '/home/cltbld', 13:52:01 INFO - 'LANG': 'en_US.UTF-8', 13:52:01 INFO - 'LANGUAGE': 'en_US:en', 13:52:01 INFO - 'LOGNAME': 'cltbld', 13:52:01 INFO - 'MAIL': '/var/mail/cltbld', 13:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:52:01 INFO - 'MOZ_NO_REMOTE': '1', 13:52:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:52:01 INFO - 'NO_EM_RESTART': '1', 13:52:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:52:01 INFO - 'PWD': '/builds/slave/test', 13:52:01 INFO - 'SHELL': '/bin/bash', 13:52:01 INFO - 'SHLVL': '1', 13:52:01 INFO - 'TERM': 'linux', 13:52:01 INFO - 'TMOUT': '86400', 13:52:01 INFO - 'USER': 'cltbld', 13:52:01 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:52:01 INFO - '_': '/tools/buildbot/bin/python'} 13:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:52:01 INFO - Running setup.py (path:/tmp/pip-j5B6tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:52:01 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)) 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:52:01 INFO - Running setup.py (path:/tmp/pip-CNqNZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:52:01 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)) 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:52:01 INFO - Running setup.py (path:/tmp/pip-Qoq2hM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:52:01 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)) 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:52:01 INFO - Running setup.py (path:/tmp/pip-ZRaqMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:52:01 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)) 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:52:01 INFO - Running setup.py (path:/tmp/pip-DbBwMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:52:01 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)) 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:52:01 INFO - Running setup.py (path:/tmp/pip-dRC4nC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:52:01 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)) 13:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:52:01 INFO - Running setup.py (path:/tmp/pip-hyRNwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:52:02 INFO - Running setup.py (path:/tmp/pip-iiJ4UE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:52:02 INFO - Running setup.py (path:/tmp/pip-h0nodu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:52:02 INFO - Running setup.py (path:/tmp/pip-TCd7Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:52:02 INFO - Running setup.py (path:/tmp/pip-W6P8Pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:52:02 INFO - Running setup.py (path:/tmp/pip-225g5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:52:02 INFO - Running setup.py (path:/tmp/pip-TK5VOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:52:02 INFO - Running setup.py (path:/tmp/pip-sf7uHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:52:02 INFO - Running setup.py (path:/tmp/pip-Szww_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:52:02 INFO - Running setup.py (path:/tmp/pip-buz_Bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:52:02 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)) 13:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:52:02 INFO - Running setup.py (path:/tmp/pip-y3F9SC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:52:02 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)) 13:52:02 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)) 13:52:02 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)) 13:52:02 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)) 13:52:02 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)) 13:52:02 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)) 13:52:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52:04 INFO - Downloading blessings-1.6.tar.gz 13:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:52:04 INFO - Installing collected packages: blessings 13:52:04 INFO - Running setup.py install for blessings 13:52:04 INFO - Successfully installed blessings 13:52:04 INFO - Cleaning up... 13:52:04 INFO - Return code: 0 13:52:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:52:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:52:05 INFO - Reading from file tmpfile_stdout 13:52:05 INFO - Current package versions: 13:52:05 INFO - argparse == 1.2.1 13:52:05 INFO - blessings == 1.6 13:52:05 INFO - blobuploader == 1.2.4 13:52:05 INFO - docopt == 0.6.1 13:52:05 INFO - manifestparser == 1.1 13:52:05 INFO - mozInstall == 1.12 13:52:05 INFO - mozcrash == 0.17 13:52:05 INFO - mozdebug == 0.1 13:52:05 INFO - mozdevice == 0.48 13:52:05 INFO - mozfile == 1.2 13:52:05 INFO - mozhttpd == 0.7 13:52:05 INFO - mozinfo == 0.9 13:52:05 INFO - mozleak == 0.1 13:52:05 INFO - mozlog == 3.2 13:52:05 INFO - moznetwork == 0.27 13:52:05 INFO - mozprocess == 0.22 13:52:05 INFO - mozprofile == 0.28 13:52:05 INFO - mozrunner == 6.11 13:52:05 INFO - mozscreenshot == 0.1 13:52:05 INFO - mozsystemmonitor == 0.3 13:52:05 INFO - moztest == 0.7 13:52:05 INFO - mozversion == 1.4 13:52:05 INFO - psutil == 3.1.1 13:52:05 INFO - requests == 1.2.3 13:52:05 INFO - wsgiref == 0.1.2 13:52:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:52: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')]} 13:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52: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')]} 13:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52:05 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 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:52:05 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 13:52:05 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 13:52:05 INFO - Using env: {'DISPLAY': ':0', 13:52:05 INFO - 'HOME': '/home/cltbld', 13:52:05 INFO - 'LANG': 'en_US.UTF-8', 13:52:05 INFO - 'LANGUAGE': 'en_US:en', 13:52:05 INFO - 'LOGNAME': 'cltbld', 13:52:05 INFO - 'MAIL': '/var/mail/cltbld', 13:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:52:05 INFO - 'MOZ_NO_REMOTE': '1', 13:52:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:52:05 INFO - 'NO_EM_RESTART': '1', 13:52:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:52:05 INFO - 'PWD': '/builds/slave/test', 13:52:05 INFO - 'SHELL': '/bin/bash', 13:52:05 INFO - 'SHLVL': '1', 13:52:05 INFO - 'TERM': 'linux', 13:52:05 INFO - 'TMOUT': '86400', 13:52:05 INFO - 'USER': 'cltbld', 13:52:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:52:05 INFO - '_': '/tools/buildbot/bin/python'} 13:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 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)) 13:52:05 INFO - Cleaning up... 13:52:05 INFO - Return code: 0 13:52:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:52: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')]} 13:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52: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')]} 13:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:52: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', 'jsonschema'] in /builds/slave/test/build 13:52: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 jsonschema 13:52:05 INFO - Using env: {'DISPLAY': ':0', 13:52:05 INFO - 'HOME': '/home/cltbld', 13:52:05 INFO - 'LANG': 'en_US.UTF-8', 13:52:05 INFO - 'LANGUAGE': 'en_US:en', 13:52:05 INFO - 'LOGNAME': 'cltbld', 13:52:05 INFO - 'MAIL': '/var/mail/cltbld', 13:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:52:05 INFO - 'MOZ_NO_REMOTE': '1', 13:52:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:52:05 INFO - 'NO_EM_RESTART': '1', 13:52:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:52:05 INFO - 'PWD': '/builds/slave/test', 13:52:05 INFO - 'SHELL': '/bin/bash', 13:52:05 INFO - 'SHLVL': '1', 13:52:05 INFO - 'TERM': 'linux', 13:52:05 INFO - 'TMOUT': '86400', 13:52:05 INFO - 'USER': 'cltbld', 13:52:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:52:05 INFO - '_': '/tools/buildbot/bin/python'} 13:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:52:05 INFO - Downloading/unpacking jsonschema 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:52:07 INFO - Downloading/unpacking functools32 (from jsonschema) 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52:07 INFO - Downloading functools32-3.2.3-2.tar.gz 13:52:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:52:07 INFO - warning: no files found matching '*.txt' 13:52:07 INFO - no previously-included directories found matching 'build' 13:52:07 INFO - no previously-included directories found matching 'dist' 13:52:07 INFO - no previously-included directories found matching '.git*' 13:52:07 INFO - Installing collected packages: jsonschema, functools32 13:52:07 INFO - Running setup.py install for functools32 13:52:07 INFO - warning: no files found matching '*.txt' 13:52:07 INFO - no previously-included directories found matching 'build' 13:52:07 INFO - no previously-included directories found matching 'dist' 13:52:07 INFO - no previously-included directories found matching '.git*' 13:52:07 INFO - Successfully installed jsonschema functools32 13:52:07 INFO - Cleaning up... 13:52:07 INFO - Return code: 0 13:52:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:52:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f182e7cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', '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 13:52:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:52:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:52:07 INFO - Using env: {'DISPLAY': ':0', 13:52:07 INFO - 'HOME': '/home/cltbld', 13:52:07 INFO - 'LANG': 'en_US.UTF-8', 13:52:07 INFO - 'LANGUAGE': 'en_US:en', 13:52:07 INFO - 'LOGNAME': 'cltbld', 13:52:07 INFO - 'MAIL': '/var/mail/cltbld', 13:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:52:07 INFO - 'MOZ_NO_REMOTE': '1', 13:52:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:52:07 INFO - 'NO_EM_RESTART': '1', 13:52:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:52:07 INFO - 'PWD': '/builds/slave/test', 13:52:07 INFO - 'SHELL': '/bin/bash', 13:52:07 INFO - 'SHLVL': '1', 13:52:07 INFO - 'TERM': 'linux', 13:52:07 INFO - 'TMOUT': '86400', 13:52:07 INFO - 'USER': 'cltbld', 13:52:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:52:07 INFO - '_': '/tools/buildbot/bin/python'} 13:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:52:08 INFO - Downloading/unpacking flake8 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52: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 13:52:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:52:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:52:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:52:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:52:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:52:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 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 13:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:52:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:52:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:52:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:52:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:52:10 INFO - no previously-included directories found matching 'docs/_build' 13:52:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:52:10 INFO - Running setup.py install for pep8 13:52:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:52:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:52:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:52:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:52:10 INFO - no previously-included directories found matching 'docs/_build' 13:52:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:52:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:52:10 INFO - Cleaning up... 13:52:10 INFO - Return code: 0 13:52:10 INFO - Running post-action listener: _resource_record_post_action 13:52:10 INFO - Running post-action listener: _start_resource_monitoring 13:52:10 INFO - Starting resource monitoring. 13:52:10 INFO - ##### 13:52:10 INFO - ##### Finished create-virtualenv step (success) 13:52:10 INFO - ##### 13:52:10 INFO - ##### 13:52:10 INFO - ##### Running install step. 13:52:10 INFO - ##### 13:52:10 INFO - Running pre-action listener: _resource_record_pre_action 13:52:10 INFO - Running main action method: install 13:52:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:52:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:52:11 INFO - Reading from file tmpfile_stdout 13:52:11 INFO - Detecting whether we're running mozinstall >=1.0... 13:52:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:52:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:52:11 INFO - Reading from file tmpfile_stdout 13:52:11 INFO - Output received: 13:52:11 INFO - Usage: mozinstall [options] installer 13:52:11 INFO - Options: 13:52:11 INFO - -h, --help show this help message and exit 13:52:11 INFO - -d DEST, --destination=DEST 13:52:11 INFO - Directory to install application into. [default: 13:52:11 INFO - "/builds/slave/test"] 13:52:11 INFO - --app=APP Application being installed. [default: firefox] 13:52:11 INFO - mkdir: /builds/slave/test/build/application 13:52:11 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'] 13:52:11 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 13:52:27 INFO - Reading from file tmpfile_stdout 13:52:27 INFO - Output received: 13:52:27 INFO - /builds/slave/test/build/application/firefox/firefox 13:52:27 INFO - Running post-action listener: _resource_record_post_action 13:52:27 INFO - ##### 13:52:27 INFO - ##### Finished install step (success) 13:52:27 INFO - ##### 13:52:27 INFO - ##### 13:52:27 INFO - ##### Running run-tests step. 13:52:27 INFO - ##### 13:52:27 INFO - Running pre-action listener: _resource_record_pre_action 13:52:27 INFO - Running main action method: run_tests 13:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:52:27 INFO - Python 2.7.3 13:52:27 INFO - Return code: 0 13:52:27 INFO - grabbing minidump binary from tooltool 13:52:27 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')]} 13:52:27 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 0xf039c0>, '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 0xf048c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf04d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:52:27 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 13:52:27 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 13:52:27 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 13:52:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:52:27 INFO - Return code: 0 13:52:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:52:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:52:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:52:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:52:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:52:28 INFO - Return code: 0 13:52:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:52:28 INFO - Using env: {'DISPLAY': ':0', 13:52:28 INFO - 'HOME': '/home/cltbld', 13:52:28 INFO - 'LANG': 'en_US.UTF-8', 13:52:28 INFO - 'LANGUAGE': 'en_US:en', 13:52:28 INFO - 'LOGNAME': 'cltbld', 13:52:28 INFO - 'MAIL': '/var/mail/cltbld', 13:52:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:52:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:52:28 INFO - 'MOZ_NO_REMOTE': '1', 13:52:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:52:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:52:28 INFO - 'NO_EM_RESTART': '1', 13:52:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:52:28 INFO - 'PWD': '/builds/slave/test', 13:52:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:52:28 INFO - 'SHELL': '/bin/bash', 13:52:28 INFO - 'SHLVL': '1', 13:52:28 INFO - 'TERM': 'linux', 13:52:28 INFO - 'TMOUT': '86400', 13:52:28 INFO - 'USER': 'cltbld', 13:52:28 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652', 13:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:52:28 INFO - '_': '/tools/buildbot/bin/python'} 13:52:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:52:28 INFO - mozversion application_buildid: 20160527123448 13:52:28 INFO - mozversion application_changeset: 82636afc630ccb7a81d18eedd3886178f341e8c6 13:52:28 INFO - mozversion application_display_name: Nightly 13:52:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:52:28 INFO - mozversion application_name: Firefox 13:52:28 INFO - mozversion application_remotingname: firefox 13:52:28 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:52:28 INFO - mozversion application_vendor: Mozilla 13:52:28 INFO - mozversion application_version: 49.0a1 13:52:28 INFO - mozversion platform_buildid: 20160527123448 13:52:28 INFO - mozversion platform_changeset: 82636afc630ccb7a81d18eedd3886178f341e8c6 13:52:28 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:52:28 INFO - mozversion platform_version: 49.0a1 13:52:28 INFO - using testdate: 1464382348 13:52:28 INFO - actual date: 1464382348 13:52:28 INFO - starting webserver on 'localhost:38919' 13:52:28 INFO - SUITE-START | Running 2 tests 13:52:28 INFO - TEST-START | dromaeo_css 13:52:28 INFO - operating with platform_type : linux_ 13:52:28 INFO - Initialising browser for dromaeo_css test... 13:52:28 INFO - TEST-INFO | started process 29022 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGNLrXb/profile http://localhost:38919/getInfo.html) 13:52:32 INFO - PROCESS | 29022 | __metrics Screen width/height:1600/1200 13:52:32 INFO - PROCESS | 29022 | colorDepth:24 13:52:32 INFO - PROCESS | 29022 | Browser inner width/height: 1024/697 13:52:32 INFO - PROCESS | 29022 | __metrics 13:52:33 ERROR - PROCESS | 29022 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:52:34 INFO - TEST-INFO | 29022: exit 0 13:52:34 INFO - Browser initialized. 13:52:34 INFO - Running cycle 1/1 for dromaeo_css test... 13:52:34 INFO - TEST-INFO | started process 29101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGNLrXb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:52:46 INFO - PROCESS | 29101 | RSS: Main: 156696576 13:52:46 INFO - PROCESS | 29101 | 13:53:38 INFO - PROCESS | 29101 | 1464382418179 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] 13:55:49 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:38919/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38919/tests/dromaeo/cssquery-ext.html) 13:55:50 INFO - PROCESS | 29101 | RSS: Main: 230531072 13:55:50 INFO - PROCESS | 29101 | 13:58:53 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:38919/tests/dromaeo/cssquery-ext.html (next: http://localhost:38919/tests/dromaeo/cssquery-jquery.html) 13:58:53 INFO - PROCESS | 29101 | RSS: Main: 237649920 13:58:53 INFO - PROCESS | 29101 | 14:01:56 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:38919/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38919/tests/dromaeo/cssquery-mootools.html) 14:01:56 INFO - PROCESS | 29101 | RSS: Main: 233570304 14:01:56 INFO - PROCESS | 29101 | 14:04:59 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:38919/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38919/tests/dromaeo/cssquery-prototype.html) 14:04:59 INFO - PROCESS | 29101 | RSS: Main: 234594304 14:04:59 INFO - PROCESS | 29101 | 14:08:02 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:38919/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38919/tests/dromaeo/cssquery-yui.html) 14:08:03 INFO - PROCESS | 29101 | RSS: Main: 213839872 14:08:03 INFO - PROCESS | 29101 | 14:11:05 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:38919/tests/dromaeo/cssquery-yui.html (next: http://localhost:38919/tests/dromaeo/cssquery-dojo.html) 14:11:06 INFO - PROCESS | 29101 | RSS: Main: 283287552 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | __start_tp_report 14:11:06 INFO - PROCESS | 29101 | _x_x_mozilla_page_load 14:11:06 INFO - PROCESS | 29101 | _x_x_mozilla_page_load_details 14:11:06 INFO - PROCESS | 29101 | |i|pagename|runs| 14:11:06 INFO - PROCESS | 29101 | |0;dojo.html;2677.424353763941;2692.434588736365;2720.8095433319663;2728.2906444324476;2746.0526118489115;4695.953040469594;4723.574878260956;4764.571188593026;4790.281457781339;4848.1031009263315;4683.531646835315;4712.293156026598;4716.716996980175;4791.041791641677;4812.061647978646;4287.078278170193;4343.891402714941;4399.5820397062225;4426.490953540336;4544.204764166274;4459.464864216298;4482.887927801815;4490.1019796040755;4524.343970124346;4549.636029117679;4224.577542245782;4277.8288868445225;4359.149965756678;4385.561443855605;4420.955790442086;4371.060222052274;4390.231709450865;4392.407025051609;4469.7318160910445;4486.035502367007;4403.64770818337;4406;4409.669274804402;4416.072624748806;4452.087322098994;4566.885827854297;4618.8152473901;4654.511276315972;4666.32338310943;4763.04739052191;5078;5142.07442660321;5145.434002259748;5148.536631703165;5151.227315902607;5097.1844504879045;5125.718085505296;5130.563902068328;5134.383873935114;5159.1229490986625;4702.553257440538;4747.026859493795;4772.379590653228;4777.976110119427;4782.450018247877;5318.97340513295;5336.332958380202;5336.653117547425;5342.225377320345;5344.759485823147;5063.316452278895;5099.719515426687;5103.897922041539;5109.795608175706;5112.156494178419;5000.224965130411;5077.086124497552;5087.898242035212;5097.260897169889;5153.123968925292;5079.5428411443145;5101.8724531887;5106.387233531926;5114.718690471986;5161.148410512298;4943.1596628573225;5080.59355251579;5086.16078347076;5086.92369614456;5090.007448547498;4297.8495752648505;4323.913521729548;4325.3728209409455;4343.717658352197;4385.736855788663;3736.7944763038295;3759.473673685686;3764.868229612005;3801.353769859131;3802.524684414448;4305.332673435622;4441.045175287264;4456.910861782746;4492.752898590609;4496.887577810516;5003.274525193827;5066.417362003417;5107.336046313955;5110.872228194325;5130.025295193901;4391.5827996340295;4404.185225733186;4412.940894185436;4418.116376724668;4464.843730469418;4498.055408364215;4608.815647374067;4617.653675974316;4619.2378257587125;4661.790219440133;4799.568038876448;4825.5174482551465;4830.2513110467935;4838.975805120952;4851.58761505276;5099.6685215460875;5099.949000510096;5104.081265372269;5108.616853736059;5158.664686795496;4759.119562880878;4796.592289655418;4831.130396528659;4840.00779840139;4843.564079232886;4977.377827771528;5003.949960500348;5022.1964485683575;5026.472220416804;5076.137056700296;5079.238114282887;5088.669236499616;5092.974535127449;5095.439501654889;5144.485551444825;4878.121938051185;4909.4354149273295;4940.580050695731;4942.036302921039;4944.752762361795;4944.4066711993355;4948.307236986752;4963.478834722302;4970.030843985388;5004.6246531511015;4833.05755377698;4834.444038935568;4867.70793752376;4876.146674331907;4900.044491324157;4859.805607775649;4917.606591472602;4929.112759703288;4958.677685950402;5009.248612708123;4859.95140048595;4871.90256194867;4879.292502587176;4880.633952453652;4920.778564964514 14:11:06 INFO - PROCESS | 29101 | |1;ext.html;570.5977286013361;603.6046389614804;604.247711599059;612.9387061293871;621.1366200980638;39733;41195.58804411955;41302.58697413029;41430.792846035765;45003;41056.79471602638;41778.58221417785;41997.58002419982;42043.78978105113;42150.789246053806;7669;7853.725119620814;7862.803429914241;7870.409719271049;7870.567118808492;7734.381249500027;7846.372290216797;7849.136594974549;7855;7855.41084418668;7865.8820117698;7925.445218834684;7938.087119981189;7943;7943.404244681672;7806.687732490694;7874.409419293576;7889.723859664884;7889.763307100796;7891.921080789176;7706.19084996073;7817.218278172138;7817.648205830695;7818.8827167592535;7828.138904720477;38174.42738358927;38679.22641547153;38683;38701.80649096765;38703.806480967694;3009.5335223040465;3292.407366674022;3436.1924947637285;3706.5552133743854;3737.6262373762406;29416.70583294161;29569.852150739323;29607.555886661718;29768;30680;3434.261633748731;3489.5638045244345;3491.895243142684;3492.7205823534055;3578.212793185521;252779;252824;253196;253684;254244;46383.53616463859;47788;51264;53559.46440535545;54411.18383224255;52648;55896;56287.71856140693;56861.43138568562;56975.43024569784;14979.176145311894;15160.241987900561;15166.24168791578;15177.16525591081;15186.088834669956;9789.902100979041;9960.601575937026;9991.100800927952;11159.163062770325;11453.599124030785;16096.034237945762;16658.4169554065;16696.74954875678;16739.33042678279;16801.075940823128;5944.821655350346;5982.521398288127;6075.605085669417;6159.81520554384;6246.531510136758;863.935204859638;914.154407173362;991.9683529129769;994.3884511025667;1041.6874937518714;27518.587221191698;28057.43885122286;28365.858170709016;28848.278793029924;28964.565531517044;986.6053578568486;1001.8728929953802;1010.434156872154;1071.50175168547;1073.4954571351452;14545.727061527401;14752.704945901021;15081.924590376977;15111.39554417833;15217.771733424008;371249;372957;374485;377228;377330;48851;48922;48937;49054.50945490571;49103.75448122736;1102.4873433853297;1199.7300607363272;1246.6384748423038;1263.7788387032233;1266.183311763907;28164;29142.854285728437;29195.270118247212;29212.384302571823;29964.550531741606;42227.14387845314;42564.787176063925;42971.3554296692;42987.78506107449;43021.35467967921;291733;296489;297755;298728;299095;31291.84354078215;35671.64328356683;36337;37490.62509374871;39891;41744.79127604343;42156;42444.36333454939;42823.57176428196;43055.35416968811;35789.82105089458;35815.28369432649;36202.8189859049;36482;36735;35822.641773581934;35879.64120358763;36028;36401.635983639826;36480.45279320866;35398.64601353953;35404.822975884956;35644.10889727777;35726;36151.45772813357;31292.217694557818;32129.839350803097;32788.34423311472;32866.67133328636;32921.34157316793;24817.262791604415;24949;25010.74989250084;25525;25678.614820778075 14:11:06 INFO - PROCESS | 29101 | |2;jquery.html;354.2260161547018;358.9964100358997;361.64920027573254;367.7977112588077;374.25896724485534;15744.370225190993;16265.593360166002;16618.66762664746;16806.57983550412;16990.999999999985;14910.776338354934;15192.16443095632;15589.298481568328;15594.688106237869;15608.141552214624;4818.71087734737;4893.926591101136;5033.798648054073;5084.262781896623;5094.566961808232;3668.669819716225;3828.540575130988;3836.2135762168687;3880.941785873214;4913.975430122862;3823.3500304948225;3860.5946375630574;3919.5884432134644;5828.388019257981;5834.970825145869;5707.229524014268;5795;5796.101604251347;5825.330087039981;5825.912611310834;5109.41241757199;5117.5650069744115;5121.334226550562;5127.102757017507;5129.974350128263;15763.133027683472;18471.538211544685;19124;19606.80393196064;20806.47983800402;1944.669406200935;1961.205711686769;1972.5758961823133;1993.5016245938516;2479.814013948961;12647.177933434297;12762;12847.421866016051;13118.147320424141;13330.200187988752;1710.2389436700673;1746.2840235503384;1757.6308975115242;1808.9638207235782;1847.6858933981255;53407.732961335336;56867;64514.354856451304;65260.3473965259;66261.66869165623;9314;9336.486493242937;9346.906530934739;9452.90547094534;9753.658621948198;12781.297028663326;12831.615051548468;12857.100002999887;12859.935700321439;12917.09580329368;7357.5217610855125;7461.104667439833;7485.139208991036;7488.439505831933;7671.578063206577;7259.373985161542;7436.553806771611;7443.330100291;7513.323800857949;7581.962090189514;6095.329513753483;6099.207103076572;6106.1146133811235;6122.418370254819;6174;1671.1143094160116;1678.8740844436716;1707.16348988997;1721.2426532325737;1773.202059073422;877.3025444771422;927.3137877970388;943.5753910740194;981.9395053342373;994.1152374386802;16507.33970641185;17018;17070.306839814766;17347.566310842078;17572.648547028988;895.2390468102061;915.2266334947092;959.098447459386;964.5514835601339;982.513655740413;9920.80158396828;9925.702228933;10183.541740621542;10192.28653994228;10205.438700871524;362061;366165;371947;375740;377657;67058;68591.65704171447;68855.3114468859;69053.30946690569;69054.30945690478;878.486085639895;920.0983036624145;965.3725078698902;974.8001659659894;983.5672304186136;15136.621584460476;15576.766348504554;15663.765043524128;15825.920870396036;16264.918675406547;23643.054277829382;24321.635175472027;30540.694593053784;31163.220919476287;33435;42476;44076.55923440724;44754.77622611866;46186.538134618226;46269.768651156526;7449.92550074514;7556.1310449299035;7639.350655194371;7674.654640541077;7850.764477065697;11311.604093856675;12007.219530730474;12058.698532536757;12078.39608019578;12434;9545.856812147684;9866.309358344846;9982.251331150048;10005.599776008878;10035.24735644844;10029.69910902674;10327.845082323622;10585.841212381974;10599.682009539727;10806;9547.713568592953;9705.980872008338;9762.267829912644;9809;9855.852162217428;7674.961625192062;7707.036620422447;7713.922860771545;7741.612919354123;7826.686932522635;9361.953190234006;9486.715398538056;9635.084666956627;9882.308238423242;10264.281500294908 14:11:06 INFO - PROCESS | 29101 | |3;mootools.html;1267.8668739782318;1443.1052747296674;1455.9781603275949;1586.7937168168137;1741.8078730114446;6704.731810727571;7547.283008114227;7835.608219589027;7839.412044096694;8038.477498962578;7841.725539606115;7848.96075519623;7882.448228623999;7887.369010479162;7895.447318687694;2207.878566678832;2239.0931672672514;2241.932742017742;2245.4161917901383;2245.6743772153027;2208.7791220877943;2235.9217427390045;2263.875486848223;2265.660150977358;2269.0356598445596;2205.2391924785925;2227.13141874669;2235.35174799308;2235.642297232443;2235.7987781099696;2131.904064317094;2155.633542297813;2162.1243396424393;2162.535054963175;2163.664631982045;2164.4372463159534;2164.5238047629496;2165.404513758713;2167;2169.4684802223396;7451.254874512505;7786.688532458695;7854.136045035041;7856;7859.292663660241;4550.180967425862;4708;4709.270063140219;4725.976370118162;4726.172919739032;2970.495015847311;2996.161074899032;3002.23443022028;3004.2038859702197;3059.4034163338156;915.9862602060974;923.1691477670083;934.8737920380729;938.8028514012066;964.7829238421369;10448.320859144207;10573.418461984587;10584.629537966212;10625.256232063759;10682.252242343038;4065.7560546367313;4155.439015732837;4199.832006719758;4216.936745948813;4222.7888605570215;8386.916130838614;8418.158184181655;8458.196471335214;8460.111688272766;8473;4572.1312950539295;4603.654725895572;4629.560191781776;4630.120277147331;4635.6986795858165;3768.6796622286865;4124.670026397881;4132.979335103365;4146.274401979642;4302.741835489881;3441.5353927219503;3470.6182319944787;3523.753337266418;3565.090901820071;3653.6529029742132;1019.6278358399279;1024.2522958240527;1025.6820385680462;1029.0789743179812;1030.0420608833856;780.617497426257;800.5956991719145;898.1512470715242;904.8326059678981;906.759708677201;5089.312942752681;5208.427073021964;5347.679139251657;5364.597655175878;5377.946220537823;896.9237614802815;909.622506659742;917.1516347378648;924.7503174142944;959.510649568725;3626.8730594429067;3660.4875317455576;3675.4486826976167;3724.795136267477;3792.051987003249;9175.265978721687;9488.240940724725;9554.522273886278;9614.423134611805;9632.181264592447;9445.811083778286;9514.857277140849;9551.379160354694;9554.808903822024;9565.330426870052;7391.928170415367;7437.367823734935;7529.661165247577;7539.660715267716;7554.508956917783;5302.469753024667;5357.499900027999;5450.264214331015;5513.283273174462;5529.115341545495;8483.490990540588;8787.780305492413;8854.070322616033;8871.112888711077;8949.149830766068;9021;9200.493972831558;9217.723468295962;9219.216366608645;9307.581158847732;6186.597871138361;6360.80917572455;6369.044643303356;6706.329367063255;6841.760538381132;6870.519063665495;6963.93036069653;7519.8496030077995;7527.548347099191;7687.846243074995;6846.623435711083;6865.347791959756;6867.828304292432;6920.896186557105;7136.785896423115;6675.933240667531;6699.363560461748;6748.392644661808;6769.627670477974;6807.21717002551;6408.679566021587;6421.389967953037;6551.115599394021;6606.207255129223;6615.073889655549;4802;4820.421549414093;4842.15262329098;4869.878253043702;4887.828925987574;4750.952490475051;4755.2153894607;4835.226363781778;4845.685030473008;4854.393200849894 14:11:06 INFO - PROCESS | 29101 | |4;prototype.html;291.12371760999395;297.4482335268077;299.11611188936683;305.1516783342309;307.7414971424005;4784.162771514984;4784.66507344486;4785.282207668852;4790.137775200463;4818.759062046896;4707.646926480519;4726.952730472694;4727.078219747151;4727.905441891168;4748.952510474895;3528.311979164064;3614.0964758810296;3636.5817930938015;3765.284595926765;3776.8300426480873;3736;3739.5325584301963;3743.3636281832155;3752.3808571585655;3753.6434038766274;3489.982550087246;3691.6308369163003;3693.5013773140686;3696.537932758405;3698.889033328991;3587.246678197582;3689.4465830125428;3697.6688392178794;3703;3732.104294969188;3254.658260882597;3257.713203284713;3263.6246831614194;3278.96721032789;3288.9506657400157;4521.864344069683;4544.8863778405475;4569.702969306984;4578.748168850712;4579.862604121881;3974.125692347679;4208.347706105528;4212.157568486315;4222.85220017301;4236.872893813191;4953.801847926079;4968.850934471935;4969.726665033421;4973.378327709036;4978.576820970222;3506.4740288956605;3510.1049232445816;3511.7541772075965;3520.1023738946406;3521.2781379817334;5023.547880690755;5157.510036546522;5191.506806853343;5224.52979231871;5235.973820130875;3539.3629146753833;3564.322778672044;3571.2143328467696;3582.6059133495296;3583.068402215443;3594.3350480161253;3614.8192590370377;3618.565772107364;3646.635336466385;3658.2500587379523;4169.45797046382;4170.791460426966;4185.979070104691;4191.6856235781825;4193.867655732935;4833.945573131405;4953.554180123734;4985.376827896513;4997.025580011863;4997.475265097185;1244.800831866897;1247.2952781678505;1247.357610830423;1249.8000319948765;1264.4689230523202;788.948718333318;789.8420315936836;815.4414226255034;830.3772170871846;833.3999520345342;4422.75674837887;4426.42861784062;4440.313610265807;4445.422095127613;4451.243288640939;5120.948790512047;5128.205128205134;5138.254953031789;5142.897142057138;5152.92270615941;4318.740875547477;4401.261501706872;4403.899025243689;4425.09285596452;4441.489228738672;2688.5563881959647;2693.8955028438245;2698.244491542371;2698.7301269873246;2738.6713594368803;4788.736619485892;4810.2063159578975;4823.421189457217;4828.396450443694;4838;4949.65352425334;5069.518395752398;5125.794968201305;5167.379914410295;5169.638125331266;4748.121597504542;4771.642126840431;4790.473047964721;4799.472058073609;4809.398825146856;4984.950150498594;4999.275105109811;5041.773120209673;5059;5064.46823083571;4749.857504274877;4933.260010998379;4938.777755001105;4967.254911763264;4973.9253911190635;4741.241401375763;4756.524347565216;4771.95228047715;4791.353167322367;4795.904081918412;3537.557805274341;3550.094725844893;3554.129238336624;3554.608993010715;3569.946450803188;4859.805607775649;5019.874503137451;5052.91362357095;5064.139096353702;5072.28987941681;4842;4852.544236728897;4870.439899411613;4871;4896.90206195867;4813.735244561442;4826.91394436249;4903.901921961469;4905.411350637946;4923.424504158635;4626.676132670816;4743.2489156288575;4767.427908650984;4800.639952003544;4808.643764555412;4754.144254034169;4909.337239472626;5046.4196617388;5048.974755126348;5063.3417655704525;4920.21276595743;4921.975390123026;4929.531694489017;4934.383202099738;4945.4312754033745 14:11:06 INFO - PROCESS | 29101 | |5;yui.html;572.8739677271;596.1001408694362;601.377574210692;688.1741909708348;696.554205308603;11330.320180789158;11370.31778093314;11372.090232781378;11888.286702797837;12222.08334374922;11836.7632647347;11861.406929653504;11887.702807429818;11897.7620447591;11949.58176463824;632.4718859752113;777.5490215674923;806.9838603227932;810.4205493072452;810.5744484145812;801.234820746186;803.8392321535687;805.8388322335527;806.5120602035772;806.6410447350931;662.8309781005837;853.7950891785989;854.4360721923532;854.8974123105236;855.4268640011207;674.2347435660536;674.6559254780097;675.1864003875319;817.8527864984301;844.0209357145682;612.7518355066205;613.4724137241968;613.6594190224424;614.2690198663576;614.9631022138686;12167.634970950798;12192.756144877052;12216.938915305456;12282.447289871889;12295.446704898211;11045.39250341231;11081.833772493419;11282.548698052151;11614.535418583246;11895.821562676567;10820.350778953289;10853.782924341547;10872.61945831892;10905.509252083595;11078.833817492745;3872.4965754452023;3892.6885849132;3992.161646054332;4014.9197016059516;4134.049168691218;46502;47012.29481557745;47583.5241647586;47606.28590571144;47791;9120.407173533831;9132.634694612274;9152.63389464414;9324.580393882292;9347.532623368805;15690.058596484247;15721.135337556545;15724.877141585739;15727.663148632266;16236.188190590423;6431.421172094534;6630.237522684856;6642.86714265712;6694.163229596301;6801.5238933274195;3578.3916734154736;3592.9101772455897;3663.2124093320062;3678.8595535384115;3736.4208547675153;2665.0539058634295;2720.7551320380867;2731.01683393978;2737.986310068437;2741.5065288247906;471.9244920812722;481.1819906159543;490.5094905094905;491.1012846490913;493.38573476022583;571.9685417302088;589.0457458916583;609.844489655144;621.5493767018966;628.0829988217163;7847.568383738835;8016.759497215093;8091.959540202261;8198.631061602242;8204.8359032819;606.7538668125582;613.9723712432951;644.8258970078147;655.77048033188;658.8517583543664;3633.981830090833;3776.3202623528027;3841.9231615368094;3912.256671232514;3924.2543916656314;55224.72387638036;55399.44600553943;55797;55915.44084559184;56004.71997640067;49499.25751113736;49884;50696.23955640668;50821;51660.741696291276;46792.7660361696;46978.5302146981;47161.5283847164;47638.523614764104;47808.28287575689;6950.826229344307;7050.788476345718;7055.823604409931;7123.715051398093;7294.489385742947;31114.22214444657;31466.37067258688;31549.793519695482;31589.210269743442;31670.683293166774;37594;40176.19647606973;40863;40953.79523102365;41236.58763412447;8920.464772113435;9318.394304370195;9374.67188648394;9430.528473576156;9585.568649410654;9920.40477571348;10276;10351.741206469898;10646.787064258826;10744.409057501913;8639.654413823377;10668.359898406121;10731.89268107309;10819.783604328028;11074.335539867634;11156.8554954885;11449.14131440162;11564.884351156381;11607.535698571963;12171.513139474322;10920.672379828618;11048.889511104786;11458.828117578076;11948.940255298668;12083.154179207371;9665.053165768568;9720.319577629498;9926.702198934043;9940.850887236842;9957.253206009433;9791.44363823801;9848.458334791656;10050.899491004997;10065.194784417376;10106.444145572064 14:11:06 INFO - PROCESS | 29101 | __end_tp_report 14:11:06 INFO - PROCESS | 29101 | __start_cc_report 14:11:06 INFO - PROCESS | 29101 | _x_x_mozilla_cycle_collect,1012 14:11:06 INFO - PROCESS | 29101 | __end_cc_report 14:11:06 INFO - PROCESS | 29101 | __startTimestamp1464383466186__endTimestamp 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | ------- Summary: start ------- 14:11:06 INFO - PROCESS | 29101 | Number of tests: 6 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | [#0] dojo.html Cycles:165 Average:4719.77 Median:4838.98 stddev:487.61 (10.1%) stddev-sans-first:462.03 14:11:06 INFO - PROCESS | 29101 | Values: 2677.4 2692.4 2720.8 2728.3 2746.1 4696.0 4723.6 4764.6 4790.3 4848.1 4683.5 4712.3 4716.7 4791.0 4812.1 4287.1 4343.9 4399.6 4426.5 4544.2 4459.5 4482.9 4490.1 4524.3 4549.6 4224.6 4277.8 4359.1 4385.6 4421.0 4371.1 4390.2 4392.4 4469.7 4486.0 4403.6 4406.0 4409.7 4416.1 4452.1 4566.9 4618.8 4654.5 4666.3 4763.0 5078.0 5142.1 5145.4 5148.5 5151.2 5097.2 5125.7 5130.6 5134.4 5159.1 4702.6 4747.0 4772.4 4778.0 4782.5 5319.0 5336.3 5336.7 5342.2 5344.8 5063.3 5099.7 5103.9 5109.8 5112.2 5000.2 5077.1 5087.9 5097.3 5153.1 5079.5 5101.9 5106.4 5114.7 5161.1 4943.2 5080.6 5086.2 5086.9 5090.0 4297.8 4323.9 4325.4 4343.7 4385.7 3736.8 3759.5 3764.9 3801.4 3802.5 4305.3 4441.0 4456.9 4492.8 4496.9 5003.3 5066.4 5107.3 5110.9 5130.0 4391.6 4404.2 4412.9 4418.1 4464.8 4498.1 4608.8 4617.7 4619.2 4661.8 4799.6 4825.5 4830.3 4839.0 4851.6 5099.7 5099.9 5104.1 5108.6 5158.7 4759.1 4796.6 4831.1 4840.0 4843.6 4977.4 5003.9 5022.2 5026.5 5076.1 5079.2 5088.7 5093.0 5095.4 5144.5 4878.1 4909.4 4940.6 4942.0 4944.8 4944.4 4948.3 4963.5 4970.0 5004.6 4833.1 4834.4 4867.7 4876.1 4900.0 4859.8 4917.6 4929.1 4958.7 5009.2 4860.0 4871.9 4879.3 4880.6 4920.8 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | [#1] ext.html Cycles:180 Average:46936.21 Median:188347.31 stddev:81989.01 (43.5%) stddev-sans-first:82145.11 14:11:06 INFO - PROCESS | 29101 | Values: 570.6 603.6 604.2 612.9 621.1 39733.0 41195.6 41302.6 41430.8 45003.0 41056.8 41778.6 41997.6 42043.8 42150.8 7669.0 7853.7 7862.8 7870.4 7870.6 7734.4 7846.4 7849.1 7855.0 7855.4 7865.9 7925.4 7938.1 7943.0 7943.4 7806.7 7874.4 7889.7 7889.8 7891.9 7706.2 7817.2 7817.6 7818.9 7828.1 38174.4 38679.2 38683.0 38701.8 38703.8 3009.5 3292.4 3436.2 3706.6 3737.6 29416.7 29569.9 29607.6 29768.0 30680.0 3434.3 3489.6 3491.9 3492.7 3578.2 252779.0 252824.0 253196.0 253684.0 254244.0 46383.5 47788.0 51264.0 53559.5 54411.2 52648.0 55896.0 56287.7 56861.4 56975.4 14979.2 15160.2 15166.2 15177.2 15186.1 9789.9 9960.6 9991.1 11159.2 11453.6 16096.0 16658.4 16696.7 16739.3 16801.1 5944.8 5982.5 6075.6 6159.8 6246.5 863.9 914.2 992.0 994.4 1041.7 27518.6 28057.4 28365.9 28848.3 28964.6 986.6 1001.9 1010.4 1071.5 1073.5 14545.7 14752.7 15081.9 15111.4 15217.8 371249.0 372957.0 374485.0 377228.0 377330.0 48851.0 48922.0 48937.0 49054.5 49103.8 1102.5 1199.7 1246.6 1263.8 1266.2 28164.0 29142.9 29195.3 29212.4 29964.6 42227.1 42564.8 42971.4 42987.8 43021.4 291733.0 296489.0 297755.0 298728.0 299095.0 31291.8 35671.6 36337.0 37490.6 39891.0 41744.8 42156.0 42444.4 42823.6 43055.4 35789.8 35815.3 36202.8 36482.0 36735.0 35822.6 35879.6 36028.0 36401.6 36480.5 35398.6 35404.8 35644.1 35726.0 36151.5 31292.2 32129.8 32788.3 32866.7 32921.3 24817.3 24949.0 25010.7 25525.0 25678.6 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | [#2] jquery.html Cycles:180 Average:22893.15 Median:45470.66 stddev:60901.17 (133.9%) stddev-sans-first:61048.51 14:11:06 INFO - PROCESS | 29101 | Values: 354.2 359.0 361.6 367.8 374.3 15744.4 16265.6 16618.7 16806.6 16991.0 14910.8 15192.2 15589.3 15594.7 15608.1 4818.7 4893.9 5033.8 5084.3 5094.6 3668.7 3828.5 3836.2 3880.9 4914.0 3823.4 3860.6 3919.6 5828.4 5835.0 5707.2 5795.0 5796.1 5825.3 5825.9 5109.4 5117.6 5121.3 5127.1 5130.0 15763.1 18471.5 19124.0 19606.8 20806.5 1944.7 1961.2 1972.6 1993.5 2479.8 12647.2 12762.0 12847.4 13118.1 13330.2 1710.2 1746.3 1757.6 1809.0 1847.7 53407.7 56867.0 64514.4 65260.3 66261.7 9314.0 9336.5 9346.9 9452.9 9753.7 12781.3 12831.6 12857.1 12859.9 12917.1 7357.5 7461.1 7485.1 7488.4 7671.6 7259.4 7436.6 7443.3 7513.3 7582.0 6095.3 6099.2 6106.1 6122.4 6174.0 1671.1 1678.9 1707.2 1721.2 1773.2 877.3 927.3 943.6 981.9 994.1 16507.3 17018.0 17070.3 17347.6 17572.6 895.2 915.2 959.1 964.6 982.5 9920.8 9925.7 10183.5 10192.3 10205.4 362061.0 366165.0 371947.0 375740.0 377657.0 67058.0 68591.7 68855.3 69053.3 69054.3 878.5 920.1 965.4 974.8 983.6 15136.6 15576.8 15663.8 15825.9 16264.9 23643.1 24321.6 30540.7 31163.2 33435.0 42476.0 44076.6 44754.8 46186.5 46269.8 7449.9 7556.1 7639.4 7674.7 7850.8 11311.6 12007.2 12058.7 12078.4 12434.0 9545.9 9866.3 9982.3 10005.6 10035.2 10029.7 10327.8 10585.8 10599.7 10806.0 9547.7 9706.0 9762.3 9809.0 9855.9 7675.0 7707.0 7713.9 7741.6 7826.7 9362.0 9486.7 9635.1 9882.3 10264.3 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | [#3] mootools.html Cycles:180 Average:5143.57 Median:5371.27 stddev:2837.33 (52.8%) stddev-sans-first:2830.34 14:11:06 INFO - PROCESS | 29101 | Values: 1267.9 1443.1 1456.0 1586.8 1741.8 6704.7 7547.3 7835.6 7839.4 8038.5 7841.7 7849.0 7882.4 7887.4 7895.4 2207.9 2239.1 2241.9 2245.4 2245.7 2208.8 2235.9 2263.9 2265.7 2269.0 2205.2 2227.1 2235.4 2235.6 2235.8 2131.9 2155.6 2162.1 2162.5 2163.7 2164.4 2164.5 2165.4 2167.0 2169.5 7451.3 7786.7 7854.1 7856.0 7859.3 4550.2 4708.0 4709.3 4726.0 4726.2 2970.5 2996.2 3002.2 3004.2 3059.4 916.0 923.2 934.9 938.8 964.8 10448.3 10573.4 10584.6 10625.3 10682.3 4065.8 4155.4 4199.8 4216.9 4222.8 8386.9 8418.2 8458.2 8460.1 8473.0 4572.1 4603.7 4629.6 4630.1 4635.7 3768.7 4124.7 4133.0 4146.3 4302.7 3441.5 3470.6 3523.8 3565.1 3653.7 1019.6 1024.3 1025.7 1029.1 1030.0 780.6 800.6 898.2 904.8 906.8 5089.3 5208.4 5347.7 5364.6 5377.9 896.9 909.6 917.2 924.8 959.5 3626.9 3660.5 3675.4 3724.8 3792.1 9175.3 9488.2 9554.5 9614.4 9632.2 9445.8 9514.9 9551.4 9554.8 9565.3 7391.9 7437.4 7529.7 7539.7 7554.5 5302.5 5357.5 5450.3 5513.3 5529.1 8483.5 8787.8 8854.1 8871.1 8949.1 9021.0 9200.5 9217.7 9219.2 9307.6 6186.6 6360.8 6369.0 6706.3 6841.8 6870.5 6963.9 7519.8 7527.5 7687.8 6846.6 6865.3 6867.8 6920.9 7136.8 6675.9 6699.4 6748.4 6769.6 6807.2 6408.7 6421.4 6551.1 6606.2 6615.1 4802.0 4820.4 4842.2 4869.9 4887.8 4751.0 4755.2 4835.2 4845.7 4854.4 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | [#4] prototype.html Cycles:180 Average:4101.29 Median:4742.25 stddev:1190.78 (25.1%) stddev-sans-first:1159.28 14:11:06 INFO - PROCESS | 29101 | Values: 291.1 297.4 299.1 305.2 307.7 4784.2 4784.7 4785.3 4790.1 4818.8 4707.6 4727.0 4727.1 4727.9 4749.0 3528.3 3614.1 3636.6 3765.3 3776.8 3736.0 3739.5 3743.4 3752.4 3753.6 3490.0 3691.6 3693.5 3696.5 3698.9 3587.2 3689.4 3697.7 3703.0 3732.1 3254.7 3257.7 3263.6 3279.0 3289.0 4521.9 4544.9 4569.7 4578.7 4579.9 3974.1 4208.3 4212.2 4222.9 4236.9 4953.8 4968.9 4969.7 4973.4 4978.6 3506.5 3510.1 3511.8 3520.1 3521.3 5023.5 5157.5 5191.5 5224.5 5236.0 3539.4 3564.3 3571.2 3582.6 3583.1 3594.3 3614.8 3618.6 3646.6 3658.3 4169.5 4170.8 4186.0 4191.7 4193.9 4833.9 4953.6 4985.4 4997.0 4997.5 1244.8 1247.3 1247.4 1249.8 1264.5 788.9 789.8 815.4 830.4 833.4 4422.8 4426.4 4440.3 4445.4 4451.2 5120.9 5128.2 5138.3 5142.9 5152.9 4318.7 4401.3 4403.9 4425.1 4441.5 2688.6 2693.9 2698.2 2698.7 2738.7 4788.7 4810.2 4823.4 4828.4 4838.0 4949.7 5069.5 5125.8 5167.4 5169.6 4748.1 4771.6 4790.5 4799.5 4809.4 4985.0 4999.3 5041.8 5059.0 5064.5 4749.9 4933.3 4938.8 4967.3 4973.9 4741.2 4756.5 4772.0 4791.4 4795.9 3537.6 3550.1 3554.1 3554.6 3569.9 4859.8 5019.9 5052.9 5064.1 5072.3 4842.0 4852.5 4870.4 4871.0 4896.9 4813.7 4826.9 4903.9 4905.4 4923.4 4626.7 4743.2 4767.4 4800.6 4808.6 4754.1 4909.3 5046.4 5049.0 5063.3 4920.2 4922.0 4929.5 4934.4 4945.4 14:11:06 INFO - PROCESS | 29101 | 14:11:06 INFO - PROCESS | 29101 | [#5] yui.html Cycles:180 Average:13070.76 Median:47799.64 stddev:15473.27 (32.4%) stddev-sans-first:15488.21 14:11:06 INFO - PROCESS | 29101 | Values: 572.9 596.1 601.4 688.2 696.6 11330.3 11370.3 11372.1 11888.3 12222.1 11836.8 11861.4 11887.7 11897.8 11949.6 632.5 777.5 807.0 810.4 810.6 801.2 803.8 805.8 806.5 806.6 662.8 853.8 854.4 854.9 855.4 674.2 674.7 675.2 817.9 844.0 612.8 613.5 613.7 614.3 615.0 12167.6 12192.8 12216.9 12282.4 12295.4 11045.4 11081.8 11282.5 11614.5 11895.8 10820.4 10853.8 10872.6 10905.5 11078.8 3872.5 3892.7 3992.2 4014.9 4134.0 46502.0 47012.3 47583.5 47606.3 47791.0 9120.4 9132.6 9152.6 9324.6 9347.5 15690.1 15721.1 15724.9 15727.7 16236.2 6431.4 6630.2 6642.9 6694.2 6801.5 3578.4 3592.9 3663.2 3678.9 3736.4 2665.1 2720.8 2731.0 2738.0 2741.5 471.9 481.2 490.5 491.1 493.4 572.0 589.0 609.8 621.5 628.1 7847.6 8016.8 8092.0 8198.6 8204.8 606.8 614.0 644.8 655.8 658.9 3634.0 3776.3 3841.9 3912.3 3924.3 55224.7 55399.4 55797.0 55915.4 56004.7 49499.3 49884.0 50696.2 50821.0 51660.7 46792.8 46978.5 47161.5 47638.5 47808.3 6950.8 7050.8 7055.8 7123.7 7294.5 31114.2 31466.4 31549.8 31589.2 31670.7 37594.0 40176.2 40863.0 40953.8 41236.6 8920.5 9318.4 9374.7 9430.5 9585.6 9920.4 10276.0 10351.7 10646.8 10744.4 8639.7 10668.4 10731.9 10819.8 11074.3 11156.9 11449.1 11564.9 11607.5 12171.5 10920.7 11048.9 11458.8 11948.9 12083.2 9665.1 9720.3 9926.7 9940.9 9957.3 9791.4 9848.5 10050.9 10065.2 10106.4 14:11:06 INFO - PROCESS | 29101 | -------- Summary: end -------- 14:11:06 INFO - PROCESS | 29101 | 14:11:07 INFO - TEST-INFO | 29101: exit 0 14:11:07 INFO - TEST-OK | dromaeo_css | took 1118752ms 14:11:07 INFO - TEST-START | kraken 14:11:07 INFO - operating with platform_type : linux_ 14:11:07 INFO - Initialising browser for kraken test... 14:11:07 INFO - TEST-INFO | started process 29276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJJ0Xma/profile http://localhost:38919/getInfo.html) 14:11:11 INFO - PROCESS | 29276 | __metrics Screen width/height:1600/1200 14:11:11 INFO - PROCESS | 29276 | colorDepth:24 14:11:11 INFO - PROCESS | 29276 | Browser inner width/height: 1024/697 14:11:11 INFO - PROCESS | 29276 | __metrics 14:11:12 ERROR - PROCESS | 29276 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:11:13 INFO - TEST-INFO | 29276: exit 0 14:11:13 INFO - Browser initialized. 14:11:13 INFO - Running cycle 1/1 for kraken test... 14:11:13 INFO - TEST-INFO | started process 29354 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJJ0Xma/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:11:25 INFO - PROCESS | 29354 | RSS: Main: 156860416 14:11:25 INFO - PROCESS | 29354 | 14:11:27 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38919/tests/kraken/driver.html?testName=audio-beat-detection) 14:11:28 INFO - PROCESS | 29354 | RSS: Main: 212496384 14:11:28 INFO - PROCESS | 29354 | 14:11:31 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38919/tests/kraken/driver.html?testName=audio-dft) 14:11:32 INFO - PROCESS | 29354 | RSS: Main: 199270400 14:11:32 INFO - PROCESS | 29354 | 14:11:35 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38919/tests/kraken/driver.html?testName=audio-fft) 14:11:36 INFO - PROCESS | 29354 | RSS: Main: 269484032 14:11:36 INFO - PROCESS | 29354 | 14:11:38 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38919/tests/kraken/driver.html?testName=audio-oscillator) 14:11:38 INFO - PROCESS | 29354 | RSS: Main: 178601984 14:11:38 INFO - PROCESS | 29354 | 14:11:41 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38919/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:11:41 INFO - PROCESS | 29354 | RSS: Main: 183390208 14:11:41 INFO - PROCESS | 29354 | 14:11:45 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38919/tests/kraken/driver.html?testName=imaging-darkroom) 14:11:45 INFO - PROCESS | 29354 | RSS: Main: 219017216 14:11:45 INFO - PROCESS | 29354 | 14:11:49 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38919/tests/kraken/driver.html?testName=imaging-desaturate) 14:11:49 INFO - PROCESS | 29354 | RSS: Main: 219455488 14:11:49 INFO - PROCESS | 29354 | 14:11:52 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38919/tests/kraken/driver.html?testName=json-parse-financial) 14:11:53 INFO - PROCESS | 29354 | RSS: Main: 219238400 14:11:53 INFO - PROCESS | 29354 | 14:11:54 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38919/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:11:55 INFO - PROCESS | 29354 | RSS: Main: 206913536 14:11:55 INFO - PROCESS | 29354 | 14:11:57 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:11:58 INFO - PROCESS | 29354 | RSS: Main: 212520960 14:11:58 INFO - PROCESS | 29354 | 14:12:00 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:12:01 INFO - PROCESS | 29354 | RSS: Main: 222060544 14:12:01 INFO - PROCESS | 29354 | 14:12:03 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:12:04 INFO - PROCESS | 29354 | RSS: Main: 227282944 14:12:04 INFO - PROCESS | 29354 | 14:12:07 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:12:07 INFO - PROCESS | 29354 | RSS: Main: 224141312 14:12:07 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | Cycle 1(1): loaded http://localhost:38919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38919/tests/kraken/driver.html?testName=ai-astar) 14:12:09 INFO - PROCESS | 29354 | RSS: Main: 269873152 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | __start_tp_report 14:12:09 INFO - PROCESS | 29354 | _x_x_mozilla_page_load 14:12:09 INFO - PROCESS | 29354 | _x_x_mozilla_page_load_details 14:12:09 INFO - PROCESS | 29354 | |i|pagename|runs| 14:12:09 INFO - PROCESS | 29354 | |0;ai-astar;113;108;112;112;113;122;112;112;112;112 14:12:09 INFO - PROCESS | 29354 | |1;audio-beat-detection;162;135;131;187;131;130;163;130;187;130 14:12:09 INFO - PROCESS | 29354 | |2;audio-dft;161;160;160;162;161;162;163;161;161;160 14:12:09 INFO - PROCESS | 29354 | |3;audio-fft;75;76;75;106;76;76;76;75;100;75 14:12:09 INFO - PROCESS | 29354 | |4;audio-oscillator;84;84;128;84;85;87;119;83;84;129 14:12:09 INFO - PROCESS | 29354 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 14:12:09 INFO - PROCESS | 29354 | |6;imaging-darkroom;146;146;146;146;146;147;146;146;146;145 14:12:09 INFO - PROCESS | 29354 | |7;imaging-desaturate;94;94;95;95;95;95;94;95;95;94 14:12:09 INFO - PROCESS | 29354 | |8;json-parse-financial;65;65;66;64;65;66;65;80;64;62 14:12:09 INFO - PROCESS | 29354 | |9;json-stringify-tinderbox;65;66;66;65;65;65;66;65;69;66 14:12:09 INFO - PROCESS | 29354 | |10;stanford-crypto-aes;83;83;83;82;84;84;86;85;85;83 14:12:09 INFO - PROCESS | 29354 | |11;stanford-crypto-ccm;144;145;138;131;128;143;138;128;194;132 14:12:09 INFO - PROCESS | 29354 | |12;stanford-crypto-pbkdf2;173;170;169;174;170;172;170;172;172;171 14:12:09 INFO - PROCESS | 29354 | |13;stanford-crypto-sha256-iterative;66;66;67;67;65;65;66;65;66;65 14:12:09 INFO - PROCESS | 29354 | __end_tp_report 14:12:09 INFO - PROCESS | 29354 | __start_cc_report 14:12:09 INFO - PROCESS | 29354 | _x_x_mozilla_cycle_collect,1942 14:12:09 INFO - PROCESS | 29354 | __end_cc_report 14:12:09 INFO - PROCESS | 29354 | __startTimestamp1464383529949__endTimestamp 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | ------- Summary: start ------- 14:12:09 INFO - PROCESS | 29354 | Number of tests: 14 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#0] ai-astar Cycles:10 Average:112.80 Median:112.00 stddev:3.52 (3.1%) stddev-sans-first:3.73 14:12:09 INFO - PROCESS | 29354 | Values: 113.0 108.0 112.0 112.0 113.0 122.0 112.0 112.0 112.0 112.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:148.50 stddev:23.99 (16.2%) stddev-sans-first:24.95 14:12:09 INFO - PROCESS | 29354 | Values: 162.0 135.0 131.0 187.0 131.0 130.0 163.0 130.0 187.0 130.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 14:12:09 INFO - PROCESS | 29354 | Values: 161.0 160.0 160.0 162.0 161.0 162.0 163.0 161.0 161.0 160.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.50 stddev:11.69 (15.5%) stddev-sans-first:12.20 14:12:09 INFO - PROCESS | 29354 | Values: 75.0 76.0 75.0 106.0 76.0 76.0 76.0 75.0 100.0 75.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:84.00 stddev:19.96 (23.8%) stddev-sans-first:20.63 14:12:09 INFO - PROCESS | 29354 | Values: 84.0 84.0 128.0 84.0 85.0 87.0 119.0 83.0 84.0 129.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:12:09 INFO - PROCESS | 29354 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 14:12:09 INFO - PROCESS | 29354 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 145.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 14:12:09 INFO - PROCESS | 29354 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:65.00 stddev:4.98 (7.7%) stddev-sans-first:5.27 14:12:09 INFO - PROCESS | 29354 | Values: 65.0 65.0 66.0 64.0 65.0 66.0 65.0 80.0 64.0 62.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.27 14:12:09 INFO - PROCESS | 29354 | Values: 65.0 66.0 66.0 65.0 65.0 65.0 66.0 65.0 69.0 66.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.27 14:12:09 INFO - PROCESS | 29354 | Values: 83.0 83.0 83.0 82.0 84.0 84.0 86.0 85.0 85.0 83.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:140.50 stddev:19.33 (13.8%) stddev-sans-first:20.49 14:12:09 INFO - PROCESS | 29354 | Values: 144.0 145.0 138.0 131.0 128.0 143.0 138.0 128.0 194.0 132.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:172.00 stddev:1.57 (0.9%) stddev-sans-first:1.54 14:12:09 INFO - PROCESS | 29354 | Values: 173.0 170.0 169.0 174.0 170.0 172.0 170.0 172.0 172.0 171.0 14:12:09 INFO - PROCESS | 29354 | 14:12:09 INFO - PROCESS | 29354 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 14:12:09 INFO - PROCESS | 29354 | Values: 66.0 66.0 67.0 67.0 65.0 65.0 66.0 65.0 66.0 65.0 14:12:09 INFO - PROCESS | 29354 | -------- Summary: end -------- 14:12:09 INFO - PROCESS | 29354 | 14:12:10 INFO - TEST-INFO | 29354: exit 0 14:12:11 INFO - TEST-OK | kraken | took 64064ms 14:12:11 INFO - SUITE-END | took 1182s 14:12:11 INFO - Completed test suite (00:19:42) 14:12:11 INFO - javascript benchmark 14:12:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2677.424353763941, 2692.434588736365, 2720.8095433319663, 2728.2906444324476, 2746.0526118489115, 4695.953040469594, 4723.574878260956, 4764.571188593026, 4790.281457781339, 4848.1031009263315, 4683.531646835315, 4712.293156026598, 4716.716996980175, 4791.041791641677, 4812.061647978646, 4287.078278170193, 4343.891402714941, 4399.5820397062225, 4426.490953540336, 4544.204764166274, 4459.464864216298, 4482.887927801815, 4490.1019796040755, 4524.343970124346, 4549.636029117679, 4224.577542245782, 4277.8288868445225, 4359.149965756678, 4385.561443855605, 4420.955790442086, 4371.060222052274, 4390.231709450865, 4392.407025051609, 4469.7318160910445, 4486.035502367007, 4403.64770818337, 4406.0, 4409.669274804402, 4416.072624748806, 4452.087322098994, 4566.885827854297, 4618.8152473901, 4654.511276315972, 4666.32338310943, 4763.04739052191, 5078.0, 5142.07442660321, 5145.434002259748, 5148.536631703165, 5151.227315902607, 5097.1844504879045, 5125.718085505296, 5130.563902068328, 5134.383873935114, 5159.1229490986625, 4702.553257440538, 4747.026859493795, 4772.379590653228, 4777.976110119427, 4782.450018247877, 5318.97340513295, 5336.332958380202, 5336.653117547425, 5342.225377320345, 5344.759485823147, 5063.316452278895, 5099.719515426687, 5103.897922041539, 5109.795608175706, 5112.156494178419, 5000.224965130411, 5077.086124497552, 5087.898242035212, 5097.260897169889, 5153.123968925292, 5079.5428411443145, 5101.8724531887, 5106.387233531926, 5114.718690471986, 5161.148410512298, 4943.1596628573225, 5080.59355251579, 5086.16078347076, 5086.92369614456, 5090.007448547498, 4297.8495752648505, 4323.913521729548, 4325.3728209409455, 4343.717658352197, 4385.736855788663, 3736.7944763038295, 3759.473673685686, 3764.868229612005, 3801.353769859131, 3802.524684414448, 4305.332673435622, 4441.045175287264, 4456.910861782746, 4492.752898590609, 4496.887577810516, 5003.274525193827, 5066.417362003417, 5107.336046313955, 5110.872228194325, 5130.025295193901, 4391.5827996340295, 4404.185225733186, 4412.940894185436, 4418.116376724668, 4464.843730469418, 4498.055408364215, 4608.815647374067, 4617.653675974316, 4619.2378257587125, 4661.790219440133, 4799.568038876448, 4825.5174482551465, 4830.2513110467935, 4838.975805120952, 4851.58761505276, 5099.6685215460875, 5099.949000510096, 5104.081265372269, 5108.616853736059, 5158.664686795496, 4759.119562880878, 4796.592289655418, 4831.130396528659, 4840.00779840139, 4843.564079232886, 4977.377827771528, 5003.949960500348, 5022.1964485683575, 5026.472220416804, 5076.137056700296, 5079.238114282887, 5088.669236499616, 5092.974535127449, 5095.439501654889, 5144.485551444825, 4878.121938051185, 4909.4354149273295, 4940.580050695731, 4942.036302921039, 4944.752762361795, 4944.4066711993355, 4948.307236986752, 4963.478834722302, 4970.030843985388, 5004.6246531511015, 4833.05755377698, 4834.444038935568, 4867.70793752376, 4876.146674331907, 4900.044491324157, 4859.805607775649, 4917.606591472602, 4929.112759703288, 4958.677685950402, 5009.248612708123, 4859.95140048595, 4871.90256194867, 4879.292502587176, 4880.633952453652, 4920.778564964514], "value": 4689.328751065024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.5977286013361, 603.6046389614804, 604.247711599059, 612.9387061293871, 621.1366200980638, 39733.0, 41195.58804411955, 41302.58697413029, 41430.792846035765, 45003.0, 41056.79471602638, 41778.58221417785, 41997.58002419982, 42043.78978105113, 42150.789246053806, 7669.0, 7853.725119620814, 7862.803429914241, 7870.409719271049, 7870.567118808492, 7734.381249500027, 7846.372290216797, 7849.136594974549, 7855.0, 7855.41084418668, 7865.8820117698, 7925.445218834684, 7938.087119981189, 7943.0, 7943.404244681672, 7806.687732490694, 7874.409419293576, 7889.723859664884, 7889.763307100796, 7891.921080789176, 7706.19084996073, 7817.218278172138, 7817.648205830695, 7818.8827167592535, 7828.138904720477, 38174.42738358927, 38679.22641547153, 38683.0, 38701.80649096765, 38703.806480967694, 3009.5335223040465, 3292.407366674022, 3436.1924947637285, 3706.5552133743854, 3737.6262373762406, 29416.70583294161, 29569.852150739323, 29607.555886661718, 29768.0, 30680.0, 3434.261633748731, 3489.5638045244345, 3491.895243142684, 3492.7205823534055, 3578.212793185521, 252779.0, 252824.0, 253196.0, 253684.0, 254244.0, 46383.53616463859, 47788.0, 51264.0, 53559.46440535545, 54411.18383224255, 52648.0, 55896.0, 56287.71856140693, 56861.43138568562, 56975.43024569784, 14979.176145311894, 15160.241987900561, 15166.24168791578, 15177.16525591081, 15186.088834669956, 9789.902100979041, 9960.601575937026, 9991.100800927952, 11159.163062770325, 11453.599124030785, 16096.034237945762, 16658.4169554065, 16696.74954875678, 16739.33042678279, 16801.075940823128, 5944.821655350346, 5982.521398288127, 6075.605085669417, 6159.81520554384, 6246.531510136758, 863.935204859638, 914.154407173362, 991.9683529129769, 994.3884511025667, 1041.6874937518714, 27518.587221191698, 28057.43885122286, 28365.858170709016, 28848.278793029924, 28964.565531517044, 986.6053578568486, 1001.8728929953802, 1010.434156872154, 1071.50175168547, 1073.4954571351452, 14545.727061527401, 14752.704945901021, 15081.924590376977, 15111.39554417833, 15217.771733424008, 371249.0, 372957.0, 374485.0, 377228.0, 377330.0, 48851.0, 48922.0, 48937.0, 49054.50945490571, 49103.75448122736, 1102.4873433853297, 1199.7300607363272, 1246.6384748423038, 1263.7788387032233, 1266.183311763907, 28164.0, 29142.854285728437, 29195.270118247212, 29212.384302571823, 29964.550531741606, 42227.14387845314, 42564.787176063925, 42971.3554296692, 42987.78506107449, 43021.35467967921, 291733.0, 296489.0, 297755.0, 298728.0, 299095.0, 31291.84354078215, 35671.64328356683, 36337.0, 37490.62509374871, 39891.0, 41744.79127604343, 42156.0, 42444.36333454939, 42823.57176428196, 43055.35416968811, 35789.82105089458, 35815.28369432649, 36202.8189859049, 36482.0, 36735.0, 35822.641773581934, 35879.64120358763, 36028.0, 36401.635983639826, 36480.45279320866, 35398.64601353953, 35404.822975884956, 35644.10889727777, 35726.0, 36151.45772813357, 31292.217694557818, 32129.839350803097, 32788.34423311472, 32866.67133328636, 32921.34157316793, 24817.262791604415, 24949.0, 25010.74989250084, 25525.0, 25678.614820778075], "value": 17784.902492994046, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.2260161547018, 358.9964100358997, 361.64920027573254, 367.7977112588077, 374.25896724485534, 15744.370225190993, 16265.593360166002, 16618.66762664746, 16806.57983550412, 16990.999999999985, 14910.776338354934, 15192.16443095632, 15589.298481568328, 15594.688106237869, 15608.141552214624, 4818.71087734737, 4893.926591101136, 5033.798648054073, 5084.262781896623, 5094.566961808232, 3668.669819716225, 3828.540575130988, 3836.2135762168687, 3880.941785873214, 4913.975430122862, 3823.3500304948225, 3860.5946375630574, 3919.5884432134644, 5828.388019257981, 5834.970825145869, 5707.229524014268, 5795.0, 5796.101604251347, 5825.330087039981, 5825.912611310834, 5109.41241757199, 5117.5650069744115, 5121.334226550562, 5127.102757017507, 5129.974350128263, 15763.133027683472, 18471.538211544685, 19124.0, 19606.80393196064, 20806.47983800402, 1944.669406200935, 1961.205711686769, 1972.5758961823133, 1993.5016245938516, 2479.814013948961, 12647.177933434297, 12762.0, 12847.421866016051, 13118.147320424141, 13330.200187988752, 1710.2389436700673, 1746.2840235503384, 1757.6308975115242, 1808.9638207235782, 1847.6858933981255, 53407.732961335336, 56867.0, 64514.354856451304, 65260.3473965259, 66261.66869165623, 9314.0, 9336.486493242937, 9346.906530934739, 9452.90547094534, 9753.658621948198, 12781.297028663326, 12831.615051548468, 12857.100002999887, 12859.935700321439, 12917.09580329368, 7357.5217610855125, 7461.104667439833, 7485.139208991036, 7488.439505831933, 7671.578063206577, 7259.373985161542, 7436.553806771611, 7443.330100291, 7513.323800857949, 7581.962090189514, 6095.329513753483, 6099.207103076572, 6106.1146133811235, 6122.418370254819, 6174.0, 1671.1143094160116, 1678.8740844436716, 1707.16348988997, 1721.2426532325737, 1773.202059073422, 877.3025444771422, 927.3137877970388, 943.5753910740194, 981.9395053342373, 994.1152374386802, 16507.33970641185, 17018.0, 17070.306839814766, 17347.566310842078, 17572.648547028988, 895.2390468102061, 915.2266334947092, 959.098447459386, 964.5514835601339, 982.513655740413, 9920.80158396828, 9925.702228933, 10183.541740621542, 10192.28653994228, 10205.438700871524, 362061.0, 366165.0, 371947.0, 375740.0, 377657.0, 67058.0, 68591.65704171447, 68855.3114468859, 69053.30946690569, 69054.30945690478, 878.486085639895, 920.0983036624145, 965.3725078698902, 974.8001659659894, 983.5672304186136, 15136.621584460476, 15576.766348504554, 15663.765043524128, 15825.920870396036, 16264.918675406547, 23643.054277829382, 24321.635175472027, 30540.694593053784, 31163.220919476287, 33435.0, 42476.0, 44076.55923440724, 44754.77622611866, 46186.538134618226, 46269.768651156526, 7449.92550074514, 7556.1310449299035, 7639.350655194371, 7674.654640541077, 7850.764477065697, 11311.604093856675, 12007.219530730474, 12058.698532536757, 12078.39608019578, 12434.0, 9545.856812147684, 9866.309358344846, 9982.251331150048, 10005.599776008878, 10035.24735644844, 10029.69910902674, 10327.845082323622, 10585.841212381974, 10599.682009539727, 10806.0, 9547.713568592953, 9705.980872008338, 9762.267829912644, 9809.0, 9855.852162217428, 7674.961625192062, 7707.036620422447, 7713.922860771545, 7741.612919354123, 7826.686932522635, 9361.953190234006, 9486.715398538056, 9635.084666956627, 9882.308238423242, 10264.281500294908], "value": 8101.228729743252, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.8668739782318, 1443.1052747296674, 1455.9781603275949, 1586.7937168168137, 1741.8078730114446, 6704.731810727571, 7547.283008114227, 7835.608219589027, 7839.412044096694, 8038.477498962578, 7841.725539606115, 7848.96075519623, 7882.448228623999, 7887.369010479162, 7895.447318687694, 2207.878566678832, 2239.0931672672514, 2241.932742017742, 2245.4161917901383, 2245.6743772153027, 2208.7791220877943, 2235.9217427390045, 2263.875486848223, 2265.660150977358, 2269.0356598445596, 2205.2391924785925, 2227.13141874669, 2235.35174799308, 2235.642297232443, 2235.7987781099696, 2131.904064317094, 2155.633542297813, 2162.1243396424393, 2162.535054963175, 2163.664631982045, 2164.4372463159534, 2164.5238047629496, 2165.404513758713, 2167.0, 2169.4684802223396, 7451.254874512505, 7786.688532458695, 7854.136045035041, 7856.0, 7859.292663660241, 4550.180967425862, 4708.0, 4709.270063140219, 4725.976370118162, 4726.172919739032, 2970.495015847311, 2996.161074899032, 3002.23443022028, 3004.2038859702197, 3059.4034163338156, 915.9862602060974, 923.1691477670083, 934.8737920380729, 938.8028514012066, 964.7829238421369, 10448.320859144207, 10573.418461984587, 10584.629537966212, 10625.256232063759, 10682.252242343038, 4065.7560546367313, 4155.439015732837, 4199.832006719758, 4216.936745948813, 4222.7888605570215, 8386.916130838614, 8418.158184181655, 8458.196471335214, 8460.111688272766, 8473.0, 4572.1312950539295, 4603.654725895572, 4629.560191781776, 4630.120277147331, 4635.6986795858165, 3768.6796622286865, 4124.670026397881, 4132.979335103365, 4146.274401979642, 4302.741835489881, 3441.5353927219503, 3470.6182319944787, 3523.753337266418, 3565.090901820071, 3653.6529029742132, 1019.6278358399279, 1024.2522958240527, 1025.6820385680462, 1029.0789743179812, 1030.0420608833856, 780.617497426257, 800.5956991719145, 898.1512470715242, 904.8326059678981, 906.759708677201, 5089.312942752681, 5208.427073021964, 5347.679139251657, 5364.597655175878, 5377.946220537823, 896.9237614802815, 909.622506659742, 917.1516347378648, 924.7503174142944, 959.510649568725, 3626.8730594429067, 3660.4875317455576, 3675.4486826976167, 3724.795136267477, 3792.051987003249, 9175.265978721687, 9488.240940724725, 9554.522273886278, 9614.423134611805, 9632.181264592447, 9445.811083778286, 9514.857277140849, 9551.379160354694, 9554.808903822024, 9565.330426870052, 7391.928170415367, 7437.367823734935, 7529.661165247577, 7539.660715267716, 7554.508956917783, 5302.469753024667, 5357.499900027999, 5450.264214331015, 5513.283273174462, 5529.115341545495, 8483.490990540588, 8787.780305492413, 8854.070322616033, 8871.112888711077, 8949.149830766068, 9021.0, 9200.493972831558, 9217.723468295962, 9219.216366608645, 9307.581158847732, 6186.597871138361, 6360.80917572455, 6369.044643303356, 6706.329367063255, 6841.760538381132, 6870.519063665495, 6963.93036069653, 7519.8496030077995, 7527.548347099191, 7687.846243074995, 6846.623435711083, 6865.347791959756, 6867.828304292432, 6920.896186557105, 7136.785896423115, 6675.933240667531, 6699.363560461748, 6748.392644661808, 6769.627670477974, 6807.21717002551, 6408.679566021587, 6421.389967953037, 6551.115599394021, 6606.207255129223, 6615.073889655549, 4802.0, 4820.421549414093, 4842.15262329098, 4869.878253043702, 4887.828925987574, 4750.952490475051, 4755.2153894607, 4835.226363781778, 4845.685030473008, 4854.393200849894], "value": 4157.251722796855, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.12371760999395, 297.4482335268077, 299.11611188936683, 305.1516783342309, 307.7414971424005, 4784.162771514984, 4784.66507344486, 4785.282207668852, 4790.137775200463, 4818.759062046896, 4707.646926480519, 4726.952730472694, 4727.078219747151, 4727.905441891168, 4748.952510474895, 3528.311979164064, 3614.0964758810296, 3636.5817930938015, 3765.284595926765, 3776.8300426480873, 3736.0, 3739.5325584301963, 3743.3636281832155, 3752.3808571585655, 3753.6434038766274, 3489.982550087246, 3691.6308369163003, 3693.5013773140686, 3696.537932758405, 3698.889033328991, 3587.246678197582, 3689.4465830125428, 3697.6688392178794, 3703.0, 3732.104294969188, 3254.658260882597, 3257.713203284713, 3263.6246831614194, 3278.96721032789, 3288.9506657400157, 4521.864344069683, 4544.8863778405475, 4569.702969306984, 4578.748168850712, 4579.862604121881, 3974.125692347679, 4208.347706105528, 4212.157568486315, 4222.85220017301, 4236.872893813191, 4953.801847926079, 4968.850934471935, 4969.726665033421, 4973.378327709036, 4978.576820970222, 3506.4740288956605, 3510.1049232445816, 3511.7541772075965, 3520.1023738946406, 3521.2781379817334, 5023.547880690755, 5157.510036546522, 5191.506806853343, 5224.52979231871, 5235.973820130875, 3539.3629146753833, 3564.322778672044, 3571.2143328467696, 3582.6059133495296, 3583.068402215443, 3594.3350480161253, 3614.8192590370377, 3618.565772107364, 3646.635336466385, 3658.2500587379523, 4169.45797046382, 4170.791460426966, 4185.979070104691, 4191.6856235781825, 4193.867655732935, 4833.945573131405, 4953.554180123734, 4985.376827896513, 4997.025580011863, 4997.475265097185, 1244.800831866897, 1247.2952781678505, 1247.357610830423, 1249.8000319948765, 1264.4689230523202, 788.948718333318, 789.8420315936836, 815.4414226255034, 830.3772170871846, 833.3999520345342, 4422.75674837887, 4426.42861784062, 4440.313610265807, 4445.422095127613, 4451.243288640939, 5120.948790512047, 5128.205128205134, 5138.254953031789, 5142.897142057138, 5152.92270615941, 4318.740875547477, 4401.261501706872, 4403.899025243689, 4425.09285596452, 4441.489228738672, 2688.5563881959647, 2693.8955028438245, 2698.244491542371, 2698.7301269873246, 2738.6713594368803, 4788.736619485892, 4810.2063159578975, 4823.421189457217, 4828.396450443694, 4838.0, 4949.65352425334, 5069.518395752398, 5125.794968201305, 5167.379914410295, 5169.638125331266, 4748.121597504542, 4771.642126840431, 4790.473047964721, 4799.472058073609, 4809.398825146856, 4984.950150498594, 4999.275105109811, 5041.773120209673, 5059.0, 5064.46823083571, 4749.857504274877, 4933.260010998379, 4938.777755001105, 4967.254911763264, 4973.9253911190635, 4741.241401375763, 4756.524347565216, 4771.95228047715, 4791.353167322367, 4795.904081918412, 3537.557805274341, 3550.094725844893, 3554.129238336624, 3554.608993010715, 3569.946450803188, 4859.805607775649, 5019.874503137451, 5052.91362357095, 5064.139096353702, 5072.28987941681, 4842.0, 4852.544236728897, 4870.439899411613, 4871.0, 4896.90206195867, 4813.735244561442, 4826.91394436249, 4903.901921961469, 4905.411350637946, 4923.424504158635, 4626.676132670816, 4743.2489156288575, 4767.427908650984, 4800.639952003544, 4808.643764555412, 4754.144254034169, 4909.337239472626, 5046.4196617388, 5048.974755126348, 5063.3417655704525, 4920.21276595743, 4921.975390123026, 4929.531694489017, 4934.383202099738, 4945.4312754033745], "value": 3722.374127803864, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.8739677271, 596.1001408694362, 601.377574210692, 688.1741909708348, 696.554205308603, 11330.320180789158, 11370.31778093314, 11372.090232781378, 11888.286702797837, 12222.08334374922, 11836.7632647347, 11861.406929653504, 11887.702807429818, 11897.7620447591, 11949.58176463824, 632.4718859752113, 777.5490215674923, 806.9838603227932, 810.4205493072452, 810.5744484145812, 801.234820746186, 803.8392321535687, 805.8388322335527, 806.5120602035772, 806.6410447350931, 662.8309781005837, 853.7950891785989, 854.4360721923532, 854.8974123105236, 855.4268640011207, 674.2347435660536, 674.6559254780097, 675.1864003875319, 817.8527864984301, 844.0209357145682, 612.7518355066205, 613.4724137241968, 613.6594190224424, 614.2690198663576, 614.9631022138686, 12167.634970950798, 12192.756144877052, 12216.938915305456, 12282.447289871889, 12295.446704898211, 11045.39250341231, 11081.833772493419, 11282.548698052151, 11614.535418583246, 11895.821562676567, 10820.350778953289, 10853.782924341547, 10872.61945831892, 10905.509252083595, 11078.833817492745, 3872.4965754452023, 3892.6885849132, 3992.161646054332, 4014.9197016059516, 4134.049168691218, 46502.0, 47012.29481557745, 47583.5241647586, 47606.28590571144, 47791.0, 9120.407173533831, 9132.634694612274, 9152.63389464414, 9324.580393882292, 9347.532623368805, 15690.058596484247, 15721.135337556545, 15724.877141585739, 15727.663148632266, 16236.188190590423, 6431.421172094534, 6630.237522684856, 6642.86714265712, 6694.163229596301, 6801.5238933274195, 3578.3916734154736, 3592.9101772455897, 3663.2124093320062, 3678.8595535384115, 3736.4208547675153, 2665.0539058634295, 2720.7551320380867, 2731.01683393978, 2737.986310068437, 2741.5065288247906, 471.9244920812722, 481.1819906159543, 490.5094905094905, 491.1012846490913, 493.38573476022583, 571.9685417302088, 589.0457458916583, 609.844489655144, 621.5493767018966, 628.0829988217163, 7847.568383738835, 8016.759497215093, 8091.959540202261, 8198.631061602242, 8204.8359032819, 606.7538668125582, 613.9723712432951, 644.8258970078147, 655.77048033188, 658.8517583543664, 3633.981830090833, 3776.3202623528027, 3841.9231615368094, 3912.256671232514, 3924.2543916656314, 55224.72387638036, 55399.44600553943, 55797.0, 55915.44084559184, 56004.71997640067, 49499.25751113736, 49884.0, 50696.23955640668, 50821.0, 51660.741696291276, 46792.7660361696, 46978.5302146981, 47161.5283847164, 47638.523614764104, 47808.28287575689, 6950.826229344307, 7050.788476345718, 7055.823604409931, 7123.715051398093, 7294.489385742947, 31114.22214444657, 31466.37067258688, 31549.793519695482, 31589.210269743442, 31670.683293166774, 37594.0, 40176.19647606973, 40863.0, 40953.79523102365, 41236.58763412447, 8920.464772113435, 9318.394304370195, 9374.67188648394, 9430.528473576156, 9585.568649410654, 9920.40477571348, 10276.0, 10351.741206469898, 10646.787064258826, 10744.409057501913, 8639.654413823377, 10668.359898406121, 10731.89268107309, 10819.783604328028, 11074.335539867634, 11156.8554954885, 11449.14131440162, 11564.884351156381, 11607.535698571963, 12171.513139474322, 10920.672379828618, 11048.889511104786, 11458.828117578076, 11948.940255298668, 12083.154179207371, 9665.053165768568, 9720.319577629498, 9926.702198934043, 9940.850887236842, 9957.253206009433, 9791.44363823801, 9848.458334791656, 10050.899491004997, 10065.194784417376, 10106.444145572064], "value": 5880.123661446082, "unit": "score"}], "name": "dromaeo_css", "value": 6282.455963758384, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 112.0, 112.0, 113.0, 122.0, 112.0, 112.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 135.0, 131.0, 187.0, 131.0, 130.0, 163.0, 130.0, 187.0, 130.0], "value": 148.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 161.0, 162.0, 163.0, 161.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 106.0, 76.0, 76.0, 76.0, 75.0, 100.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 128.0, 84.0, 85.0, 87.0, 119.0, 83.0, 84.0, 129.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 64.0, 65.0, 66.0, 65.0, 80.0, 64.0, 62.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 69.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 82.0, 84.0, 84.0, 86.0, 85.0, 85.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 138.0, 131.0, 128.0, 143.0, 138.0, 128.0, 194.0, 132.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 169.0, 174.0, 170.0, 172.0, 170.0, 172.0, 172.0, 171.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 65.0, 65.0, 66.0, 65.0, 66.0, 65.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1552.6999999999998, "alertThreshold": 2.0}]} 14:12:11 INFO - Return code: 0 14:12:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:12:11 ERROR - # TBPL SUCCESS # 14:12:11 INFO - Running post-action listener: _resource_record_post_action 14:12:11 INFO - ##### 14:12:11 INFO - ##### Finished run-tests step (success) 14:12:11 INFO - ##### 14:12:11 INFO - Running post-run listener: _resource_record_post_run 14:12:11 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 526917632; Read time: 12; Write time: 505392 14:12:11 INFO - TinderboxPrint: CPU usage
13.1% 14:12:11 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:12:11 INFO - TinderboxPrint: I/O write bytes / time
526,917,632 / 505,392 14:12:11 INFO - TinderboxPrint: CPU idle
8,322.9 (86.9%) 14:12:11 INFO - TinderboxPrint: CPU user
1,210.0 (12.6%) 14:12:11 INFO - TinderboxPrint: Swap in / out
0 / 0 14:12:11 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236929024; Read time: 0; Write time: 238924 14:12:11 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289988608; Read time: 12; Write time: 266468 14:12:11 INFO - Running post-run listener: _upload_blobber_files 14:12:11 INFO - Blob upload gear active. 14:12:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:12:11 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 14:12:11 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:12:11 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:12:12 INFO - (blobuploader) - INFO - Open directory for files ... 14:12:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:12:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:12:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:12:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:12:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:12:13 INFO - (blobuploader) - INFO - Done attempting. 14:12:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:12:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:12:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:12:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:12:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:12:17 INFO - (blobuploader) - INFO - Done attempting. 14:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:12:18 INFO - (blobuploader) - INFO - Done attempting. 14:12:18 INFO - (blobuploader) - INFO - Iteration through files over. 14:12:18 INFO - Return code: 0 14:12:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:12:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:12:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c0e19e076cf6dc9775b5b54840c4431676164eb912c5fd665d19838c686b7d0dce77cea345e80a5b369ab12840c065f22b6df4767094093abc2963494f563cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/54227a2be4523ce6311b6313315c01135be8f87702352b45ee1d57a9d2d0e3498bd4c85111e510682fd1c20cc172fca03305282b9bf29b42144029cb74b93feb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/40f298f72c180fc31a772f82c869a1461798597519efb422e3d891d253ae02e190699e9ebafa0ae4e1829d854cdf5159d1ab800d92a8aab7595e0223435c4277"} 14:12:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:12:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:12:18 INFO - Contents: 14:12:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c0e19e076cf6dc9775b5b54840c4431676164eb912c5fd665d19838c686b7d0dce77cea345e80a5b369ab12840c065f22b6df4767094093abc2963494f563cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/54227a2be4523ce6311b6313315c01135be8f87702352b45ee1d57a9d2d0e3498bd4c85111e510682fd1c20cc172fca03305282b9bf29b42144029cb74b93feb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/40f298f72c180fc31a772f82c869a1461798597519efb422e3d891d253ae02e190699e9ebafa0ae4e1829d854cdf5159d1ab800d92a8aab7595e0223435c4277"} 14:12:18 INFO - Running post-run listener: copy_logs_to_upload_dir 14:12:18 INFO - Copying logs to upload dir... 14:12:18 INFO - mkdir: /builds/slave/test/build/upload/logs 14:12:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.651492 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-27 14:12:17.804791) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 14:12:17.807926) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c0e19e076cf6dc9775b5b54840c4431676164eb912c5fd665d19838c686b7d0dce77cea345e80a5b369ab12840c065f22b6df4767094093abc2963494f563cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/54227a2be4523ce6311b6313315c01135be8f87702352b45ee1d57a9d2d0e3498bd4c85111e510682fd1c20cc172fca03305282b9bf29b42144029cb74b93feb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/40f298f72c180fc31a772f82c869a1461798597519efb422e3d891d253ae02e190699e9ebafa0ae4e1829d854cdf5159d1ab800d92a8aab7595e0223435c4277"} build_url:https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/LUQZSE_3R9yna1B0kLBUGQ/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/Larch-Non-PGO/sha512/3c0e19e076cf6dc9775b5b54840c4431676164eb912c5fd665d19838c686b7d0dce77cea345e80a5b369ab12840c065f22b6df4767094093abc2963494f563cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/54227a2be4523ce6311b6313315c01135be8f87702352b45ee1d57a9d2d0e3498bd4c85111e510682fd1c20cc172fca03305282b9bf29b42144029cb74b93feb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/40f298f72c180fc31a772f82c869a1461798597519efb422e3d891d253ae02e190699e9ebafa0ae4e1829d854cdf5159d1ab800d92a8aab7595e0223435c4277"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 14:12:17.836464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:12:17.837226) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464381909.120148-1610408652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:12:17.918895) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 14:12:17.919600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 14:12:17.920234) ========= ========= Total master_lag: 0.18 =========