builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1464815689.46 results: success (0) buildid: 20160601125549 builduid: 28de62a60f624693919506460f2479dd revision: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.456132) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.456616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.456900) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.471711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.472023) ========= 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=db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 14:14:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-06-01 14:14:49 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117177 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.602854) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.603179) ========= 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=db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017590 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.652369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:49.652739) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev b86cd69b94c7f4b31a1f665b91a44c84638bedd6 --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 b86cd69b94c7f4b31a1f665b91a44c84638bedd6 --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=db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 14:14:49,778 truncating revision to first 12 chars 2016-06-01 14:14:49,778 Setting DEBUG logging. 2016-06-01 14:14:49,778 attempt 1/10 2016-06-01 14:14:49,778 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/b86cd69b94c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 14:14:50,149 unpacking tar archive at: larch-b86cd69b94c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.716923 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:50.383342) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:50.383831) ========= script_repo_revision: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:50.384305) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:50.384618) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 14:14:50.395343) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-01 14:14:50.395695) ========= /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=db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:14:50 INFO - MultiFileLogger online at 20160601 14:14:50 in /builds/slave/test 14:14:50 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 14:14:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:14:50 INFO - {'append_to_log': False, 14:14:50 INFO - 'base_work_dir': '/builds/slave/test', 14:14:50 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 14:14:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:14:50 INFO - 'branch': 'Larch-Non-PGO', 14:14:50 INFO - 'buildbot_json_path': 'buildprops.json', 14:14:50 INFO - 'config_files': ('talos/linux_config.py',), 14:14:50 INFO - 'default_actions': ('clobber', 14:14:50 INFO - 'read-buildbot-config', 14:14:50 INFO - 'download-and-extract', 14:14:50 INFO - 'populate-webroot', 14:14:50 INFO - 'create-virtualenv', 14:14:50 INFO - 'install', 14:14:50 INFO - 'run-tests'), 14:14:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:14:50 INFO - 'download_minidump_stackwalk': True, 14:14:50 INFO - 'download_symbols': 'ondemand', 14:14:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:14:50 INFO - 'tooltool.py': '/tools/tooltool.py', 14:14:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:14:50 INFO - '/tools/misc-python/virtualenv.py')}, 14:14:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:14:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:14:50 INFO - 'installer_path': 'installer.exe', 14:14:50 INFO - 'log_level': 'info', 14:14:50 INFO - 'log_name': 'talos', 14:14:50 INFO - 'log_to_console': True, 14:14:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:14:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:14:50 INFO - 'opt_config_files': (), 14:14:50 INFO - 'pip_index': False, 14:14:50 INFO - 'sps_profile': False, 14:14:50 INFO - 'sps_profile_interval': 0, 14:14:50 INFO - 'suite': 'dromaeojs', 14:14:50 INFO - 'system_bits': '32', 14:14:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:14:50 INFO - 'title': 'talos-linux64-ix-031', 14:14:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:14:50 INFO - 'use_talos_json': True, 14:14:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:14:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:14:50 INFO - 'work_dir': 'build'} 14:14:50 INFO - ##### 14:14:50 INFO - ##### Running clobber step. 14:14:50 INFO - ##### 14:14:50 INFO - Running pre-action listener: _resource_record_pre_action 14:14:50 INFO - Running main action method: clobber 14:14:50 INFO - rmtree: /builds/slave/test/build 14:14:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:14:51 INFO - Running post-action listener: _resource_record_post_action 14:14:51 INFO - ##### 14:14:51 INFO - ##### Finished clobber step (success) 14:14:51 INFO - ##### 14:14:51 INFO - ##### 14:14:51 INFO - ##### Running read-buildbot-config step. 14:14:51 INFO - ##### 14:14:51 INFO - Running pre-action listener: _resource_record_pre_action 14:14:51 INFO - Running main action method: read_buildbot_config 14:14:51 INFO - Using buildbot properties: 14:14:51 INFO - { 14:14:51 INFO - "project": "", 14:14:51 INFO - "product": "firefox", 14:14:51 INFO - "script_repo_revision": "production", 14:14:51 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 14:14:51 INFO - "repository": "", 14:14:51 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 14:14:51 INFO - "buildid": "20160601125549", 14:14:51 INFO - "pgo_build": "False", 14:14:51 INFO - "basedir": "/builds/slave/test", 14:14:51 INFO - "buildnumber": 5, 14:14:51 INFO - "slavename": "talos-linux64-ix-031", 14:14:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:14:51 INFO - "platform": "ubuntu64_hw", 14:14:51 INFO - "scheduler": "tests-larch-linux64-talos", 14:14:51 INFO - "branch": "larch", 14:14:51 INFO - "repo_path": "projects/larch", 14:14:51 INFO - "revision": "b86cd69b94c7f4b31a1f665b91a44c84638bedd6", 14:14:51 INFO - "stage_platform": "linux64", 14:14:51 INFO - "builduid": "28de62a60f624693919506460f2479dd", 14:14:51 INFO - "slavebuilddir": "test" 14:14:51 INFO - } 14:14:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:14:51 INFO - Running post-action listener: _resource_record_post_action 14:14:51 INFO - ##### 14:14:51 INFO - ##### Finished read-buildbot-config step (success) 14:14:51 INFO - ##### 14:14:51 INFO - ##### 14:14:51 INFO - ##### Running download-and-extract step. 14:14:51 INFO - ##### 14:14:51 INFO - Running pre-action listener: _resource_record_pre_action 14:14:51 INFO - Running main action method: download_and_extract 14:14:51 INFO - mkdir: /builds/slave/test/build/tests 14:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:14:51 INFO - https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:14:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:51 INFO - Downloaded 1361 bytes. 14:14:51 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - Using the following test package requirements: 14:14:51 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:14:51 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:14:51 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:14:51 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:52 INFO - u'jsshell-linux-x86_64.zip'], 14:14:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:14:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:14:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:14:52 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:14:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:14:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:14:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:14:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:14: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')]} 14:14:52 INFO - https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:14:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:14:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:14:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:53 INFO - Downloaded 22850307 bytes. 14:14:53 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'] 14:14:53 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 14:14:54 INFO - Return code: 0 14:14:54 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 14:14: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')]} 14:14:54 INFO - https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:14:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:14:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:14:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:55 INFO - Downloaded 22850307 bytes. 14:14:55 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'] 14:14:55 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 14:14:57 INFO - Return code: 0 14:14:57 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')]} 14:14:57 INFO - https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:14:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:14:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:14:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:58 INFO - Downloaded 10834063 bytes. 14:14:58 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'] 14:14:58 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 14:14:58 INFO - Return code: 0 14:14: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')]} 14:14:58 INFO - https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:14:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:14:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:14:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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 14:14:59 INFO - Downloaded 55177226 bytes. 14:14:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:14:59 INFO - mkdir: /builds/slave/test/properties 14:14:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:14:59 INFO - Writing to file /builds/slave/test/properties/build_url 14:14:59 INFO - Contents: 14:14:59 INFO - build_url:https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:15:00 INFO - Running post-action listener: _resource_record_post_action 14:15:00 INFO - Running post-action listener: set_extra_try_arguments 14:15:00 INFO - ##### 14:15:00 INFO - ##### Finished download-and-extract step (success) 14:15:00 INFO - ##### 14:15:00 INFO - ##### 14:15:00 INFO - ##### Running populate-webroot step. 14:15:00 INFO - ##### 14:15:00 INFO - Running pre-action listener: _resource_record_pre_action 14:15:00 INFO - Running main action method: populate_webroot 14:15:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:15:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:15:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:15:00 INFO - '--tppagecycles', 14:15:00 INFO - '7'], 14:15:00 INFO - 'tests': ['tsvgm']}}, 14:15:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:15:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:15:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:15:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:15:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:15:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:15:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['damp', 'tps']}, 14:15:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'tests': ['damp', 'tps']}, 14:15:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['dromaeo_dom']}, 14:15:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:15:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['a11yr', 14:15:00 INFO - 'ts_paint', 14:15:00 INFO - 'tpaint', 14:15:00 INFO - 'sessionrestore', 14:15:00 INFO - 'sessionrestore_no_auto_restore', 14:15:00 INFO - 'tabpaint']}, 14:15:00 INFO - 'other-e10s': {'tests': ['a11yr', 14:15:00 INFO - 'ts_paint', 14:15:00 INFO - 'tpaint', 14:15:00 INFO - 'sessionrestore', 14:15:00 INFO - 'sessionrestore_no_auto_restore', 14:15:00 INFO - 'tabpaint']}, 14:15:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:15:00 INFO - 'ts_paint', 14:15:00 INFO - 'tpaint', 14:15:00 INFO - 'sessionrestore', 14:15:00 INFO - 'sessionrestore_no_auto_restore', 14:15:00 INFO - 'tabpaint']}, 14:15:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:15:00 INFO - 'ts_paint', 14:15:00 INFO - 'tpaint', 14:15:00 INFO - 'sessionrestore', 14:15:00 INFO - 'sessionrestore_no_auto_restore', 14:15:00 INFO - 'tabpaint']}, 14:15:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['a11yr', 14:15:00 INFO - 'ts_paint', 14:15:00 INFO - 'tpaint', 14:15:00 INFO - 'sessionrestore', 14:15:00 INFO - 'sessionrestore_no_auto_restore', 14:15:00 INFO - 'tabpaint']}, 14:15:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['a11yr', 14:15:00 INFO - 'ts_paint', 14:15:00 INFO - 'tpaint', 14:15:00 INFO - 'sessionrestore', 14:15:00 INFO - 'sessionrestore_no_auto_restore', 14:15:00 INFO - 'tabpaint']}, 14:15:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['tsvgx', 14:15:00 INFO - 'tsvgr_opacity', 14:15:00 INFO - 'tart', 14:15:00 INFO - 'tscrollx', 14:15:00 INFO - 'cart']}, 14:15:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:15:00 INFO - 'tsvgr_opacity', 14:15:00 INFO - 'tart', 14:15:00 INFO - 'tscrollx', 14:15:00 INFO - 'cart']}, 14:15:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'talos_options': ['--disable-e10s'], 14:15:00 INFO - 'tests': ['tp5o']}, 14:15:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'tests': ['tp5o']}, 14:15:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'talos_options': ['--disable-e10s', 14:15:00 INFO - '--xperf_path', 14:15:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:15:00 INFO - 'tests': ['tp5n']}, 14:15:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:15:00 INFO - 'talos_options': ['--xperf_path', 14:15:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:15:00 INFO - 'tests': ['tp5n']}}, 14:15:00 INFO - 'talos.zip': {'path': '', 14:15:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:15:00 INFO - Running post-action listener: _resource_record_post_action 14:15:00 INFO - ##### 14:15:00 INFO - ##### Finished populate-webroot step (success) 14:15:00 INFO - ##### 14:15:00 INFO - ##### 14:15:00 INFO - ##### Running create-virtualenv step. 14:15:00 INFO - ##### 14:15:00 INFO - Running pre-action listener: _resource_record_pre_action 14:15:00 INFO - Running main action method: create_virtualenv 14:15:00 INFO - Creating virtualenv /builds/slave/test/build/venv 14:15:00 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 14:15:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:15:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:15:00 INFO - Using real prefix '/usr' 14:15:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:15:01 INFO - Installing distribute.............................................................................................................................................................................................done. 14:15:03 INFO - Installing pip.................done. 14:15:03 INFO - Return code: 0 14:15:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:03 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:03 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 14:15:03 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 14:15:03 INFO - Using env: {'DISPLAY': ':0', 14:15:03 INFO - 'HOME': '/home/cltbld', 14:15:03 INFO - 'LANG': 'en_US.UTF-8', 14:15:03 INFO - 'LANGUAGE': 'en_US:en', 14:15:03 INFO - 'LOGNAME': 'cltbld', 14:15:03 INFO - 'MAIL': '/var/mail/cltbld', 14:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:03 INFO - 'MOZ_NO_REMOTE': '1', 14:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:03 INFO - 'NO_EM_RESTART': '1', 14:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:03 INFO - 'PWD': '/builds/slave/test', 14:15:03 INFO - 'SHELL': '/bin/bash', 14:15:03 INFO - 'SHLVL': '1', 14:15:03 INFO - 'TERM': 'linux', 14:15:03 INFO - 'TMOUT': '86400', 14:15:03 INFO - 'USER': 'cltbld', 14:15:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:03 INFO - '_': '/tools/buildbot/bin/python'} 14:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:03 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 14:15:03 INFO - Cleaning up... 14:15:03 INFO - Return code: 0 14:15:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:03 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:03 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 14:15:03 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 14:15:03 INFO - Using env: {'DISPLAY': ':0', 14:15:03 INFO - 'HOME': '/home/cltbld', 14:15:03 INFO - 'LANG': 'en_US.UTF-8', 14:15:03 INFO - 'LANGUAGE': 'en_US:en', 14:15:03 INFO - 'LOGNAME': 'cltbld', 14:15:03 INFO - 'MAIL': '/var/mail/cltbld', 14:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:03 INFO - 'MOZ_NO_REMOTE': '1', 14:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:03 INFO - 'NO_EM_RESTART': '1', 14:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:03 INFO - 'PWD': '/builds/slave/test', 14:15:03 INFO - 'SHELL': '/bin/bash', 14:15:03 INFO - 'SHLVL': '1', 14:15:03 INFO - 'TERM': 'linux', 14:15:03 INFO - 'TMOUT': '86400', 14:15:03 INFO - 'USER': 'cltbld', 14:15:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:03 INFO - '_': '/tools/buildbot/bin/python'} 14:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:03 INFO - Downloading/unpacking psutil>=3.1.1 14:15:03 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 14:15:03 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 14:15:03 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 14:15:03 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 14:15:03 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 14:15:03 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 14:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:15:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:15:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:15:05 INFO - Installing collected packages: psutil 14:15:05 INFO - Running setup.py install for psutil 14:15:05 INFO - building 'psutil._psutil_linux' extension 14:15:05 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 14:15:05 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 14:15:05 INFO - building 'psutil._psutil_posix' extension 14:15:05 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 14:15:06 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 14:15:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:15:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:15:06 INFO - Successfully installed psutil 14:15:06 INFO - Cleaning up... 14:15:06 INFO - Return code: 0 14:15:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:06 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:06 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 14:15:06 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 14:15:06 INFO - Using env: {'DISPLAY': ':0', 14:15:06 INFO - 'HOME': '/home/cltbld', 14:15:06 INFO - 'LANG': 'en_US.UTF-8', 14:15:06 INFO - 'LANGUAGE': 'en_US:en', 14:15:06 INFO - 'LOGNAME': 'cltbld', 14:15:06 INFO - 'MAIL': '/var/mail/cltbld', 14:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:06 INFO - 'MOZ_NO_REMOTE': '1', 14:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:06 INFO - 'NO_EM_RESTART': '1', 14:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:06 INFO - 'PWD': '/builds/slave/test', 14:15:06 INFO - 'SHELL': '/bin/bash', 14:15:06 INFO - 'SHLVL': '1', 14:15:06 INFO - 'TERM': 'linux', 14:15:06 INFO - 'TMOUT': '86400', 14:15:06 INFO - 'USER': 'cltbld', 14:15:06 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:06 INFO - '_': '/tools/buildbot/bin/python'} 14:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:15:06 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 14:15:06 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 14:15:06 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 14:15:06 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 14:15:06 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 14:15:06 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 14:15:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:15:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:15:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:15:08 INFO - Installing collected packages: mozsystemmonitor 14:15:08 INFO - Running setup.py install for mozsystemmonitor 14:15:08 INFO - Successfully installed mozsystemmonitor 14:15:08 INFO - Cleaning up... 14:15:08 INFO - Return code: 0 14:15:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:08 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:08 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 14:15:08 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 14:15:08 INFO - Using env: {'DISPLAY': ':0', 14:15:08 INFO - 'HOME': '/home/cltbld', 14:15:08 INFO - 'LANG': 'en_US.UTF-8', 14:15:08 INFO - 'LANGUAGE': 'en_US:en', 14:15:08 INFO - 'LOGNAME': 'cltbld', 14:15:08 INFO - 'MAIL': '/var/mail/cltbld', 14:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:08 INFO - 'MOZ_NO_REMOTE': '1', 14:15:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:08 INFO - 'NO_EM_RESTART': '1', 14:15:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:08 INFO - 'PWD': '/builds/slave/test', 14:15:08 INFO - 'SHELL': '/bin/bash', 14:15:08 INFO - 'SHLVL': '1', 14:15:08 INFO - 'TERM': 'linux', 14:15:08 INFO - 'TMOUT': '86400', 14:15:08 INFO - 'USER': 'cltbld', 14:15:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:08 INFO - '_': '/tools/buildbot/bin/python'} 14:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:08 INFO - Downloading/unpacking blobuploader==1.2.4 14:15: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 14:15: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 14:15: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 14:15: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 14:15: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 14:15: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 14:15:10 INFO - Downloading blobuploader-1.2.4.tar.gz 14:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:15:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:15: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 14:15: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 14:15: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 14:15: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 14:15: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 14:15: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 14:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:15:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:11 INFO - Downloading docopt-0.6.1.tar.gz 14:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:15:11 INFO - Installing collected packages: blobuploader, requests, docopt 14:15:11 INFO - Running setup.py install for blobuploader 14:15:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:15:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:15:11 INFO - Running setup.py install for requests 14:15:11 INFO - Running setup.py install for docopt 14:15:11 INFO - Successfully installed blobuploader requests docopt 14:15:11 INFO - Cleaning up... 14:15:11 INFO - Return code: 0 14:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:11 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')]} 14:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:11 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')]} 14:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:11 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:11 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 14:15:11 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 14:15:11 INFO - Using env: {'DISPLAY': ':0', 14:15:11 INFO - 'HOME': '/home/cltbld', 14:15:11 INFO - 'LANG': 'en_US.UTF-8', 14:15:11 INFO - 'LANGUAGE': 'en_US:en', 14:15:11 INFO - 'LOGNAME': 'cltbld', 14:15:11 INFO - 'MAIL': '/var/mail/cltbld', 14:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:11 INFO - 'MOZ_NO_REMOTE': '1', 14:15:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:11 INFO - 'NO_EM_RESTART': '1', 14:15:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:11 INFO - 'PWD': '/builds/slave/test', 14:15:11 INFO - 'SHELL': '/bin/bash', 14:15:11 INFO - 'SHLVL': '1', 14:15:11 INFO - 'TERM': 'linux', 14:15:11 INFO - 'TMOUT': '86400', 14:15:11 INFO - 'USER': 'cltbld', 14:15:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:11 INFO - '_': '/tools/buildbot/bin/python'} 14:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:15:12 INFO - Running setup.py (path:/tmp/pip-vbdabU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:15:12 INFO - Running setup.py (path:/tmp/pip-HzHm0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:15:12 INFO - Running setup.py (path:/tmp/pip-5BUePH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:15:12 INFO - Running setup.py (path:/tmp/pip-GVphay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:15:12 INFO - Running setup.py (path:/tmp/pip-H_XLip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:15:12 INFO - Running setup.py (path:/tmp/pip-bYD_Nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:15:12 INFO - Running setup.py (path:/tmp/pip-uk4jCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:15:12 INFO - Running setup.py (path:/tmp/pip-0e6qei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:15:12 INFO - Running setup.py (path:/tmp/pip-O1a7Kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:15:12 INFO - Running setup.py (path:/tmp/pip-v7Q4OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:15:12 INFO - Running setup.py (path:/tmp/pip-5l7wF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:15:12 INFO - Running setup.py (path:/tmp/pip-EzcL_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:15:13 INFO - Running setup.py (path:/tmp/pip-VzyLYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:15:13 INFO - Running setup.py (path:/tmp/pip-vT4s0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:13 INFO - Running setup.py (path:/tmp/pip-VuzaL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:15:13 INFO - Running setup.py (path:/tmp/pip-pAo_NS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:15:13 INFO - Running setup.py (path:/tmp/pip-qJtRS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:15:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:15:13 INFO - Running setup.py install for manifestparser 14:15:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:15:13 INFO - Running setup.py install for mozcrash 14:15:13 INFO - Running setup.py install for mozdebug 14:15:13 INFO - Running setup.py install for mozdevice 14:15:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:15:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:15:13 INFO - Running setup.py install for mozfile 14:15:13 INFO - Running setup.py install for mozhttpd 14:15:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:15:13 INFO - Running setup.py install for mozinfo 14:15:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Running setup.py install for mozInstall 14:15:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Running setup.py install for mozleak 14:15:14 INFO - Running setup.py install for mozlog 14:15:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Running setup.py install for moznetwork 14:15:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Running setup.py install for mozprocess 14:15:14 INFO - Running setup.py install for mozprofile 14:15:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Running setup.py install for mozrunner 14:15:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:15:14 INFO - Running setup.py install for mozscreenshot 14:15:14 INFO - Running setup.py install for moztest 14:15:15 INFO - Running setup.py install for mozversion 14:15:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:15:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:15:15 INFO - Cleaning up... 14:15:15 INFO - Return code: 0 14:15:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:15 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')]} 14:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:15 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')]} 14:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:15 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:15 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 14:15:15 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 14:15:15 INFO - Using env: {'DISPLAY': ':0', 14:15:15 INFO - 'HOME': '/home/cltbld', 14:15:15 INFO - 'LANG': 'en_US.UTF-8', 14:15:15 INFO - 'LANGUAGE': 'en_US:en', 14:15:15 INFO - 'LOGNAME': 'cltbld', 14:15:15 INFO - 'MAIL': '/var/mail/cltbld', 14:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:15 INFO - 'MOZ_NO_REMOTE': '1', 14:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:15 INFO - 'NO_EM_RESTART': '1', 14:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:15 INFO - 'PWD': '/builds/slave/test', 14:15:15 INFO - 'SHELL': '/bin/bash', 14:15:15 INFO - 'SHLVL': '1', 14:15:15 INFO - 'TERM': 'linux', 14:15:15 INFO - 'TMOUT': '86400', 14:15:15 INFO - 'USER': 'cltbld', 14:15:15 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:15 INFO - '_': '/tools/buildbot/bin/python'} 14:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:15:15 INFO - Running setup.py (path:/tmp/pip-ce247h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:15:15 INFO - Running setup.py (path:/tmp/pip-siBrfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:15:15 INFO - Running setup.py (path:/tmp/pip-LNiseX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:15:15 INFO - Running setup.py (path:/tmp/pip-0WzYIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:15:15 INFO - Running setup.py (path:/tmp/pip-6kGJY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:15:15 INFO - Running setup.py (path:/tmp/pip-RPpZt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:15:15 INFO - Running setup.py (path:/tmp/pip-PGnOMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:15:15 INFO - Running setup.py (path:/tmp/pip-R8zt4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:15:15 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)) 14:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:15:15 INFO - Running setup.py (path:/tmp/pip-nofEiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:15:16 INFO - Running setup.py (path:/tmp/pip-_H2xrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:15:16 INFO - Running setup.py (path:/tmp/pip-NAufNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:15:16 INFO - Running setup.py (path:/tmp/pip-8QAaeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:15:16 INFO - Running setup.py (path:/tmp/pip-s6AY_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:15:16 INFO - Running setup.py (path:/tmp/pip-9E3Z5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:16 INFO - Running setup.py (path:/tmp/pip-PpgjS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:15:16 INFO - Running setup.py (path:/tmp/pip-29t3xT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:15:16 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)) 14:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:15:16 INFO - Running setup.py (path:/tmp/pip-Ncbn2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:15:16 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)) 14:15:16 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)) 14:15:16 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)) 14:15:16 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)) 14:15:16 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)) 14:15:16 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)) 14:15:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:18 INFO - Downloading blessings-1.6.tar.gz 14:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:15:18 INFO - Installing collected packages: blessings 14:15:18 INFO - Running setup.py install for blessings 14:15:18 INFO - Successfully installed blessings 14:15:18 INFO - Cleaning up... 14:15:18 INFO - Return code: 0 14:15:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:15:19 INFO - Reading from file tmpfile_stdout 14:15:19 INFO - Current package versions: 14:15:19 INFO - argparse == 1.2.1 14:15:19 INFO - blessings == 1.6 14:15:19 INFO - blobuploader == 1.2.4 14:15:19 INFO - docopt == 0.6.1 14:15:19 INFO - manifestparser == 1.1 14:15:19 INFO - mozInstall == 1.12 14:15:19 INFO - mozcrash == 0.17 14:15:19 INFO - mozdebug == 0.1 14:15:19 INFO - mozdevice == 0.48 14:15:19 INFO - mozfile == 1.2 14:15:19 INFO - mozhttpd == 0.7 14:15:19 INFO - mozinfo == 0.9 14:15:19 INFO - mozleak == 0.1 14:15:19 INFO - mozlog == 3.2 14:15:19 INFO - moznetwork == 0.27 14:15:19 INFO - mozprocess == 0.22 14:15:19 INFO - mozprofile == 0.28 14:15:19 INFO - mozrunner == 6.11 14:15:19 INFO - mozscreenshot == 0.1 14:15:19 INFO - mozsystemmonitor == 0.3 14:15:19 INFO - moztest == 0.7 14:15:19 INFO - mozversion == 1.4 14:15:19 INFO - psutil == 3.1.1 14:15:19 INFO - requests == 1.2.3 14:15:19 INFO - wsgiref == 0.1.2 14:15:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:19 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:19 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 14:15:19 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 14:15:19 INFO - Using env: {'DISPLAY': ':0', 14:15:19 INFO - 'HOME': '/home/cltbld', 14:15:19 INFO - 'LANG': 'en_US.UTF-8', 14:15:19 INFO - 'LANGUAGE': 'en_US:en', 14:15:19 INFO - 'LOGNAME': 'cltbld', 14:15:19 INFO - 'MAIL': '/var/mail/cltbld', 14:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:19 INFO - 'MOZ_NO_REMOTE': '1', 14:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:19 INFO - 'NO_EM_RESTART': '1', 14:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:19 INFO - 'PWD': '/builds/slave/test', 14:15:19 INFO - 'SHELL': '/bin/bash', 14:15:19 INFO - 'SHLVL': '1', 14:15:19 INFO - 'TERM': 'linux', 14:15:19 INFO - 'TMOUT': '86400', 14:15:19 INFO - 'USER': 'cltbld', 14:15:19 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:19 INFO - '_': '/tools/buildbot/bin/python'} 14:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 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)) 14:15:19 INFO - Cleaning up... 14:15:19 INFO - Return code: 0 14:15:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:19 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:19 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 14:15:19 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 14:15:19 INFO - Using env: {'DISPLAY': ':0', 14:15:19 INFO - 'HOME': '/home/cltbld', 14:15:19 INFO - 'LANG': 'en_US.UTF-8', 14:15:19 INFO - 'LANGUAGE': 'en_US:en', 14:15:19 INFO - 'LOGNAME': 'cltbld', 14:15:19 INFO - 'MAIL': '/var/mail/cltbld', 14:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:19 INFO - 'MOZ_NO_REMOTE': '1', 14:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:19 INFO - 'NO_EM_RESTART': '1', 14:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:19 INFO - 'PWD': '/builds/slave/test', 14:15:19 INFO - 'SHELL': '/bin/bash', 14:15:19 INFO - 'SHLVL': '1', 14:15:19 INFO - 'TERM': 'linux', 14:15:19 INFO - 'TMOUT': '86400', 14:15:19 INFO - 'USER': 'cltbld', 14:15:19 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:19 INFO - '_': '/tools/buildbot/bin/python'} 14:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:19 INFO - Downloading/unpacking jsonschema 14:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:15:21 INFO - Downloading/unpacking functools32 (from jsonschema) 14:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:21 INFO - Downloading functools32-3.2.3-2.tar.gz 14:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:15:21 INFO - warning: no files found matching '*.txt' 14:15:21 INFO - no previously-included directories found matching 'build' 14:15:21 INFO - no previously-included directories found matching 'dist' 14:15:21 INFO - no previously-included directories found matching '.git*' 14:15:21 INFO - Installing collected packages: jsonschema, functools32 14:15:21 INFO - Running setup.py install for functools32 14:15:21 INFO - warning: no files found matching '*.txt' 14:15:21 INFO - no previously-included directories found matching 'build' 14:15:21 INFO - no previously-included directories found matching 'dist' 14:15:21 INFO - no previously-included directories found matching '.git*' 14:15:21 INFO - Successfully installed jsonschema functools32 14:15:21 INFO - Cleaning up... 14:15:22 INFO - Return code: 0 14:15:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:15:22 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')]} 14:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:22 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')]} 14:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:22 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 0x7fd13ede8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, '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': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', '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 14:15:22 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 14:15:22 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 14:15:22 INFO - Using env: {'DISPLAY': ':0', 14:15:22 INFO - 'HOME': '/home/cltbld', 14:15:22 INFO - 'LANG': 'en_US.UTF-8', 14:15:22 INFO - 'LANGUAGE': 'en_US:en', 14:15:22 INFO - 'LOGNAME': 'cltbld', 14:15:22 INFO - 'MAIL': '/var/mail/cltbld', 14:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:22 INFO - 'MOZ_NO_REMOTE': '1', 14:15:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:22 INFO - 'NO_EM_RESTART': '1', 14:15:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:22 INFO - 'PWD': '/builds/slave/test', 14:15:22 INFO - 'SHELL': '/bin/bash', 14:15:22 INFO - 'SHLVL': '1', 14:15:22 INFO - 'TERM': 'linux', 14:15:22 INFO - 'TMOUT': '86400', 14:15:22 INFO - 'USER': 'cltbld', 14:15:22 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:22 INFO - '_': '/tools/buildbot/bin/python'} 14:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:22 INFO - Downloading/unpacking flake8 14:15:22 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 14:15:22 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 14:15:22 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 14:15:22 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 14:15:22 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 14:15:22 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 14:15:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:15:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:15:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 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 14:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:15:24 INFO - no previously-included directories found matching 'docs/_build' 14:15:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:15:24 INFO - Running setup.py install for pep8 14:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:15:24 INFO - no previously-included directories found matching 'docs/_build' 14:15:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:15:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:15:25 INFO - Cleaning up... 14:15:25 INFO - Return code: 0 14:15:25 INFO - Running post-action listener: _resource_record_post_action 14:15:25 INFO - Running post-action listener: _start_resource_monitoring 14:15:25 INFO - Starting resource monitoring. 14:15:25 INFO - ##### 14:15:25 INFO - ##### Finished create-virtualenv step (success) 14:15:25 INFO - ##### 14:15:25 INFO - ##### 14:15:25 INFO - ##### Running install step. 14:15:25 INFO - ##### 14:15:25 INFO - Running pre-action listener: _resource_record_pre_action 14:15:25 INFO - Running main action method: install 14:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:15:25 INFO - Reading from file tmpfile_stdout 14:15:25 INFO - Detecting whether we're running mozinstall >=1.0... 14:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:15:25 INFO - Reading from file tmpfile_stdout 14:15:25 INFO - Output received: 14:15:25 INFO - Usage: mozinstall [options] installer 14:15:25 INFO - Options: 14:15:25 INFO - -h, --help show this help message and exit 14:15:25 INFO - -d DEST, --destination=DEST 14:15:25 INFO - Directory to install application into. [default: 14:15:25 INFO - "/builds/slave/test"] 14:15:25 INFO - --app=APP Application being installed. [default: firefox] 14:15:25 INFO - mkdir: /builds/slave/test/build/application 14:15:25 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'] 14:15:25 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 14:15:41 INFO - Reading from file tmpfile_stdout 14:15:41 INFO - Output received: 14:15:41 INFO - /builds/slave/test/build/application/firefox/firefox 14:15:41 INFO - Running post-action listener: _resource_record_post_action 14:15:41 INFO - ##### 14:15:41 INFO - ##### Finished install step (success) 14:15:41 INFO - ##### 14:15:41 INFO - ##### 14:15:41 INFO - ##### Running run-tests step. 14:15:41 INFO - ##### 14:15:41 INFO - Running pre-action listener: _resource_record_pre_action 14:15:41 INFO - Running main action method: run_tests 14:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:15:41 INFO - Python 2.7.3 14:15:41 INFO - Return code: 0 14:15:41 INFO - grabbing minidump binary from tooltool 14:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:41 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 0x1a879c0>, '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 0x1a888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:15:41 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 14:15:41 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 14:15:41 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 14:15:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:15:41 INFO - Return code: 0 14:15:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:15:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:15:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:15:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:15:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:15:42 INFO - Return code: 0 14:15:42 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/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 14:15:42 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/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 14:15:42 INFO - Using env: {'DISPLAY': ':0', 14:15:42 INFO - 'HOME': '/home/cltbld', 14:15:42 INFO - 'LANG': 'en_US.UTF-8', 14:15:42 INFO - 'LANGUAGE': 'en_US:en', 14:15:42 INFO - 'LOGNAME': 'cltbld', 14:15:42 INFO - 'MAIL': '/var/mail/cltbld', 14:15:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:15:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:42 INFO - 'MOZ_NO_REMOTE': '1', 14:15:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:15:42 INFO - 'NO_EM_RESTART': '1', 14:15:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:42 INFO - 'PWD': '/builds/slave/test', 14:15:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:15:42 INFO - 'SHELL': '/bin/bash', 14:15:42 INFO - 'SHLVL': '1', 14:15:42 INFO - 'TERM': 'linux', 14:15:42 INFO - 'TMOUT': '86400', 14:15:42 INFO - 'USER': 'cltbld', 14:15:42 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058', 14:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:42 INFO - '_': '/tools/buildbot/bin/python'} 14:15:42 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/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 14:15:42 INFO - mozversion application_buildid: 20160601125549 14:15:42 INFO - mozversion application_changeset: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 14:15:42 INFO - mozversion application_display_name: Nightly 14:15:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:15:42 INFO - mozversion application_name: Firefox 14:15:42 INFO - mozversion application_remotingname: firefox 14:15:42 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:15:42 INFO - mozversion application_vendor: Mozilla 14:15:42 INFO - mozversion application_version: 49.0a1 14:15:42 INFO - mozversion platform_buildid: 20160601125549 14:15:42 INFO - mozversion platform_changeset: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 14:15:42 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:15:42 INFO - mozversion platform_version: 49.0a1 14:15:42 INFO - using testdate: 1464815742 14:15:42 INFO - actual date: 1464815742 14:15:42 INFO - starting webserver on 'localhost:39238' 14:15:42 INFO - SUITE-START | Running 2 tests 14:15:42 INFO - TEST-START | dromaeo_css 14:15:42 INFO - operating with platform_type : linux_ 14:15:42 INFO - Initialising browser for dromaeo_css test... 14:15:42 INFO - TEST-INFO | started process 16379 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP95X6e/profile http://localhost:39238/getInfo.html) 14:15:46 INFO - PROCESS | 16379 | __metrics Screen width/height:1600/1200 14:15:46 INFO - PROCESS | 16379 | colorDepth:24 14:15:46 INFO - PROCESS | 16379 | Browser inner width/height: 1024/697 14:15:46 INFO - PROCESS | 16379 | __metrics 14:15:47 ERROR - PROCESS | 16379 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:15:48 INFO - TEST-INFO | 16379: exit 0 14:15:48 INFO - Browser initialized. 14:15:48 INFO - Running cycle 1/1 for dromaeo_css test... 14:15:48 INFO - TEST-INFO | started process 16459 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP95X6e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:16:00 INFO - PROCESS | 16459 | RSS: Main: 158142464 14:16:00 INFO - PROCESS | 16459 | 14:16:52 INFO - PROCESS | 16459 | 1464815812203 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] 14:19:03 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:39238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39238/tests/dromaeo/cssquery-ext.html) 14:19:04 INFO - PROCESS | 16459 | RSS: Main: 263409664 14:19:04 INFO - PROCESS | 16459 | 14:22:07 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:39238/tests/dromaeo/cssquery-ext.html (next: http://localhost:39238/tests/dromaeo/cssquery-jquery.html) 14:22:07 INFO - PROCESS | 16459 | RSS: Main: 243519488 14:22:07 INFO - PROCESS | 16459 | 14:25:10 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:39238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39238/tests/dromaeo/cssquery-mootools.html) 14:25:10 INFO - PROCESS | 16459 | RSS: Main: 240377856 14:25:10 INFO - PROCESS | 16459 | 14:28:12 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:39238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39238/tests/dromaeo/cssquery-prototype.html) 14:28:13 INFO - PROCESS | 16459 | RSS: Main: 239206400 14:28:13 INFO - PROCESS | 16459 | 14:31:16 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:39238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39238/tests/dromaeo/cssquery-yui.html) 14:31:17 INFO - PROCESS | 16459 | RSS: Main: 211337216 14:31:17 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:39238/tests/dromaeo/cssquery-yui.html (next: http://localhost:39238/tests/dromaeo/cssquery-dojo.html) 14:34:20 INFO - PROCESS | 16459 | RSS: Main: 273715200 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | __start_tp_report 14:34:20 INFO - PROCESS | 16459 | _x_x_mozilla_page_load 14:34:20 INFO - PROCESS | 16459 | _x_x_mozilla_page_load_details 14:34:20 INFO - PROCESS | 16459 | |i|pagename|runs| 14:34:20 INFO - PROCESS | 16459 | |0;dojo.html;2649.9470010599784;2668.6397336359587;2695.1779707189307;2719.442514284573;2723.6595425571804;4572.862814115573;4689.155951928651;4699.224627936391;4761.642876784238;4797.856064318072;4694.554017368353;4707.317438971347;4741.691790033646;4806.735629540374;4810.422749270093;4255.744655320683;4286.549912259215;4356.433663623725;4375.409319741826;4517.864464066083;4441.622462090726;4463.933041004388;4472.195004899117;4497.235469970113;4519.954800451987;4231.894202644928;4291.549387314319;4301.376300436419;4359.411479450266;4377.562243775629;4307.698461107724;4313.309870420718;4322.610965013164;4411.316245981878;4419.292913133915;4336.089421221547;4340.47914250292;4350.434443522354;4379.189849877751;4394.07724377881;4535.977320113411;4603.424571928509;4621.445426548802;4712.552307530779;4716.622670186377;5089.54194122527;5155.175171972504;5155.2524883892;5157.149070403379;5160.432352441266;5095.414027386861;5177.844664660029;5185.740712964337;5186.844394668166;5195.766190521435;4612.146752850734;4690.390249267642;4731.503192164842;4835.830745923911;4843.975780121077;5174.094533456705;5244.449332820075;5248.029114613809;5249.632525723239;5251.36983561975;4984.950150498521;4991.900161996812;5022.924656130161;5041.7227052511735;5047.141985862412;4902.28916807061;5015.84952451427;5046.369203849519;5064;5073.315102461121;5032.169692000852;5037.269595908646;5041.773120209599;5044.949550504448;5061.848144555669;5018.949810501921;5021.949780502221;5022.3219865317715;5027.271045698426;5077.492250774893;4086;4120.62914337711;4121.052158003616;4234.216669916075;4284.700070995001;3501.509788629594;3678.282734866675;3693.8891833245048;3706.425504046877;3746.8126593670754;4415.492218394861;4427.867163985085;4429.047754732748;4464.821407143678;4473.753943533072;5077.568406685473;5082.1360368737405;5084.491550844886;5086.05907907031;5139.665921715151;4339.652827773771;4446.82212711488;4489.169503641771;4492.258777301774;4521.208788462006;4585.977070114628;4589.0821835633005;4592.10453961481;4594.1730488512385;4641.466231383434;4589.701669391479;4871.14754917888;4927.556519913225;4930.605551555938;4933.210686290176;4871.2206047032305;5075.568576671024;5132.358455193101;5145.305383773143;5145.948540514697;4843.24929635907;4872.4883887192755;4874.488178741181;4876.975615121902;4926.137925862888;4857.319975203403;5054.924176137288;5059.06407314648;5060.974695126501;5122.564582010571;5081.390233172043;5127.1540195867265;5146.6911985281;5154;5160.09698302803;4992.301077849032;4994.825181118644;4998.950010499849;5001.624878134082;5054.974725126351;4764.475907650225;4939.604831613534;4997.400311962588;5013.498650134958;5016.272640467038;4920.950790492192;4966.701997879994;4985.75071246429;5013.222950442687;5018.899622007612;5052.924206136838;5061.7469126544265;5103.18349064148;5111.156659151198;5114.769835357343;4789.7605119743175;4852.393450818648;4915.778789954532;4946.901061978812;4960.826371076995 14:34:20 INFO - PROCESS | 16459 | |1;ext.html;546.7648910968285;574.597781552913;596.2517041113402;602.8824379246049;607.6050567131364;27826.443471130566;40198.59801401985;41529;41583.58416415835;41621.167576648484;39210.60789392105;39634.40548391776;39707.205855882865;39740;39751;7666.616669166548;7803.726869559567;7816.335611473031;7817.491863028913;7820.061592608895;7815.609219539028;7942.3646108311195;7954.801129971768;7959.0847052589115;7973.760787176394;7951.204879512032;7972.1629228931;7980.3615710743;8003.679852805881;8003.719869804529;7889.132195458495;7940.444168908208;7952.403569732293;7954.125046244909;7962.283394494465;7826.686932522693;7920.6039698014865;7934.246246606564;7940.682372705085;7943.721969731089;39533.209335813124;39955;39976.60023399758;40011.79994100011;40022.59977400247;3313.6520665330277;3317.1872891141584;3318.668133186662;3386.86452541898;3547.379208638478;29388.265293367836;29572.40855182906;29580.852095739385;29647.55528667072;29886.252843678863;3299.686529779667;3389.881354152593;3398.3203359327986;3404.2510647657477;3421.8802341917913;261057;265814;265897;266057;266544.6672766643;55718;55867;55991;56019;56094.71952640211;59758.70120649456;59809.70095149583;59853.401465985655;59866.70066649639;59953.10539287951;14319.642008949859;14764.261786910827;14786.112833229825;14803.851961480463;14809.555713328617;11630.418479076012;11867.287962722265;11875.88124118765;11876;11893.940530297294;17154.31382744701;17704.734428983575;17797.46607601748;17822.732659010126;17852.285908563514;6274.780382686584;6329.145565348711;6343.302236753953;6487;6509.511786616023;809.8623234050226;869.1786261982493;898.9460632362079;916.4730280088976;925.0343021080205;28527.714722852506;28554.42891142207;28613;28865.85567072151;29401.411971760444;766.2452484302958;947.4788866123605;965.6282331302359;1015.8730158730159;1056.984145237822;14899.47851825181;14943;14959.626009349853;15110.244487775568;15169.544913652608;375948;376284;377962;378612;379131;50268;50351.24473132906;50410.747946260766;50473.24290135651;50665.746671267145;1067.2849191042021;1074.0601973273385;1182.7102359921873;1186.1993154620768;1212.1514939542355;29032.854835725688;29511.852440737657;29825;30218.54672179963;30331.090067298017;43015.56984430116;43470.78264608657;43485;43511.347329789445;43555.12889742251;238922;239701;239867;240316;240808;37174.44238336481;38962.80518597389;38993.610063898996;38999.2200156001;39105.608943911335;43134.56865431306;43344.349834751876;43662;44529.332060018474;44626.330605040304;37810.81094594619;37849.4322585156;37938.62061379351;38015.80992095022;38340.23319533649;35629.64370356263;36631.81684091563;37083.814580926926;37375.813120934225;37473;36446;36554.634453655126;36611.45082823813;37330.81334593309;37422.625773741915;31860.203494912814;32922.48262678917;32927.67072329342;33394;33434.66565334315;25284.241472755846;25558.616620751076;25752.871235643703;25789.097381591553;26260.343491412867 14:34:20 INFO - PROCESS | 16459 | |2;jquery.html;357.1464200560659;357.4120571659621;363.30245927818584;373.42306137018323;374.3766628563442;15578.143202123878;16744.581385465368;16847;17167.484975450756;17409.47771566852;15610.76583851243;15861.92069039656;16634;16663.41678041266;16705.248263828125;5025.120603894322;5120.692758434488;5221.112410890139;5225.163973764198;5225.216217567377;3745.0262931637835;3928.5874983126787;5109.310243117187;5670.773169073233;6029.487493563052;6035.728392222339;6073.969630151843;6075.119107729377;6090.512758979271;6091.908621370683;5899.174115623816;6001.339852616209;6084.452399284041;6089.543284253698;6092.390760923917;3371.4100032494216;4848.29699693546;5207.973960130213;5217.713025783581;5277.02375060615;19448;20123;20189.495262618406;20382.592348153103;20493.79506204949;1946.5425624978118;2045.3250427358935;2256.1201131558705;2301.6777651128855;2309.7690230976937;8527.18991695788;12052.517899284017;12698.682532936658;13014.804777928339;13156.276404797733;1712.8030276518236;1737.1661602430904;1753.0708724375975;1826.196473551633;1827.3566032665433;62931.05603415953;63197.05204421983;63339.36660633381;63744.362556374304;65368.673156633915;9661.13049825505;9801.607935682492;9812.754681133028;10060.295779295526;10296.07335339823;13915.860841391457;13984.650383740285;14031.01782875189;14068.507602233873;14069;6918.169819621678;7120.359167674934;7224.313690199423;7298;7306.086739157606;6702.463802895757;6759.155105611799;6913.792586222421;6928.237893831674;6941.548799328028;4245.363195520635;4341.82632694695;4369.584889435499;4417.204903117406;4456.688031837805;1630.8776841736917;1654.7766051582882;1692.2046638080083;1713.306111025037;1714.2800023989955;383.1092709450539;385.21800744488735;397.69774971021656;830.2735106781566;884.429542944797;10374.17006639467;10496.422696751599;10526.947365263124;10649;10684.94657526723;382.142091005694;387.5001248389546;389.3964355249352;395.5253695565333;398.17776291957597;5659.717014149276;5663.122216056518;5769.6538207706835;9208.401453905475;10205.591776328863;361818;362674;362909;363504.9440832929;366633;48654.270185947236;48937.51062489329;49884.750576246886;78561.21438785653;79350;920.9769755756159;931.3759780946782;947.8957015077358;952.0574631115285;960.3469640644289;15511.922440387725;15585.454509092127;15609.843901560838;15834.60413489672;16103.597410064842;34866.302673946884;35976.64023359733;36136.81931590325;37676.2464750709;37884.24231515409;49194.50805492042;50814;50949.23576146286;51035.23447148222;51231;7191.6695411349065;7297.343239108293;7353.301436363537;7399.387292812269;7734.613269336398;6130.785422510191;9505;10668.146548275965;10803.567857285621;11425.857414258508;7966.442349035512;8332.375071869512;8623.353248506262;8693.695720649746;9045.90954090477;9767.902320976984;10013.949930250303;10111.848322275024;10124.797504050026;10189.133923616278;9108.726738197865;9185.494797786187;9199.172074513326;9400.05999400082;9413.058694130532;6837.589744615339;6995.195552511526;7024.824379390556;7261.346478816932;7266.745663901738;8909.242714369091;8979.640814367353;9055.864162037444;9180.495072770795;9364.578593963424 14:34:20 INFO - PROCESS | 16459 | |3;mootools.html;1250.8186312984612;1409.0770545292833;1423.9857601423985;1546.3891762753713;1707.8804483686147;6580.835479113024;7502.69989200432;7765.650545725442;7800.024996875391;7967.880481792778;7786.9610651946805;7829.6085195740125;7900.407469439786;7923.960380198091;7935.682572697099;2209.5139069404745;2239.428945618865;2239.7200349956256;2242.4730188405792;2242.551489702058;2135.935921922337;2137.861039032458;2138;2157.385145233609;2228.008536201391;2191.397365724423;2199.3621849663577;2206.746224184223;2208.2271205078173;2224.8887555622237;2110.0715685098507;2112.5563631637374;2117.8305735541276;2118.6821976703463;2119.1205649655453;2125.8618189817767;2181.073043956312;2182.694422780812;2184.4757258258064;2184.9781502184933;7461.253874612549;7732.381409487228;7800.765977020698;7803.258690424401;7821.608919554056;4359.760213188273;4465.68740188187;4476.820927162909;4479.59683628475;4481.820727170909;2802.8738706758245;2869.741723244897;2919.693432189632;2947.82313061217;2953.3650265193087;857.0229937870832;874.3748220022715;883.3904605821963;887.1217494680285;896.3501461440469;10032;10048.196144308511;10069.244806639532;10126.189904807672;10133.797324053552;3983.3029219886403;4080.9387859182134;4116.855910043134;4117.444145040381;4155.2728272552185;7927.8810817837775;7984;8008.79978000543;8012.399070069651;8014;4482.036362182146;4483.036147228361;4490.932636010462;4494.348319493655;4495.213337665896;3446.724262059029;4034;4115.382692596075;4131.5455299916985;4305.74165550068;3433.484977253382;3461.4115600348;3527.9118022049656;3552.9111772205383;3561.3055454186183;1033.261218228955;1037.9221558383151;1039.5114296280735;1042.0933787604831;1051.5005372448027;752.6387334079673;761.7905076104116;761.7905076104116;840.995795021021;891.9063498332582;5197.610179236573;5255.421903590602;5299.072662284085;5364.570834333244;5413.9729301353245;790.5059437662821;816.7549735079452;840.2605706977943;871.8082021955287;905.9456432614064;3540.6105328413855;3637.8726744563814;3669.1010702377553;3696.057505336176;3757.8496860125833;9056.637734490678;9411.670591529264;9413.341066125306;9427.3400861939;9446;9317.068293170765;9424.104710052534;9439.76400589977;9454.810903782023;9460.526973651427;7010.439164866799;7267.818304542428;7268.927310726931;7272.672729727175;7274.890876636855;4846.53790304939;5000.2499625056535;5058.215976523645;5296.126139186991;5356.544693701079;8669.436486628349;8768.386212965032;8877.156670116328;8895;8896.243819275434;8621.224089831945;8880.422772519765;8993.865092023494;9000.81998360016;9006.324525660471;5817.127430885401;6311;6478.449331806661;6532.706028228646;6645.269020407654;7261.745838895613;7266.273372662691;7330.047093877762;7339.9633001834645;7343.37581305595;6307.180066591313;6365.745370185326;6386.87226255463;6459.192600924885;6469.870602587827;5984.850378740566;6105.236845394325;6306.4639505642535;6473.158489396348;6555.934440655532;6007.609505382136;6023.3374328824675;6062.818115456544;6071.544634152545;6079.817605471843;4468.824064274779;4610.723356598614;4662.300654901792;4663.393758811333;4671.532846715301;4330;4331.631811296075;4393.714408563433;4644.9767751161025;4712 14:34:20 INFO - PROCESS | 16459 | |4;prototype.html;290.84730516478845;292.9531274996001;297.48683520926403;303.4431817614677;305.3435114503815;4785.282207668847;4805.669250413546;4813.759312034397;4830.589399901004;4844.577711144428;4729.692569982949;4756.7621618919;4769.379980602525;4778.641601879855;4786.138495070886;3527.7001454876367;3651.608196631211;3655.689266412358;3751.737378383514;3759.9060023499496;3715.18265981485;3716.498272733187;3721.38597131473;3733.45864849598;3739.9626003739886;3500.597431295408;3686.2996030754207;3689.9631003690024;3707.7775333479944;3708.2398108387715;3464.255185135208;3603.657652522993;3605.4952306677087;3610.765300255488;3652.7443078984484;3229.917977477548;3261.331427057471;3298.093024418304;3299.2741596848655;3304.273059926836;4515.780739200431;4531.116432295704;4543.931841022388;4572.702774319659;4636.9536304637195;3955.4264631628253;4186.979065104685;4192.350185721188;4266.871993840159;4271.914561708749;4943.233798761198;4947.406311242638;4970.304157417964;4971.477994810565;5001.824936127218;3400;3490.5636795400574;3515.929681406383;3518.20840310928;3532.540769699948;5174.741262936838;5219.5041471060185;5235.188545775372;5245.423003469654;5248.81629142982;3548.1307079765625;3553.964460355415;3556.8577256909953;3580.445031020144;3581.659742324475;3598;3619.5475565554307;3622.293652737691;3622.746407751432;3625.057485053905;3960.2277555876904;4139.379093135994;4151.418801367811;4174.812133454002;4197.916041679149;4929.408470983505;4946.183879659816;4965;4971.130052240844;4977.626677999092;1240.2868350698204;1242.19257482637;1252.3738130934532;1253.1228140301826;1255.3095797311441;761.8133557278502;783.455498428598;799.1808396393627;805.1706742055575;806.9636866341028;4343.956560434419;4393.1872603568445;4393.6704747144095;4450.755208463501;4508.639308855284;5146.433892271847;5150.948490515122;5153.974230128901;5158.406783219978;5167.896642067138;4476.171908196994;4479.977600112044;4484.304932735431;4486.801467281009;4495.527969563214;2599.7910971398014;2739.6164536964843;2743.6296100026243;2769.8338099714083;2769.9307517312227;4857.854264372075;4862.495814655455;4900.975495122573;4955.876103097451;4976;5028.698278103326;5254.816081437131;5270.736463176826;5273.2617433559335;5275.604329675213;4953.157963146202;4967.850964471072;4981.850544483599;4982.551570358685;4983.451820299764;5173.456787037307;5210.791568337376;5216.739163041757;5217.660852044605;5223.138182200047;4854.9514504855915;5074.82238121664;5103.209002604603;5106.948930510647;5124.180915774995;4942.8764280893265;4945.184044632595;4948.307236986752;4960.379952505937;4969.527894850128;3597.7661452005536;3620.9094772630897;3631.49159117729;3636;3640.890773276806;5092.618053645917;5194.558462530576;5197.350331208599;5224.346956630421;5253;5055.191169412877;5072.594192464668;5075.6193285502995;5082.796688132434;5085.237214417867;5064.18972964324;5072.695638261716;5078.365204349457;5084.618653600921;5086.694798312114;4683.718976861399;4927.679700819435;4974.651774375759;4980.277859710394;4987.027529631687;4792.496787837366;4889.3888263967;4999.150144475375;5032.974835125801;5078.162103252922;4870.561649451566;4928.67963582366;4933.58064564516;4935.703857768545;4970.40355157369 14:34:20 INFO - PROCESS | 16459 | |5;yui.html;579.8057650687019;597.8953683105456;598.5630489742488;667.5293917787958;730.6894569807832;11739.765204695912;12330.938345308283;12449.93775031126;12479.875201247985;12481.375931203438;12621.305828179446;12722.491100355976;12738.426770795313;12743.171693839893;12750.362481875893;620.3455354600894;767.6353731977295;780.7657702689213;802.1016461563029;806.681360862459;795.1174196641723;799.1968072087544;799.9200079992013;803.5058439059965;804.264098350009;841.2680967558233;843.472829481574;845.1337379186322;845.6532821543168;845.8350621628784;639.2201514152727;796.7291121018882;821.2485575698229;831.2560258568577;837.8826964224983;601.5398220361427;604.1783174882157;604.2839235505895;604.5133667397743;605.4187979539647;12698.936505317506;12703.936480317632;12724;12736.04479664029;12740.872591274061;11688;11713;11781.882181178249;11908.523659053628;12355.93822030884;11751.471183796664;11843.881561184364;11893.108016898681;11954.820677689842;12046.939765301206;4043.3126368517424;4076.1236334187997;4138.0689344897155;4160.375943608453;4176.561461046607;58026;58550.70724646392;58569.7071514644;58837.705811471096;59782.99999999956;9045;9152.725418237464;9273.768155796159;9403.482808445464;9415.482148481899;16058.598535036484;17513.562160945825;17513.649727005642;17554;17590.560235994202;6964.616216699046;7001.054857594261;7125.5368401053765;7155.284471552803;7320.304571065741;3682.650148235913;3691.5201023866725;3725.5715592706642;3737.2712321097124;3881.4565960765517;2806.649168853893;2903.027485792283;2905.099419180061;2906.7238612331794;2910.1415082550698;534.9037173308842;537.9596530260246;545.2638937434432;552.5469115325475;553.2586334311961;488.2822251290597;508.6236185222908;563.1187192044438;580.8431723434649;589.2545929399313;7827.25641064098;7891.565963872042;7940.3450239238355;8000.919990800134;8069.435139540175;579.8202557207279;582.2721598002497;597.7310210405248;604.2899592978321;606.3057798820327;3579.3736096183065;3804.2962052020466;3862.517185351831;3877.9806100969877;3880.2821478026653;80670;83460.58269708612;84234.15765842263;84372.57813711015;84393;49959.75020124876;63983;65372.019419707794;71050;79935.60032199918;68370.3162968374;69122;69532.3046769536;70081.29918700748;70360.6481967597;7273.127224733066;7283.271672832674;7456.217097204715;7460.962695186489;7524.592130518182;42680;42978.78510607427;43679.34480982724;44597;45486;35680.64319356773;47000.52999469962;61485;62395;63433.36566634275;10871.40207288575;10887.244559349412;10897.455127243447;10908;10982.780344392908;10438.895611043792;10566.260361774603;10693.679189624323;11119.16606254518;11161.107111430903;10651.360918344924;10767;10990.560377584807;11262.943685281522;11300.434978251218;9779.41323520591;10130.79738405213;10231.283810133516;10383.792324153626;10462.47687615601;9151.17639412456;9378.390404623678;9963.40219586798;9990.450525221182;10311.020453056948;8144.959275203586;8274.29668478186;8291.378146638906;8393.076761556102;8609;7954.721584744506;8020.879686804819;8115.715949941724;8331.29184019341;8406.369522285731 14:34:20 INFO - PROCESS | 16459 | __end_tp_report 14:34:20 INFO - PROCESS | 16459 | __start_cc_report 14:34:20 INFO - PROCESS | 16459 | _x_x_mozilla_cycle_collect,975 14:34:20 INFO - PROCESS | 16459 | __end_cc_report 14:34:20 INFO - PROCESS | 16459 | __startTimestamp1464816860414__endTimestamp 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | ------- Summary: start ------- 14:34:20 INFO - PROCESS | 16459 | Number of tests: 6 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | [#0] dojo.html Cycles:165 Average:4708.15 Median:4871.22 stddev:506.04 (10.4%) stddev-sans-first:481.15 14:34:20 INFO - PROCESS | 16459 | Values: 2649.9 2668.6 2695.2 2719.4 2723.7 4572.9 4689.2 4699.2 4761.6 4797.9 4694.6 4707.3 4741.7 4806.7 4810.4 4255.7 4286.5 4356.4 4375.4 4517.9 4441.6 4463.9 4472.2 4497.2 4520.0 4231.9 4291.5 4301.4 4359.4 4377.6 4307.7 4313.3 4322.6 4411.3 4419.3 4336.1 4340.5 4350.4 4379.2 4394.1 4536.0 4603.4 4621.4 4712.6 4716.6 5089.5 5155.2 5155.3 5157.1 5160.4 5095.4 5177.8 5185.7 5186.8 5195.8 4612.1 4690.4 4731.5 4835.8 4844.0 5174.1 5244.4 5248.0 5249.6 5251.4 4985.0 4991.9 5022.9 5041.7 5047.1 4902.3 5015.8 5046.4 5064.0 5073.3 5032.2 5037.3 5041.8 5044.9 5061.8 5018.9 5021.9 5022.3 5027.3 5077.5 4086.0 4120.6 4121.1 4234.2 4284.7 3501.5 3678.3 3693.9 3706.4 3746.8 4415.5 4427.9 4429.0 4464.8 4473.8 5077.6 5082.1 5084.5 5086.1 5139.7 4339.7 4446.8 4489.2 4492.3 4521.2 4586.0 4589.1 4592.1 4594.2 4641.5 4589.7 4871.1 4927.6 4930.6 4933.2 4871.2 5075.6 5132.4 5145.3 5145.9 4843.2 4872.5 4874.5 4877.0 4926.1 4857.3 5054.9 5059.1 5061.0 5122.6 5081.4 5127.2 5146.7 5154.0 5160.1 4992.3 4994.8 4999.0 5001.6 5055.0 4764.5 4939.6 4997.4 5013.5 5016.3 4921.0 4966.7 4985.8 5013.2 5018.9 5052.9 5061.7 5103.2 5111.2 5114.8 4789.8 4852.4 4915.8 4946.9 4960.8 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | [#1] ext.html Cycles:180 Average:46299.43 Median:208534.81 stddev:78789.85 (37.8%) stddev-sans-first:78935.98 14:34:20 INFO - PROCESS | 16459 | Values: 546.8 574.6 596.3 602.9 607.6 27826.4 40198.6 41529.0 41583.6 41621.2 39210.6 39634.4 39707.2 39740.0 39751.0 7666.6 7803.7 7816.3 7817.5 7820.1 7815.6 7942.4 7954.8 7959.1 7973.8 7951.2 7972.2 7980.4 8003.7 8003.7 7889.1 7940.4 7952.4 7954.1 7962.3 7826.7 7920.6 7934.2 7940.7 7943.7 39533.2 39955.0 39976.6 40011.8 40022.6 3313.7 3317.2 3318.7 3386.9 3547.4 29388.3 29572.4 29580.9 29647.6 29886.3 3299.7 3389.9 3398.3 3404.3 3421.9 261057.0 265814.0 265897.0 266057.0 266544.7 55718.0 55867.0 55991.0 56019.0 56094.7 59758.7 59809.7 59853.4 59866.7 59953.1 14319.6 14764.3 14786.1 14803.9 14809.6 11630.4 11867.3 11875.9 11876.0 11893.9 17154.3 17704.7 17797.5 17822.7 17852.3 6274.8 6329.1 6343.3 6487.0 6509.5 809.9 869.2 898.9 916.5 925.0 28527.7 28554.4 28613.0 28865.9 29401.4 766.2 947.5 965.6 1015.9 1057.0 14899.5 14943.0 14959.6 15110.2 15169.5 375948.0 376284.0 377962.0 378612.0 379131.0 50268.0 50351.2 50410.7 50473.2 50665.7 1067.3 1074.1 1182.7 1186.2 1212.2 29032.9 29511.9 29825.0 30218.5 30331.1 43015.6 43470.8 43485.0 43511.3 43555.1 238922.0 239701.0 239867.0 240316.0 240808.0 37174.4 38962.8 38993.6 38999.2 39105.6 43134.6 43344.3 43662.0 44529.3 44626.3 37810.8 37849.4 37938.6 38015.8 38340.2 35629.6 36631.8 37083.8 37375.8 37473.0 36446.0 36554.6 36611.5 37330.8 37422.6 31860.2 32922.5 32927.7 33394.0 33434.7 25284.2 25558.6 25752.9 25789.1 26260.3 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | [#2] jquery.html Cycles:180 Average:22566.21 Median:26751.28 stddev:59874.68 (223.8%) stddev-sans-first:60019.42 14:34:20 INFO - PROCESS | 16459 | Values: 357.1 357.4 363.3 373.4 374.4 15578.1 16744.6 16847.0 17167.5 17409.5 15610.8 15861.9 16634.0 16663.4 16705.2 5025.1 5120.7 5221.1 5225.2 5225.2 3745.0 3928.6 5109.3 5670.8 6029.5 6035.7 6074.0 6075.1 6090.5 6091.9 5899.2 6001.3 6084.5 6089.5 6092.4 3371.4 4848.3 5208.0 5217.7 5277.0 19448.0 20123.0 20189.5 20382.6 20493.8 1946.5 2045.3 2256.1 2301.7 2309.8 8527.2 12052.5 12698.7 13014.8 13156.3 1712.8 1737.2 1753.1 1826.2 1827.4 62931.1 63197.1 63339.4 63744.4 65368.7 9661.1 9801.6 9812.8 10060.3 10296.1 13915.9 13984.7 14031.0 14068.5 14069.0 6918.2 7120.4 7224.3 7298.0 7306.1 6702.5 6759.2 6913.8 6928.2 6941.5 4245.4 4341.8 4369.6 4417.2 4456.7 1630.9 1654.8 1692.2 1713.3 1714.3 383.1 385.2 397.7 830.3 884.4 10374.2 10496.4 10526.9 10649.0 10684.9 382.1 387.5 389.4 395.5 398.2 5659.7 5663.1 5769.7 9208.4 10205.6 361818.0 362674.0 362909.0 363504.9 366633.0 48654.3 48937.5 49884.8 78561.2 79350.0 921.0 931.4 947.9 952.1 960.3 15511.9 15585.5 15609.8 15834.6 16103.6 34866.3 35976.6 36136.8 37676.2 37884.2 49194.5 50814.0 50949.2 51035.2 51231.0 7191.7 7297.3 7353.3 7399.4 7734.6 6130.8 9505.0 10668.1 10803.6 11425.9 7966.4 8332.4 8623.4 8693.7 9045.9 9767.9 10013.9 10111.8 10124.8 10189.1 9108.7 9185.5 9199.2 9400.1 9413.1 6837.6 6995.2 7024.8 7261.3 7266.7 8909.2 8979.6 9055.9 9180.5 9364.6 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | [#3] mootools.html Cycles:180 Average:4995.72 Median:5297.60 stddev:2765.31 (52.2%) stddev-sans-first:2758.75 14:34:20 INFO - PROCESS | 16459 | Values: 1250.8 1409.1 1424.0 1546.4 1707.9 6580.8 7502.7 7765.7 7800.0 7967.9 7787.0 7829.6 7900.4 7924.0 7935.7 2209.5 2239.4 2239.7 2242.5 2242.6 2135.9 2137.9 2138.0 2157.4 2228.0 2191.4 2199.4 2206.7 2208.2 2224.9 2110.1 2112.6 2117.8 2118.7 2119.1 2125.9 2181.1 2182.7 2184.5 2185.0 7461.3 7732.4 7800.8 7803.3 7821.6 4359.8 4465.7 4476.8 4479.6 4481.8 2802.9 2869.7 2919.7 2947.8 2953.4 857.0 874.4 883.4 887.1 896.4 10032.0 10048.2 10069.2 10126.2 10133.8 3983.3 4080.9 4116.9 4117.4 4155.3 7927.9 7984.0 8008.8 8012.4 8014.0 4482.0 4483.0 4490.9 4494.3 4495.2 3446.7 4034.0 4115.4 4131.5 4305.7 3433.5 3461.4 3527.9 3552.9 3561.3 1033.3 1037.9 1039.5 1042.1 1051.5 752.6 761.8 761.8 841.0 891.9 5197.6 5255.4 5299.1 5364.6 5414.0 790.5 816.8 840.3 871.8 905.9 3540.6 3637.9 3669.1 3696.1 3757.8 9056.6 9411.7 9413.3 9427.3 9446.0 9317.1 9424.1 9439.8 9454.8 9460.5 7010.4 7267.8 7268.9 7272.7 7274.9 4846.5 5000.2 5058.2 5296.1 5356.5 8669.4 8768.4 8877.2 8895.0 8896.2 8621.2 8880.4 8993.9 9000.8 9006.3 5817.1 6311.0 6478.4 6532.7 6645.3 7261.7 7266.3 7330.0 7340.0 7343.4 6307.2 6365.7 6386.9 6459.2 6469.9 5984.9 6105.2 6306.5 6473.2 6555.9 6007.6 6023.3 6062.8 6071.5 6079.8 4468.8 4610.7 4662.3 4663.4 4671.5 4330.0 4331.6 4393.7 4645.0 4712.0 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | [#4] prototype.html Cycles:180 Average:4149.66 Median:4785.71 stddev:1228.37 (25.7%) stddev-sans-first:1197.18 14:34:20 INFO - PROCESS | 16459 | Values: 290.8 293.0 297.5 303.4 305.3 4785.3 4805.7 4813.8 4830.6 4844.6 4729.7 4756.8 4769.4 4778.6 4786.1 3527.7 3651.6 3655.7 3751.7 3759.9 3715.2 3716.5 3721.4 3733.5 3740.0 3500.6 3686.3 3690.0 3707.8 3708.2 3464.3 3603.7 3605.5 3610.8 3652.7 3229.9 3261.3 3298.1 3299.3 3304.3 4515.8 4531.1 4543.9 4572.7 4637.0 3955.4 4187.0 4192.4 4266.9 4271.9 4943.2 4947.4 4970.3 4971.5 5001.8 3400.0 3490.6 3515.9 3518.2 3532.5 5174.7 5219.5 5235.2 5245.4 5248.8 3548.1 3554.0 3556.9 3580.4 3581.7 3598.0 3619.5 3622.3 3622.7 3625.1 3960.2 4139.4 4151.4 4174.8 4197.9 4929.4 4946.2 4965.0 4971.1 4977.6 1240.3 1242.2 1252.4 1253.1 1255.3 761.8 783.5 799.2 805.2 807.0 4344.0 4393.2 4393.7 4450.8 4508.6 5146.4 5150.9 5154.0 5158.4 5167.9 4476.2 4480.0 4484.3 4486.8 4495.5 2599.8 2739.6 2743.6 2769.8 2769.9 4857.9 4862.5 4901.0 4955.9 4976.0 5028.7 5254.8 5270.7 5273.3 5275.6 4953.2 4967.9 4981.9 4982.6 4983.5 5173.5 5210.8 5216.7 5217.7 5223.1 4855.0 5074.8 5103.2 5106.9 5124.2 4942.9 4945.2 4948.3 4960.4 4969.5 3597.8 3620.9 3631.5 3636.0 3640.9 5092.6 5194.6 5197.4 5224.3 5253.0 5055.2 5072.6 5075.6 5082.8 5085.2 5064.2 5072.7 5078.4 5084.6 5086.7 4683.7 4927.7 4974.7 4980.3 4987.0 4792.5 4889.4 4999.2 5033.0 5078.2 4870.6 4928.7 4933.6 4935.7 4970.4 14:34:20 INFO - PROCESS | 16459 | 14:34:20 INFO - PROCESS | 16459 | [#5] yui.html Cycles:180 Average:15997.84 Median:29566.49 stddev:22118.15 (74.8%) stddev-sans-first:22149.90 14:34:20 INFO - PROCESS | 16459 | Values: 579.8 597.9 598.6 667.5 730.7 11739.8 12330.9 12449.9 12479.9 12481.4 12621.3 12722.5 12738.4 12743.2 12750.4 620.3 767.6 780.8 802.1 806.7 795.1 799.2 799.9 803.5 804.3 841.3 843.5 845.1 845.7 845.8 639.2 796.7 821.2 831.3 837.9 601.5 604.2 604.3 604.5 605.4 12698.9 12703.9 12724.0 12736.0 12740.9 11688.0 11713.0 11781.9 11908.5 12355.9 11751.5 11843.9 11893.1 11954.8 12046.9 4043.3 4076.1 4138.1 4160.4 4176.6 58026.0 58550.7 58569.7 58837.7 59783.0 9045.0 9152.7 9273.8 9403.5 9415.5 16058.6 17513.6 17513.6 17554.0 17590.6 6964.6 7001.1 7125.5 7155.3 7320.3 3682.7 3691.5 3725.6 3737.3 3881.5 2806.6 2903.0 2905.1 2906.7 2910.1 534.9 538.0 545.3 552.5 553.3 488.3 508.6 563.1 580.8 589.3 7827.3 7891.6 7940.3 8000.9 8069.4 579.8 582.3 597.7 604.3 606.3 3579.4 3804.3 3862.5 3878.0 3880.3 80670.0 83460.6 84234.2 84372.6 84393.0 49959.8 63983.0 65372.0 71050.0 79935.6 68370.3 69122.0 69532.3 70081.3 70360.6 7273.1 7283.3 7456.2 7461.0 7524.6 42680.0 42978.8 43679.3 44597.0 45486.0 35680.6 47000.5 61485.0 62395.0 63433.4 10871.4 10887.2 10897.5 10908.0 10982.8 10438.9 10566.3 10693.7 11119.2 11161.1 10651.4 10767.0 10990.6 11262.9 11300.4 9779.4 10130.8 10231.3 10383.8 10462.5 9151.2 9378.4 9963.4 9990.5 10311.0 8145.0 8274.3 8291.4 8393.1 8609.0 7954.7 8020.9 8115.7 8331.3 8406.4 14:34:20 INFO - PROCESS | 16459 | -------- Summary: end -------- 14:34:20 INFO - PROCESS | 16459 | 14:34:21 INFO - TEST-INFO | 16459: exit 0 14:34:21 INFO - TEST-OK | dromaeo_css | took 1118752ms 14:34:21 INFO - TEST-START | kraken 14:34:21 INFO - operating with platform_type : linux_ 14:34:21 INFO - Initialising browser for kraken test... 14:34:21 INFO - TEST-INFO | started process 16638 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsZWYCy/profile http://localhost:39238/getInfo.html) 14:34:25 INFO - PROCESS | 16638 | __metrics Screen width/height:1600/1200 14:34:25 INFO - PROCESS | 16638 | colorDepth:24 14:34:25 INFO - PROCESS | 16638 | Browser inner width/height: 1024/697 14:34:25 INFO - PROCESS | 16638 | __metrics 14:34:26 ERROR - PROCESS | 16638 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:34:27 INFO - TEST-INFO | 16638: exit 0 14:34:27 INFO - Browser initialized. 14:34:27 INFO - Running cycle 1/1 for kraken test... 14:34:27 INFO - TEST-INFO | started process 16718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsZWYCy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:39 INFO - PROCESS | 16718 | RSS: Main: 157859840 14:34:39 INFO - PROCESS | 16718 | 14:34:42 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39238/tests/kraken/driver.html?testName=audio-beat-detection) 14:34:43 INFO - PROCESS | 16718 | RSS: Main: 215343104 14:34:43 INFO - PROCESS | 16718 | 14:34:45 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39238/tests/kraken/driver.html?testName=audio-dft) 14:34:46 INFO - PROCESS | 16718 | RSS: Main: 201248768 14:34:46 INFO - PROCESS | 16718 | 14:34:49 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39238/tests/kraken/driver.html?testName=audio-fft) 14:34:50 INFO - PROCESS | 16718 | RSS: Main: 270753792 14:34:50 INFO - PROCESS | 16718 | 14:34:52 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39238/tests/kraken/driver.html?testName=audio-oscillator) 14:34:53 INFO - PROCESS | 16718 | RSS: Main: 180936704 14:34:53 INFO - PROCESS | 16718 | 14:34:55 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:34:55 INFO - PROCESS | 16718 | RSS: Main: 185241600 14:34:55 INFO - PROCESS | 16718 | 14:34:59 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39238/tests/kraken/driver.html?testName=imaging-darkroom) 14:34:59 INFO - PROCESS | 16718 | RSS: Main: 221188096 14:34:59 INFO - PROCESS | 16718 | 14:35:03 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39238/tests/kraken/driver.html?testName=imaging-desaturate) 14:35:03 INFO - PROCESS | 16718 | RSS: Main: 217223168 14:35:03 INFO - PROCESS | 16718 | 14:35:06 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39238/tests/kraken/driver.html?testName=json-parse-financial) 14:35:07 INFO - PROCESS | 16718 | RSS: Main: 219533312 14:35:07 INFO - PROCESS | 16718 | 14:35:09 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:35:09 INFO - PROCESS | 16718 | RSS: Main: 205230080 14:35:09 INFO - PROCESS | 16718 | 14:35:11 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:35:12 INFO - PROCESS | 16718 | RSS: Main: 208961536 14:35:12 INFO - PROCESS | 16718 | 14:35:14 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:35:15 INFO - PROCESS | 16718 | RSS: Main: 216236032 14:35:15 INFO - PROCESS | 16718 | 14:35:17 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:35:18 INFO - PROCESS | 16718 | RSS: Main: 223551488 14:35:18 INFO - PROCESS | 16718 | 14:35:21 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:35:22 INFO - PROCESS | 16718 | RSS: Main: 225193984 14:35:22 INFO - PROCESS | 16718 | 14:35:23 INFO - PROCESS | 16718 | Cycle 1(1): loaded http://localhost:39238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39238/tests/kraken/driver.html?testName=ai-astar) 14:35:24 INFO - PROCESS | 16718 | RSS: Main: 267587584 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | __start_tp_report 14:35:24 INFO - PROCESS | 16718 | _x_x_mozilla_page_load 14:35:24 INFO - PROCESS | 16718 | _x_x_mozilla_page_load_details 14:35:24 INFO - PROCESS | 16718 | |i|pagename|runs| 14:35:24 INFO - PROCESS | 16718 | |0;ai-astar;116;110;115;115;114;113;113;113;114;114 14:35:24 INFO - PROCESS | 16718 | |1;audio-beat-detection;166;130;132;186;131;132;150;137;190;129 14:35:24 INFO - PROCESS | 16718 | |2;audio-dft;160;160;162;161;161;161;161;161;163;161 14:35:24 INFO - PROCESS | 16718 | |3;audio-fft;75;77;76;102;76;76;73;75;98;74 14:35:24 INFO - PROCESS | 16718 | |4;audio-oscillator;85;85;113;84;84;84;123;85;85;133 14:35:24 INFO - PROCESS | 16718 | |5;imaging-gaussian-blur;117;117;117;116;118;117;117;117;117;117 14:35:24 INFO - PROCESS | 16718 | |6;imaging-darkroom;146;147;146;147;148;147;146;148;147;146 14:35:24 INFO - PROCESS | 16718 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;96;95 14:35:24 INFO - PROCESS | 16718 | |8;json-parse-financial;62;62;64;61;63;63;63;62;62;64 14:35:24 INFO - PROCESS | 16718 | |9;json-stringify-tinderbox;65;65;65;64;65;66;65;66;64;65 14:35:24 INFO - PROCESS | 16718 | |10;stanford-crypto-aes;84;89;86;83;87;85;84;84;124;83 14:35:24 INFO - PROCESS | 16718 | |11;stanford-crypto-ccm;150;136;140;141;135;142;149;133;151;134 14:35:24 INFO - PROCESS | 16718 | |12;stanford-crypto-pbkdf2;178;170;171;175;172;174;174;171;172;173 14:35:24 INFO - PROCESS | 16718 | |13;stanford-crypto-sha256-iterative;67;68;67;72;71;66;66;68;66;69 14:35:24 INFO - PROCESS | 16718 | __end_tp_report 14:35:24 INFO - PROCESS | 16718 | __start_cc_report 14:35:24 INFO - PROCESS | 16718 | _x_x_mozilla_cycle_collect,1911 14:35:24 INFO - PROCESS | 16718 | __end_cc_report 14:35:24 INFO - PROCESS | 16718 | __startTimestamp1464816924150__endTimestamp 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | ------- Summary: start ------- 14:35:24 INFO - PROCESS | 16718 | Number of tests: 14 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:1.64 (1.4%) stddev-sans-first:1.51 14:35:24 INFO - PROCESS | 16718 | Values: 116.0 110.0 115.0 115.0 114.0 113.0 113.0 113.0 114.0 114.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:143.50 stddev:23.90 (16.7%) stddev-sans-first:24.48 14:35:24 INFO - PROCESS | 16718 | Values: 166.0 130.0 132.0 186.0 131.0 132.0 150.0 137.0 190.0 129.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 14:35:24 INFO - PROCESS | 16718 | Values: 160.0 160.0 162.0 161.0 161.0 161.0 161.0 161.0 163.0 161.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#3] audio-fft Cycles:10 Average:80.20 Median:76.00 stddev:10.54 (13.9%) stddev-sans-first:11.01 14:35:24 INFO - PROCESS | 16718 | Values: 75.0 77.0 76.0 102.0 76.0 76.0 73.0 75.0 98.0 74.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.50 stddev:19.16 (22.7%) stddev-sans-first:19.89 14:35:24 INFO - PROCESS | 16718 | Values: 85.0 85.0 113.0 84.0 84.0 84.0 123.0 85.0 85.0 133.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 14:35:24 INFO - PROCESS | 16718 | Values: 117.0 117.0 117.0 116.0 118.0 117.0 117.0 117.0 117.0 117.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 14:35:24 INFO - PROCESS | 16718 | Values: 146.0 147.0 146.0 147.0 148.0 147.0 146.0 148.0 147.0 146.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 14:35:24 INFO - PROCESS | 16718 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 14:35:24 INFO - PROCESS | 16718 | Values: 62.0 62.0 64.0 61.0 63.0 63.0 63.0 62.0 62.0 64.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 14:35:24 INFO - PROCESS | 16718 | Values: 65.0 65.0 65.0 64.0 65.0 66.0 65.0 66.0 64.0 65.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:84.50 stddev:12.48 (14.8%) stddev-sans-first:13.11 14:35:24 INFO - PROCESS | 16718 | Values: 84.0 89.0 86.0 83.0 87.0 85.0 84.0 84.0 124.0 83.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:141.50 stddev:6.84 (4.8%) stddev-sans-first:6.45 14:35:24 INFO - PROCESS | 16718 | Values: 150.0 136.0 140.0 141.0 135.0 142.0 149.0 133.0 151.0 134.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.50 stddev:2.36 (1.4%) stddev-sans-first:1.67 14:35:24 INFO - PROCESS | 16718 | Values: 178.0 170.0 171.0 175.0 172.0 174.0 174.0 171.0 172.0 173.0 14:35:24 INFO - PROCESS | 16718 | 14:35:24 INFO - PROCESS | 16718 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:2.11 (3.1%) stddev-sans-first:2.20 14:35:24 INFO - PROCESS | 16718 | Values: 67.0 68.0 67.0 72.0 71.0 66.0 66.0 68.0 66.0 69.0 14:35:24 INFO - PROCESS | 16718 | -------- Summary: end -------- 14:35:24 INFO - PROCESS | 16718 | 14:35:25 INFO - TEST-INFO | 16718: exit 0 14:35:25 INFO - TEST-OK | kraken | took 63966ms 14:35:25 INFO - SUITE-END | took 1182s 14:35:25 INFO - Completed test suite (00:19:42) 14:35:25 INFO - javascript benchmark 14:35:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2649.9470010599784, 2668.6397336359587, 2695.1779707189307, 2719.442514284573, 2723.6595425571804, 4572.862814115573, 4689.155951928651, 4699.224627936391, 4761.642876784238, 4797.856064318072, 4694.554017368353, 4707.317438971347, 4741.691790033646, 4806.735629540374, 4810.422749270093, 4255.744655320683, 4286.549912259215, 4356.433663623725, 4375.409319741826, 4517.864464066083, 4441.622462090726, 4463.933041004388, 4472.195004899117, 4497.235469970113, 4519.954800451987, 4231.894202644928, 4291.549387314319, 4301.376300436419, 4359.411479450266, 4377.562243775629, 4307.698461107724, 4313.309870420718, 4322.610965013164, 4411.316245981878, 4419.292913133915, 4336.089421221547, 4340.47914250292, 4350.434443522354, 4379.189849877751, 4394.07724377881, 4535.977320113411, 4603.424571928509, 4621.445426548802, 4712.552307530779, 4716.622670186377, 5089.54194122527, 5155.175171972504, 5155.2524883892, 5157.149070403379, 5160.432352441266, 5095.414027386861, 5177.844664660029, 5185.740712964337, 5186.844394668166, 5195.766190521435, 4612.146752850734, 4690.390249267642, 4731.503192164842, 4835.830745923911, 4843.975780121077, 5174.094533456705, 5244.449332820075, 5248.029114613809, 5249.632525723239, 5251.36983561975, 4984.950150498521, 4991.900161996812, 5022.924656130161, 5041.7227052511735, 5047.141985862412, 4902.28916807061, 5015.84952451427, 5046.369203849519, 5064.0, 5073.315102461121, 5032.169692000852, 5037.269595908646, 5041.773120209599, 5044.949550504448, 5061.848144555669, 5018.949810501921, 5021.949780502221, 5022.3219865317715, 5027.271045698426, 5077.492250774893, 4086.0, 4120.62914337711, 4121.052158003616, 4234.216669916075, 4284.700070995001, 3501.509788629594, 3678.282734866675, 3693.8891833245048, 3706.425504046877, 3746.8126593670754, 4415.492218394861, 4427.867163985085, 4429.047754732748, 4464.821407143678, 4473.753943533072, 5077.568406685473, 5082.1360368737405, 5084.491550844886, 5086.05907907031, 5139.665921715151, 4339.652827773771, 4446.82212711488, 4489.169503641771, 4492.258777301774, 4521.208788462006, 4585.977070114628, 4589.0821835633005, 4592.10453961481, 4594.1730488512385, 4641.466231383434, 4589.701669391479, 4871.14754917888, 4927.556519913225, 4930.605551555938, 4933.210686290176, 4871.2206047032305, 5075.568576671024, 5132.358455193101, 5145.305383773143, 5145.948540514697, 4843.24929635907, 4872.4883887192755, 4874.488178741181, 4876.975615121902, 4926.137925862888, 4857.319975203403, 5054.924176137288, 5059.06407314648, 5060.974695126501, 5122.564582010571, 5081.390233172043, 5127.1540195867265, 5146.6911985281, 5154.0, 5160.09698302803, 4992.301077849032, 4994.825181118644, 4998.950010499849, 5001.624878134082, 5054.974725126351, 4764.475907650225, 4939.604831613534, 4997.400311962588, 5013.498650134958, 5016.272640467038, 4920.950790492192, 4966.701997879994, 4985.75071246429, 5013.222950442687, 5018.899622007612, 5052.924206136838, 5061.7469126544265, 5103.18349064148, 5111.156659151198, 5114.769835357343, 4789.7605119743175, 4852.393450818648, 4915.778789954532, 4946.901061978812, 4960.826371076995], "value": 4675.558900605267, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.7648910968285, 574.597781552913, 596.2517041113402, 602.8824379246049, 607.6050567131364, 27826.443471130566, 40198.59801401985, 41529.0, 41583.58416415835, 41621.167576648484, 39210.60789392105, 39634.40548391776, 39707.205855882865, 39740.0, 39751.0, 7666.616669166548, 7803.726869559567, 7816.335611473031, 7817.491863028913, 7820.061592608895, 7815.609219539028, 7942.3646108311195, 7954.801129971768, 7959.0847052589115, 7973.760787176394, 7951.204879512032, 7972.1629228931, 7980.3615710743, 8003.679852805881, 8003.719869804529, 7889.132195458495, 7940.444168908208, 7952.403569732293, 7954.125046244909, 7962.283394494465, 7826.686932522693, 7920.6039698014865, 7934.246246606564, 7940.682372705085, 7943.721969731089, 39533.209335813124, 39955.0, 39976.60023399758, 40011.79994100011, 40022.59977400247, 3313.6520665330277, 3317.1872891141584, 3318.668133186662, 3386.86452541898, 3547.379208638478, 29388.265293367836, 29572.40855182906, 29580.852095739385, 29647.55528667072, 29886.252843678863, 3299.686529779667, 3389.881354152593, 3398.3203359327986, 3404.2510647657477, 3421.8802341917913, 261057.0, 265814.0, 265897.0, 266057.0, 266544.6672766643, 55718.0, 55867.0, 55991.0, 56019.0, 56094.71952640211, 59758.70120649456, 59809.70095149583, 59853.401465985655, 59866.70066649639, 59953.10539287951, 14319.642008949859, 14764.261786910827, 14786.112833229825, 14803.851961480463, 14809.555713328617, 11630.418479076012, 11867.287962722265, 11875.88124118765, 11876.0, 11893.940530297294, 17154.31382744701, 17704.734428983575, 17797.46607601748, 17822.732659010126, 17852.285908563514, 6274.780382686584, 6329.145565348711, 6343.302236753953, 6487.0, 6509.511786616023, 809.8623234050226, 869.1786261982493, 898.9460632362079, 916.4730280088976, 925.0343021080205, 28527.714722852506, 28554.42891142207, 28613.0, 28865.85567072151, 29401.411971760444, 766.2452484302958, 947.4788866123605, 965.6282331302359, 1015.8730158730159, 1056.984145237822, 14899.47851825181, 14943.0, 14959.626009349853, 15110.244487775568, 15169.544913652608, 375948.0, 376284.0, 377962.0, 378612.0, 379131.0, 50268.0, 50351.24473132906, 50410.747946260766, 50473.24290135651, 50665.746671267145, 1067.2849191042021, 1074.0601973273385, 1182.7102359921873, 1186.1993154620768, 1212.1514939542355, 29032.854835725688, 29511.852440737657, 29825.0, 30218.54672179963, 30331.090067298017, 43015.56984430116, 43470.78264608657, 43485.0, 43511.347329789445, 43555.12889742251, 238922.0, 239701.0, 239867.0, 240316.0, 240808.0, 37174.44238336481, 38962.80518597389, 38993.610063898996, 38999.2200156001, 39105.608943911335, 43134.56865431306, 43344.349834751876, 43662.0, 44529.332060018474, 44626.330605040304, 37810.81094594619, 37849.4322585156, 37938.62061379351, 38015.80992095022, 38340.23319533649, 35629.64370356263, 36631.81684091563, 37083.814580926926, 37375.813120934225, 37473.0, 36446.0, 36554.634453655126, 36611.45082823813, 37330.81334593309, 37422.625773741915, 31860.203494912814, 32922.48262678917, 32927.67072329342, 33394.0, 33434.66565334315, 25284.241472755846, 25558.616620751076, 25752.871235643703, 25789.097381591553, 26260.343491412867], "value": 17868.483824262872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.1464200560659, 357.4120571659621, 363.30245927818584, 373.42306137018323, 374.3766628563442, 15578.143202123878, 16744.581385465368, 16847.0, 17167.484975450756, 17409.47771566852, 15610.76583851243, 15861.92069039656, 16634.0, 16663.41678041266, 16705.248263828125, 5025.120603894322, 5120.692758434488, 5221.112410890139, 5225.163973764198, 5225.216217567377, 3745.0262931637835, 3928.5874983126787, 5109.310243117187, 5670.773169073233, 6029.487493563052, 6035.728392222339, 6073.969630151843, 6075.119107729377, 6090.512758979271, 6091.908621370683, 5899.174115623816, 6001.339852616209, 6084.452399284041, 6089.543284253698, 6092.390760923917, 3371.4100032494216, 4848.29699693546, 5207.973960130213, 5217.713025783581, 5277.02375060615, 19448.0, 20123.0, 20189.495262618406, 20382.592348153103, 20493.79506204949, 1946.5425624978118, 2045.3250427358935, 2256.1201131558705, 2301.6777651128855, 2309.7690230976937, 8527.18991695788, 12052.517899284017, 12698.682532936658, 13014.804777928339, 13156.276404797733, 1712.8030276518236, 1737.1661602430904, 1753.0708724375975, 1826.196473551633, 1827.3566032665433, 62931.05603415953, 63197.05204421983, 63339.36660633381, 63744.362556374304, 65368.673156633915, 9661.13049825505, 9801.607935682492, 9812.754681133028, 10060.295779295526, 10296.07335339823, 13915.860841391457, 13984.650383740285, 14031.01782875189, 14068.507602233873, 14069.0, 6918.169819621678, 7120.359167674934, 7224.313690199423, 7298.0, 7306.086739157606, 6702.463802895757, 6759.155105611799, 6913.792586222421, 6928.237893831674, 6941.548799328028, 4245.363195520635, 4341.82632694695, 4369.584889435499, 4417.204903117406, 4456.688031837805, 1630.8776841736917, 1654.7766051582882, 1692.2046638080083, 1713.306111025037, 1714.2800023989955, 383.1092709450539, 385.21800744488735, 397.69774971021656, 830.2735106781566, 884.429542944797, 10374.17006639467, 10496.422696751599, 10526.947365263124, 10649.0, 10684.94657526723, 382.142091005694, 387.5001248389546, 389.3964355249352, 395.5253695565333, 398.17776291957597, 5659.717014149276, 5663.122216056518, 5769.6538207706835, 9208.401453905475, 10205.591776328863, 361818.0, 362674.0, 362909.0, 363504.9440832929, 366633.0, 48654.270185947236, 48937.51062489329, 49884.750576246886, 78561.21438785653, 79350.0, 920.9769755756159, 931.3759780946782, 947.8957015077358, 952.0574631115285, 960.3469640644289, 15511.922440387725, 15585.454509092127, 15609.843901560838, 15834.60413489672, 16103.597410064842, 34866.302673946884, 35976.64023359733, 36136.81931590325, 37676.2464750709, 37884.24231515409, 49194.50805492042, 50814.0, 50949.23576146286, 51035.23447148222, 51231.0, 7191.6695411349065, 7297.343239108293, 7353.301436363537, 7399.387292812269, 7734.613269336398, 6130.785422510191, 9505.0, 10668.146548275965, 10803.567857285621, 11425.857414258508, 7966.442349035512, 8332.375071869512, 8623.353248506262, 8693.695720649746, 9045.90954090477, 9767.902320976984, 10013.949930250303, 10111.848322275024, 10124.797504050026, 10189.133923616278, 9108.726738197865, 9185.494797786187, 9199.172074513326, 9400.05999400082, 9413.058694130532, 6837.589744615339, 6995.195552511526, 7024.824379390556, 7261.346478816932, 7266.745663901738, 8909.242714369091, 8979.640814367353, 9055.864162037444, 9180.495072770795, 9364.578593963424], "value": 7597.814588271109, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.8186312984612, 1409.0770545292833, 1423.9857601423985, 1546.3891762753713, 1707.8804483686147, 6580.835479113024, 7502.69989200432, 7765.650545725442, 7800.024996875391, 7967.880481792778, 7786.9610651946805, 7829.6085195740125, 7900.407469439786, 7923.960380198091, 7935.682572697099, 2209.5139069404745, 2239.428945618865, 2239.7200349956256, 2242.4730188405792, 2242.551489702058, 2135.935921922337, 2137.861039032458, 2138.0, 2157.385145233609, 2228.008536201391, 2191.397365724423, 2199.3621849663577, 2206.746224184223, 2208.2271205078173, 2224.8887555622237, 2110.0715685098507, 2112.5563631637374, 2117.8305735541276, 2118.6821976703463, 2119.1205649655453, 2125.8618189817767, 2181.073043956312, 2182.694422780812, 2184.4757258258064, 2184.9781502184933, 7461.253874612549, 7732.381409487228, 7800.765977020698, 7803.258690424401, 7821.608919554056, 4359.760213188273, 4465.68740188187, 4476.820927162909, 4479.59683628475, 4481.820727170909, 2802.8738706758245, 2869.741723244897, 2919.693432189632, 2947.82313061217, 2953.3650265193087, 857.0229937870832, 874.3748220022715, 883.3904605821963, 887.1217494680285, 896.3501461440469, 10032.0, 10048.196144308511, 10069.244806639532, 10126.189904807672, 10133.797324053552, 3983.3029219886403, 4080.9387859182134, 4116.855910043134, 4117.444145040381, 4155.2728272552185, 7927.8810817837775, 7984.0, 8008.79978000543, 8012.399070069651, 8014.0, 4482.036362182146, 4483.036147228361, 4490.932636010462, 4494.348319493655, 4495.213337665896, 3446.724262059029, 4034.0, 4115.382692596075, 4131.5455299916985, 4305.74165550068, 3433.484977253382, 3461.4115600348, 3527.9118022049656, 3552.9111772205383, 3561.3055454186183, 1033.261218228955, 1037.9221558383151, 1039.5114296280735, 1042.0933787604831, 1051.5005372448027, 752.6387334079673, 761.7905076104116, 761.7905076104116, 840.995795021021, 891.9063498332582, 5197.610179236573, 5255.421903590602, 5299.072662284085, 5364.570834333244, 5413.9729301353245, 790.5059437662821, 816.7549735079452, 840.2605706977943, 871.8082021955287, 905.9456432614064, 3540.6105328413855, 3637.8726744563814, 3669.1010702377553, 3696.057505336176, 3757.8496860125833, 9056.637734490678, 9411.670591529264, 9413.341066125306, 9427.3400861939, 9446.0, 9317.068293170765, 9424.104710052534, 9439.76400589977, 9454.810903782023, 9460.526973651427, 7010.439164866799, 7267.818304542428, 7268.927310726931, 7272.672729727175, 7274.890876636855, 4846.53790304939, 5000.2499625056535, 5058.215976523645, 5296.126139186991, 5356.544693701079, 8669.436486628349, 8768.386212965032, 8877.156670116328, 8895.0, 8896.243819275434, 8621.224089831945, 8880.422772519765, 8993.865092023494, 9000.81998360016, 9006.324525660471, 5817.127430885401, 6311.0, 6478.449331806661, 6532.706028228646, 6645.269020407654, 7261.745838895613, 7266.273372662691, 7330.047093877762, 7339.9633001834645, 7343.37581305595, 6307.180066591313, 6365.745370185326, 6386.87226255463, 6459.192600924885, 6469.870602587827, 5984.850378740566, 6105.236845394325, 6306.4639505642535, 6473.158489396348, 6555.934440655532, 6007.609505382136, 6023.3374328824675, 6062.818115456544, 6071.544634152545, 6079.817605471843, 4468.824064274779, 4610.723356598614, 4662.300654901792, 4663.393758811333, 4671.532846715301, 4330.0, 4331.631811296075, 4393.714408563433, 4644.9767751161025, 4712.0], "value": 4033.159590403358, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.84730516478845, 292.9531274996001, 297.48683520926403, 303.4431817614677, 305.3435114503815, 4785.282207668847, 4805.669250413546, 4813.759312034397, 4830.589399901004, 4844.577711144428, 4729.692569982949, 4756.7621618919, 4769.379980602525, 4778.641601879855, 4786.138495070886, 3527.7001454876367, 3651.608196631211, 3655.689266412358, 3751.737378383514, 3759.9060023499496, 3715.18265981485, 3716.498272733187, 3721.38597131473, 3733.45864849598, 3739.9626003739886, 3500.597431295408, 3686.2996030754207, 3689.9631003690024, 3707.7775333479944, 3708.2398108387715, 3464.255185135208, 3603.657652522993, 3605.4952306677087, 3610.765300255488, 3652.7443078984484, 3229.917977477548, 3261.331427057471, 3298.093024418304, 3299.2741596848655, 3304.273059926836, 4515.780739200431, 4531.116432295704, 4543.931841022388, 4572.702774319659, 4636.9536304637195, 3955.4264631628253, 4186.979065104685, 4192.350185721188, 4266.871993840159, 4271.914561708749, 4943.233798761198, 4947.406311242638, 4970.304157417964, 4971.477994810565, 5001.824936127218, 3400.0, 3490.5636795400574, 3515.929681406383, 3518.20840310928, 3532.540769699948, 5174.741262936838, 5219.5041471060185, 5235.188545775372, 5245.423003469654, 5248.81629142982, 3548.1307079765625, 3553.964460355415, 3556.8577256909953, 3580.445031020144, 3581.659742324475, 3598.0, 3619.5475565554307, 3622.293652737691, 3622.746407751432, 3625.057485053905, 3960.2277555876904, 4139.379093135994, 4151.418801367811, 4174.812133454002, 4197.916041679149, 4929.408470983505, 4946.183879659816, 4965.0, 4971.130052240844, 4977.626677999092, 1240.2868350698204, 1242.19257482637, 1252.3738130934532, 1253.1228140301826, 1255.3095797311441, 761.8133557278502, 783.455498428598, 799.1808396393627, 805.1706742055575, 806.9636866341028, 4343.956560434419, 4393.1872603568445, 4393.6704747144095, 4450.755208463501, 4508.639308855284, 5146.433892271847, 5150.948490515122, 5153.974230128901, 5158.406783219978, 5167.896642067138, 4476.171908196994, 4479.977600112044, 4484.304932735431, 4486.801467281009, 4495.527969563214, 2599.7910971398014, 2739.6164536964843, 2743.6296100026243, 2769.8338099714083, 2769.9307517312227, 4857.854264372075, 4862.495814655455, 4900.975495122573, 4955.876103097451, 4976.0, 5028.698278103326, 5254.816081437131, 5270.736463176826, 5273.2617433559335, 5275.604329675213, 4953.157963146202, 4967.850964471072, 4981.850544483599, 4982.551570358685, 4983.451820299764, 5173.456787037307, 5210.791568337376, 5216.739163041757, 5217.660852044605, 5223.138182200047, 4854.9514504855915, 5074.82238121664, 5103.209002604603, 5106.948930510647, 5124.180915774995, 4942.8764280893265, 4945.184044632595, 4948.307236986752, 4960.379952505937, 4969.527894850128, 3597.7661452005536, 3620.9094772630897, 3631.49159117729, 3636.0, 3640.890773276806, 5092.618053645917, 5194.558462530576, 5197.350331208599, 5224.346956630421, 5253.0, 5055.191169412877, 5072.594192464668, 5075.6193285502995, 5082.796688132434, 5085.237214417867, 5064.18972964324, 5072.695638261716, 5078.365204349457, 5084.618653600921, 5086.694798312114, 4683.718976861399, 4927.679700819435, 4974.651774375759, 4980.277859710394, 4987.027529631687, 4792.496787837366, 4889.3888263967, 4999.150144475375, 5032.974835125801, 5078.162103252922, 4870.561649451566, 4928.67963582366, 4933.58064564516, 4935.703857768545, 4970.40355157369], "value": 3755.634840566557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.8057650687019, 597.8953683105456, 598.5630489742488, 667.5293917787958, 730.6894569807832, 11739.765204695912, 12330.938345308283, 12449.93775031126, 12479.875201247985, 12481.375931203438, 12621.305828179446, 12722.491100355976, 12738.426770795313, 12743.171693839893, 12750.362481875893, 620.3455354600894, 767.6353731977295, 780.7657702689213, 802.1016461563029, 806.681360862459, 795.1174196641723, 799.1968072087544, 799.9200079992013, 803.5058439059965, 804.264098350009, 841.2680967558233, 843.472829481574, 845.1337379186322, 845.6532821543168, 845.8350621628784, 639.2201514152727, 796.7291121018882, 821.2485575698229, 831.2560258568577, 837.8826964224983, 601.5398220361427, 604.1783174882157, 604.2839235505895, 604.5133667397743, 605.4187979539647, 12698.936505317506, 12703.936480317632, 12724.0, 12736.04479664029, 12740.872591274061, 11688.0, 11713.0, 11781.882181178249, 11908.523659053628, 12355.93822030884, 11751.471183796664, 11843.881561184364, 11893.108016898681, 11954.820677689842, 12046.939765301206, 4043.3126368517424, 4076.1236334187997, 4138.0689344897155, 4160.375943608453, 4176.561461046607, 58026.0, 58550.70724646392, 58569.7071514644, 58837.705811471096, 59782.99999999956, 9045.0, 9152.725418237464, 9273.768155796159, 9403.482808445464, 9415.482148481899, 16058.598535036484, 17513.562160945825, 17513.649727005642, 17554.0, 17590.560235994202, 6964.616216699046, 7001.054857594261, 7125.5368401053765, 7155.284471552803, 7320.304571065741, 3682.650148235913, 3691.5201023866725, 3725.5715592706642, 3737.2712321097124, 3881.4565960765517, 2806.649168853893, 2903.027485792283, 2905.099419180061, 2906.7238612331794, 2910.1415082550698, 534.9037173308842, 537.9596530260246, 545.2638937434432, 552.5469115325475, 553.2586334311961, 488.2822251290597, 508.6236185222908, 563.1187192044438, 580.8431723434649, 589.2545929399313, 7827.25641064098, 7891.565963872042, 7940.3450239238355, 8000.919990800134, 8069.435139540175, 579.8202557207279, 582.2721598002497, 597.7310210405248, 604.2899592978321, 606.3057798820327, 3579.3736096183065, 3804.2962052020466, 3862.517185351831, 3877.9806100969877, 3880.2821478026653, 80670.0, 83460.58269708612, 84234.15765842263, 84372.57813711015, 84393.0, 49959.75020124876, 63983.0, 65372.019419707794, 71050.0, 79935.60032199918, 68370.3162968374, 69122.0, 69532.3046769536, 70081.29918700748, 70360.6481967597, 7273.127224733066, 7283.271672832674, 7456.217097204715, 7460.962695186489, 7524.592130518182, 42680.0, 42978.78510607427, 43679.34480982724, 44597.0, 45486.0, 35680.64319356773, 47000.52999469962, 61485.0, 62395.0, 63433.36566634275, 10871.40207288575, 10887.244559349412, 10897.455127243447, 10908.0, 10982.780344392908, 10438.895611043792, 10566.260361774603, 10693.679189624323, 11119.16606254518, 11161.107111430903, 10651.360918344924, 10767.0, 10990.560377584807, 11262.943685281522, 11300.434978251218, 9779.41323520591, 10130.79738405213, 10231.283810133516, 10383.792324153626, 10462.47687615601, 9151.17639412456, 9378.390404623678, 9963.40219586798, 9990.450525221182, 10311.020453056948, 8144.959275203586, 8274.29668478186, 8291.378146638906, 8393.076761556102, 8609.0, 7954.721584744506, 8020.879686804819, 8115.715949941724, 8331.29184019341, 8406.369522285731], "value": 6233.862698459578, "unit": "score"}], "name": "dromaeo_css", "value": 6255.919043979191, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 115.0, 115.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 130.0, 132.0, 186.0, 131.0, 132.0, 150.0, 137.0, 190.0, 129.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 163.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 102.0, 76.0, 76.0, 73.0, 75.0, 98.0, 74.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 113.0, 84.0, 84.0, 84.0, 123.0, 85.0, 85.0, 133.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 148.0, 147.0, 146.0, 148.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 61.0, 63.0, 63.0, 63.0, 62.0, 62.0, 64.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 66.0, 65.0, 66.0, 64.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 86.0, 83.0, 87.0, 85.0, 84.0, 84.0, 124.0, 83.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 136.0, 140.0, 141.0, 135.0, 142.0, 149.0, 133.0, 151.0, 134.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 170.0, 171.0, 175.0, 172.0, 174.0, 174.0, 171.0, 172.0, 173.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 72.0, 71.0, 66.0, 66.0, 68.0, 66.0, 69.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1557.0, "alertThreshold": 2.0}]} 14:35:25 INFO - Return code: 0 14:35:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:35:25 ERROR - # TBPL SUCCESS # 14:35:25 INFO - Running post-action listener: _resource_record_post_action 14:35:25 INFO - ##### 14:35:25 INFO - ##### Finished run-tests step (success) 14:35:25 INFO - ##### 14:35:25 INFO - Running post-run listener: _resource_record_post_run 14:35:25 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 524349440; Read time: 0; Write time: 623372 14:35:25 INFO - TinderboxPrint: CPU usage
13.1% 14:35:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:35:25 INFO - TinderboxPrint: I/O write bytes / time
524,349,440 / 623,372 14:35:25 INFO - TinderboxPrint: CPU idle
8,323.4 (86.9%) 14:35:25 INFO - TinderboxPrint: CPU user
1,210.1 (12.6%) 14:35:25 INFO - TinderboxPrint: Swap in / out
0 / 0 14:35:25 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 243773440; Read time: 0; Write time: 321228 14:35:25 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 280576000; Read time: 0; Write time: 302144 14:35:26 INFO - Running post-run listener: _upload_blobber_files 14:35:26 INFO - Blob upload gear active. 14:35:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:35:26 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:35:26 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:35:26 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:35:26 INFO - (blobuploader) - INFO - Open directory for files ... 14:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:31 INFO - (blobuploader) - INFO - Done attempting. 14:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:31 INFO - (blobuploader) - INFO - Done attempting. 14:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:32 INFO - (blobuploader) - INFO - Done attempting. 14:35:32 INFO - (blobuploader) - INFO - Iteration through files over. 14:35:32 INFO - Return code: 0 14:35:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:35:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:35:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/427cde808706cf88793ed17128aa37b648a4253838f3353fa2f0f15d6372eefaf86cb09e00ff4e5fda27815a934aeb4d114ecae3665f13f986e19866cf05d7fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9bc26e93eada1a456057f0189af30f28387adec74f0dfaa47d29ed8872f377efe3d108814d2b045eb783d353adc415c29203f62de625e2d50e3b1defb9d4f895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/76a6f6bd4a8184bb9e21bb869c9b2bba1225b8945b0a51b4623385889e32f6987c3eb5d878bfd4483b804ad39d3eed2eb8664c769cea53e241a3323d2ac7b0f6"} 14:35:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:35:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:35:32 INFO - Contents: 14:35:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/427cde808706cf88793ed17128aa37b648a4253838f3353fa2f0f15d6372eefaf86cb09e00ff4e5fda27815a934aeb4d114ecae3665f13f986e19866cf05d7fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9bc26e93eada1a456057f0189af30f28387adec74f0dfaa47d29ed8872f377efe3d108814d2b045eb783d353adc415c29203f62de625e2d50e3b1defb9d4f895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/76a6f6bd4a8184bb9e21bb869c9b2bba1225b8945b0a51b4623385889e32f6987c3eb5d878bfd4483b804ad39d3eed2eb8664c769cea53e241a3323d2ac7b0f6"} 14:35:32 INFO - Running post-run listener: copy_logs_to_upload_dir 14:35:32 INFO - Copying logs to upload dir... 14:35:32 INFO - mkdir: /builds/slave/test/build/upload/logs 14:35:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.233140 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-01 14:35:32.665520) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 14:35:32.668922) ========= 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=db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058 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/427cde808706cf88793ed17128aa37b648a4253838f3353fa2f0f15d6372eefaf86cb09e00ff4e5fda27815a934aeb4d114ecae3665f13f986e19866cf05d7fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9bc26e93eada1a456057f0189af30f28387adec74f0dfaa47d29ed8872f377efe3d108814d2b045eb783d353adc415c29203f62de625e2d50e3b1defb9d4f895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/76a6f6bd4a8184bb9e21bb869c9b2bba1225b8945b0a51b4623385889e32f6987c3eb5d878bfd4483b804ad39d3eed2eb8664c769cea53e241a3323d2ac7b0f6"} build_url:https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011366 build_url: 'https://queue.taskcluster.net/v1/task/DqJZpwVCS_SjtJC3SGAL7w/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/427cde808706cf88793ed17128aa37b648a4253838f3353fa2f0f15d6372eefaf86cb09e00ff4e5fda27815a934aeb4d114ecae3665f13f986e19866cf05d7fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9bc26e93eada1a456057f0189af30f28387adec74f0dfaa47d29ed8872f377efe3d108814d2b045eb783d353adc415c29203f62de625e2d50e3b1defb9d4f895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/76a6f6bd4a8184bb9e21bb869c9b2bba1225b8945b0a51b4623385889e32f6987c3eb5d878bfd4483b804ad39d3eed2eb8664c769cea53e241a3323d2ac7b0f6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 14:35:32.694213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:35:32.694558) ========= 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=db0483c52d987ecb441a263b000001ad-1464815688.920240-1114518058 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004459 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:35:32.778479) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 14:35:32.778793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 14:35:32.779083) ========= ========= Total master_lag: 0.19 =========