builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-055 starttime: 1450268201.0 results: success (0) buildid: 20151216030231 builduid: 08c0a5efce44464daf9a5a72f5daac2d revision: 0babaa3edcf908c393b68a3dc2d1c2a2450c31ed ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:40.998824) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:40.999574) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:40.999905) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006459 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.026418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.026786) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.063246) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.063732) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004656 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.113930) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.114240) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.114676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.114981) ========= 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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 04:16:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2015-12-16 04:16:41 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.183942 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.316237) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.316846) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018137 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.373863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.374246) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0babaa3edcf908c393b68a3dc2d1c2a2450c31ed --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0babaa3edcf908c393b68a3dc2d1c2a2450c31ed --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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 04:16:41,754 truncating revision to first 12 chars 2015-12-16 04:16:41,755 Setting DEBUG logging. 2015-12-16 04:16:41,755 attempt 1/10 2015-12-16 04:16:41,755 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0babaa3edcf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 04:16:41,983 unpacking tar archive at: mozilla-central-0babaa3edcf9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.495072 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.881955) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.882269) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.891507) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.891800) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:16:41.892216) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2015-12-16 04:16:41.892551) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:16:42 INFO - MultiFileLogger online at 20151216 04:16:42 in /builds/slave/test 04:16:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:16:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:16:42 INFO - {'append_to_log': False, 04:16:42 INFO - 'base_work_dir': '/builds/slave/test', 04:16:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:16:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:16:42 INFO - 'branch': 'Firefox-Non-PGO', 04:16:42 INFO - 'buildbot_json_path': 'buildprops.json', 04:16:42 INFO - 'config_files': ('talos/linux_config.py',), 04:16:42 INFO - 'default_actions': ('clobber', 04:16:42 INFO - 'read-buildbot-config', 04:16:42 INFO - 'download-and-extract', 04:16:42 INFO - 'populate-webroot', 04:16:42 INFO - 'create-virtualenv', 04:16:42 INFO - 'install', 04:16:42 INFO - 'run-tests'), 04:16:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:16:42 INFO - 'download_minidump_stackwalk': True, 04:16:42 INFO - 'download_symbols': 'ondemand', 04:16:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:16:42 INFO - 'tooltool.py': '/tools/tooltool.py', 04:16:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:16:42 INFO - '/tools/misc-python/virtualenv.py')}, 04:16:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:16:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:16:42 INFO - 'installer_path': 'installer.exe', 04:16:42 INFO - 'log_level': 'info', 04:16:42 INFO - 'log_name': 'talos', 04:16:42 INFO - 'log_to_console': True, 04:16:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:16:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:16:42 INFO - 'opt_config_files': (), 04:16:42 INFO - 'pip_index': False, 04:16:42 INFO - 'sps_profile': False, 04:16:42 INFO - 'sps_profile_interval': 0, 04:16:42 INFO - 'suite': 'dromaeojs-e10s', 04:16:42 INFO - 'system_bits': '64', 04:16:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:16:42 INFO - 'title': 'talos-linux64-ix-055', 04:16:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:16:42 INFO - 'use_talos_json': True, 04:16:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:16:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:16:42 INFO - 'work_dir': 'build'} 04:16:42 INFO - ##### 04:16:42 INFO - ##### Running clobber step. 04:16:42 INFO - ##### 04:16:42 INFO - Running pre-action listener: _resource_record_pre_action 04:16:42 INFO - Running main action method: clobber 04:16:42 INFO - rmtree: /builds/slave/test/build 04:16:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:16:43 INFO - Running post-action listener: _resource_record_post_action 04:16:43 INFO - ##### 04:16:43 INFO - ##### Running read-buildbot-config step. 04:16:43 INFO - ##### 04:16:43 INFO - Running pre-action listener: _resource_record_pre_action 04:16:43 INFO - Running main action method: read_buildbot_config 04:16:43 INFO - Using buildbot properties: 04:16:43 INFO - { 04:16:43 INFO - "properties": { 04:16:43 INFO - "buildnumber": 121, 04:16:43 INFO - "product": "firefox", 04:16:43 INFO - "script_repo_revision": "production", 04:16:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:16:43 INFO - "repository": "", 04:16:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:16:43 INFO - "buildid": "20151216030231", 04:16:43 INFO - "slavename": "talos-linux64-ix-055", 04:16:43 INFO - "pgo_build": "False", 04:16:43 INFO - "basedir": "/builds/slave/test", 04:16:43 INFO - "project": "", 04:16:43 INFO - "platform": "ubuntu64_hw", 04:16:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:16:43 INFO - "slavebuilddir": "test", 04:16:43 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:16:43 INFO - "branch": "mozilla-central", 04:16:43 INFO - "repo_path": "mozilla-central", 04:16:43 INFO - "stage_platform": "linux64", 04:16:43 INFO - "builduid": "08c0a5efce44464daf9a5a72f5daac2d", 04:16:43 INFO - "revision": "0babaa3edcf908c393b68a3dc2d1c2a2450c31ed" 04:16:43 INFO - }, 04:16:43 INFO - "sourcestamp": { 04:16:43 INFO - "repository": "", 04:16:43 INFO - "hasPatch": false, 04:16:43 INFO - "project": "", 04:16:43 INFO - "branch": "mozilla-central-linux64-talos", 04:16:43 INFO - "changes": [ 04:16:43 INFO - { 04:16:43 INFO - "category": null, 04:16:43 INFO - "files": [ 04:16:43 INFO - { 04:16:43 INFO - "url": null, 04:16:43 INFO - "name": "https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 04:16:43 INFO - } 04:16:43 INFO - ], 04:16:43 INFO - "repository": "", 04:16:43 INFO - "rev": "0babaa3edcf908c393b68a3dc2d1c2a2450c31ed", 04:16:43 INFO - "who": "cbook@mozilla.com", 04:16:43 INFO - "when": 1450268161, 04:16:43 INFO - "number": 7365649, 04:16:43 INFO - "comments": "merge mozilla-inbound to mozilla-central a=merge", 04:16:43 INFO - "project": "", 04:16:43 INFO - "at": "Wed 16 Dec 2015 04:16:01", 04:16:43 INFO - "branch": "mozilla-central-linux64-talos", 04:16:43 INFO - "revlink": "", 04:16:43 INFO - "properties": [ 04:16:43 INFO - [ 04:16:43 INFO - "buildid", 04:16:43 INFO - "20151216030231", 04:16:43 INFO - "Change" 04:16:43 INFO - ], 04:16:43 INFO - [ 04:16:43 INFO - "builduid", 04:16:43 INFO - "08c0a5efce44464daf9a5a72f5daac2d", 04:16:43 INFO - "Change" 04:16:43 INFO - ], 04:16:43 INFO - [ 04:16:43 INFO - "pgo_build", 04:16:43 INFO - "False", 04:16:43 INFO - "Change" 04:16:43 INFO - ] 04:16:43 INFO - ], 04:16:43 INFO - "revision": "0babaa3edcf908c393b68a3dc2d1c2a2450c31ed" 04:16:43 INFO - } 04:16:43 INFO - ], 04:16:43 INFO - "revision": "0babaa3edcf908c393b68a3dc2d1c2a2450c31ed" 04:16:43 INFO - } 04:16:43 INFO - } 04:16:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 04:16:43 INFO - Running post-action listener: _resource_record_post_action 04:16:43 INFO - ##### 04:16:43 INFO - ##### Running download-and-extract step. 04:16:43 INFO - ##### 04:16:43 INFO - Running pre-action listener: _resource_record_pre_action 04:16:43 INFO - Running main action method: download_and_extract 04:16:43 INFO - mkdir: /builds/slave/test/build/tests 04:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:43 INFO - https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:16:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/test_packages.json 04:16:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/test_packages.json 04:16:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:16:47 INFO - Downloaded 1302 bytes. 04:16:47 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:16:47 INFO - Using the following test package requirements: 04:16:47 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:16:47 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:16:47 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'jsshell-linux-x86_64.zip'], 04:16:47 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:16:47 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:16:47 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:16:47 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:16:47 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:16:47 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:16:47 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:16:47 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:16:47 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:16:47 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')]} 04:16:47 INFO - https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:16:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:16:48 INFO - Downloaded 21868914 bytes. 04:16:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:16:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:16:49 INFO - Return code: 0 04:16:49 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:49 INFO - https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:16:55 INFO - Downloaded 21868914 bytes. 04:16:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:16:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:16:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:16:56 INFO - Return code: 0 04:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:56 INFO - https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:16:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:16:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:16:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:16:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:16:57 INFO - Downloaded 11132052 bytes. 04:16:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:16:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:16:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:16:57 INFO - Return code: 0 04:16: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')]} 04:16:58 INFO - https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:16:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:16:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:16:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:16:59 INFO - Downloaded 57744342 bytes. 04:16:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:16:59 INFO - mkdir: /builds/slave/test/properties 04:16:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:16:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:16:59 INFO - Contents: 04:16:59 INFO - build_url:https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:16:59 INFO - Running post-action listener: _resource_record_post_action 04:16:59 INFO - Running post-action listener: set_extra_try_arguments 04:16:59 INFO - ##### 04:16:59 INFO - ##### Running populate-webroot step. 04:16:59 INFO - ##### 04:16:59 INFO - Running pre-action listener: _resource_record_pre_action 04:16:59 INFO - Running main action method: populate_webroot 04:16:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:16:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:16:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:16:59 INFO - '--tppagecycles', 04:16:59 INFO - '7'], 04:16:59 INFO - 'tests': ['tsvgm']}}, 04:16:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:16:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:16:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:16:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:16:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'tests': ['damp', 'tps']}, 04:16:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['damp', 'tps']}, 04:16:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:16:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:16:59 INFO - 'other': {'tests': ['a11yr', 04:16:59 INFO - 'ts_paint', 04:16:59 INFO - 'tpaint', 04:16:59 INFO - 'sessionrestore', 04:16:59 INFO - 'sessionrestore_no_auto_restore']}, 04:16:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['a11yr', 04:16:59 INFO - 'ts_paint', 04:16:59 INFO - 'tpaint', 04:16:59 INFO - 'sessionrestore', 04:16:59 INFO - 'sessionrestore_no_auto_restore']}, 04:16:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['a11yr', 04:16:59 INFO - 'ts_paint', 04:16:59 INFO - 'tpaint', 04:16:59 INFO - 'sessionrestore', 04:16:59 INFO - 'sessionrestore_no_auto_restore']}, 04:16:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['a11yr', 04:16:59 INFO - 'ts_paint', 04:16:59 INFO - 'tpaint', 04:16:59 INFO - 'sessionrestore', 04:16:59 INFO - 'sessionrestore_no_auto_restore']}, 04:16:59 INFO - 'other_l64': {'tests': ['a11yr', 04:16:59 INFO - 'ts_paint', 04:16:59 INFO - 'tpaint', 04:16:59 INFO - 'sessionrestore', 04:16:59 INFO - 'sessionrestore_no_auto_restore']}, 04:16:59 INFO - 'other_nol64': {'tests': ['a11yr', 04:16:59 INFO - 'ts_paint', 04:16:59 INFO - 'tpaint', 04:16:59 INFO - 'sessionrestore', 04:16:59 INFO - 'sessionrestore_no_auto_restore']}, 04:16:59 INFO - 'svgr': {'tests': ['tsvgx', 04:16:59 INFO - 'tsvgr_opacity', 04:16:59 INFO - 'tart', 04:16:59 INFO - 'tscrollx', 04:16:59 INFO - 'cart']}, 04:16:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['tsvgx', 04:16:59 INFO - 'tsvgr_opacity', 04:16:59 INFO - 'tart', 04:16:59 INFO - 'tscrollx', 04:16:59 INFO - 'cart']}, 04:16:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'tests': ['tp5o']}, 04:16:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'talos_options': ['--e10s'], 04:16:59 INFO - 'tests': ['tp5o']}, 04:16:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'talos_options': ['--xperf_path', 04:16:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:59 INFO - 'tests': ['tp5n']}, 04:16:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:16:59 INFO - 'talos_options': ['--e10s', 04:16:59 INFO - '--xperf_path', 04:16:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:59 INFO - 'tests': ['tp5n']}}, 04:16:59 INFO - 'talos.zip': {'path': '', 04:16:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:16:59 INFO - Running post-action listener: _resource_record_post_action 04:16:59 INFO - ##### 04:16:59 INFO - ##### Running create-virtualenv step. 04:16:59 INFO - ##### 04:16:59 INFO - Running pre-action listener: _resource_record_pre_action 04:16:59 INFO - Running main action method: create_virtualenv 04:16:59 INFO - Creating virtualenv /builds/slave/test/build/venv 04:16:59 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 04:16:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:00 INFO - Using real prefix '/usr' 04:17:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:00 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:02 INFO - Installing pip.................done. 04:17:02 INFO - Return code: 0 04:17:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:17:02 INFO - Using env: {'DISPLAY': ':0', 04:17:02 INFO - 'HOME': '/home/cltbld', 04:17:02 INFO - 'LANG': 'en_US.UTF-8', 04:17:02 INFO - 'LANGUAGE': 'en_US:en', 04:17:02 INFO - 'LOGNAME': 'cltbld', 04:17:02 INFO - 'MAIL': '/var/mail/cltbld', 04:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:02 INFO - 'MOZ_NO_REMOTE': '1', 04:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:02 INFO - 'NO_EM_RESTART': '1', 04:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:02 INFO - 'PWD': '/builds/slave/test', 04:17:02 INFO - 'SHELL': '/bin/bash', 04:17:02 INFO - 'SHLVL': '1', 04:17:02 INFO - 'TERM': 'linux', 04:17:02 INFO - 'TMOUT': '86400', 04:17:02 INFO - 'USER': 'cltbld', 04:17:02 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:02 INFO - '_': '/tools/buildbot/bin/python'} 04:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:02 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 04:17:02 INFO - Cleaning up... 04:17:02 INFO - Return code: 0 04:17:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 04:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 04:17:02 INFO - Using env: {'DISPLAY': ':0', 04:17:02 INFO - 'HOME': '/home/cltbld', 04:17:02 INFO - 'LANG': 'en_US.UTF-8', 04:17:02 INFO - 'LANGUAGE': 'en_US:en', 04:17:02 INFO - 'LOGNAME': 'cltbld', 04:17:02 INFO - 'MAIL': '/var/mail/cltbld', 04:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:02 INFO - 'MOZ_NO_REMOTE': '1', 04:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:02 INFO - 'NO_EM_RESTART': '1', 04:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:02 INFO - 'PWD': '/builds/slave/test', 04:17:02 INFO - 'SHELL': '/bin/bash', 04:17:02 INFO - 'SHLVL': '1', 04:17:02 INFO - 'TERM': 'linux', 04:17:02 INFO - 'TMOUT': '86400', 04:17:02 INFO - 'USER': 'cltbld', 04:17:02 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:02 INFO - '_': '/tools/buildbot/bin/python'} 04:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:03 INFO - Downloading/unpacking psutil>=0.7.1 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:04 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:17:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:04 INFO - Installing collected packages: psutil 04:17:04 INFO - Running setup.py install for psutil 04:17:04 INFO - building 'psutil._psutil_linux' extension 04:17:04 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 04:17: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 04:17:05 INFO - building 'psutil._psutil_posix' extension 04:17: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 04:17: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:17:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:05 INFO - Successfully installed psutil 04:17:05 INFO - Cleaning up... 04:17:05 INFO - Return code: 0 04:17:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 04:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 04:17:05 INFO - Using env: {'DISPLAY': ':0', 04:17:05 INFO - 'HOME': '/home/cltbld', 04:17:05 INFO - 'LANG': 'en_US.UTF-8', 04:17:05 INFO - 'LANGUAGE': 'en_US:en', 04:17:05 INFO - 'LOGNAME': 'cltbld', 04:17:05 INFO - 'MAIL': '/var/mail/cltbld', 04:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:05 INFO - 'MOZ_NO_REMOTE': '1', 04:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:05 INFO - 'NO_EM_RESTART': '1', 04:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:05 INFO - 'PWD': '/builds/slave/test', 04:17:05 INFO - 'SHELL': '/bin/bash', 04:17:05 INFO - 'SHLVL': '1', 04:17:05 INFO - 'TERM': 'linux', 04:17:05 INFO - 'TMOUT': '86400', 04:17:05 INFO - 'USER': 'cltbld', 04:17:05 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:05 INFO - '_': '/tools/buildbot/bin/python'} 04:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:17:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:17:07 INFO - Installing collected packages: mozsystemmonitor 04:17:07 INFO - Running setup.py install for mozsystemmonitor 04:17:07 INFO - Successfully installed mozsystemmonitor 04:17:07 INFO - Cleaning up... 04:17:07 INFO - Return code: 0 04:17:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:17:07 INFO - Using env: {'DISPLAY': ':0', 04:17:07 INFO - 'HOME': '/home/cltbld', 04:17:07 INFO - 'LANG': 'en_US.UTF-8', 04:17:07 INFO - 'LANGUAGE': 'en_US:en', 04:17:07 INFO - 'LOGNAME': 'cltbld', 04:17:07 INFO - 'MAIL': '/var/mail/cltbld', 04:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:07 INFO - 'MOZ_NO_REMOTE': '1', 04:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:07 INFO - 'NO_EM_RESTART': '1', 04:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:07 INFO - 'PWD': '/builds/slave/test', 04:17:07 INFO - 'SHELL': '/bin/bash', 04:17:07 INFO - 'SHLVL': '1', 04:17:07 INFO - 'TERM': 'linux', 04:17:07 INFO - 'TMOUT': '86400', 04:17:07 INFO - 'USER': 'cltbld', 04:17:07 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:07 INFO - '_': '/tools/buildbot/bin/python'} 04:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:07 INFO - Downloading/unpacking blobuploader==1.2.4 04:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:09 INFO - Downloading blobuploader-1.2.4.tar.gz 04:17:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:17:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:17:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:10 INFO - Downloading docopt-0.6.1.tar.gz 04:17:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:17:10 INFO - Installing collected packages: blobuploader, requests, docopt 04:17:10 INFO - Running setup.py install for blobuploader 04:17:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:17:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:17:10 INFO - Running setup.py install for requests 04:17:10 INFO - Running setup.py install for docopt 04:17:10 INFO - Successfully installed blobuploader requests docopt 04:17:10 INFO - Cleaning up... 04:17:10 INFO - Return code: 0 04:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 04:17:10 INFO - Using env: {'DISPLAY': ':0', 04:17:10 INFO - 'HOME': '/home/cltbld', 04:17:10 INFO - 'LANG': 'en_US.UTF-8', 04:17:10 INFO - 'LANGUAGE': 'en_US:en', 04:17:10 INFO - 'LOGNAME': 'cltbld', 04:17:10 INFO - 'MAIL': '/var/mail/cltbld', 04:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:10 INFO - 'MOZ_NO_REMOTE': '1', 04:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:10 INFO - 'NO_EM_RESTART': '1', 04:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:10 INFO - 'PWD': '/builds/slave/test', 04:17:10 INFO - 'SHELL': '/bin/bash', 04:17:10 INFO - 'SHLVL': '1', 04:17:10 INFO - 'TERM': 'linux', 04:17:10 INFO - 'TMOUT': '86400', 04:17:10 INFO - 'USER': 'cltbld', 04:17:10 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:10 INFO - '_': '/tools/buildbot/bin/python'} 04:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:11 INFO - Running setup.py (path:/tmp/pip-jqlB5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:11 INFO - Running setup.py (path:/tmp/pip-pM_o6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:11 INFO - Running setup.py (path:/tmp/pip-yZ5spc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:11 INFO - Running setup.py (path:/tmp/pip-bcWWPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:11 INFO - Running setup.py (path:/tmp/pip-3bOuEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:11 INFO - Running setup.py (path:/tmp/pip-6MueH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:11 INFO - Running setup.py (path:/tmp/pip-Y4rHa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:11 INFO - Running setup.py (path:/tmp/pip-yuiXge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:11 INFO - Running setup.py (path:/tmp/pip-ZDM0t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:11 INFO - Running setup.py (path:/tmp/pip-AQzgBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:11 INFO - Running setup.py (path:/tmp/pip-Fo_q0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:11 INFO - Running setup.py (path:/tmp/pip-7_z4BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:11 INFO - Running setup.py (path:/tmp/pip-QCKw70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:12 INFO - Running setup.py (path:/tmp/pip-uPiDhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:12 INFO - Running setup.py (path:/tmp/pip-Ton1ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:12 INFO - Running setup.py (path:/tmp/pip-YKJL6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:12 INFO - Running setup.py (path:/tmp/pip-pShcRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:17:12 INFO - Running setup.py install for manifestparser 04:17:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:17:12 INFO - Running setup.py install for mozcrash 04:17:12 INFO - Running setup.py install for mozdebug 04:17:12 INFO - Running setup.py install for mozdevice 04:17:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:17:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:17:12 INFO - Running setup.py install for mozfile 04:17:12 INFO - Running setup.py install for mozhttpd 04:17:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:17:12 INFO - Running setup.py install for mozinfo 04:17:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:17:12 INFO - Running setup.py install for mozInstall 04:17:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Running setup.py install for mozleak 04:17:13 INFO - Running setup.py install for mozlog 04:17:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Running setup.py install for moznetwork 04:17:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Running setup.py install for mozprocess 04:17:13 INFO - Running setup.py install for mozprofile 04:17:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Running setup.py install for mozrunner 04:17:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:17:13 INFO - Running setup.py install for mozscreenshot 04:17:13 INFO - Running setup.py install for moztest 04:17:13 INFO - Running setup.py install for mozversion 04:17:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:17:14 INFO - Cleaning up... 04:17:14 INFO - Return code: 0 04:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:14 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')]} 04:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:14 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')]} 04:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:17:14 INFO - Using env: {'DISPLAY': ':0', 04:17:14 INFO - 'HOME': '/home/cltbld', 04:17:14 INFO - 'LANG': 'en_US.UTF-8', 04:17:14 INFO - 'LANGUAGE': 'en_US:en', 04:17:14 INFO - 'LOGNAME': 'cltbld', 04:17:14 INFO - 'MAIL': '/var/mail/cltbld', 04:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:14 INFO - 'MOZ_NO_REMOTE': '1', 04:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:14 INFO - 'NO_EM_RESTART': '1', 04:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:14 INFO - 'PWD': '/builds/slave/test', 04:17:14 INFO - 'SHELL': '/bin/bash', 04:17:14 INFO - 'SHLVL': '1', 04:17:14 INFO - 'TERM': 'linux', 04:17:14 INFO - 'TMOUT': '86400', 04:17:14 INFO - 'USER': 'cltbld', 04:17:14 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:14 INFO - '_': '/tools/buildbot/bin/python'} 04:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:14 INFO - Running setup.py (path:/tmp/pip-jLD6qL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:14 INFO - Running setup.py (path:/tmp/pip-IzNP4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:14 INFO - Running setup.py (path:/tmp/pip-C33Oq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:14 INFO - Running setup.py (path:/tmp/pip-jtQ2dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:14 INFO - Running setup.py (path:/tmp/pip-pWmkR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:14 INFO - Running setup.py (path:/tmp/pip-8gghYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:14 INFO - Running setup.py (path:/tmp/pip-0dhHIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:14 INFO - Running setup.py (path:/tmp/pip-P6T0_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:14 INFO - Running setup.py (path:/tmp/pip-0EMobg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:14 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)) 04:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:14 INFO - Running setup.py (path:/tmp/pip-EH4JWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:15 INFO - Running setup.py (path:/tmp/pip-ZgaSmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:15 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:15 INFO - Running setup.py (path:/tmp/pip-p8uXma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:15 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:15 INFO - Running setup.py (path:/tmp/pip-Tthvr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:15 INFO - Running setup.py (path:/tmp/pip-ub985Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:15 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:15 INFO - Running setup.py (path:/tmp/pip-UAKQSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:15 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:15 INFO - Running setup.py (path:/tmp/pip-yEpugX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:15 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)) 04:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:15 INFO - Running setup.py (path:/tmp/pip-4X1l_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:15 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)) 04:17:15 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:15 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:15 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)) 04:17:15 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:15 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17:15 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 04:17:15 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 04:17:15 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 04:17:15 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 04:17:15 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 04:17:15 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 04:17:17 INFO - Downloading blessings-1.5.1.tar.gz 04:17:17 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 04:17:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:17:17 INFO - Installing collected packages: blessings 04:17:17 INFO - Running setup.py install for blessings 04:17:17 INFO - Successfully installed blessings 04:17:17 INFO - Cleaning up... 04:17:17 INFO - Return code: 0 04:17:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:17:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:17 INFO - Reading from file tmpfile_stdout 04:17:17 INFO - Current package versions: 04:17:17 INFO - argparse == 1.2.1 04:17:17 INFO - blessings == 1.5.1 04:17:17 INFO - blobuploader == 1.2.4 04:17:17 INFO - docopt == 0.6.1 04:17:17 INFO - manifestparser == 1.1 04:17:17 INFO - mozInstall == 1.12 04:17:17 INFO - mozcrash == 0.16 04:17:17 INFO - mozdebug == 0.1 04:17:17 INFO - mozdevice == 0.47 04:17:17 INFO - mozfile == 1.2 04:17:17 INFO - mozhttpd == 0.7 04:17:17 INFO - mozinfo == 0.9 04:17:17 INFO - mozleak == 0.1 04:17:17 INFO - mozlog == 3.1 04:17:17 INFO - moznetwork == 0.27 04:17:17 INFO - mozprocess == 0.22 04:17:17 INFO - mozprofile == 0.27 04:17:17 INFO - mozrunner == 6.11 04:17:17 INFO - mozscreenshot == 0.1 04:17:17 INFO - mozsystemmonitor == 0.0 04:17:17 INFO - moztest == 0.7 04:17:17 INFO - mozversion == 1.4 04:17:17 INFO - psutil == 3.1.1 04:17:17 INFO - requests == 1.2.3 04:17:17 INFO - wsgiref == 0.1.2 04:17:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:17:17 INFO - Using env: {'DISPLAY': ':0', 04:17:17 INFO - 'HOME': '/home/cltbld', 04:17:17 INFO - 'LANG': 'en_US.UTF-8', 04:17:17 INFO - 'LANGUAGE': 'en_US:en', 04:17:17 INFO - 'LOGNAME': 'cltbld', 04:17:17 INFO - 'MAIL': '/var/mail/cltbld', 04:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:17 INFO - 'MOZ_NO_REMOTE': '1', 04:17:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:17 INFO - 'NO_EM_RESTART': '1', 04:17:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:17 INFO - 'PWD': '/builds/slave/test', 04:17:17 INFO - 'SHELL': '/bin/bash', 04:17:17 INFO - 'SHLVL': '1', 04:17:17 INFO - 'TERM': 'linux', 04:17:17 INFO - 'TMOUT': '86400', 04:17:17 INFO - 'USER': 'cltbld', 04:17:17 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:17 INFO - '_': '/tools/buildbot/bin/python'} 04:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:17 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 1)) 04:17:17 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 2)) 04:17:17 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 3)) 04:17:17 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 4)) 04:17:17 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 5)) 04:17:17 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 6)) 04:17:17 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 7)) 04:17:17 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 8)) 04:17:17 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:17:17 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 3)) 04:17:17 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:17:17 INFO - Cleaning up... 04:17:17 INFO - Return code: 0 04:17:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:17:17 INFO - Using env: {'DISPLAY': ':0', 04:17:17 INFO - 'HOME': '/home/cltbld', 04:17:17 INFO - 'LANG': 'en_US.UTF-8', 04:17:17 INFO - 'LANGUAGE': 'en_US:en', 04:17:17 INFO - 'LOGNAME': 'cltbld', 04:17:17 INFO - 'MAIL': '/var/mail/cltbld', 04:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:17 INFO - 'MOZ_NO_REMOTE': '1', 04:17:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:17 INFO - 'NO_EM_RESTART': '1', 04:17:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:17 INFO - 'PWD': '/builds/slave/test', 04:17:17 INFO - 'SHELL': '/bin/bash', 04:17:17 INFO - 'SHLVL': '1', 04:17:17 INFO - 'TERM': 'linux', 04:17:17 INFO - 'TMOUT': '86400', 04:17:17 INFO - 'USER': 'cltbld', 04:17:17 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:17 INFO - '_': '/tools/buildbot/bin/python'} 04:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:18 INFO - Downloading/unpacking jsonschema 04:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:17:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 04:17:19 INFO - Downloading/unpacking functools32 (from jsonschema) 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:17:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:17:19 INFO - warning: no files found matching '*.txt' 04:17:19 INFO - no previously-included directories found matching 'build' 04:17:19 INFO - no previously-included directories found matching 'dist' 04:17:19 INFO - no previously-included directories found matching '.git*' 04:17:19 INFO - Installing collected packages: jsonschema, functools32 04:17:20 INFO - Running setup.py install for functools32 04:17:20 INFO - warning: no files found matching '*.txt' 04:17:20 INFO - no previously-included directories found matching 'build' 04:17:20 INFO - no previously-included directories found matching 'dist' 04:17:20 INFO - no previously-included directories found matching '.git*' 04:17:20 INFO - Successfully installed jsonschema functools32 04:17:20 INFO - Cleaning up... 04:17:20 INFO - Return code: 0 04:17:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:17:20 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')]} 04:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:20 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')]} 04:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7fc11849cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, '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': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', '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 04:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:17:20 INFO - Using env: {'DISPLAY': ':0', 04:17:20 INFO - 'HOME': '/home/cltbld', 04:17:20 INFO - 'LANG': 'en_US.UTF-8', 04:17:20 INFO - 'LANGUAGE': 'en_US:en', 04:17:20 INFO - 'LOGNAME': 'cltbld', 04:17:20 INFO - 'MAIL': '/var/mail/cltbld', 04:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:20 INFO - 'MOZ_NO_REMOTE': '1', 04:17:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:20 INFO - 'NO_EM_RESTART': '1', 04:17:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:20 INFO - 'PWD': '/builds/slave/test', 04:17:20 INFO - 'SHELL': '/bin/bash', 04:17:20 INFO - 'SHLVL': '1', 04:17:20 INFO - 'TERM': 'linux', 04:17:20 INFO - 'TMOUT': '86400', 04:17:20 INFO - 'USER': 'cltbld', 04:17:20 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:20 INFO - '_': '/tools/buildbot/bin/python'} 04:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:20 INFO - Downloading/unpacking flake8 04:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:17:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 04:17:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:17:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 04:17:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 04:17:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:17:22 INFO - no previously-included directories found matching 'docs/_build' 04:17:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:17:22 INFO - Running setup.py install for pep8 04:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:17:22 INFO - no previously-included directories found matching 'docs/_build' 04:17:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:17:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:17:22 INFO - Cleaning up... 04:17:22 INFO - Return code: 0 04:17:22 INFO - Running post-action listener: _resource_record_post_action 04:17:22 INFO - Running post-action listener: _start_resource_monitoring 04:17:23 INFO - Starting resource monitoring. 04:17:23 INFO - ##### 04:17:23 INFO - ##### Running install step. 04:17:23 INFO - ##### 04:17:23 INFO - Running pre-action listener: _resource_record_pre_action 04:17:23 INFO - Running main action method: install 04:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:23 INFO - Reading from file tmpfile_stdout 04:17:23 INFO - Detecting whether we're running mozinstall >=1.0... 04:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:17:23 INFO - Reading from file tmpfile_stdout 04:17:23 INFO - Output received: 04:17:23 INFO - Usage: mozinstall [options] installer 04:17:23 INFO - Options: 04:17:23 INFO - -h, --help show this help message and exit 04:17:23 INFO - -d DEST, --destination=DEST 04:17:23 INFO - Directory to install application into. [default: 04:17:23 INFO - "/builds/slave/test"] 04:17:23 INFO - --app=APP Application being installed. [default: firefox] 04:17:23 INFO - mkdir: /builds/slave/test/build/application 04:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:17:37 INFO - Reading from file tmpfile_stdout 04:17:37 INFO - Output received: 04:17:37 INFO - /builds/slave/test/build/application/firefox/firefox 04:17:37 INFO - Running post-action listener: _resource_record_post_action 04:17:37 INFO - ##### 04:17:37 INFO - ##### Running run-tests step. 04:17:37 INFO - ##### 04:17:37 INFO - Running pre-action listener: _resource_record_pre_action 04:17:37 INFO - Running main action method: run_tests 04:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:17:37 INFO - Python 2.7.3 04:17:37 INFO - Return code: 0 04:17:37 INFO - grabbing minidump binary from tooltool 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:37 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174f0b0>, '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 0x174ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1750440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:17:37 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 04:17:37 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 04:17:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:17:37 INFO - Return code: 0 04:17:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:17:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:17:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:17:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:17:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:17:38 INFO - Return code: 0 04:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost'] in /builds/slave/test/build 04:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost 04:17:38 INFO - Using env: {'DISPLAY': ':0', 04:17:38 INFO - 'HOME': '/home/cltbld', 04:17:38 INFO - 'LANG': 'en_US.UTF-8', 04:17:38 INFO - 'LANGUAGE': 'en_US:en', 04:17:38 INFO - 'LOGNAME': 'cltbld', 04:17:38 INFO - 'MAIL': '/var/mail/cltbld', 04:17:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:17:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:38 INFO - 'MOZ_NO_REMOTE': '1', 04:17:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:17:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:38 INFO - 'NO_EM_RESTART': '1', 04:17:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:38 INFO - 'PWD': '/builds/slave/test', 04:17:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:17:38 INFO - 'SHELL': '/bin/bash', 04:17:38 INFO - 'SHLVL': '1', 04:17:38 INFO - 'TERM': 'linux', 04:17:38 INFO - 'TMOUT': '86400', 04:17:38 INFO - 'USER': 'cltbld', 04:17:38 INFO - 'XDG_SESSION_COOKIE': '820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081', 04:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:38 INFO - '_': '/tools/buildbot/bin/python'} 04:17:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost'] with output_timeout 3600 04:17:39 INFO - mozversion INFO | application_buildid: 20151216030231 04:17:39 INFO - mozversion INFO | application_changeset: 0babaa3edcf908c393b68a3dc2d1c2a2450c31ed 04:17:39 INFO - mozversion INFO | application_display_name: Nightly 04:17:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:17:39 INFO - mozversion INFO | application_name: Firefox 04:17:39 INFO - mozversion INFO | application_remotingname: firefox 04:17:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:17:39 INFO - mozversion INFO | application_vendor: Mozilla 04:17:39 INFO - mozversion INFO | application_version: 46.0a1 04:17:39 INFO - mozversion INFO | platform_buildid: 20151216030231 04:17:39 INFO - mozversion INFO | platform_changeset: 0babaa3edcf908c393b68a3dc2d1c2a2450c31ed 04:17:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:17:39 INFO - mozversion INFO | platform_version: 46.0a1 04:17:39 INFO - 2015-12-16 04:17:39,024 DEBUG : using testdate: 1450268259 04:17:39 INFO - 2015-12-16 04:17:39,024 DEBUG : actual date: 1450268259 04:17:39 INFO - 2015-12-16 04:17:39,036 INFO : starting webserver on 'localhost:41747' 04:17:39 INFO - 2015-12-16 04:17:39,037 INFO : Starting test suite talos-linux64-ix-055.e 04:17:39 INFO - 2015-12-16 04:17:39,037 INFO : Starting test dromaeo_css 04:17:39 INFO - 2015-12-16 04:17:39,037 DEBUG : operating with platform_type : linux_ 04:17:39 INFO - 2015-12-16 04:17:39,038 INFO : Initialising browser for dromaeo_css test... 04:17:39 INFO - 2015-12-16 04:17:39,050 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphsbghS/profile http://localhost:41747/getInfo.html 04:17:43 INFO - 2015-12-16 04:17:43,974 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:17:43 INFO - 2015-12-16 04:17:43,974 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:17:43 INFO - 2015-12-16 04:17:43,975 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:17:43 INFO - 2015-12-16 04:17:43,975 DEBUG : BROWSER_OUTPUT: __metrics 04:17:44 INFO - 2015-12-16 04:17:44,124 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:17:45 INFO - 2015-12-16 04:17:45,064 INFO : Browser initialized. 04:17:45 INFO - 2015-12-16 04:17:45,065 INFO : Running cycle 1/1 for dromaeo_css test... 04:17:45 INFO - 2015-12-16 04:17:45,065 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphsbghS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:17:57 INFO - 2015-12-16 04:17:57,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 143314944 04:17:57 INFO - 2015-12-16 04:17:57,042 DEBUG : BROWSER_OUTPUT: 04:18:46 INFO - 2015-12-16 04:18:46,662 DEBUG : BROWSER_OUTPUT: 1450268326662 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] 04:20:58 INFO - 2015-12-16 04:20:58,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41747/tests/dromaeo/cssquery-ext.html) 04:20:59 INFO - 2015-12-16 04:20:59,501 DEBUG : BROWSER_OUTPUT: RSS: Main: 146362368 04:20:59 INFO - 2015-12-16 04:20:59,501 DEBUG : BROWSER_OUTPUT: 04:24:01 INFO - 2015-12-16 04:24:01,527 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/dromaeo/cssquery-ext.html (next: http://localhost:41747/tests/dromaeo/cssquery-jquery.html) 04:24:02 INFO - 2015-12-16 04:24:02,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 143753216 04:24:02 INFO - 2015-12-16 04:24:02,116 DEBUG : BROWSER_OUTPUT: 04:27:03 INFO - 2015-12-16 04:27:03,943 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41747/tests/dromaeo/cssquery-mootools.html) 04:27:04 INFO - 2015-12-16 04:27:04,536 DEBUG : BROWSER_OUTPUT: RSS: Main: 144941056 04:27:04 INFO - 2015-12-16 04:27:04,536 DEBUG : BROWSER_OUTPUT: 04:30:06 INFO - 2015-12-16 04:30:06,356 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41747/tests/dromaeo/cssquery-prototype.html) 04:30:06 INFO - 2015-12-16 04:30:06,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 144109568 04:30:06 INFO - 2015-12-16 04:30:06,978 DEBUG : BROWSER_OUTPUT: 04:33:09 INFO - 2015-12-16 04:33:09,555 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41747/tests/dromaeo/cssquery-yui.html) 04:33:10 INFO - 2015-12-16 04:33:10,189 DEBUG : BROWSER_OUTPUT: RSS: Main: 144695296 04:33:10 INFO - 2015-12-16 04:33:10,189 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,566 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/dromaeo/cssquery-yui.html (next: http://localhost:41747/tests/dromaeo/cssquery-dojo.html) 04:36:12 INFO - 2015-12-16 04:36:12,824 DEBUG : BROWSER_OUTPUT: RSS: Main: 146825216 04:36:12 INFO - 2015-12-16 04:36:12,824 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,824 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:36:12 INFO - 2015-12-16 04:36:12,825 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:36:12 INFO - 2015-12-16 04:36:12,825 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:36:12 INFO - 2015-12-16 04:36:12,825 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:36:12 INFO - 2015-12-16 04:36:12,825 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2686.9731302686973;2707.0660622085375;2738.931526711831;2748.711385304543;2768.8892444302223;4586.403767510219;4722.173619616567;4723.0553889222165;4746.833860814872;4752.287156926463;4721.480637129922;4733.90532189356;4734.076855013273;4742.33607294979;4880.682755620882;4344.522102568715;4348.152110338485;4412.580804823537;4413.955860441387;4441.4004109445295;4424.336349547578;4473.1501014807245;4488.326750987361;4536.500984891659;4584.40402747644;4212.410262563244;4291.098869237464;4303.203907277164;4303.505096913864;4443.42235509385;4235.0682849773;4324.135172965388;4346.174226896911;4356.390105385217;4368.650507959355;4400.581944715247;4412.205802955466;4419.18245124653;4426.291793313055;4460.8884777880485;4459.308807134899;4572.245579479415;4591.770411479446;4696.9530304696855;4735.076660051292;5146.871328216825;5192.636515443921;5228.843134705965;5233.555147812441;5240.266362709223;5254.632175747699;5256.106461901486;5266.7629956652045;5273.894522109536;5310.283111779899;4602.79287432063;4675.064987002613;4696.178168820443;4805.687630304019;4850.442199147108;5286.101362768339;5297.390800057966;5325.201219816981;5331.040412725747;5336.439673834269;5121.4878512149235;5122.564582010496;5126.897462050738;5135.15269980449;5150.716710580954;4928.211486162197;4989.426215985136;5010.874728131826;5079.466656001141;5113.9772045591035;5098.821541246039;5137.948620513747;5156.278121063054;5160.071187186268;5206.088934436459;4830.420349558076;5121.411037730634;5121.795128194905;5123.692578445305;5130.384353877484;4149.771762553029;4204.117135401539;4216.746995180238;4220.957790422057;4257.957420425818;3737.682297004785;3740.9064773380883;3749.9437508437395;3751.9062023449633;3783.697304215656;4196.51740049892;4309.202797482476;4429.335599660077;4444.888877778081;4476.820927162942;4995.075910956485;5053.84838454847;5068.873278168076;5082.466341034212;5119.411267704188;4453.51011388747;4496.910061798746;4502.842400515966;4505.797239124247;4519.570640789186;4321.632661223768;4368.563143685606;4600.148972440109;4604.355390245369;4638.930416043762;4761.214399624023;4804.399450068741;4826.734529600765;4830.516948305141;4859.878503037382;5148.948510514847;5173.922391164211;5175.792968281226;5186.9221961669855;5187.014467251211;4733.266343716729;4805.327254184347;4812.181929071996;4904.558589726942;4957.975210123926;5115.2071428928575;5120.206368012964;5130.615203859651;5140.100482415637;5150.948490515047;5182.611304152279;5188.922166167435;5191.195364718475;5194.272801807674;5236.895262094813;4814.446338671098;4826.493218212037;4937.580305674058;4955.529224723646;5002.299678045004;4930.654854160318;4939.728314942713;4959.677620954626;4972.825951091694;5022.84931452057;4850.636202284913;4858.732769697701;4875.512448755097;4895.632827537877;4941.925871112008;4862.562369386772;4927.531884470969;4966.627502937222;4968.925466117938;5006.649534532694;4841.564259216688;4960.652754307308;4962.082014827269;4978.701277923336;4997.300377947017 04:36:12 INFO - 2015-12-16 04:36:12,825 DEBUG : BROWSER_OUTPUT: |1;ext.html;568.8606291458593;570.1533223163602;577.053632043449;581.3750218515097;585.2917969257198;34366.14084647882;34494.65505344946;34556;34575.13562160947;34618.65381346186;39613.40579891304;39797.20405591886;40016.59983400165;40067.799661001656;40113.398299025466;8032.558209298486;8182.836343273131;8183.140770219131;8189.344852411822;8204.958975205116;7911.406644501685;8021.919780802175;8037.115917249098;8037.919620803805;8045.034595848507;7931.801704957365;7979.640916158787;8003.479773814713;8015.078265999397;8022.277994980451;8032.558209298486;8063.516189028648;8111.837763244702;8129.227723366271;8162.918370816305;7872.976513053323;7975.282224599814;7992.8401431971615;8012.5593092379895;8033.437659363847;35917.28165436703;36230.63769362299;36284.63715362839;36307.4553881692;36475.81762091199;3086.4135814195456;3182.236263296815;3212.3575284943104;3358.899233023013;3414.8804791832417;27268;27474.313142171406;27619.723802761917;27775.86112069447;28022.2994425141;3410.880619178317;3421.6578342165835;3502;3505.3418057398185;3539.7876127432437;205464;206537;206675;207315;207484;51690.44928652146;51990.74004629953;52001.219981700306;52037.2194417084;52097.21854172191;47898.5210147901;48446.757766211646;54989.450105499236;55097.724511377186;61300;13503.864961350457;13517.256550889795;13624.114432561852;13632.182069075689;13753.79369309461;11126.054285385671;11141.554337826557;11164.832527512093;11188.216824822184;11254.21220514556;18132.365367212085;18171.63656726858;18197.181126849322;18228.635427291647;18311.54221144482;7284.526505777214;7503.287187717158;7553.848923021509;7562.432817538596;7593.734219302298;860.3762272352619;861.6984055580497;897.3539051882634;986.2652324018509;1016.4968340671415;25643.102491412712;25768.8711556441;26053;26087;26138.86930565335;888.657866721317;953.8283109040301;958.6165533786541;980.666573365045;983.1986930651497;12345.876541234653;12704.74590508203;12830.80753788694;12846.550370737166;13172.47310107585;255949;257076;258040;259193;260631;43608.12783744308;43701.34447983283;43771;43881.34177987333;43899.12201756011;1223.8959688426405;1233.975320493585;1247.7566874459394;1251.4931452761464;1252.473960936409;23732.288031359087;26439.471210576066;27905.302367440978;28489.28776780514;28576.857115714287;39627.2074558513;39791;39985.80007099946;40162.79918600388;40179.19641607093;195135;197108;197132;197184;197195;28113.71886281193;28481;28930.7106928928;28931.566026509197;28969.275768105967;32809.68761249523;32985.67014329826;35120.64879351276;35892.82053589715;36304.81847590745;30945.226369340948;31429.8428507856;31488.842555787076;32042.19894502563;32045.51931721073;31203.12960585109;31443.39152119701;31629.525557117122;31693.84153079312;31968.52047219247;31232.21919452032;31700.5244921331;31851;31895.840520797246;32277.35445291128;26659;28029.439411212068;28658.85670571634;28687;28998.27504312409;22650.660240096742;23070.884645576665;23209.883950580814;23311.18410855612;23353.76646233516 04:36:12 INFO - 2015-12-16 04:36:12,825 DEBUG : BROWSER_OUTPUT: |2;jquery.html;334.2496096263888;337.4061651493371;344.5039143633168;344.62435944820146;345.3042120127941;12581.93709031456;12994.350282485862;15050;15245.847541524581;17290.308387664492;9628.326017178782;9952.552135153917;10015.699529014122;14561.344739486722;15100.622484437894;4452.621527170187;4593.517680643535;4605.240135377659;4615.261558150696;4615.330777037328;4608.769561521927;4987.276844857493;4988.526090021461;4993.651714037208;4996;4952.87617809554;4953.678010929278;4956.330895329121;4959.504049595048;4973.477784832595;4899.828506002291;4963.70217786934;4990.87522811929;5015.247712843066;5049.974750126225;4529.750863702495;4545.772711364419;4546.1135078659345;4552.4764652065105;4552.931706024412;11841.28952262867;11936.940315298369;12034.879651203551;12105.697357566043;16896.90170138949;1849.3712137873054;1880.61447403282;1880.61447403282;1883.0867029490687;1893.829555340026;9130.360874738772;9133.08669133088;9190.264778817678;9301.860472092923;9307.441553506744;1746.4847869878417;1750.6148647297575;1758.0561007726926;1763.2706825065025;1803.0173555412332;53317.43267585111;54215.457845421435;55171.72414137904;56134;56585.43414565884;9322.766930826783;9465.668701595549;9515;9585.808283834283;9642.855357169648;10598.629047983437;11037.282576632653;11180.664580062448;12110.515579376726;12132.757344853053;5388.595855310868;5528.115501519737;5905.675187864708;6897.862042759217;6914.757983470555;6627.138471998609;6629.900551491732;6715.227748808852;6749.807790236778;6751.122354094035;4160.084781348099;4225.3873188388125;4238.512571054368;4239.0885959518855;4247.426597409372;1692.6022384739576;1721.5093698295925;1739.5911960689227;1750.0287340526131;1773.4147731248656;390.6659805866;405.2300628805294;408.9059516311265;422.5964204185057;852.2542775071812;9404.200642945427;9736.318457707892;9836.262280328861;9942.353747006422;9943.602255909682;400.18961602754655;412.07900342733967;412.91741651669787;417.92268430339874;418.87643145272216;4279.593438623326;5554.111342185231;5560.277163968739;5577.8844231153935;5601.075822489243;464471;467249;478030;481209;491468;70430;74222;74666;75451.62274188704;77880.61059694666;448.8227150275623;450.70929250633816;464.29427270548575;466.4612372709526;466.50550416557877;9180.569454194385;9477.573509191965;9649.951750241204;9695.6121755129;9811.950940245253;26826.463470730865;29187.7081229185;33534.65861365518;33656.1585960353;33844.83077584596;39408.2266298023;41773.164536708486;41790.373144403464;42106.368404473345;44738.3289250655;8592.65629374818;8816.338774591803;8987.146221108984;9002.909970900208;9026.097390260922;16034.919825400799;16097.919510402842;16103;16180.919095404448;16351.754723678916;13757;13887.583372498437;13981.650458738613;13982.58052258434;14103.435862565793;12916.935415322863;13335.466581337027;13355.866441335462;13606.523771667944;14128.78806817919;13824.101433406797;13917.512887048904;14116.788248176492;14248.430062797786;14502.1298722077;8797.780055498664;8824.647014119364;8889.111088891059;8923.866142007746;9120.726378208665;12983.675408114872;13510.72978540404;13662.385192666155;13671.726565468436;13946.651333716738 04:36:12 INFO - 2015-12-16 04:36:12,826 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1463.3122432456742;1684.3262694922034;1701.9319227230912;1704.1990264575645;1755.1048965027835;6205.255369355672;6328.24061112666;7280.963595182024;7538.7738367848915;7541.773746787591;6722.764703235388;6858.725650973955;6974.47691423144;6980.72077116916;7005.369516743492;2033.8169564739133;2061.9587608247825;2070.2650559051544;2072.057213967641;2077.054940002303;2089.8850563219016;2115.883626400547;2118.33272519156;2136.7542732585716;2143.506993391513;2039.255671679841;2045.3966080006435;2056.4036429435446;2065.9690104648444;2066.4007437843006;1984.8412127029803;1998.490384951828;1999.9900000499904;2009.4474019644642;2010.2963962613042;2030.0661695620033;2048.1602542957316;2050.815426611597;2077.657186564215;2082.55225126597;7128.6435678215885;7173.5337203081635;7181.74863879767;7188.604626745527;7224.638768061628;4115.464989551339;4254.234237837188;4256.4679415073115;4257.701960862741;4258.063226090255;2680.423708902576;2683.0877501649534;2700.486907487571;2763.847988360639;2774.209350335145;894.4320356573558;895.8029233568604;897.1521911793358;901.7294811556573;923.8845144356956;9834.557444914995;9875.259355548362;9886.851697224547;9894.653687120915;9901.75245618858;3818.1218319786603;3832.1760821423527;3854.094287842355;3901.180752042103;3902.863399780994;8444.197801208875;8478.06741258461;8522.360822938304;8530.360222983301;8567.957160214284;4267.082577245845;4288.078063216423;4290.849820256276;4291.957080429218;4306.20335237982;3323.1692076980753;3382.797032178028;3815.8855234343014;3876.573576906538;3907.7069219808627;3513.94729079064;3528.1532432216595;3552.2007548301426;3578.7673801202836;3590.1563132663805;1083.8536797532233;1086.576235268246;1099.796537640539;1102.3441052573705;1117.0560876059717;1194.8805119487981;1199.7120691034263;1217.4825699077855;1217.713837248246;1232.5870833270956;6778.356056174655;6890.689918953659;6891.517593768388;6999.265077166926;7137.321954414322;966.6181858165985;978.1099199728213;983.1250187333279;1037.2211657008268;1063.6649455421518;5418.485243901823;5454.4545545445135;5464.28964234647;5470.370907345706;5479.123340265538;8589.26991205742;8749.212570868653;8848.513331766686;8928.464292142493;8935.910640893639;8737.213650771333;8803.69187078462;8851.291896648252;8874.86687699685;8883.022867484571;6834.111565496453;6870.557182991528;6888.173419189729;7005.404540614105;7078.823029424203;6709.832254193489;6770.52606317553;6780.423663988616;6781.93218067813;6782.93217067823;7413.962930185315;7689.615519224129;7800.492967957065;7830.804229894298;7837.6081195941115;7833.255840695125;7979.680812767424;8094.271515563392;8127.75616731499;8141.348692104736;5339.839804805862;5440.183972404013;5894.498967587631;6070.453659170696;6143.907841382294;7015.403690686348;7030.191527974144;7045.154581450259;7056.611886346095;7078.610676412847;6351.2060992375955;6690.531662783558;6691;6728.495362847708;6796.184457865088;6324.873502530016;6382.840428989312;6495.188101487314;6685.763133820259;6689.331066893272;6568.376004279586;6627.37039981201;6627.867442651216;6628;6637.834054148491;6074.301455332693;6080.209572755513;6174.25908890936;6175.536834737502;6180.103884936569;6014.248218972629;6018.097285407224;6020.337762845996;6026.367231440636;6046.183765191643 04:36:12 INFO - 2015-12-16 04:36:12,828 DEBUG : BROWSER_OUTPUT: |4;prototype.html;306.06649718359006;310.3111093372713;314.7104663709387;315.663818033794;319.9600049993751;5557.833265002051;5688.260526131602;5701.2018317435595;5703.001974654435;5703.77184912604;5606.074997625397;5607.523360514361;5612.943870561293;5625;5634.746436410361;4278.807453664577;4340.327249276367;4345.543717909622;4354.80403381847;4367.58507941745;4352.912941741163;4369.847055353063;4374.628156606676;4378.299472084467;4407.3609326647775;4335.263005289093;4341.044970106572;4346.087321662455;4350.0429905420915;4354.194474022316;4338.370936214263;4338.696291259613;4341.175176716445;4355.390245365651;4358.912821743579;3735.7384983051197;3827.808609569538;3834.961650383488;3852.57621661617;3859.8842034739005;5257.106291930381;5391.865203369908;5405.891882162374;5422.810201642924;5426.240326354314;4939.135651261015;4946.480619534969;4956.479569645171;4969.527894849984;4975;5929.169916211734;5931.466168044896;5933.376995415506;5933.732982015819;5938.61399009063;4009.158076803875;4068.65416439603;4095.795210239476;4097.508299004109;4118.588141185887;6139.84650383744;6244.0633904914175;6244.250689917239;6247.531435142382;6262.436380725711;4287.121140166259;4316.266234740102;4322.416473776063;4323.502797178302;4324.524302326741;4387.846425375161;4390.868273951786;4393.538678438782;4399.824007039747;4400.515943246241;4873.439554451284;4969.080720066727;4972.65191436596;4994.675346102492;4998.750062496861;5887.676177810176;5889.411058894077;5904.881902361929;5905.055191169392;5916.112583112567;1526.9160196189093;1554;1557.1435710359258;1559.3918371834993;1560.3680509393714;1048.0881632979356;1063.0645032371617;1063.5798859450474;1075.978480430387;1085.1861104171871;5120.1039818031695;5142.1772516397195;5143.408508021551;5161.5870730341485;5161.612879034087;6169.475594574512;6178.598391104564;6181.041938499539;6190.938090619126;6193.659348735772;5124.436312005676;5132.5124113209185;5143.7685304161405;5163.638545301868;5167.793288268503;3261.5596894419423;3278.672132786702;3311.3211791582667;3326.966730332714;3330.1008727643402;5711;5746.683932383676;5805.970970145207;5806.535477161817;5902.321233058254;6372.458341041063;6385.968070159713;6388.456981156561;6392.712327945161;6398.424141827258;5866.178734977107;5867.501262392744;5869.5010924071075;5871.295444546597;5876.3536011037895;5987.101934709828;6204.224471941007;6209.503239740719;6233.625982441068;6242.406971337716;5992.760289588367;6006.189164462741;6028.246469191351;6058.091286307089;6073;5806.44838740319;5851.093080572347;5858.765649374148;5863.413658634273;5883.823485295448;4135.7932103394105;4206;4208.579142085767;4211.978940105279;4215.641670457922;6000.759969601167;6172.783952561818;6175.505959523138;6178.66017369049;6188.381161883775;5942.584019118794;5944.5838791284195;5945.375735547878;5954.76180952757;5959.7020148991505;5919.556033297434;5956.166136740946;5966.612170208922;5968.104784282219;5980.102984552178;5727.513161381329;5828.271466066742;5834.883302333845;5841.094630332305;5842.853928651818;5971.91042134377;5996.920554300268;6009.789657361971;6015.72929218195;6026.126211699367;5841.707914604338;5845.3570107289015;5850.795222167204;5856.707164641665;5859.707014649336 04:36:12 INFO - 2015-12-16 04:36:12,828 DEBUG : BROWSER_OUTPUT: |5;yui.html;649.9252585952615;653.5980372071176;696.0707455546848;731.1043971135357;742.046470285683;11823.527058917643;12035.458404371802;12057;12101.273923564593;12112.757744845108;13792.10351327163;13980.161190328587;13981.300934953262;13993.860061399408;14008.579742607713;797.4417907464768;822.321584692628;844.2907957315854;844.4426678392263;845.8562044452458;825.7852958230873;828.3787159630277;831.9708810191645;833.5707110837893;833.6957010691115;861.5993562993207;864.6022829498438;864.6584599083359;868.227277722824;870.5212133326645;845.7631863078348;846.6528723223449;849.1126772522729;850.268768858781;851.0255757158051;630.2059405149499;631.0471188505389;634.0077778277027;635.7711223959371;638.1544453598954;11562.653120406401;11604.071674266039;11667.59163429276;11676.766464670744;11722.12084093688;10497.107745841613;10665.253432259746;10665.84001239982;10912.345259284468;11649.534018639244;9093.408928419698;9112.635494580209;9233.399829011092;9265.25877929764;9463.101005404476;3200.679932006804;3201.5357773123;3231.757618178646;3237.96762032379;3251.3009702913987;39503.80248098741;40110.79944600288;40290;40305.39541906874;40557.18885622301;9050.185483306534;9224.953875230582;9263.861042084374;9328.766780830534;9415.764605884771;14237.928810355881;14307.213103279211;14398.928005360116;14464;14464.493742718954;6185.134081228632;6252.937470625327;6326.177596912372;6355.61866288015;6437;3390.5931288245074;3446.5347178131133;3466.8093254871224;3481.4777783332197;3520.3663340598428;2421.6730741350043;2442.023190723725;2442.973950940609;2448.4858179782445;2472.295395812185;462.6090953144655;476.16195495926723;481.40306020534285;483.7774623673079;488.47001003911157;519.2756105233254;589.675678376891;609.7835268479714;610.8106486989047;611.6910959965189;7748.302652761278;8673.479591224546;8788.8681669775;8985.326100542485;9040.728778136536;448.0255444408399;506.02590014222477;513.6661170239374;537.3664061253745;611.8164550634792;3693.353663108945;3761.586225515191;3769.6607305342104;3867.903302417462;3907.238088572701;48597.757011214715;49010.26484602734;49297.753511232215;49309.50690493121;49415;44366.77816610961;44743.77628111904;44790.77604611956;45017.324740128926;45023.32465013027;39505.40741888874;40208;40319.79840100781;40412;40673;7794.259545343183;7848.254415830487;7886.487378320391;7915.26096447717;8036.95981520112;29395;29862;30191.547126793554;30240;30384.84807575948;35438.46842297419;35549.28901421905;37431.43852842155;38715;38730.6126938727;9170.856149197021;9235.630574777202;9391.765205869908;9554;9566.330356874952;9946.254030947852;10090.949545252226;10097.747556311151;10241.48792560354;10372.948135259276;9902.702918912444;9977.700668979942;10052.84920726175;10123.24075694311;10504.789904202025;9839.754006149904;9949;10016.148627366756;10029.648962286285;10308.32995855267;9782.804343913223;9793.314467987173;10626.415547144672;10777.08394786452;11004.834927475935;8904.643814247358;9111.172056146946;9323.673671421468;9446.905530944603;9482.573284202366;8943.463392196489;8959.865602016105;9054.502002389932;9277.86083208766;9615.51922403891 04:36:12 INFO - 2015-12-16 04:36:12,829 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:36:12 INFO - 2015-12-16 04:36:12,829 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:36:12 INFO - 2015-12-16 04:36:12,829 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,331 04:36:12 INFO - 2015-12-16 04:36:12,829 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269372819__endTimestamp 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4723.48 Median:4830.52 stddev:509.19 (10.5%) stddev-sans-first:485.04 04:36:12 INFO - 2015-12-16 04:36:12,830 DEBUG : BROWSER_OUTPUT: Values: 2687.0 2707.1 2738.9 2748.7 2768.9 4586.4 4722.2 4723.1 4746.8 4752.3 4721.5 4733.9 4734.1 4742.3 4880.7 4344.5 4348.2 4412.6 4414.0 4441.4 4424.3 4473.2 4488.3 4536.5 4584.4 4212.4 4291.1 4303.2 4303.5 4443.4 4235.1 4324.1 4346.2 4356.4 4368.7 4400.6 4412.2 4419.2 4426.3 4460.9 4459.3 4572.2 4591.8 4697.0 4735.1 5146.9 5192.6 5228.8 5233.6 5240.3 5254.6 5256.1 5266.8 5273.9 5310.3 4602.8 4675.1 4696.2 4805.7 4850.4 5286.1 5297.4 5325.2 5331.0 5336.4 5121.5 5122.6 5126.9 5135.2 5150.7 4928.2 4989.4 5010.9 5079.5 5114.0 5098.8 5137.9 5156.3 5160.1 5206.1 4830.4 5121.4 5121.8 5123.7 5130.4 4149.8 4204.1 4216.7 4221.0 4258.0 3737.7 3740.9 3749.9 3751.9 3783.7 4196.5 4309.2 4429.3 4444.9 4476.8 4995.1 5053.8 5068.9 5082.5 5119.4 4453.5 4496.9 4502.8 4505.8 4519.6 4321.6 4368.6 4600.1 4604.4 4638.9 4761.2 4804.4 4826.7 4830.5 4859.9 5148.9 5173.9 5175.8 5186.9 5187.0 4733.3 4805.3 4812.2 4904.6 4958.0 5115.2 5120.2 5130.6 5140.1 5150.9 5182.6 5188.9 5191.2 5194.3 5236.9 4814.4 4826.5 4937.6 4955.5 5002.3 4930.7 4939.7 4959.7 4972.8 5022.8 4850.6 4858.7 4875.5 4895.6 4941.9 4862.6 4927.5 4966.6 4968.9 5006.6 4841.6 4960.7 4962.1 4978.7 4997.3 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:37935.36 Median:18172.28 stddev:57710.56 (317.6%) stddev-sans-first:57804.25 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: Values: 568.9 570.2 577.1 581.4 585.3 34366.1 34494.7 34556.0 34575.1 34618.7 39613.4 39797.2 40016.6 40067.8 40113.4 8032.6 8182.8 8183.1 8189.3 8205.0 7911.4 8021.9 8037.1 8037.9 8045.0 7931.8 7979.6 8003.5 8015.1 8022.3 8032.6 8063.5 8111.8 8129.2 8162.9 7873.0 7975.3 7992.8 8012.6 8033.4 35917.3 36230.6 36284.6 36307.5 36475.8 3086.4 3182.2 3212.4 3358.9 3414.9 27268.0 27474.3 27619.7 27775.9 28022.3 3410.9 3421.7 3502.0 3505.3 3539.8 205464.0 206537.0 206675.0 207315.0 207484.0 51690.4 51990.7 52001.2 52037.2 52097.2 47898.5 48446.8 54989.5 55097.7 61300.0 13503.9 13517.3 13624.1 13632.2 13753.8 11126.1 11141.6 11164.8 11188.2 11254.2 18132.4 18171.6 18197.2 18228.6 18311.5 7284.5 7503.3 7553.8 7562.4 7593.7 860.4 861.7 897.4 986.3 1016.5 25643.1 25768.9 26053.0 26087.0 26138.9 888.7 953.8 958.6 980.7 983.2 12345.9 12704.7 12830.8 12846.6 13172.5 255949.0 257076.0 258040.0 259193.0 260631.0 43608.1 43701.3 43771.0 43881.3 43899.1 1223.9 1234.0 1247.8 1251.5 1252.5 23732.3 26439.5 27905.3 28489.3 28576.9 39627.2 39791.0 39985.8 40162.8 40179.2 195135.0 197108.0 197132.0 197184.0 197195.0 28113.7 28481.0 28930.7 28931.6 28969.3 32809.7 32985.7 35120.6 35892.8 36304.8 30945.2 31429.8 31488.8 32042.2 32045.5 31203.1 31443.4 31629.5 31693.8 31968.5 31232.2 31700.5 31851.0 31895.8 32277.4 26659.0 28029.4 28658.9 28687.0 28998.3 22650.7 23070.9 23209.9 23311.2 23353.8 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25301.38 Median:2490.23 stddev:78017.51 (3132.9%) stddev-sans-first:78213.85 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: Values: 334.2 337.4 344.5 344.6 345.3 12581.9 12994.4 15050.0 15245.8 17290.3 9628.3 9952.6 10015.7 14561.3 15100.6 4452.6 4593.5 4605.2 4615.3 4615.3 4608.8 4987.3 4988.5 4993.7 4996.0 4952.9 4953.7 4956.3 4959.5 4973.5 4899.8 4963.7 4990.9 5015.2 5050.0 4529.8 4545.8 4546.1 4552.5 4552.9 11841.3 11936.9 12034.9 12105.7 16896.9 1849.4 1880.6 1880.6 1883.1 1893.8 9130.4 9133.1 9190.3 9301.9 9307.4 1746.5 1750.6 1758.1 1763.3 1803.0 53317.4 54215.5 55171.7 56134.0 56585.4 9322.8 9465.7 9515.0 9585.8 9642.9 10598.6 11037.3 11180.7 12110.5 12132.8 5388.6 5528.1 5905.7 6897.9 6914.8 6627.1 6629.9 6715.2 6749.8 6751.1 4160.1 4225.4 4238.5 4239.1 4247.4 1692.6 1721.5 1739.6 1750.0 1773.4 390.7 405.2 408.9 422.6 852.3 9404.2 9736.3 9836.3 9942.4 9943.6 400.2 412.1 412.9 417.9 418.9 4279.6 5554.1 5560.3 5577.9 5601.1 464471.0 467249.0 478030.0 481209.0 491468.0 70430.0 74222.0 74666.0 75451.6 77880.6 448.8 450.7 464.3 466.5 466.5 9180.6 9477.6 9650.0 9695.6 9812.0 26826.5 29187.7 33534.7 33656.2 33844.8 39408.2 41773.2 41790.4 42106.4 44738.3 8592.7 8816.3 8987.1 9002.9 9026.1 16034.9 16097.9 16103.0 16180.9 16351.8 13757.0 13887.6 13981.7 13982.6 14103.4 12916.9 13335.5 13355.9 13606.5 14128.8 13824.1 13917.5 14116.8 14248.4 14502.1 8797.8 8824.6 8889.1 8923.9 9120.7 12983.7 13510.7 13662.4 13671.7 13946.7 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,831 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5061.17 Median:6177.82 stddev:2634.80 (42.6%) stddev-sans-first:2628.31 04:36:12 INFO - 2015-12-16 04:36:12,833 DEBUG : BROWSER_OUTPUT: Values: 1463.3 1684.3 1701.9 1704.2 1755.1 6205.3 6328.2 7281.0 7538.8 7541.8 6722.8 6858.7 6974.5 6980.7 7005.4 2033.8 2062.0 2070.3 2072.1 2077.1 2089.9 2115.9 2118.3 2136.8 2143.5 2039.3 2045.4 2056.4 2066.0 2066.4 1984.8 1998.5 2000.0 2009.4 2010.3 2030.1 2048.2 2050.8 2077.7 2082.6 7128.6 7173.5 7181.7 7188.6 7224.6 4115.5 4254.2 4256.5 4257.7 4258.1 2680.4 2683.1 2700.5 2763.8 2774.2 894.4 895.8 897.2 901.7 923.9 9834.6 9875.3 9886.9 9894.7 9901.8 3818.1 3832.2 3854.1 3901.2 3902.9 8444.2 8478.1 8522.4 8530.4 8568.0 4267.1 4288.1 4290.8 4292.0 4306.2 3323.2 3382.8 3815.9 3876.6 3907.7 3513.9 3528.2 3552.2 3578.8 3590.2 1083.9 1086.6 1099.8 1102.3 1117.1 1194.9 1199.7 1217.5 1217.7 1232.6 6778.4 6890.7 6891.5 6999.3 7137.3 966.6 978.1 983.1 1037.2 1063.7 5418.5 5454.5 5464.3 5470.4 5479.1 8589.3 8749.2 8848.5 8928.5 8935.9 8737.2 8803.7 8851.3 8874.9 8883.0 6834.1 6870.6 6888.2 7005.4 7078.8 6709.8 6770.5 6780.4 6781.9 6782.9 7414.0 7689.6 7800.5 7830.8 7837.6 7833.3 7979.7 8094.3 8127.8 8141.3 5339.8 5440.2 5894.5 6070.5 6143.9 7015.4 7030.2 7045.2 7056.6 7078.6 6351.2 6690.5 6691.0 6728.5 6796.2 6324.9 6382.8 6495.2 6685.8 6689.3 6568.4 6627.4 6627.9 6628.0 6637.8 6074.3 6080.2 6174.3 6175.5 6180.1 6014.2 6018.1 6020.3 6026.4 6046.2 04:36:12 INFO - 2015-12-16 04:36:12,833 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,833 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4927.82 Median:5581.95 stddev:1448.23 (25.9%) stddev-sans-first:1410.13 04:36:12 INFO - 2015-12-16 04:36:12,833 DEBUG : BROWSER_OUTPUT: Values: 306.1 310.3 314.7 315.7 320.0 5557.8 5688.3 5701.2 5703.0 5703.8 5606.1 5607.5 5612.9 5625.0 5634.7 4278.8 4340.3 4345.5 4354.8 4367.6 4352.9 4369.8 4374.6 4378.3 4407.4 4335.3 4341.0 4346.1 4350.0 4354.2 4338.4 4338.7 4341.2 4355.4 4358.9 3735.7 3827.8 3835.0 3852.6 3859.9 5257.1 5391.9 5405.9 5422.8 5426.2 4939.1 4946.5 4956.5 4969.5 4975.0 5929.2 5931.5 5933.4 5933.7 5938.6 4009.2 4068.7 4095.8 4097.5 4118.6 6139.8 6244.1 6244.3 6247.5 6262.4 4287.1 4316.3 4322.4 4323.5 4324.5 4387.8 4390.9 4393.5 4399.8 4400.5 4873.4 4969.1 4972.7 4994.7 4998.8 5887.7 5889.4 5904.9 5905.1 5916.1 1526.9 1554.0 1557.1 1559.4 1560.4 1048.1 1063.1 1063.6 1076.0 1085.2 5120.1 5142.2 5143.4 5161.6 5161.6 6169.5 6178.6 6181.0 6190.9 6193.7 5124.4 5132.5 5143.8 5163.6 5167.8 3261.6 3278.7 3311.3 3327.0 3330.1 5711.0 5746.7 5806.0 5806.5 5902.3 6372.5 6386.0 6388.5 6392.7 6398.4 5866.2 5867.5 5869.5 5871.3 5876.4 5987.1 6204.2 6209.5 6233.6 6242.4 5992.8 6006.2 6028.2 6058.1 6073.0 5806.4 5851.1 5858.8 5863.4 5883.8 4135.8 4206.0 4208.6 4212.0 4215.6 6000.8 6172.8 6175.5 6178.7 6188.4 5942.6 5944.6 5945.4 5954.8 5959.7 5919.6 5956.2 5966.6 5968.1 5980.1 5727.5 5828.3 5834.9 5841.1 5842.9 5971.9 5996.9 6009.8 6015.7 6026.1 5841.7 5845.4 5850.8 5856.7 5859.7 04:36:12 INFO - 2015-12-16 04:36:12,833 DEBUG : BROWSER_OUTPUT: 04:36:12 INFO - 2015-12-16 04:36:12,833 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12039.50 Median:49154.01 stddev:13562.01 (27.6%) stddev-sans-first:13573.09 04:36:12 INFO - 2015-12-16 04:36:12,834 DEBUG : BROWSER_OUTPUT: Values: 649.9 653.6 696.1 731.1 742.0 11823.5 12035.5 12057.0 12101.3 12112.8 13792.1 13980.2 13981.3 13993.9 14008.6 797.4 822.3 844.3 844.4 845.9 825.8 828.4 832.0 833.6 833.7 861.6 864.6 864.7 868.2 870.5 845.8 846.7 849.1 850.3 851.0 630.2 631.0 634.0 635.8 638.2 11562.7 11604.1 11667.6 11676.8 11722.1 10497.1 10665.3 10665.8 10912.3 11649.5 9093.4 9112.6 9233.4 9265.3 9463.1 3200.7 3201.5 3231.8 3238.0 3251.3 39503.8 40110.8 40290.0 40305.4 40557.2 9050.2 9225.0 9263.9 9328.8 9415.8 14237.9 14307.2 14398.9 14464.0 14464.5 6185.1 6252.9 6326.2 6355.6 6437.0 3390.6 3446.5 3466.8 3481.5 3520.4 2421.7 2442.0 2443.0 2448.5 2472.3 462.6 476.2 481.4 483.8 488.5 519.3 589.7 609.8 610.8 611.7 7748.3 8673.5 8788.9 8985.3 9040.7 448.0 506.0 513.7 537.4 611.8 3693.4 3761.6 3769.7 3867.9 3907.2 48597.8 49010.3 49297.8 49309.5 49415.0 44366.8 44743.8 44790.8 45017.3 45023.3 39505.4 40208.0 40319.8 40412.0 40673.0 7794.3 7848.3 7886.5 7915.3 8037.0 29395.0 29862.0 30191.5 30240.0 30384.8 35438.5 35549.3 37431.4 38715.0 38730.6 9170.9 9235.6 9391.8 9554.0 9566.3 9946.3 10090.9 10097.7 10241.5 10372.9 9902.7 9977.7 10052.8 10123.2 10504.8 9839.8 9949.0 10016.1 10029.6 10308.3 9782.8 9793.3 10626.4 10777.1 11004.8 8904.6 9111.2 9323.7 9446.9 9482.6 8943.5 8959.9 9054.5 9277.9 9615.5 04:36:12 INFO - 2015-12-16 04:36:12,835 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:36:12 INFO - 2015-12-16 04:36:12,835 DEBUG : BROWSER_OUTPUT: 04:36:13 INFO - 2015-12-16 04:36:13,520 INFO : Browser exited with error code: 0 04:36:13 INFO - 2015-12-16 04:36:13,539 INFO : Completed test dromaeo_css (00:18:34) 04:36:13 INFO - 2015-12-16 04:36:13,539 INFO : Starting test kraken 04:36:13 INFO - 2015-12-16 04:36:13,539 DEBUG : operating with platform_type : linux_ 04:36:13 INFO - 2015-12-16 04:36:13,539 INFO : Initialising browser for kraken test... 04:36:13 INFO - 2015-12-16 04:36:13,545 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA1GYmo/profile http://localhost:41747/getInfo.html 04:36:18 INFO - 2015-12-16 04:36:18,368 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:36:18 INFO - 2015-12-16 04:36:18,368 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:36:18 INFO - 2015-12-16 04:36:18,368 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:36:18 INFO - 2015-12-16 04:36:18,368 DEBUG : BROWSER_OUTPUT: __metrics 04:36:18 INFO - 2015-12-16 04:36:18,485 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:36:19 INFO - 2015-12-16 04:36:19,500 INFO : Browser initialized. 04:36:19 INFO - 2015-12-16 04:36:19,501 INFO : Running cycle 1/1 for kraken test... 04:36:19 INFO - 2015-12-16 04:36:19,501 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA1GYmo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:36:31 INFO - 2015-12-16 04:36:31,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 143101952 04:36:31 INFO - 2015-12-16 04:36:31,529 DEBUG : BROWSER_OUTPUT: 04:36:34 INFO - 2015-12-16 04:36:34,108 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41747/tests/kraken/driver.html?testName=audio-beat-detection) 04:36:34 INFO - 2015-12-16 04:36:34,730 DEBUG : BROWSER_OUTPUT: RSS: Main: 143835136 04:36:34 INFO - 2015-12-16 04:36:34,730 DEBUG : BROWSER_OUTPUT: 04:36:37 INFO - 2015-12-16 04:36:37,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41747/tests/kraken/driver.html?testName=audio-dft) 04:36:38 INFO - 2015-12-16 04:36:38,110 DEBUG : BROWSER_OUTPUT: RSS: Main: 143282176 04:36:38 INFO - 2015-12-16 04:36:38,110 DEBUG : BROWSER_OUTPUT: 04:36:41 INFO - 2015-12-16 04:36:41,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41747/tests/kraken/driver.html?testName=audio-fft) 04:36:41 INFO - 2015-12-16 04:36:41,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 137986048 04:36:41 INFO - 2015-12-16 04:36:41,622 DEBUG : BROWSER_OUTPUT: 04:36:43 INFO - 2015-12-16 04:36:43,703 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41747/tests/kraken/driver.html?testName=audio-oscillator) 04:36:44 INFO - 2015-12-16 04:36:44,326 DEBUG : BROWSER_OUTPUT: RSS: Main: 138928128 04:36:44 INFO - 2015-12-16 04:36:44,326 DEBUG : BROWSER_OUTPUT: 04:36:46 INFO - 2015-12-16 04:36:46,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:36:47 INFO - 2015-12-16 04:36:47,152 DEBUG : BROWSER_OUTPUT: RSS: Main: 140693504 04:36:47 INFO - 2015-12-16 04:36:47,152 DEBUG : BROWSER_OUTPUT: 04:36:50 INFO - 2015-12-16 04:36:50,256 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41747/tests/kraken/driver.html?testName=imaging-darkroom) 04:36:50 INFO - 2015-12-16 04:36:50,849 DEBUG : BROWSER_OUTPUT: RSS: Main: 141590528 04:36:50 INFO - 2015-12-16 04:36:50,849 DEBUG : BROWSER_OUTPUT: 04:36:54 INFO - 2015-12-16 04:36:54,310 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41747/tests/kraken/driver.html?testName=imaging-desaturate) 04:36:54 INFO - 2015-12-16 04:36:54,911 DEBUG : BROWSER_OUTPUT: RSS: Main: 139018240 04:36:54 INFO - 2015-12-16 04:36:54,911 DEBUG : BROWSER_OUTPUT: 04:36:57 INFO - 2015-12-16 04:36:57,810 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41747/tests/kraken/driver.html?testName=json-parse-financial) 04:36:58 INFO - 2015-12-16 04:36:58,399 DEBUG : BROWSER_OUTPUT: RSS: Main: 139558912 04:36:58 INFO - 2015-12-16 04:36:58,399 DEBUG : BROWSER_OUTPUT: 04:37:00 INFO - 2015-12-16 04:37:00,188 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:00 INFO - 2015-12-16 04:37:00,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 141004800 04:37:00 INFO - 2015-12-16 04:37:00,774 DEBUG : BROWSER_OUTPUT: 04:37:02 INFO - 2015-12-16 04:37:02,689 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:37:03 INFO - 2015-12-16 04:37:03,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 140279808 04:37:03 INFO - 2015-12-16 04:37:03,311 DEBUG : BROWSER_OUTPUT: 04:37:05 INFO - 2015-12-16 04:37:05,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:37:06 INFO - 2015-12-16 04:37:06,109 DEBUG : BROWSER_OUTPUT: RSS: Main: 139857920 04:37:06 INFO - 2015-12-16 04:37:06,110 DEBUG : BROWSER_OUTPUT: 04:37:08 INFO - 2015-12-16 04:37:08,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:37:09 INFO - 2015-12-16 04:37:09,278 DEBUG : BROWSER_OUTPUT: RSS: Main: 140754944 04:37:09 INFO - 2015-12-16 04:37:09,279 DEBUG : BROWSER_OUTPUT: 04:37:11 INFO - 2015-12-16 04:37:11,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:37:12 INFO - 2015-12-16 04:37:12,561 DEBUG : BROWSER_OUTPUT: RSS: Main: 141365248 04:37:12 INFO - 2015-12-16 04:37:12,561 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,351 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41747/tests/kraken/driver.html?testName=ai-astar) 04:37:14 INFO - 2015-12-16 04:37:14,609 DEBUG : BROWSER_OUTPUT: RSS: Main: 140783616 04:37:14 INFO - 2015-12-16 04:37:14,609 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,609 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;111;114;114;115;124;114;114;114;114 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;131;166;183;140;156;161;137;163;130 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;162;162;161;162;162;167;165;165;163 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: |3;audio-fft;86;72;72;73;72;76;134;72;72;73 04:37:14 INFO - 2015-12-16 04:37:14,610 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;133;95;95;96;135;96;95;134 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;109;110;109;110;110;110;112 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;149;148;148;148;148;148;147 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;96;96;96;96;96;96;96 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;73;73;80;73;73;74;73;72;75 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;69;68;68;69;68;68;68;68 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;85;86;87;86;86;87;97;86;89 04:37:14 INFO - 2015-12-16 04:37:14,611 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;123;129;122;138;129;130;138;125;126 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;152;165;149;153;145;151;149;150;151 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;72;72;76;73;74;70;72;73;72 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,800 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269434604__endTimestamp 04:37:14 INFO - 2015-12-16 04:37:14,612 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,613 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:37:14 INFO - 2015-12-16 04:37:14,613 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:37:14 INFO - 2015-12-16 04:37:14,613 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.80 Median:114.00 stddev:3.39 (3.0%) stddev-sans-first:3.59 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: Values: 114.0 111.0 114.0 114.0 115.0 124.0 114.0 114.0 114.0 114.0 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:152.20 Median:158.50 stddev:17.25 (10.9%) stddev-sans-first:18.27 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: Values: 155.0 131.0 166.0 183.0 140.0 156.0 161.0 137.0 163.0 130.0 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:1.89 (1.2%) stddev-sans-first:1.99 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: Values: 164.0 162.0 162.0 161.0 162.0 162.0 167.0 165.0 165.0 163.0 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.20 Median:72.50 stddev:19.40 (26.8%) stddev-sans-first:20.46 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: Values: 86.0 72.0 72.0 73.0 72.0 76.0 134.0 72.0 72.0 73.0 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:95.00 stddev:18.78 (19.8%) stddev-sans-first:19.43 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 133.0 95.0 95.0 96.0 135.0 96.0 95.0 134.0 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,614 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 109.0 110.0 109.0 110.0 110.0 110.0 112.0 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 149.0 148.0 148.0 148.0 148.0 148.0 147.0 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.90 Median:73.00 stddev:2.28 (3.1%) stddev-sans-first:2.40 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 73.0 80.0 73.0 73.0 74.0 73.0 72.0 75.0 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 69.0 68.0 68.0 69.0 68.0 68.0 68.0 68.0 04:37:14 INFO - 2015-12-16 04:37:14,615 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:86.50 stddev:3.57 (4.1%) stddev-sans-first:3.67 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: Values: 85.0 85.0 86.0 87.0 86.0 86.0 87.0 97.0 86.0 89.0 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:129.50 stddev:5.52 (4.3%) stddev-sans-first:5.84 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: Values: 130.0 123.0 129.0 122.0 138.0 129.0 130.0 138.0 125.0 126.0 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.60 Median:151.00 stddev:6.19 (4.1%) stddev-sans-first:5.50 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: Values: 141.0 152.0 165.0 149.0 153.0 145.0 151.0 149.0 150.0 151.0 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: 04:37:14 INFO - 2015-12-16 04:37:14,616 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:72.50 stddev:1.65 (2.3%) stddev-sans-first:1.66 04:37:14 INFO - 2015-12-16 04:37:14,617 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 76.0 73.0 74.0 70.0 72.0 73.0 72.0 04:37:14 INFO - 2015-12-16 04:37:14,617 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:37:14 INFO - 2015-12-16 04:37:14,617 DEBUG : BROWSER_OUTPUT: 04:37:15 INFO - 2015-12-16 04:37:15,488 INFO : Browser exited with error code: 0 04:37:15 INFO - 2015-12-16 04:37:15,542 INFO : Completed test kraken (00:01:02) 04:37:15 INFO - 2015-12-16 04:37:15,542 INFO : Starting test v8_7 04:37:15 INFO - 2015-12-16 04:37:15,542 DEBUG : operating with platform_type : linux_ 04:37:15 INFO - 2015-12-16 04:37:15,542 INFO : Initialising browser for v8_7 test... 04:37:15 INFO - 2015-12-16 04:37:15,548 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzr2YRj/profile http://localhost:41747/getInfo.html 04:37:20 INFO - 2015-12-16 04:37:20,129 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:37:20 INFO - 2015-12-16 04:37:20,129 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:37:20 INFO - 2015-12-16 04:37:20,129 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:37:20 INFO - 2015-12-16 04:37:20,129 DEBUG : BROWSER_OUTPUT: __metrics 04:37:20 INFO - 2015-12-16 04:37:20,246 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:37:21 INFO - 2015-12-16 04:37:21,156 INFO : Browser initialized. 04:37:21 INFO - 2015-12-16 04:37:21,157 INFO : Running cycle 1/1 for v8_7 test... 04:37:21 INFO - 2015-12-16 04:37:21,157 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzr2YRj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:37:33 INFO - 2015-12-16 04:37:33,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 143667200 04:37:33 INFO - 2015-12-16 04:37:33,218 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,706 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41747/tests/v8_7/run.html (next: http://localhost:41747/tests/v8_7/run.html) 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 138760192 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:37:54 INFO - 2015-12-16 04:37:54,957 DEBUG : BROWSER_OUTPUT: |0;Richards;166.00265604249668 04:37:54 INFO - 2015-12-16 04:37:54,958 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.38755980861245 04:37:54 INFO - 2015-12-16 04:37:54,958 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.01785714285717 04:37:54 INFO - 2015-12-16 04:37:54,958 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5223.958333333333 04:37:54 INFO - 2015-12-16 04:37:54,958 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1062.6992561105208 04:37:54 INFO - 2015-12-16 04:37:54,959 DEBUG : BROWSER_OUTPUT: |5;Earley;708.7172218284904 04:37:54 INFO - 2015-12-16 04:37:54,959 DEBUG : BROWSER_OUTPUT: |6;Boyer;12192.77108433735 04:37:54 INFO - 2015-12-16 04:37:54,959 DEBUG : BROWSER_OUTPUT: |7;RegExp;26205.128205128207 04:37:54 INFO - 2015-12-16 04:37:54,959 DEBUG : BROWSER_OUTPUT: |8;Splay;591.2581216774955 04:37:54 INFO - 2015-12-16 04:37:54,959 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 04:37:54 INFO - 2015-12-16 04:37:54,959 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:37:54 INFO - 2015-12-16 04:37:54,960 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269474954__endTimestamp 04:37:54 INFO - 2015-12-16 04:37:54,960 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,960 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:37:54 INFO - 2015-12-16 04:37:54,960 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:37:54 INFO - 2015-12-16 04:37:54,960 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,960 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.00 Median:166.00 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,961 DEBUG : BROWSER_OUTPUT: Values: 166.0 04:37:54 INFO - 2015-12-16 04:37:54,961 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,961 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.39 Median:191.39 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,961 DEBUG : BROWSER_OUTPUT: Values: 191.4 04:37:54 INFO - 2015-12-16 04:37:54,961 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,962 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.02 Median:279.02 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,962 DEBUG : BROWSER_OUTPUT: Values: 279.0 04:37:54 INFO - 2015-12-16 04:37:54,963 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,963 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5223.96 Median:5223.96 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,963 DEBUG : BROWSER_OUTPUT: Values: 5224.0 04:37:54 INFO - 2015-12-16 04:37:54,963 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,964 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1062.70 Median:1062.70 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,964 DEBUG : BROWSER_OUTPUT: Values: 1062.7 04:37:54 INFO - 2015-12-16 04:37:54,964 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,964 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:708.72 Median:708.72 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,964 DEBUG : BROWSER_OUTPUT: Values: 708.7 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12192.77 Median:12192.77 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: Values: 12192.8 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26205.13 Median:26205.13 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: Values: 26205.1 04:37:54 INFO - 2015-12-16 04:37:54,965 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,966 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:591.26 Median:591.26 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,966 DEBUG : BROWSER_OUTPUT: Values: 591.3 04:37:54 INFO - 2015-12-16 04:37:54,966 DEBUG : BROWSER_OUTPUT: 04:37:54 INFO - 2015-12-16 04:37:54,966 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 04:37:54 INFO - 2015-12-16 04:37:54,966 DEBUG : BROWSER_OUTPUT: Values: 4840.6 04:37:54 INFO - 2015-12-16 04:37:54,966 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:37:54 INFO - 2015-12-16 04:37:54,967 DEBUG : BROWSER_OUTPUT: 04:37:55 INFO - 2015-12-16 04:37:55,884 INFO : Browser exited with error code: 0 04:37:55 INFO - 2015-12-16 04:37:55,896 INFO : Completed test v8_7 (00:00:40) 04:37:55 INFO - 2015-12-16 04:37:55,965 INFO : Completed test suite (00:20:16) 04:37:55 INFO - 2015-12-16 04:37:55,966 DEBUG : Working with test: dromaeo_css 04:37:55 INFO - 2015-12-16 04:37:55,966 DEBUG : Generating results file: dromaeo_css 04:37:55 INFO - 2015-12-16 04:37:55,967 DEBUG : Working with test: kraken 04:37:55 INFO - 2015-12-16 04:37:55,967 DEBUG : Generating results file: kraken 04:37:55 INFO - 2015-12-16 04:37:55,967 INFO : javascript benchmark 04:37:55 INFO - 2015-12-16 04:37:55,967 DEBUG : Working with test: v8_7 04:37:55 INFO - 2015-12-16 04:37:55,968 DEBUG : Generating results file: v8_7 04:37:55 INFO - 2015-12-16 04:37:55,968 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:37:56 INFO - 2015-12-16 04:37:56,026 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 04:37:56 INFO - 2015-12-16 04:37:56,026 DEBUG : process_Request line: dromaeo_css 6243.56 graph.html#tests=[[72,94,43]] 04:37:56 INFO - 2015-12-16 04:37:56,027 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:37:56 INFO - 2015-12-16 04:37:56,063 DEBUG : process_Request line: kraken 1553.00 graph.html#tests=[[232,94,43]] 04:37:56 INFO - 2015-12-16 04:37:56,064 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:37:56 INFO - 2015-12-16 04:37:56,103 DEBUG : process_Request line: v8_7 21336.88 graph.html#tests=[[230,94,43]] 04:37:56 INFO - 2015-12-16 04:37:56,104 INFO : javascript benchmark 04:37:56 INFO - 2015-12-16 04:37:56,108 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2686.9731302686973, 2707.0660622085375, 2738.931526711831, 2748.711385304543, 2768.8892444302223, 4586.403767510219, 4722.173619616567, 4723.0553889222165, 4746.833860814872, 4752.287156926463, 4721.480637129922, 4733.90532189356, 4734.076855013273, 4742.33607294979, 4880.682755620882, 4344.522102568715, 4348.152110338485, 4412.580804823537, 4413.955860441387, 4441.4004109445295, 4424.336349547578, 4473.1501014807245, 4488.326750987361, 4536.500984891659, 4584.40402747644, 4212.410262563244, 4291.098869237464, 4303.203907277164, 4303.505096913864, 4443.42235509385, 4235.0682849773, 4324.135172965388, 4346.174226896911, 4356.390105385217, 4368.650507959355, 4400.581944715247, 4412.205802955466, 4419.18245124653, 4426.291793313055, 4460.8884777880485, 4459.308807134899, 4572.245579479415, 4591.770411479446, 4696.9530304696855, 4735.076660051292, 5146.871328216825, 5192.636515443921, 5228.843134705965, 5233.555147812441, 5240.266362709223, 5254.632175747699, 5256.106461901486, 5266.7629956652045, 5273.894522109536, 5310.283111779899, 4602.79287432063, 4675.064987002613, 4696.178168820443, 4805.687630304019, 4850.442199147108, 5286.101362768339, 5297.390800057966, 5325.201219816981, 5331.040412725747, 5336.439673834269, 5121.4878512149235, 5122.564582010496, 5126.897462050738, 5135.15269980449, 5150.716710580954, 4928.211486162197, 4989.426215985136, 5010.874728131826, 5079.466656001141, 5113.9772045591035, 5098.821541246039, 5137.948620513747, 5156.278121063054, 5160.071187186268, 5206.088934436459, 4830.420349558076, 5121.411037730634, 5121.795128194905, 5123.692578445305, 5130.384353877484, 4149.771762553029, 4204.117135401539, 4216.746995180238, 4220.957790422057, 4257.957420425818, 3737.682297004785, 3740.9064773380883, 3749.9437508437395, 3751.9062023449633, 3783.697304215656, 4196.51740049892, 4309.202797482476, 4429.335599660077, 4444.888877778081, 4476.820927162942, 4995.075910956485, 5053.84838454847, 5068.873278168076, 5082.466341034212, 5119.411267704188, 4453.51011388747, 4496.910061798746, 4502.842400515966, 4505.797239124247, 4519.570640789186, 4321.632661223768, 4368.563143685606, 4600.148972440109, 4604.355390245369, 4638.930416043762, 4761.214399624023, 4804.399450068741, 4826.734529600765, 4830.516948305141, 4859.878503037382, 5148.948510514847, 5173.922391164211, 5175.792968281226, 5186.9221961669855, 5187.014467251211, 4733.266343716729, 4805.327254184347, 4812.181929071996, 4904.558589726942, 4957.975210123926, 5115.2071428928575, 5120.206368012964, 5130.615203859651, 5140.100482415637, 5150.948490515047, 5182.611304152279, 5188.922166167435, 5191.195364718475, 5194.272801807674, 5236.895262094813, 4814.446338671098, 4826.493218212037, 4937.580305674058, 4955.529224723646, 5002.299678045004, 4930.654854160318, 4939.728314942713, 4959.677620954626, 4972.825951091694, 5022.84931452057, 4850.636202284913, 4858.732769697701, 4875.512448755097, 4895.632827537877, 4941.925871112008, 4862.562369386772, 4927.531884470969, 4966.627502937222, 4968.925466117938, 5006.649534532694, 4841.564259216688, 4960.652754307308, 4962.082014827269, 4978.701277923336, 4997.300377947017], "unit": "score", "name": "dojo.html", "value": 4691.1277617413425}, {"lowerIsBetter": false, "replicates": [568.8606291458593, 570.1533223163602, 577.053632043449, 581.3750218515097, 585.2917969257198, 34366.14084647882, 34494.65505344946, 34556.0, 34575.13562160947, 34618.65381346186, 39613.40579891304, 39797.20405591886, 40016.59983400165, 40067.799661001656, 40113.398299025466, 8032.558209298486, 8182.836343273131, 8183.140770219131, 8189.344852411822, 8204.958975205116, 7911.406644501685, 8021.919780802175, 8037.115917249098, 8037.919620803805, 8045.034595848507, 7931.801704957365, 7979.640916158787, 8003.479773814713, 8015.078265999397, 8022.277994980451, 8032.558209298486, 8063.516189028648, 8111.837763244702, 8129.227723366271, 8162.918370816305, 7872.976513053323, 7975.282224599814, 7992.8401431971615, 8012.5593092379895, 8033.437659363847, 35917.28165436703, 36230.63769362299, 36284.63715362839, 36307.4553881692, 36475.81762091199, 3086.4135814195456, 3182.236263296815, 3212.3575284943104, 3358.899233023013, 3414.8804791832417, 27268.0, 27474.313142171406, 27619.723802761917, 27775.86112069447, 28022.2994425141, 3410.880619178317, 3421.6578342165835, 3502.0, 3505.3418057398185, 3539.7876127432437, 205464.0, 206537.0, 206675.0, 207315.0, 207484.0, 51690.44928652146, 51990.74004629953, 52001.219981700306, 52037.2194417084, 52097.21854172191, 47898.5210147901, 48446.757766211646, 54989.450105499236, 55097.724511377186, 61300.0, 13503.864961350457, 13517.256550889795, 13624.114432561852, 13632.182069075689, 13753.79369309461, 11126.054285385671, 11141.554337826557, 11164.832527512093, 11188.216824822184, 11254.21220514556, 18132.365367212085, 18171.63656726858, 18197.181126849322, 18228.635427291647, 18311.54221144482, 7284.526505777214, 7503.287187717158, 7553.848923021509, 7562.432817538596, 7593.734219302298, 860.3762272352619, 861.6984055580497, 897.3539051882634, 986.2652324018509, 1016.4968340671415, 25643.102491412712, 25768.8711556441, 26053.0, 26087.0, 26138.86930565335, 888.657866721317, 953.8283109040301, 958.6165533786541, 980.666573365045, 983.1986930651497, 12345.876541234653, 12704.74590508203, 12830.80753788694, 12846.550370737166, 13172.47310107585, 255949.0, 257076.0, 258040.0, 259193.0, 260631.0, 43608.12783744308, 43701.34447983283, 43771.0, 43881.34177987333, 43899.12201756011, 1223.8959688426405, 1233.975320493585, 1247.7566874459394, 1251.4931452761464, 1252.473960936409, 23732.288031359087, 26439.471210576066, 27905.302367440978, 28489.28776780514, 28576.857115714287, 39627.2074558513, 39791.0, 39985.80007099946, 40162.79918600388, 40179.19641607093, 195135.0, 197108.0, 197132.0, 197184.0, 197195.0, 28113.71886281193, 28481.0, 28930.7106928928, 28931.566026509197, 28969.275768105967, 32809.68761249523, 32985.67014329826, 35120.64879351276, 35892.82053589715, 36304.81847590745, 30945.226369340948, 31429.8428507856, 31488.842555787076, 32042.19894502563, 32045.51931721073, 31203.12960585109, 31443.39152119701, 31629.525557117122, 31693.84153079312, 31968.52047219247, 31232.21919452032, 31700.5244921331, 31851.0, 31895.840520797246, 32277.35445291128, 26659.0, 28029.439411212068, 28658.85670571634, 28687.0, 28998.27504312409, 22650.660240096742, 23070.884645576665, 23209.883950580814, 23311.18410855612, 23353.76646233516], "unit": "score", "name": "ext.html", "value": 16483.48206764042}, {"lowerIsBetter": false, "replicates": [334.2496096263888, 337.4061651493371, 344.5039143633168, 344.62435944820146, 345.3042120127941, 12581.93709031456, 12994.350282485862, 15050.0, 15245.847541524581, 17290.308387664492, 9628.326017178782, 9952.552135153917, 10015.699529014122, 14561.344739486722, 15100.622484437894, 4452.621527170187, 4593.517680643535, 4605.240135377659, 4615.261558150696, 4615.330777037328, 4608.769561521927, 4987.276844857493, 4988.526090021461, 4993.651714037208, 4996.0, 4952.87617809554, 4953.678010929278, 4956.330895329121, 4959.504049595048, 4973.477784832595, 4899.828506002291, 4963.70217786934, 4990.87522811929, 5015.247712843066, 5049.974750126225, 4529.750863702495, 4545.772711364419, 4546.1135078659345, 4552.4764652065105, 4552.931706024412, 11841.28952262867, 11936.940315298369, 12034.879651203551, 12105.697357566043, 16896.90170138949, 1849.3712137873054, 1880.61447403282, 1880.61447403282, 1883.0867029490687, 1893.829555340026, 9130.360874738772, 9133.08669133088, 9190.264778817678, 9301.860472092923, 9307.441553506744, 1746.4847869878417, 1750.6148647297575, 1758.0561007726926, 1763.2706825065025, 1803.0173555412332, 53317.43267585111, 54215.457845421435, 55171.72414137904, 56134.0, 56585.43414565884, 9322.766930826783, 9465.668701595549, 9515.0, 9585.808283834283, 9642.855357169648, 10598.629047983437, 11037.282576632653, 11180.664580062448, 12110.515579376726, 12132.757344853053, 5388.595855310868, 5528.115501519737, 5905.675187864708, 6897.862042759217, 6914.757983470555, 6627.138471998609, 6629.900551491732, 6715.227748808852, 6749.807790236778, 6751.122354094035, 4160.084781348099, 4225.3873188388125, 4238.512571054368, 4239.0885959518855, 4247.426597409372, 1692.6022384739576, 1721.5093698295925, 1739.5911960689227, 1750.0287340526131, 1773.4147731248656, 390.6659805866, 405.2300628805294, 408.9059516311265, 422.5964204185057, 852.2542775071812, 9404.200642945427, 9736.318457707892, 9836.262280328861, 9942.353747006422, 9943.602255909682, 400.18961602754655, 412.07900342733967, 412.91741651669787, 417.92268430339874, 418.87643145272216, 4279.593438623326, 5554.111342185231, 5560.277163968739, 5577.8844231153935, 5601.075822489243, 464471.0, 467249.0, 478030.0, 481209.0, 491468.0, 70430.0, 74222.0, 74666.0, 75451.62274188704, 77880.61059694666, 448.8227150275623, 450.70929250633816, 464.29427270548575, 466.4612372709526, 466.50550416557877, 9180.569454194385, 9477.573509191965, 9649.951750241204, 9695.6121755129, 9811.950940245253, 26826.463470730865, 29187.7081229185, 33534.65861365518, 33656.1585960353, 33844.83077584596, 39408.2266298023, 41773.164536708486, 41790.373144403464, 42106.368404473345, 44738.3289250655, 8592.65629374818, 8816.338774591803, 8987.146221108984, 9002.909970900208, 9026.097390260922, 16034.919825400799, 16097.919510402842, 16103.0, 16180.919095404448, 16351.754723678916, 13757.0, 13887.583372498437, 13981.650458738613, 13982.58052258434, 14103.435862565793, 12916.935415322863, 13335.466581337027, 13355.866441335462, 13606.523771667944, 14128.78806817919, 13824.101433406797, 13917.512887048904, 14116.788248176492, 14248.430062797786, 14502.1298722077, 8797.780055498664, 8824.647014119364, 8889.111088891059, 8923.866142007746, 9120.726378208665, 12983.675408114872, 13510.72978540404, 13662.385192666155, 13671.726565468436, 13946.651333716738], "unit": "score", "name": "jquery.html", "value": 7277.888319371042}, {"lowerIsBetter": false, "replicates": [1463.3122432456742, 1684.3262694922034, 1701.9319227230912, 1704.1990264575645, 1755.1048965027835, 6205.255369355672, 6328.24061112666, 7280.963595182024, 7538.7738367848915, 7541.773746787591, 6722.764703235388, 6858.725650973955, 6974.47691423144, 6980.72077116916, 7005.369516743492, 2033.8169564739133, 2061.9587608247825, 2070.2650559051544, 2072.057213967641, 2077.054940002303, 2089.8850563219016, 2115.883626400547, 2118.33272519156, 2136.7542732585716, 2143.506993391513, 2039.255671679841, 2045.3966080006435, 2056.4036429435446, 2065.9690104648444, 2066.4007437843006, 1984.8412127029803, 1998.490384951828, 1999.9900000499904, 2009.4474019644642, 2010.2963962613042, 2030.0661695620033, 2048.1602542957316, 2050.815426611597, 2077.657186564215, 2082.55225126597, 7128.6435678215885, 7173.5337203081635, 7181.74863879767, 7188.604626745527, 7224.638768061628, 4115.464989551339, 4254.234237837188, 4256.4679415073115, 4257.701960862741, 4258.063226090255, 2680.423708902576, 2683.0877501649534, 2700.486907487571, 2763.847988360639, 2774.209350335145, 894.4320356573558, 895.8029233568604, 897.1521911793358, 901.7294811556573, 923.8845144356956, 9834.557444914995, 9875.259355548362, 9886.851697224547, 9894.653687120915, 9901.75245618858, 3818.1218319786603, 3832.1760821423527, 3854.094287842355, 3901.180752042103, 3902.863399780994, 8444.197801208875, 8478.06741258461, 8522.360822938304, 8530.360222983301, 8567.957160214284, 4267.082577245845, 4288.078063216423, 4290.849820256276, 4291.957080429218, 4306.20335237982, 3323.1692076980753, 3382.797032178028, 3815.8855234343014, 3876.573576906538, 3907.7069219808627, 3513.94729079064, 3528.1532432216595, 3552.2007548301426, 3578.7673801202836, 3590.1563132663805, 1083.8536797532233, 1086.576235268246, 1099.796537640539, 1102.3441052573705, 1117.0560876059717, 1194.8805119487981, 1199.7120691034263, 1217.4825699077855, 1217.713837248246, 1232.5870833270956, 6778.356056174655, 6890.689918953659, 6891.517593768388, 6999.265077166926, 7137.321954414322, 966.6181858165985, 978.1099199728213, 983.1250187333279, 1037.2211657008268, 1063.6649455421518, 5418.485243901823, 5454.4545545445135, 5464.28964234647, 5470.370907345706, 5479.123340265538, 8589.26991205742, 8749.212570868653, 8848.513331766686, 8928.464292142493, 8935.910640893639, 8737.213650771333, 8803.69187078462, 8851.291896648252, 8874.86687699685, 8883.022867484571, 6834.111565496453, 6870.557182991528, 6888.173419189729, 7005.404540614105, 7078.823029424203, 6709.832254193489, 6770.52606317553, 6780.423663988616, 6781.93218067813, 6782.93217067823, 7413.962930185315, 7689.615519224129, 7800.492967957065, 7830.804229894298, 7837.6081195941115, 7833.255840695125, 7979.680812767424, 8094.271515563392, 8127.75616731499, 8141.348692104736, 5339.839804805862, 5440.183972404013, 5894.498967587631, 6070.453659170696, 6143.907841382294, 7015.403690686348, 7030.191527974144, 7045.154581450259, 7056.611886346095, 7078.610676412847, 6351.2060992375955, 6690.531662783558, 6691.0, 6728.495362847708, 6796.184457865088, 6324.873502530016, 6382.840428989312, 6495.188101487314, 6685.763133820259, 6689.331066893272, 6568.376004279586, 6627.37039981201, 6627.867442651216, 6628.0, 6637.834054148491, 6074.301455332693, 6080.209572755513, 6174.25908890936, 6175.536834737502, 6180.103884936569, 6014.248218972629, 6018.097285407224, 6020.337762845996, 6026.367231440636, 6046.183765191643], "unit": "score", "name": "mootools.html", "value": 4162.914474843472}, {"lowerIsBetter": false, "replicates": [306.06649718359006, 310.3111093372713, 314.7104663709387, 315.663818033794, 319.9600049993751, 5557.833265002051, 5688.260526131602, 5701.2018317435595, 5703.001974654435, 5703.77184912604, 5606.074997625397, 5607.523360514361, 5612.943870561293, 5625.0, 5634.746436410361, 4278.807453664577, 4340.327249276367, 4345.543717909622, 4354.80403381847, 4367.58507941745, 4352.912941741163, 4369.847055353063, 4374.628156606676, 4378.299472084467, 4407.3609326647775, 4335.263005289093, 4341.044970106572, 4346.087321662455, 4350.0429905420915, 4354.194474022316, 4338.370936214263, 4338.696291259613, 4341.175176716445, 4355.390245365651, 4358.912821743579, 3735.7384983051197, 3827.808609569538, 3834.961650383488, 3852.57621661617, 3859.8842034739005, 5257.106291930381, 5391.865203369908, 5405.891882162374, 5422.810201642924, 5426.240326354314, 4939.135651261015, 4946.480619534969, 4956.479569645171, 4969.527894849984, 4975.0, 5929.169916211734, 5931.466168044896, 5933.376995415506, 5933.732982015819, 5938.61399009063, 4009.158076803875, 4068.65416439603, 4095.795210239476, 4097.508299004109, 4118.588141185887, 6139.84650383744, 6244.0633904914175, 6244.250689917239, 6247.531435142382, 6262.436380725711, 4287.121140166259, 4316.266234740102, 4322.416473776063, 4323.502797178302, 4324.524302326741, 4387.846425375161, 4390.868273951786, 4393.538678438782, 4399.824007039747, 4400.515943246241, 4873.439554451284, 4969.080720066727, 4972.65191436596, 4994.675346102492, 4998.750062496861, 5887.676177810176, 5889.411058894077, 5904.881902361929, 5905.055191169392, 5916.112583112567, 1526.9160196189093, 1554.0, 1557.1435710359258, 1559.3918371834993, 1560.3680509393714, 1048.0881632979356, 1063.0645032371617, 1063.5798859450474, 1075.978480430387, 1085.1861104171871, 5120.1039818031695, 5142.1772516397195, 5143.408508021551, 5161.5870730341485, 5161.612879034087, 6169.475594574512, 6178.598391104564, 6181.041938499539, 6190.938090619126, 6193.659348735772, 5124.436312005676, 5132.5124113209185, 5143.7685304161405, 5163.638545301868, 5167.793288268503, 3261.5596894419423, 3278.672132786702, 3311.3211791582667, 3326.966730332714, 3330.1008727643402, 5711.0, 5746.683932383676, 5805.970970145207, 5806.535477161817, 5902.321233058254, 6372.458341041063, 6385.968070159713, 6388.456981156561, 6392.712327945161, 6398.424141827258, 5866.178734977107, 5867.501262392744, 5869.5010924071075, 5871.295444546597, 5876.3536011037895, 5987.101934709828, 6204.224471941007, 6209.503239740719, 6233.625982441068, 6242.406971337716, 5992.760289588367, 6006.189164462741, 6028.246469191351, 6058.091286307089, 6073.0, 5806.44838740319, 5851.093080572347, 5858.765649374148, 5863.413658634273, 5883.823485295448, 4135.7932103394105, 4206.0, 4208.579142085767, 4211.978940105279, 4215.641670457922, 6000.759969601167, 6172.783952561818, 6175.505959523138, 6178.66017369049, 6188.381161883775, 5942.584019118794, 5944.5838791284195, 5945.375735547878, 5954.76180952757, 5959.7020148991505, 5919.556033297434, 5956.166136740946, 5966.612170208922, 5968.104784282219, 5980.102984552178, 5727.513161381329, 5828.271466066742, 5834.883302333845, 5841.094630332305, 5842.853928651818, 5971.91042134377, 5996.920554300268, 6009.789657361971, 6015.72929218195, 6026.126211699367, 5841.707914604338, 5845.3570107289015, 5850.795222167204, 5856.707164641665, 5859.707014649336], "unit": "score", "name": "prototype.html", "value": 4462.344056303106}, {"lowerIsBetter": false, "replicates": [649.9252585952615, 653.5980372071176, 696.0707455546848, 731.1043971135357, 742.046470285683, 11823.527058917643, 12035.458404371802, 12057.0, 12101.273923564593, 12112.757744845108, 13792.10351327163, 13980.161190328587, 13981.300934953262, 13993.860061399408, 14008.579742607713, 797.4417907464768, 822.321584692628, 844.2907957315854, 844.4426678392263, 845.8562044452458, 825.7852958230873, 828.3787159630277, 831.9708810191645, 833.5707110837893, 833.6957010691115, 861.5993562993207, 864.6022829498438, 864.6584599083359, 868.227277722824, 870.5212133326645, 845.7631863078348, 846.6528723223449, 849.1126772522729, 850.268768858781, 851.0255757158051, 630.2059405149499, 631.0471188505389, 634.0077778277027, 635.7711223959371, 638.1544453598954, 11562.653120406401, 11604.071674266039, 11667.59163429276, 11676.766464670744, 11722.12084093688, 10497.107745841613, 10665.253432259746, 10665.84001239982, 10912.345259284468, 11649.534018639244, 9093.408928419698, 9112.635494580209, 9233.399829011092, 9265.25877929764, 9463.101005404476, 3200.679932006804, 3201.5357773123, 3231.757618178646, 3237.96762032379, 3251.3009702913987, 39503.80248098741, 40110.79944600288, 40290.0, 40305.39541906874, 40557.18885622301, 9050.185483306534, 9224.953875230582, 9263.861042084374, 9328.766780830534, 9415.764605884771, 14237.928810355881, 14307.213103279211, 14398.928005360116, 14464.0, 14464.493742718954, 6185.134081228632, 6252.937470625327, 6326.177596912372, 6355.61866288015, 6437.0, 3390.5931288245074, 3446.5347178131133, 3466.8093254871224, 3481.4777783332197, 3520.3663340598428, 2421.6730741350043, 2442.023190723725, 2442.973950940609, 2448.4858179782445, 2472.295395812185, 462.6090953144655, 476.16195495926723, 481.40306020534285, 483.7774623673079, 488.47001003911157, 519.2756105233254, 589.675678376891, 609.7835268479714, 610.8106486989047, 611.6910959965189, 7748.302652761278, 8673.479591224546, 8788.8681669775, 8985.326100542485, 9040.728778136536, 448.0255444408399, 506.02590014222477, 513.6661170239374, 537.3664061253745, 611.8164550634792, 3693.353663108945, 3761.586225515191, 3769.6607305342104, 3867.903302417462, 3907.238088572701, 48597.757011214715, 49010.26484602734, 49297.753511232215, 49309.50690493121, 49415.0, 44366.77816610961, 44743.77628111904, 44790.77604611956, 45017.324740128926, 45023.32465013027, 39505.40741888874, 40208.0, 40319.79840100781, 40412.0, 40673.0, 7794.259545343183, 7848.254415830487, 7886.487378320391, 7915.26096447717, 8036.95981520112, 29395.0, 29862.0, 30191.547126793554, 30240.0, 30384.84807575948, 35438.46842297419, 35549.28901421905, 37431.43852842155, 38715.0, 38730.6126938727, 9170.856149197021, 9235.630574777202, 9391.765205869908, 9554.0, 9566.330356874952, 9946.254030947852, 10090.949545252226, 10097.747556311151, 10241.48792560354, 10372.948135259276, 9902.702918912444, 9977.700668979942, 10052.84920726175, 10123.24075694311, 10504.789904202025, 9839.754006149904, 9949.0, 10016.148627366756, 10029.648962286285, 10308.32995855267, 9782.804343913223, 9793.314467987173, 10626.415547144672, 10777.08394786452, 11004.834927475935, 8904.643814247358, 9111.172056146946, 9323.673671421468, 9446.905530944603, 9482.573284202366, 8943.463392196489, 8959.865602016105, 9054.502002389932, 9277.86083208766, 9615.51922403891], "unit": "score", "name": "yui.html", "value": 5665.8185321378705}], "name": "dromaeo_css", "value": 6243.561453105738}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 111.0, 114.0, 114.0, 115.0, 124.0, 114.0, 114.0, 114.0, 114.0], "unit": "score", "name": "ai-astar", "value": 114.8}, {"lowerIsBetter": true, "replicates": [155.0, 131.0, 166.0, 183.0, 140.0, 156.0, 161.0, 137.0, 163.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 152.2}, {"lowerIsBetter": true, "replicates": [164.0, 162.0, 162.0, 161.0, 162.0, 162.0, 167.0, 165.0, 165.0, 163.0], "unit": "score", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [86.0, 72.0, 72.0, 73.0, 72.0, 76.0, 134.0, 72.0, 72.0, 73.0], "unit": "score", "name": "audio-fft", "value": 80.2}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 133.0, 95.0, 95.0, 96.0, 135.0, 96.0, 95.0, 134.0], "unit": "score", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 112.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 149.0, 148.0, 148.0, 148.0, 148.0, 148.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 148.0}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 73.0, 80.0, 73.0, 73.0, 74.0, 73.0, 72.0, 75.0], "unit": "score", "name": "json-parse-financial", "value": 73.9}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 69.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 86.0, 87.0, 86.0, 86.0, 87.0, 97.0, 86.0, 89.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.4}, {"lowerIsBetter": true, "replicates": [130.0, 123.0, 129.0, 122.0, 138.0, 129.0, 130.0, 138.0, 125.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 129.0}, {"lowerIsBetter": true, "replicates": [141.0, 152.0, 165.0, 149.0, 153.0, 145.0, 151.0, 149.0, 150.0, 151.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.6}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 76.0, 73.0, 74.0, 70.0, 72.0, 73.0, 72.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 72.5}], "name": "kraken", "value": 1553.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.00265604249668], "unit": "score", "name": "Richards", "value": 212.659248}, {"lowerIsBetter": false, "replicates": [191.38755980861245], "unit": "score", "name": "DeltaBlue", "value": 345.4665499999999}, {"lowerIsBetter": false, "replicates": [279.01785714285717], "unit": "score", "name": "Encrypt", "value": 953.9927040000001}, {"lowerIsBetter": false, "replicates": [5223.958333333333], "unit": "score", "name": "Decrypt", "value": 50.95389032901295}, {"lowerIsBetter": false, "replicates": [1062.6992561105208], "unit": "score", "name": "RayTrace", "value": 696.3296489999998}, {"lowerIsBetter": false, "replicates": [708.7172218284904], "unit": "score", "name": "Earley", "value": 940.379293}, {"lowerIsBetter": false, "replicates": [12192.77108433735], "unit": "score", "name": "Boyer", "value": 54.66050296442688}, {"lowerIsBetter": false, "replicates": [26205.128205128207], "unit": "score", "name": "RegExp", "value": 34.76361545988257}, {"lowerIsBetter": false, "replicates": [591.2581216774955], "unit": "score", "name": "Splay", "value": 137.8264365634366}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21336.878410510424}]} 04:37:56 INFO - RETURN: dromaeo_css: 6243.56 04:37:56 INFO - RETURN: kraken: 1553.00 04:37:56 INFO - RETURN: v8_7: 21336.88 04:37:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21336.88"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1553.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6243.56"}}} 04:37:56 INFO - Return code: 0 04:37:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:37:56 INFO - # TBPL SUCCESS # 04:37:56 INFO - Running post-action listener: _resource_record_post_action 04:37:56 INFO - Running post-run listener: _resource_record_post_run 04:37:57 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506990592; Read time: 16; Write time: 351812 04:37:57 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 231428096; Read time: 0; Write time: 210408 04:37:57 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 4096; Write bytes: 274681856; Read time: 16; Write time: 141340 04:37:57 INFO - Running post-run listener: _upload_blobber_files 04:37:57 INFO - Blob upload gear active. 04:37:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:37:57 INFO - Copying logs to upload dir... 04:37:57 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1275.104574 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2015-12-16 04:37:57.008795) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:37:57.012836) ========= 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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009941 build_url: 'https://queue.taskcluster.net/v1/task/zRrC9vLyTrGSXBAosBnbJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:37:57.064237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:37:57.064568) ========= 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=820bb6602ddf640013a48df2000001a9-1450267947.150218-672648081 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:37:57.114181) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 04:37:57.114511) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 04:37:57.114793) ========= ========= Total master_lag: 0.23 =========