builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1449778965.86 results: success (0) buildid: 20151210113952 builduid: 1a6bc0829c314e729d621b58604c676c revision: 11c67a7b46b5158bd48a7216ab45f124a71d4794 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.859700) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.860373) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.860884) ========= 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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006403 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.888017) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.888385) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.934279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.937650) ========= 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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.998978) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:45.999296) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:46.002749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:46.003081) ========= 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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-10 12:22:47-- 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% 218M=0s 2015-12-10 12:22:47 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.369330 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:46.813667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:46.814020) ========= 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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016728 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:46.868527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-10 12:22:46.868937) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 11c67a7b46b5158bd48a7216ab45f124a71d4794 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 11c67a7b46b5158bd48a7216ab45f124a71d4794 --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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-10 12:22:47,890 truncating revision to first 12 chars 2015-12-10 12:22:47,890 Setting DEBUG logging. 2015-12-10 12:22:47,890 attempt 1/10 2015-12-10 12:22:47,890 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/11c67a7b46b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-10 12:22:48,897 unpacking tar archive at: fx-team-11c67a7b46b5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.318361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-10 12:22:48.202979) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:48.203286) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:48.211198) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:48.211504) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-10 12:22:48.211923) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-10 12:22:48.212248) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:22:49 INFO - MultiFileLogger online at 20151210 12:22:49 in /builds/slave/test 12:22:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:49 INFO - {'append_to_log': False, 12:22:49 INFO - 'base_work_dir': '/builds/slave/test', 12:22:49 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:49 INFO - 'branch': 'Fx-Team-Non-PGO', 12:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:49 INFO - 'config_files': ('talos/linux_config.py',), 12:22:49 INFO - 'default_actions': ('clobber', 12:22:49 INFO - 'read-buildbot-config', 12:22:49 INFO - 'download-and-extract', 12:22:49 INFO - 'populate-webroot', 12:22:49 INFO - 'create-virtualenv', 12:22:49 INFO - 'install', 12:22:49 INFO - 'run-tests'), 12:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:49 INFO - 'download_minidump_stackwalk': True, 12:22:49 INFO - 'download_symbols': 'ondemand', 12:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:49 INFO - 'installer_path': 'installer.exe', 12:22:49 INFO - 'log_level': 'info', 12:22:49 INFO - 'log_name': 'talos', 12:22:49 INFO - 'log_to_console': True, 12:22:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:22:49 INFO - 'opt_config_files': (), 12:22:49 INFO - 'pip_index': False, 12:22:49 INFO - 'sps_profile': False, 12:22:49 INFO - 'sps_profile_interval': 0, 12:22:49 INFO - 'suite': 'dromaeojs-e10s', 12:22:49 INFO - 'system_bits': '64', 12:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:49 INFO - 'title': 'talos-linux64-ix-004', 12:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:49 INFO - 'use_talos_json': True, 12:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:49 INFO - 'work_dir': 'build'} 12:22:49 INFO - ##### 12:22:49 INFO - ##### Running clobber step. 12:22:49 INFO - ##### 12:22:49 INFO - Running pre-action listener: _resource_record_pre_action 12:22:49 INFO - Running main action method: clobber 12:22:49 INFO - rmtree: /builds/slave/test/build 12:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:49 INFO - Running post-action listener: _resource_record_post_action 12:22:49 INFO - ##### 12:22:49 INFO - ##### Running read-buildbot-config step. 12:22:49 INFO - ##### 12:22:49 INFO - Running pre-action listener: _resource_record_pre_action 12:22:49 INFO - Running main action method: read_buildbot_config 12:22:49 INFO - Using buildbot properties: 12:22:49 INFO - { 12:22:49 INFO - "properties": { 12:22:49 INFO - "buildnumber": 563, 12:22:49 INFO - "product": "firefox", 12:22:49 INFO - "script_repo_revision": "production", 12:22:49 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 12:22:49 INFO - "repository": "", 12:22:49 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 12:22:49 INFO - "buildid": "20151210113952", 12:22:49 INFO - "slavename": "talos-linux64-ix-004", 12:22:49 INFO - "pgo_build": "False", 12:22:49 INFO - "basedir": "/builds/slave/test", 12:22:49 INFO - "project": "", 12:22:49 INFO - "platform": "ubuntu64_hw", 12:22:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:22:49 INFO - "slavebuilddir": "test", 12:22:49 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:22:49 INFO - "branch": "fx-team", 12:22:49 INFO - "repo_path": "integration/fx-team", 12:22:49 INFO - "stage_platform": "linux64", 12:22:49 INFO - "builduid": "1a6bc0829c314e729d621b58604c676c", 12:22:49 INFO - "revision": "11c67a7b46b5158bd48a7216ab45f124a71d4794" 12:22:49 INFO - }, 12:22:49 INFO - "sourcestamp": { 12:22:49 INFO - "repository": "", 12:22:49 INFO - "hasPatch": false, 12:22:49 INFO - "project": "", 12:22:49 INFO - "branch": "fx-team-linux64-talos", 12:22:49 INFO - "changes": [ 12:22:49 INFO - { 12:22:49 INFO - "category": null, 12:22:49 INFO - "files": [ 12:22:49 INFO - { 12:22:49 INFO - "url": null, 12:22:49 INFO - "name": "https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 12:22:49 INFO - } 12:22:49 INFO - ], 12:22:49 INFO - "repository": "", 12:22:49 INFO - "rev": "11c67a7b46b5158bd48a7216ab45f124a71d4794", 12:22:49 INFO - "who": "felipc@gmail.com", 12:22:49 INFO - "when": 1449778916, 12:22:49 INFO - "number": 7268183, 12:22:49 INFO - "comments": "Bug 1231728 - Fixes sync/setup.js switch statement. r=markh", 12:22:49 INFO - "project": "", 12:22:49 INFO - "at": "Thu 10 Dec 2015 12:21:56", 12:22:49 INFO - "branch": "fx-team-linux64-talos", 12:22:49 INFO - "revlink": "", 12:22:49 INFO - "properties": [ 12:22:49 INFO - [ 12:22:49 INFO - "buildid", 12:22:49 INFO - "20151210113952", 12:22:49 INFO - "Change" 12:22:49 INFO - ], 12:22:49 INFO - [ 12:22:49 INFO - "builduid", 12:22:49 INFO - "1a6bc0829c314e729d621b58604c676c", 12:22:49 INFO - "Change" 12:22:49 INFO - ], 12:22:49 INFO - [ 12:22:49 INFO - "pgo_build", 12:22:49 INFO - "False", 12:22:49 INFO - "Change" 12:22:49 INFO - ] 12:22:49 INFO - ], 12:22:49 INFO - "revision": "11c67a7b46b5158bd48a7216ab45f124a71d4794" 12:22:49 INFO - } 12:22:49 INFO - ], 12:22:49 INFO - "revision": "11c67a7b46b5158bd48a7216ab45f124a71d4794" 12:22:49 INFO - } 12:22:49 INFO - } 12:22:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 12:22:49 INFO - Running post-action listener: _resource_record_post_action 12:22:49 INFO - ##### 12:22:49 INFO - ##### Running download-and-extract step. 12:22:49 INFO - ##### 12:22:49 INFO - Running pre-action listener: _resource_record_pre_action 12:22:49 INFO - Running main action method: download_and_extract 12:22:49 INFO - mkdir: /builds/slave/test/build/tests 12:22: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')]} 12:22:49 INFO - https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:22:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/test_packages.json 12:22:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/test_packages.json 12:22:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 12:22:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 12:22:51 INFO - Downloaded 1302 bytes. 12:22:51 INFO - Reading from file /builds/slave/test/build/test_packages.json 12:22:51 INFO - Using the following test package requirements: 12:22:51 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:22:51 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:22:51 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'jsshell-linux-x86_64.zip'], 12:22:51 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:22:51 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:22:51 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:22:51 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:22:51 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:22:51 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:22:51 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:22:51 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:22:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:51 INFO - https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:22:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:22:53 INFO - Downloaded 21866925 bytes. 12:22:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 12:22:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:22:54 INFO - Return code: 0 12:22:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:54 INFO - https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:22:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:23:00 INFO - Downloaded 21866925 bytes. 12:23:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 12:23:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:23:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:23:02 INFO - Return code: 0 12:23: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')]} 12:23:02 INFO - https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:23:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:23:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:23:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:23:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:23:04 INFO - Downloaded 11132038 bytes. 12:23:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 12:23:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:23:04 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 12:23:04 INFO - Return code: 0 12:23:04 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')]} 12:23:04 INFO - https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:23:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:23:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:23:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:23:05 INFO - Downloaded 57730947 bytes. 12:23:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:23:05 INFO - mkdir: /builds/slave/test/properties 12:23:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:05 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:05 INFO - Contents: 12:23:05 INFO - build_url:https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:23:05 INFO - Running post-action listener: _resource_record_post_action 12:23:05 INFO - Running post-action listener: set_extra_try_arguments 12:23:05 INFO - ##### 12:23:05 INFO - ##### Running populate-webroot step. 12:23:05 INFO - ##### 12:23:05 INFO - Running pre-action listener: _resource_record_pre_action 12:23:05 INFO - Running main action method: populate_webroot 12:23:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:05 INFO - '--tppagecycles', 12:23:05 INFO - '7'], 12:23:05 INFO - 'tests': ['tsvgm']}}, 12:23:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:23:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:23:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:23:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'tests': ['damp', 'tps']}, 12:23:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['damp', 'tps']}, 12:23:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:23:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:05 INFO - 'other': {'tests': ['a11yr', 12:23:05 INFO - 'ts_paint', 12:23:05 INFO - 'tpaint', 12:23:05 INFO - 'sessionrestore', 12:23:05 INFO - 'sessionrestore_no_auto_restore']}, 12:23:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['a11yr', 12:23:05 INFO - 'ts_paint', 12:23:05 INFO - 'tpaint', 12:23:05 INFO - 'sessionrestore', 12:23:05 INFO - 'sessionrestore_no_auto_restore']}, 12:23:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['a11yr', 12:23:05 INFO - 'ts_paint', 12:23:05 INFO - 'tpaint', 12:23:05 INFO - 'sessionrestore', 12:23:05 INFO - 'sessionrestore_no_auto_restore']}, 12:23:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['a11yr', 12:23:05 INFO - 'ts_paint', 12:23:05 INFO - 'tpaint', 12:23:05 INFO - 'sessionrestore', 12:23:05 INFO - 'sessionrestore_no_auto_restore']}, 12:23:05 INFO - 'other_l64': {'tests': ['a11yr', 12:23:05 INFO - 'ts_paint', 12:23:05 INFO - 'tpaint', 12:23:05 INFO - 'sessionrestore', 12:23:05 INFO - 'sessionrestore_no_auto_restore']}, 12:23:05 INFO - 'other_nol64': {'tests': ['a11yr', 12:23:05 INFO - 'ts_paint', 12:23:05 INFO - 'tpaint', 12:23:05 INFO - 'sessionrestore', 12:23:05 INFO - 'sessionrestore_no_auto_restore']}, 12:23:05 INFO - 'svgr': {'tests': ['tsvgx', 12:23:05 INFO - 'tsvgr_opacity', 12:23:05 INFO - 'tart', 12:23:05 INFO - 'tscrollx', 12:23:05 INFO - 'cart']}, 12:23:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['tsvgx', 12:23:05 INFO - 'tsvgr_opacity', 12:23:05 INFO - 'tart', 12:23:05 INFO - 'tscrollx', 12:23:05 INFO - 'cart']}, 12:23:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'tests': ['tp5o']}, 12:23:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'talos_options': ['--e10s'], 12:23:05 INFO - 'tests': ['tp5o']}, 12:23:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'talos_options': ['--xperf_path', 12:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:05 INFO - 'tests': ['tp5n']}, 12:23:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:05 INFO - 'talos_options': ['--e10s', 12:23:05 INFO - '--xperf_path', 12:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:05 INFO - 'tests': ['tp5n']}}, 12:23:05 INFO - 'talos.zip': {'path': '', 12:23:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:05 INFO - Running post-action listener: _resource_record_post_action 12:23:05 INFO - ##### 12:23:05 INFO - ##### Running create-virtualenv step. 12:23:05 INFO - ##### 12:23:05 INFO - Running pre-action listener: _resource_record_pre_action 12:23:05 INFO - Running main action method: create_virtualenv 12:23:05 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:05 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 12:23:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:05 INFO - Using real prefix '/usr' 12:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:06 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:08 INFO - Installing pip.................done. 12:23:08 INFO - Return code: 0 12:23:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:08 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:08 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 12:23:08 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 12:23:08 INFO - Using env: {'DISPLAY': ':0', 12:23:08 INFO - 'HOME': '/home/cltbld', 12:23:08 INFO - 'LANG': 'en_US.UTF-8', 12:23:08 INFO - 'LANGUAGE': 'en_US:en', 12:23:08 INFO - 'LOGNAME': 'cltbld', 12:23:08 INFO - 'MAIL': '/var/mail/cltbld', 12:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:08 INFO - 'MOZ_NO_REMOTE': '1', 12:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:08 INFO - 'NO_EM_RESTART': '1', 12:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:08 INFO - 'PWD': '/builds/slave/test', 12:23:08 INFO - 'SHELL': '/bin/bash', 12:23:08 INFO - 'SHLVL': '1', 12:23:08 INFO - 'TERM': 'linux', 12:23:08 INFO - 'TMOUT': '86400', 12:23:08 INFO - 'USER': 'cltbld', 12:23:08 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:08 INFO - '_': '/tools/buildbot/bin/python'} 12:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:08 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 12:23:08 INFO - Cleaning up... 12:23:08 INFO - Return code: 0 12:23:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:08 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:08 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 12:23:08 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 12:23:08 INFO - Using env: {'DISPLAY': ':0', 12:23:08 INFO - 'HOME': '/home/cltbld', 12:23:08 INFO - 'LANG': 'en_US.UTF-8', 12:23:08 INFO - 'LANGUAGE': 'en_US:en', 12:23:08 INFO - 'LOGNAME': 'cltbld', 12:23:08 INFO - 'MAIL': '/var/mail/cltbld', 12:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:08 INFO - 'MOZ_NO_REMOTE': '1', 12:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:08 INFO - 'NO_EM_RESTART': '1', 12:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:08 INFO - 'PWD': '/builds/slave/test', 12:23:08 INFO - 'SHELL': '/bin/bash', 12:23:08 INFO - 'SHLVL': '1', 12:23:08 INFO - 'TERM': 'linux', 12:23:08 INFO - 'TMOUT': '86400', 12:23:08 INFO - 'USER': 'cltbld', 12:23:08 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:08 INFO - '_': '/tools/buildbot/bin/python'} 12:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:08 INFO - Downloading/unpacking psutil>=0.7.1 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:10 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 12:23:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:10 INFO - Installing collected packages: psutil 12:23:10 INFO - Running setup.py install for psutil 12:23:10 INFO - building 'psutil._psutil_linux' extension 12:23:10 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 12:23:10 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 12:23:10 INFO - building 'psutil._psutil_posix' extension 12:23:10 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 12:23:10 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 12:23:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:11 INFO - Successfully installed psutil 12:23:11 INFO - Cleaning up... 12:23:11 INFO - Return code: 0 12:23:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:11 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:11 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 12:23:11 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 12:23:11 INFO - Using env: {'DISPLAY': ':0', 12:23:11 INFO - 'HOME': '/home/cltbld', 12:23:11 INFO - 'LANG': 'en_US.UTF-8', 12:23:11 INFO - 'LANGUAGE': 'en_US:en', 12:23:11 INFO - 'LOGNAME': 'cltbld', 12:23:11 INFO - 'MAIL': '/var/mail/cltbld', 12:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:11 INFO - 'MOZ_NO_REMOTE': '1', 12:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:11 INFO - 'NO_EM_RESTART': '1', 12:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:11 INFO - 'PWD': '/builds/slave/test', 12:23:11 INFO - 'SHELL': '/bin/bash', 12:23:11 INFO - 'SHLVL': '1', 12:23:11 INFO - 'TERM': 'linux', 12:23:11 INFO - 'TMOUT': '86400', 12:23:11 INFO - 'USER': 'cltbld', 12:23:11 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:11 INFO - '_': '/tools/buildbot/bin/python'} 12:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:23:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:23:13 INFO - Installing collected packages: mozsystemmonitor 12:23:13 INFO - Running setup.py install for mozsystemmonitor 12:23:13 INFO - Successfully installed mozsystemmonitor 12:23:13 INFO - Cleaning up... 12:23:13 INFO - Return code: 0 12:23:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23:13 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')]} 12:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:13 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')]} 12:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:13 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:13 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 12:23:13 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 12:23:13 INFO - Using env: {'DISPLAY': ':0', 12:23:13 INFO - 'HOME': '/home/cltbld', 12:23:13 INFO - 'LANG': 'en_US.UTF-8', 12:23:13 INFO - 'LANGUAGE': 'en_US:en', 12:23:13 INFO - 'LOGNAME': 'cltbld', 12:23:13 INFO - 'MAIL': '/var/mail/cltbld', 12:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:13 INFO - 'MOZ_NO_REMOTE': '1', 12:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:13 INFO - 'NO_EM_RESTART': '1', 12:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:13 INFO - 'PWD': '/builds/slave/test', 12:23:13 INFO - 'SHELL': '/bin/bash', 12:23:13 INFO - 'SHLVL': '1', 12:23:13 INFO - 'TERM': 'linux', 12:23:13 INFO - 'TMOUT': '86400', 12:23:13 INFO - 'USER': 'cltbld', 12:23:13 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:13 INFO - '_': '/tools/buildbot/bin/python'} 12:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:13 INFO - Downloading/unpacking blobuploader==1.2.4 12:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:15 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:15 INFO - Downloading docopt-0.6.1.tar.gz 12:23:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:15 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:15 INFO - Running setup.py install for blobuploader 12:23:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:15 INFO - Running setup.py install for requests 12:23:16 INFO - Running setup.py install for docopt 12:23:16 INFO - Successfully installed blobuploader requests docopt 12:23:16 INFO - Cleaning up... 12:23:16 INFO - Return code: 0 12:23:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:16 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:16 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 12:23:16 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 12:23:16 INFO - Using env: {'DISPLAY': ':0', 12:23:16 INFO - 'HOME': '/home/cltbld', 12:23:16 INFO - 'LANG': 'en_US.UTF-8', 12:23:16 INFO - 'LANGUAGE': 'en_US:en', 12:23:16 INFO - 'LOGNAME': 'cltbld', 12:23:16 INFO - 'MAIL': '/var/mail/cltbld', 12:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:16 INFO - 'MOZ_NO_REMOTE': '1', 12:23:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:16 INFO - 'NO_EM_RESTART': '1', 12:23:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:16 INFO - 'PWD': '/builds/slave/test', 12:23:16 INFO - 'SHELL': '/bin/bash', 12:23:16 INFO - 'SHLVL': '1', 12:23:16 INFO - 'TERM': 'linux', 12:23:16 INFO - 'TMOUT': '86400', 12:23:16 INFO - 'USER': 'cltbld', 12:23:16 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:16 INFO - '_': '/tools/buildbot/bin/python'} 12:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:16 INFO - Running setup.py (path:/tmp/pip-ubBaZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:16 INFO - Running setup.py (path:/tmp/pip-ZlMYuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:16 INFO - Running setup.py (path:/tmp/pip-AwCnI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:16 INFO - Running setup.py (path:/tmp/pip-CZSMNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:16 INFO - Running setup.py (path:/tmp/pip-GWSHr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:17 INFO - Running setup.py (path:/tmp/pip-A7shO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:17 INFO - Running setup.py (path:/tmp/pip-NRkaW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:17 INFO - Running setup.py (path:/tmp/pip-axhSjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:17 INFO - Running setup.py (path:/tmp/pip-uEwBxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:17 INFO - Running setup.py (path:/tmp/pip-TdC5lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:17 INFO - Running setup.py (path:/tmp/pip-2x28H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:17 INFO - Running setup.py (path:/tmp/pip-i5aN5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:17 INFO - Running setup.py (path:/tmp/pip-V_t6t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:17 INFO - Running setup.py (path:/tmp/pip-RWHyGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:17 INFO - Running setup.py (path:/tmp/pip-XjnhyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:17 INFO - Running setup.py (path:/tmp/pip-972AJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:17 INFO - Running setup.py (path:/tmp/pip-FdH8Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:17 INFO - Running setup.py install for manifestparser 12:23:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Running setup.py install for mozcrash 12:23:18 INFO - Running setup.py install for mozdebug 12:23:18 INFO - Running setup.py install for mozdevice 12:23:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Running setup.py install for mozfile 12:23:18 INFO - Running setup.py install for mozhttpd 12:23:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Running setup.py install for mozinfo 12:23:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Running setup.py install for mozInstall 12:23:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Running setup.py install for mozleak 12:23:18 INFO - Running setup.py install for mozlog 12:23:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:18 INFO - Running setup.py install for moznetwork 12:23:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:19 INFO - Running setup.py install for mozprocess 12:23:19 INFO - Running setup.py install for mozprofile 12:23:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:19 INFO - Running setup.py install for mozrunner 12:23:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:19 INFO - Running setup.py install for mozscreenshot 12:23:19 INFO - Running setup.py install for moztest 12:23:19 INFO - Running setup.py install for mozversion 12:23:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:19 INFO - Cleaning up... 12:23:19 INFO - Return code: 0 12:23:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:19 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 12:23:19 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 12:23:19 INFO - Using env: {'DISPLAY': ':0', 12:23:19 INFO - 'HOME': '/home/cltbld', 12:23:19 INFO - 'LANG': 'en_US.UTF-8', 12:23:19 INFO - 'LANGUAGE': 'en_US:en', 12:23:19 INFO - 'LOGNAME': 'cltbld', 12:23:19 INFO - 'MAIL': '/var/mail/cltbld', 12:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:19 INFO - 'MOZ_NO_REMOTE': '1', 12:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:19 INFO - 'NO_EM_RESTART': '1', 12:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:19 INFO - 'PWD': '/builds/slave/test', 12:23:19 INFO - 'SHELL': '/bin/bash', 12:23:19 INFO - 'SHLVL': '1', 12:23:19 INFO - 'TERM': 'linux', 12:23:19 INFO - 'TMOUT': '86400', 12:23:19 INFO - 'USER': 'cltbld', 12:23:19 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:19 INFO - '_': '/tools/buildbot/bin/python'} 12:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:19 INFO - Running setup.py (path:/tmp/pip-RcP4op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:19 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)) 12:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:19 INFO - Running setup.py (path:/tmp/pip-HoZOQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:20 INFO - Running setup.py (path:/tmp/pip-ZEyyxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:20 INFO - Running setup.py (path:/tmp/pip-hWo3Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:20 INFO - Running setup.py (path:/tmp/pip-m_78VT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:20 INFO - Running setup.py (path:/tmp/pip-OuJkAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:20 INFO - Running setup.py (path:/tmp/pip-yzrhbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:20 INFO - Running setup.py (path:/tmp/pip-KGxCQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:20 INFO - Running setup.py (path:/tmp/pip-s9DyHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:20 INFO - Running setup.py (path:/tmp/pip-UjM004-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:20 INFO - Running setup.py (path:/tmp/pip-C0NHV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:20 INFO - Running setup.py (path:/tmp/pip-kBuBc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:20 INFO - Running setup.py (path:/tmp/pip-gAu0rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:20 INFO - Running setup.py (path:/tmp/pip-IsOzD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:20 INFO - Running setup.py (path:/tmp/pip-JD0T3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:20 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)) 12:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:20 INFO - Running setup.py (path:/tmp/pip-bnjmif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:21 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)) 12:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:21 INFO - Running setup.py (path:/tmp/pip-TTBX4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:21 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)) 12:23:21 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)) 12:23:21 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)) 12:23:21 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)) 12:23:21 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)) 12:23:21 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)) 12:23:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:22 INFO - Downloading blessings-1.5.1.tar.gz 12:23:22 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 12:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:22 INFO - Installing collected packages: blessings 12:23:22 INFO - Running setup.py install for blessings 12:23:22 INFO - Successfully installed blessings 12:23:22 INFO - Cleaning up... 12:23:23 INFO - Return code: 0 12:23:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:23 INFO - Reading from file tmpfile_stdout 12:23:23 INFO - Current package versions: 12:23:23 INFO - argparse == 1.2.1 12:23:23 INFO - blessings == 1.5.1 12:23:23 INFO - blobuploader == 1.2.4 12:23:23 INFO - docopt == 0.6.1 12:23:23 INFO - manifestparser == 1.1 12:23:23 INFO - mozInstall == 1.12 12:23:23 INFO - mozcrash == 0.16 12:23:23 INFO - mozdebug == 0.1 12:23:23 INFO - mozdevice == 0.47 12:23:23 INFO - mozfile == 1.2 12:23:23 INFO - mozhttpd == 0.7 12:23:23 INFO - mozinfo == 0.9 12:23:23 INFO - mozleak == 0.1 12:23:23 INFO - mozlog == 3.1 12:23:23 INFO - moznetwork == 0.27 12:23:23 INFO - mozprocess == 0.22 12:23:23 INFO - mozprofile == 0.27 12:23:23 INFO - mozrunner == 6.11 12:23:23 INFO - mozscreenshot == 0.1 12:23:23 INFO - mozsystemmonitor == 0.0 12:23:23 INFO - moztest == 0.7 12:23:23 INFO - mozversion == 1.4 12:23:23 INFO - psutil == 3.1.1 12:23:23 INFO - requests == 1.2.3 12:23:23 INFO - wsgiref == 0.1.2 12:23:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:23 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')]} 12:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:23 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')]} 12:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:23 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:23 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 12:23:23 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 12:23:23 INFO - Using env: {'DISPLAY': ':0', 12:23:23 INFO - 'HOME': '/home/cltbld', 12:23:23 INFO - 'LANG': 'en_US.UTF-8', 12:23:23 INFO - 'LANGUAGE': 'en_US:en', 12:23:23 INFO - 'LOGNAME': 'cltbld', 12:23:23 INFO - 'MAIL': '/var/mail/cltbld', 12:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:23 INFO - 'MOZ_NO_REMOTE': '1', 12:23:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:23 INFO - 'NO_EM_RESTART': '1', 12:23:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:23 INFO - 'PWD': '/builds/slave/test', 12:23:23 INFO - 'SHELL': '/bin/bash', 12:23:23 INFO - 'SHLVL': '1', 12:23:23 INFO - 'TERM': 'linux', 12:23:23 INFO - 'TMOUT': '86400', 12:23:23 INFO - 'USER': 'cltbld', 12:23:23 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:23 INFO - '_': '/tools/buildbot/bin/python'} 12:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 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)) 12:23:23 INFO - Cleaning up... 12:23:23 INFO - Return code: 0 12:23:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:23 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')]} 12:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:23 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')]} 12:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:23 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:23 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 12:23:23 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 12:23:23 INFO - Using env: {'DISPLAY': ':0', 12:23:23 INFO - 'HOME': '/home/cltbld', 12:23:23 INFO - 'LANG': 'en_US.UTF-8', 12:23:23 INFO - 'LANGUAGE': 'en_US:en', 12:23:23 INFO - 'LOGNAME': 'cltbld', 12:23:23 INFO - 'MAIL': '/var/mail/cltbld', 12:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:23 INFO - 'MOZ_NO_REMOTE': '1', 12:23:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:23 INFO - 'NO_EM_RESTART': '1', 12:23:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:23 INFO - 'PWD': '/builds/slave/test', 12:23:23 INFO - 'SHELL': '/bin/bash', 12:23:23 INFO - 'SHLVL': '1', 12:23:23 INFO - 'TERM': 'linux', 12:23:23 INFO - 'TMOUT': '86400', 12:23:23 INFO - 'USER': 'cltbld', 12:23:23 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:23 INFO - '_': '/tools/buildbot/bin/python'} 12:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:23 INFO - Downloading/unpacking jsonschema 12:23:23 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 12:23:23 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 12:23:23 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 12:23:23 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 12:23:23 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 12:23:23 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 12:23:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:25 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 12:23:25 INFO - Downloading/unpacking functools32 (from jsonschema) 12:23:25 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 12:23:25 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 12:23:25 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 12:23:25 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 12:23:25 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 12:23:25 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 12:23:25 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 12:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:25 INFO - warning: no files found matching '*.txt' 12:23:25 INFO - no previously-included directories found matching 'build' 12:23:25 INFO - no previously-included directories found matching 'dist' 12:23:25 INFO - no previously-included directories found matching '.git*' 12:23:25 INFO - Installing collected packages: jsonschema, functools32 12:23:25 INFO - Running setup.py install for functools32 12:23:25 INFO - warning: no files found matching '*.txt' 12:23:25 INFO - no previously-included directories found matching 'build' 12:23:25 INFO - no previously-included directories found matching 'dist' 12:23:25 INFO - no previously-included directories found matching '.git*' 12:23:25 INFO - Successfully installed jsonschema functools32 12:23:25 INFO - Cleaning up... 12:23:25 INFO - Return code: 0 12:23:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:23:25 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')]} 12:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:25 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')]} 12:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:25 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 0x7ff323975f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, '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': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', '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 12:23:25 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 12:23:25 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 12:23:25 INFO - Using env: {'DISPLAY': ':0', 12:23:25 INFO - 'HOME': '/home/cltbld', 12:23:25 INFO - 'LANG': 'en_US.UTF-8', 12:23:25 INFO - 'LANGUAGE': 'en_US:en', 12:23:25 INFO - 'LOGNAME': 'cltbld', 12:23:25 INFO - 'MAIL': '/var/mail/cltbld', 12:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:25 INFO - 'MOZ_NO_REMOTE': '1', 12:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:25 INFO - 'NO_EM_RESTART': '1', 12:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:25 INFO - 'PWD': '/builds/slave/test', 12:23:25 INFO - 'SHELL': '/bin/bash', 12:23:25 INFO - 'SHLVL': '1', 12:23:25 INFO - 'TERM': 'linux', 12:23:25 INFO - 'TMOUT': '86400', 12:23:25 INFO - 'USER': 'cltbld', 12:23:25 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:25 INFO - '_': '/tools/buildbot/bin/python'} 12:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:26 INFO - Downloading/unpacking flake8 12:23:26 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 12:23:26 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 12:23:26 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 12:23:26 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 12:23:26 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 12:23:26 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 12:23:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:23:27 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 12:23:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:23:27 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 12:23:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 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 12:23:27 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 12:23:28 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 12:23:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:23:28 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 12:23:28 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 12:23:28 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 12:23:28 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 12:23:28 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 12:23:28 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 12:23:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 12:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:23:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:23:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:23:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:23:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:23:28 INFO - no previously-included directories found matching 'docs/_build' 12:23:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:23:28 INFO - Running setup.py install for pep8 12:23:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:23:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:23:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:23:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:23:28 INFO - no previously-included directories found matching 'docs/_build' 12:23:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:23:28 INFO - Cleaning up... 12:23:28 INFO - Return code: 0 12:23:28 INFO - Running post-action listener: _resource_record_post_action 12:23:28 INFO - Running post-action listener: _start_resource_monitoring 12:23:28 INFO - Starting resource monitoring. 12:23:28 INFO - ##### 12:23:28 INFO - ##### Running install step. 12:23:28 INFO - ##### 12:23:28 INFO - Running pre-action listener: _resource_record_pre_action 12:23:28 INFO - Running main action method: install 12:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:28 INFO - Reading from file tmpfile_stdout 12:23:28 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:28 INFO - Reading from file tmpfile_stdout 12:23:28 INFO - Output received: 12:23:28 INFO - Usage: mozinstall [options] installer 12:23:28 INFO - Options: 12:23:28 INFO - -h, --help show this help message and exit 12:23:28 INFO - -d DEST, --destination=DEST 12:23:28 INFO - Directory to install application into. [default: 12:23:28 INFO - "/builds/slave/test"] 12:23:28 INFO - --app=APP Application being installed. [default: firefox] 12:23:28 INFO - mkdir: /builds/slave/test/build/application 12:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:23:43 INFO - Reading from file tmpfile_stdout 12:23:43 INFO - Output received: 12:23:43 INFO - /builds/slave/test/build/application/firefox/firefox 12:23:43 INFO - Running post-action listener: _resource_record_post_action 12:23:43 INFO - ##### 12:23:43 INFO - ##### Running run-tests step. 12:23:43 INFO - ##### 12:23:43 INFO - Running pre-action listener: _resource_record_pre_action 12:23:43 INFO - Running main action method: run_tests 12:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:43 INFO - Python 2.7.3 12:23:43 INFO - Return code: 0 12:23:43 INFO - grabbing minidump binary from tooltool 12:23: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')]} 12:23:43 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 0x232e0b0>, '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 0x232efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232f440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:43 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 12:23:43 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 12:23:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:43 INFO - Return code: 0 12:23:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:23:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:23:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:23:44 INFO - Return code: 0 12:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] in /builds/slave/test/build 12:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost 12:23:44 INFO - Using env: {'DISPLAY': ':0', 12:23:44 INFO - 'HOME': '/home/cltbld', 12:23:44 INFO - 'LANG': 'en_US.UTF-8', 12:23:44 INFO - 'LANGUAGE': 'en_US:en', 12:23:44 INFO - 'LOGNAME': 'cltbld', 12:23:44 INFO - 'MAIL': '/var/mail/cltbld', 12:23:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:44 INFO - 'MOZ_NO_REMOTE': '1', 12:23:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:44 INFO - 'NO_EM_RESTART': '1', 12:23:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:44 INFO - 'PWD': '/builds/slave/test', 12:23:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:44 INFO - 'SHELL': '/bin/bash', 12:23:44 INFO - 'SHLVL': '1', 12:23:44 INFO - 'TERM': 'linux', 12:23:44 INFO - 'TMOUT': '86400', 12:23:44 INFO - 'USER': 'cltbld', 12:23:44 INFO - 'XDG_SESSION_COOKIE': '8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114', 12:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:44 INFO - '_': '/tools/buildbot/bin/python'} 12:23:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] with output_timeout 3600 12:23:44 INFO - mozversion INFO | application_buildid: 20151210113952 12:23:44 INFO - mozversion INFO | application_changeset: 11c67a7b46b5158bd48a7216ab45f124a71d4794 12:23:44 INFO - mozversion INFO | application_display_name: Nightly 12:23:44 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:44 INFO - mozversion INFO | application_name: Firefox 12:23:44 INFO - mozversion INFO | application_remotingname: firefox 12:23:44 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 12:23:44 INFO - mozversion INFO | application_vendor: Mozilla 12:23:44 INFO - mozversion INFO | application_version: 45.0a1 12:23:44 INFO - mozversion INFO | platform_buildid: 20151210113952 12:23:44 INFO - mozversion INFO | platform_changeset: 11c67a7b46b5158bd48a7216ab45f124a71d4794 12:23:44 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 12:23:44 INFO - mozversion INFO | platform_version: 45.0a1 12:23:44 INFO - 2015-12-10 12:23:44,686 DEBUG : using testdate: 1449779024 12:23:44 INFO - 2015-12-10 12:23:44,686 DEBUG : actual date: 1449779024 12:23:44 INFO - 2015-12-10 12:23:44,733 INFO : starting webserver on 'localhost:39092' 12:23:44 INFO - 2015-12-10 12:23:44,734 INFO : Starting test suite talos-linux64-ix-004.e 12:23:44 INFO - 2015-12-10 12:23:44,734 INFO : Starting test dromaeo_css 12:23:44 INFO - 2015-12-10 12:23:44,734 DEBUG : operating with platform_type : linux_ 12:23:44 INFO - 2015-12-10 12:23:44,734 INFO : Initialising browser for dromaeo_css test... 12:23:44 INFO - 2015-12-10 12:23:44,751 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbeASGY/profile http://localhost:39092/getInfo.html 12:23:49 INFO - 2015-12-10 12:23:49,414 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:23:49 INFO - 2015-12-10 12:23:49,414 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:23:49 INFO - 2015-12-10 12:23:49,414 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:23:49 INFO - 2015-12-10 12:23:49,414 DEBUG : BROWSER_OUTPUT: __metrics 12:23:49 INFO - 2015-12-10 12:23:49,536 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] 12:23:50 INFO - 2015-12-10 12:23:50,448 INFO : Browser initialized. 12:23:50 INFO - 2015-12-10 12:23:50,448 INFO : Running cycle 1/1 for dromaeo_css test... 12:23:50 INFO - 2015-12-10 12:23:50,449 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbeASGY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:24:02 INFO - 2015-12-10 12:24:02,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 142716928 12:24:02 INFO - 2015-12-10 12:24:02,478 DEBUG : BROWSER_OUTPUT: 12:24:52 INFO - 2015-12-10 12:24:52,106 DEBUG : BROWSER_OUTPUT: 1449779092104 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] 12:27:03 INFO - 2015-12-10 12:27:03,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39092/tests/dromaeo/cssquery-ext.html) 12:27:04 INFO - 2015-12-10 12:27:04,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 145268736 12:27:04 INFO - 2015-12-10 12:27:04,978 DEBUG : BROWSER_OUTPUT: 12:30:07 INFO - 2015-12-10 12:30:07,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/dromaeo/cssquery-ext.html (next: http://localhost:39092/tests/dromaeo/cssquery-jquery.html) 12:30:07 INFO - 2015-12-10 12:30:07,692 DEBUG : BROWSER_OUTPUT: RSS: Main: 145911808 12:30:07 INFO - 2015-12-10 12:30:07,692 DEBUG : BROWSER_OUTPUT: 12:33:09 INFO - 2015-12-10 12:33:09,476 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39092/tests/dromaeo/cssquery-mootools.html) 12:33:10 INFO - 2015-12-10 12:33:10,069 DEBUG : BROWSER_OUTPUT: RSS: Main: 145506304 12:33:10 INFO - 2015-12-10 12:33:10,069 DEBUG : BROWSER_OUTPUT: 12:36:11 INFO - 2015-12-10 12:36:11,829 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39092/tests/dromaeo/cssquery-prototype.html) 12:36:12 INFO - 2015-12-10 12:36:12,422 DEBUG : BROWSER_OUTPUT: RSS: Main: 144171008 12:36:12 INFO - 2015-12-10 12:36:12,422 DEBUG : BROWSER_OUTPUT: 12:39:15 INFO - 2015-12-10 12:39:15,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39092/tests/dromaeo/cssquery-yui.html) 12:39:15 INFO - 2015-12-10 12:39:15,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 145080320 12:39:15 INFO - 2015-12-10 12:39:15,654 DEBUG : BROWSER_OUTPUT: 12:42:17 INFO - 2015-12-10 12:42:17,807 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/dromaeo/cssquery-yui.html (next: http://localhost:39092/tests/dromaeo/cssquery-dojo.html) 12:42:18 INFO - 2015-12-10 12:42:18,058 DEBUG : BROWSER_OUTPUT: RSS: Main: 147316736 12:42:18 INFO - 2015-12-10 12:42:18,058 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,059 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:42:18 INFO - 2015-12-10 12:42:18,060 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:42:18 INFO - 2015-12-10 12:42:18,060 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:42:18 INFO - 2015-12-10 12:42:18,060 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:42:18 INFO - 2015-12-10 12:42:18,061 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2610.5170543449462;2649.9470010599803;2688.722856643095;2705.675318961724;2714.063648041425;4513.751743654106;4701.2242979908315;4715.575598196162;4727.172744769665;4749.501302363254;4667.533246675331;4673.813047478106;4682.016776476944;4752.857414277569;4766.9274413257;4335.588119128685;4391.5827996340295;4434.889127771799;4442.422485076935;4450.2434586120435;4369.060651959828;4400.295952647561;4423.225935461298;4426.402435671192;4468.240399132155;4223.66210703143;4232.3016702244095;4330.610245077926;4334.176506463762;4371.934420983672;4341.41390912226;4344.6524278058;4347.347897815322;4348.173846969065;4384.846530371422;4339.262325404688;4345.608895199415;4348.478182618074;4356.629686476654;4401.383806267125;4441.800118994653;4515.977420112911;4554;4650.627949764044;4670;4944.134776414149;4969.254611808222;4993.101241776479;4998.875927359906;5036.597072234213;5042.1428357179;5043.873903152414;5043.899122017576;5044.924326135111;5090.490950904917;4532.909341813178;4647.256438969816;4655.255159174516;4658.277966915066;4661.696989695659;5117.641765076483;5182.663126896739;5190.532852043334;5196.428392876781;5207.062728708869;4969.304297398367;4979.601631869442;4993.101241776515;4996.7001979881325;5042.243663450512;4893.535114164149;4950.925736113961;4994.2508623706735;5063.291139240509;5065.8733531662;4997.075541024922;4998.075356059141;5000.349954505964;5007.524285192901;5051.924221136685;4969.552740253394;4987.875303117451;4989.351384320015;5002.799888004512;5047.848564543069;4050.18996200761;4107;4139.8965025874595;4163.417121602978;4203.243416185116;3630.3465376232;3635.690966267844;3645.1251699592435;3649.708023358125;3679.025058359537;4175.874723758292;4318.78406079701;4325.41606883073;4338.848140315074;4350.695451318377;4937.308776771255;4956.107900577858;4958.355413796184;4968.304437378698;5096.9490305097215;4290.742555446621;4301.182775272689;4320.719153255029;4356.803943822536;4363.5418281080665;4418.779061046935;4456.554344565518;4466.620337271368;4470.821167153277;4500.3249512573375;4547.113312903952;4663.160631086369;4719.197736384892;4751;4845.3216549682365;4934.9753251233515;5028.698278103326;5040;5040.924386134284;5086.847394578169;4596.655250856133;4770.833020844254;4780.450248221499;4781.976090119527;4826.758662066953;4885.853424397132;4913.336699545515;4973.378327709036;4976.427710813303;5021.6484846061885;5010.574101201439;5035.924461133012;5048.091343558195;5055.34280543527;5079.796808127633;4718.268668356411;4841.273808928689;4869.293952376815;4889.997550502209;4913.901721965469;4863.076015557032;4896.167651499182;4927.408710954708;4942.950570494249;4963.429205641253;4744.715317080986;4749.430068391677;4786.090642778;4810.880571825728;4851.175300198904;4716.764161791965;4724.740139292235;4819.421669399694;4940.901181976412;4983.053219888209;4725.527447255247;4774.092922344744;4783.995360974234;4813.470518242921;4821.300911367762 12:42:18 INFO - 2015-12-10 12:42:18,064 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.6533531877893;567.9067794495596;571.1033677126907;572.2732130194653;583.7139801497262;26983.460330793398;27238.727612723866;35152.82423587885;35954.64045359546;36024.09939751508;39517.209655806866;40022;40145.59854401455;40153.59846401543;40358.39462408059;7867;7989.480683755566;8005.919940800605;8012.479188852735;8015.519068855858;7767.495112817677;7796.610169491502;7894.131645518988;7897.091834439056;7910.841783164333;7822.608869556528;7881.566513841736;7914.841703165933;7940.1265860755275;7942.801429964239;7810.453268271223;7812.960935195344;7825.178356272595;7870.016247969004;7902;7767.184445633184;7850.175731548163;7904.802379940547;7907.209279072104;7908.248716371935;36548;36623.4506482403;36984.26031479382;37033.444498332545;37192.256154877025;2863.2984918694847;2871.913842584726;2879.3521457672064;3142.1516190628404;3445.3453843769853;27278.90884364623;27665;27913;28008.719912800814;28036.29909252285;3396.932061358759;3530.38218311797;3563.3051554946796;3568.091915637959;3577.2130131371055;214490;214558;215010;215089;215443;51652.22521662178;53249.46750532523;56641.150382744294;56919.43080569224;61660.07509887355;55142;55236;55339.16991245135;55679.721601391735;55697;13544.729105417837;13791.793123103162;13792.793108103386;13800.102993305403;13842.723145537235;10093.848592271122;10144.594216231268;10152.543135558917;10239.692809215736;10240.463930410499;19246.4226073218;19319;19363.4190974271;19571.608567828847;19667.803321966883;7605.733799317108;7819.077831647427;7938.444308898437;7969.481983671043;8030.277275045274;812.8821320908553;817.979550511242;836.3768992100923;868.2229404682773;897.9281657467387;23341.883290583777;24516.264512064667;24535;24627.630585541232;25050.87271072806;908.4549270437684;962.1148543340144;963.2342287881152;998.1366118307611;1070.9571617135227;13202.33988300581;13343.66640833987;13419.86580134186;13467.057305988486;15295.694086118216;318986;321400;324645;324747;325336;48809.267860982116;49253.75373123112;49401.25898111531;49449.75275123601;49544.75227623911;1076.5370890516997;1083.2254937719567;1090.2095980414135;1180.557291015869;1190.5654436130924;26625.33436664099;27000;27181.320466988484;27369;27545.173644790688;39636.405453917636;40272.1945561093;40335;40345.39481907833;40411.7979410101;247223;248499;248827;249086;249584;29535.409291813612;41792.16415671731;42552;43132.78433607812;43169.13661726811;44793.10413791771;45753;46029.539704602525;46405.53594464012;46484.7675761619;32541.396688265355;32713.34573308568;33091.66908330982;33681.621389219246;33889.15277117993;33485.33029339351;33616.49575256324;33652.3269534603;33805.66194338025;33816.32367352688;32528.18679532936;32696.673033269362;33010.83494582512;33088.50367244445;33096.338073237916;27849.721502785524;29533.556996645497;30273;30290;30319.545206821473;23634.763652363257;23819.98077909425;23862.16482423107;23943.281701548283;24074 12:42:18 INFO - 2015-12-10 12:42:18,066 DEBUG : BROWSER_OUTPUT: |2;jquery.html;342.6436506033725;343.6580602300711;346.1191268222374;347.44929287079987;347.6610304952671;10282.228832837529;10734.248602597801;15071.547853564396;15266.465673701423;16904.239309231085;16053.438129665465;16091.356345746186;16246.106464144468;16337.83662163378;16723.581910452245;5506.174073888918;6002.669853158074;6187.07193920911;6197.380261973812;6211.3167551569295;6123.387661233885;6341.365863413668;6416.935830641703;6432.77485288015;6432.839179020539;5987.760489580412;6169.784057557987;6197.721102550396;6234.407731265545;6319.6208227506495;6179.938200618004;6248.500119990413;6286.622802631834;6605.405513503808;6621.668916554201;6289.465395441393;6345.587536810139;6346.540295731961;6347.682615869234;6354;19269.710954335555;20082.09630566628;20493.18027278907;20580;20677.58644827095;576.4926864359337;577.5812535911473;577.6418620455303;1882.129752128002;1887.3582981786258;13598;13615.31923403826;13827.170369777745;14022.088564243393;14238.145711257357;1835.9541011474687;1867.5526466988058;1879.8120187981228;1884.5853912139307;1909.7517322748092;61533.38466615366;63759.04361434582;64534;64582.3541764581;65328.3467165327;14822;15353.155576443403;15498.225088745517;15534.844651553565;15596.454124105601;22900.4274893129;23115;23305.766942330698;23429.297121086394;23586.292411227692;8394.24451799341;8414.78963025917;8494.447860889022;8517.190866867637;8567.357448191411;7120.6439678015895;7147.2138064812825;7193.208747037822;7226.566406015656;7239.493235473572;7520.511166774142;7537.8115547111765;7608.695652173962;7614.695412183451;7742.574158421228;1720.5698575356162;1784.9910750446165;2993.2965753048015;3003.5945147404823;3137.6862313768443;769.253823790922;825.5542007316102;826.1573195340839;833.7832163637498;921.239977018962;14802.851971480148;14849.628759281104;14977.550673479813;14978.775318370233;15154.545363638888;815.8980127484065;817.824167803925;827.6234313387395;832.6877420967137;848.5163456829548;8827.69103081402;9072.773180670403;9082.18260356558;9136.451812891248;9178.724638260863;475031;482233;486745;492048;492382;69862;71484.2851571488;71769;72270.27729722635;73926;1010.7372083258406;1013.852991316255;1016.4866742295094;1024.3853687787416;1027.019196667181;13698;14250.928745356206;14276.357563909856;14322.283885805877;14526.273686315853;32513;32701;33473.83263083669;34360;34567.827160864035;39964.800175998935;40230.19539609133;40428.19143617052;40670.186596268504;40993.59006409898;8619.396642234984;8641.274583022156;8658.956705216433;8667.739967800977;8731.825363492822;10273.84589231177;10295.07344338983;11839.58561450345;15411.460598878986;16727.74908376351;13230.338483076;13268.53560125391;13374.197548147142;13706.177629342272;13882.583522494342;12832.615021549369;13215.339233038118;13225.404856781275;13681.79477307861;13858.86141138616;13988.440462381392;14100.153990760587;14644.926775366055;14792.778108328168;14878.85121148818;8692.695755648521;8801.98777140637;8952.462852228888;8992.055834137322;9001.414908030956;13334.533291334757;13376.197428154343;13542.526011589547;13911.234882081179;14002.229877356844 12:42:18 INFO - 2015-12-10 12:42:18,069 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1490.9861294319858;1596.712591733488;1631.1681042668242;1650.1171873047915;1669.0319614623515;6391.10524526566;6657;6864.553804002736;6873.793786186417;6969.337912898279;6959.547629404098;7004.089468369105;7070.717171313141;7071.398931090864;7078.256783037771;2072.730545029148;2114.450242936832;2121.501447159924;2121.533262682215;2122.320857325649;2053.640612892745;2055.6916462530667;2060.3509894383296;2104.8000439958178;2113.968290475644;1756.5432987423235;1781.732740088984;2054.075665950328;2059.5983783162287;2102.5899949509853;2054.5685406064667;2066.5763518478752;2067.4211220858183;2068.7931206879343;2070.647989841723;2030.736004319429;2041.2549419461939;2090.9477263068393;2095.4656562576597;2105.799949004842;6832.965835170842;6877.724891004354;6890.37986581205;6892.724291028353;6898.241193468714;3985;4013.4180543821276;4032.1532478179615;4032.6572241359518;4040.5555388907196;2621.829581077243;2637.815352925296;2655.946881062368;2728.5361488546996;2754.807163498556;876.1282523888708;890.1098901098901;890.9020007799137;909.3134683314122;916.5692124701379;9156.496392698398;9269.72190834276;9288.582013809395;9305.627774888997;9312.394694344846;3708.517892673935;3770.490983717218;3805.771653700787;3833.4824798651825;3833.884983450501;7287.089113860768;7295.8540829184185;7303.269673032761;7320.304571065847;7335;4112.547619761824;4116.794160291974;4134.400511925754;4153.875383738493;4155.397467367215;3782.508273924427;3807.638274363931;3821.082940094357;3826.598207188261;3844.1927195289304;3147.763917706181;3169.952450713241;3186.824724640167;3198.856051477689;3210.181403742031;933.5658918602883;939.6053657463882;942.2556180617372;942.4157022646003;948.5209968965628;803.9477433966773;828.5360198288978;909.6361455417886;954.708813811794;1170.8829117088223;6346.873062538723;6640.535162538673;6664.033715111282;6664.400203981665;6672.165979252593;920.089111779347;923.3398120343986;1032.9018743219383;1038.4755698372426;1049.034887903131;4899.608031357483;4936.950630493721;5009.298698182258;5016.498350164954;5016.648834581617;8113.945187125636;8356.916430835736;8370.3303735701;8393.328533717287;8424.368172387096;8127.878081828777;8213.34293256538;8239.052508961546;8252.628631711588;8305.37709671777;6510.641914694737;6528.804135875931;6590.7693230736695;6609.074729537869;6618.4705223582;6303.684815759286;6317.052442133717;6336.968315158395;6339.270983836734;6341.27075386318;7190.1371835377995;7310.159331676925;7333.779986600411;7352.705891764484;7381.557106573623;7343.302386273295;7585.6965721372735;7625.351845093007;7647;7647.388208943383;5787.102999035156;5798.536117110705;5852.678102704393;6037.849053773691;6047.909281360871;6371.522135839923;6438.034294855622;6464.4828413725845;6474.870502589828;6500.869982600416;6225.782097626561;6259.96870015647;6338.144350512806;6351.110844481684;6477.967610161919;5648.661080335193;5942.405759424196;6137.570370074052;6194.132821404917;6233.283172435046;6214.9689251553455;6232.376762323732;6237.75048998035;6283.905741413791;6313.147725057059;5618.4662457066515;5712.11462223356;5713.371529131709;5718.428157184248;5733.713314334183;5827.825165245049;5869;5875.823725288248;5877;5878.382769809108 12:42:18 INFO - 2015-12-10 12:42:18,073 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.78594983511533;311.1536620392532;318.1156385249009;319.076274186231;323.0872784384115;5388.595855310848;5518.089515229987;5538.889222215554;5610.915836262459;5615.045442274818;5341.092014357559;5487.42382049885;5499.175123731432;5521.309836270466;5522.447755224475;4288.463942007249;4307.224699554079;4317.050248945227;4326.372675961999;4330.17726631939;4281.486221653406;4290.935635965448;4305.182015417076;4312.202242585116;4326.480822301328;4047.5547689754103;4145.2952997990415;4272.081502476967;4274.636655884254;4277.465316835395;4134.235166494178;4140.730852494593;4141.047559061432;4153.003279212998;4158.168366326717;3625.456181572759;3641.7814931104217;3642.1076836175043;3656.926861462756;3659.414493681025;5247.344081989751;5349.3045904032615;5365.114756065244;5375.16684913839;5384.946150538483;4856.004519073582;4866.416030076378;4871.8294859679745;4873.756312184412;4883.1698611235815;5790.13148027795;5835.737391817378;5839.036558967765;5848.64908105515;5849.210356601847;3873.360895452247;3995.4206640037323;4002.2195671844;4014.3777714454304;4026.577209393001;5994.760209591611;6079.088136779475;6079.544034197453;6091.756329746805;6107.389261073857;4060.573639767841;4065.6950728695465;4069.593040695966;4072.4095006223934;4075.2664520386616;4128.174365126986;4133;4135.028268356934;4139.91720165595;4141.544430112685;4691.859244222679;4781.378420805343;4782.402199725035;4788.928166077511;4791.185498465269;5771.740271687784;5778.913316300259;5783.624064435799;5785.942140578541;5808.709564521757;1459.6569806095558;1462.1154201708025;1464.7070585882866;1464.875485583716;1465.5383554180398;988.8714467119229;990.3265779270022;993.6373223773415;1008.5562352564848;1016.0905989139678;5035.420926593415;5040.36995375578;5058.620603454756;5073.441921388644;5076.543111120017;5978.491828194565;5979.850503737354;6032.1856549365275;6040.154378387029;6040.305364883095;5029.4719054498755;5040.47075057121;5044.520770526794;5049.040682270357;5050.090983622909;3176.634687010977;3239.967600324014;3266.6406695263504;3267.7059064684295;3275.901722948315;5590.608657394025;5652.067408877571;5665.178549110356;5668.716564171775;5675.0068737971515;6245.531585131133;6263.937360626426;6276.278228003838;6277.591956522811;6297.2128483939505;5641.464060914206;5671.971640141938;5692.7722891083895;5703.771849126071;5723.084306510938;6071.119687645355;6072.605280656743;6080.422359875804;6082.391760823883;6103.298120716175;5790.710464476843;5973.611715238496;5974.522038237017;5979.22270104901;5987.760489580368;5711.8000869969355;5718.771249150153;5730.312362516524;5736.799212027559;5743.368229494668;4113.115680128787;4154.5222299434745;4164.687648426319;4166.625013745517;4168.041350489344;5913.881722365614;6005.609635373687;6057.303410107894;6086.969565152146;6097.085437184458;5827.213326200908;5827.504662103769;5829.067349224104;5842.38654941242;5844.327902291121;5784.21913041734;5800.477956983892;5815.79644712433;5838.4453476919625;5857.765689372548;5571.860703482445;5688.63023903445;5696.658200507983;5698.2592263005545;5709.286339207599;5814.73833677494;5871.6183448075735;5883.735231914488;5886.705664716833;5890.793822216373;5739.827805165852;5740.167675687086;5745.994450970979;5747;5748.310202775527 12:42:18 INFO - 2015-12-10 12:42:18,073 DEBUG : BROWSER_OUTPUT: |5;yui.html;693.6185098195992;732.4177279063144;740.655595148256;745.9030326057615;753.5930597477362;10258.692239232816;10392.636257730981;11584.826227606582;11802.291862488257;11826.172167948236;11833.171677982524;12123.151379403424;12274.202176858498;12430.44063017164;12439.564615238469;803.533950308821;823.3166471828368;823.7899335669396;823.8393513264916;827.5572568675759;819.1767273889733;826.6197549127379;830.4311546590575;830.8338332333527;832.3798769916391;855.170484629908;859.4928991894781;863.7797361672765;866.0603245478663;867.4405008769371;854.918782715641;855.8545047341967;859.3769517100114;859.6905114158898;860.2730692564772;630.3192552043828;630.8801327747759;632.8449529865167;633.2306247908813;637.378555907986;11578.652640420802;12645;12855;12954.740905181938;13000.479980800757;10470.476476176236;11531.942340288246;11806.173567850254;11966.581169659106;12475.189112707645;9315.39449935752;9433.528323583794;9484.715458536255;9567.569459374345;9617.663381781604;3184.5860038194887;3211.0848408203783;3253.170441537393;3269.738420926344;3309.1396236978317;37416.25167496635;38101.23797524062;38245.61754382448;38369.424458633424;38398.232035359135;8268.917310826935;8446.366522510836;8522.10517895612;8577.742667719978;8601.39790214679;17265.741013884803;17335.653286934445;17337.2198251079;17355.652886942444;17356;6573.474122070223;6593.604383736895;6677.399034086956;6687.832804179934;6691.464682825363;3695.2055308108884;3695.5750088740137;3707.5057650821777;3732.122951106488;3738.4953031340965;2746.4507098580366;2771.8752656130523;2784.888604455799;2789.6512935883015;2791.1486996466274;478.9712617242977;488.8948875991679;497.64169797745564;502.23660036743945;503.60215429810773;427.8032105231566;444.12064113055686;477.2962455005936;505.94181669108525;596.4780816785313;7327;7671.961640191764;7784.143744188134;7884.329831964347;8031.317338026216;403.9838406463709;483.35230790740576;486.5353587324097;489.4615922485238;507.01132791056847;3317.038058962874;3336.966630333714;3338.332333533303;3372.5952885653387;3387.3733359328603;46352.768236158605;49587.50412495829;49636.503634963185;49677.751611241714;49967.25049124266;42224.57775422268;43314.566854331686;43318.7834060834;43385.13229735451;43765.78117109394;38811.22377552433;39786.80106599507;39893.40159897604;40259;40281.992950175896;7357;7489.325960663567;7542.924570754222;7569.1673915870315;7604.5437273763755;32022.6797732029;32157.517637234992;32464;33955.15112122118;33984.66015339914;38467.80766096152;38913.61086389213;39489.60510394859;39638.40542391924;39726.40410393904;10067.496625168858;10133.695989120339;10235;10331.483425828828;10400.58397664085;10699.732506687395;10792.460376981277;10905.454727263765;10956.835647465135;11025.117990560897;10612.991765782239;10667.199960002876;10692.62575809843;10784.19118566095;10838.729031774268;9413.717588472357;9630.518474076409;9786.559604817658;9984.500774961078;10520.473976301308;9766.56050477716;9958.751031223988;10098.091171794575;10278.434686092038;10739.194560407845;8527.488350698977;8547.993660792818;9009.954950225207;9275.814483710423;9397.812043759222;8356.623951922056;8372.162783721822;8459.91540084616;8467.66129354819;8608.870866937126 12:42:18 INFO - 2015-12-10 12:42:18,073 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:42:18 INFO - 2015-12-10 12:42:18,073 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:42:18 INFO - 2015-12-10 12:42:18,073 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,332 12:42:18 INFO - 2015-12-10 12:42:18,073 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:42:18 INFO - 2015-12-10 12:42:18,074 DEBUG : BROWSER_OUTPUT: __startTimestamp1449780138058__endTimestamp 12:42:18 INFO - 2015-12-10 12:42:18,074 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,074 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:42:18 INFO - 2015-12-10 12:42:18,074 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:42:18 INFO - 2015-12-10 12:42:18,074 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,074 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4631.30 Median:4749.50 stddev:483.92 (10.2%) stddev-sans-first:458.70 12:42:18 INFO - 2015-12-10 12:42:18,075 DEBUG : BROWSER_OUTPUT: Values: 2610.5 2649.9 2688.7 2705.7 2714.1 4513.8 4701.2 4715.6 4727.2 4749.5 4667.5 4673.8 4682.0 4752.9 4766.9 4335.6 4391.6 4434.9 4442.4 4450.2 4369.1 4400.3 4423.2 4426.4 4468.2 4223.7 4232.3 4330.6 4334.2 4371.9 4341.4 4344.7 4347.3 4348.2 4384.8 4339.3 4345.6 4348.5 4356.6 4401.4 4441.8 4516.0 4554.0 4650.6 4670.0 4944.1 4969.3 4993.1 4998.9 5036.6 5042.1 5043.9 5043.9 5044.9 5090.5 4532.9 4647.3 4655.3 4658.3 4661.7 5117.6 5182.7 5190.5 5196.4 5207.1 4969.3 4979.6 4993.1 4996.7 5042.2 4893.5 4950.9 4994.3 5063.3 5065.9 4997.1 4998.1 5000.3 5007.5 5051.9 4969.6 4987.9 4989.4 5002.8 5047.8 4050.2 4107.0 4139.9 4163.4 4203.2 3630.3 3635.7 3645.1 3649.7 3679.0 4175.9 4318.8 4325.4 4338.8 4350.7 4937.3 4956.1 4958.4 4968.3 5096.9 4290.7 4301.2 4320.7 4356.8 4363.5 4418.8 4456.6 4466.6 4470.8 4500.3 4547.1 4663.2 4719.2 4751.0 4845.3 4935.0 5028.7 5040.0 5040.9 5086.8 4596.7 4770.8 4780.5 4782.0 4826.8 4885.9 4913.3 4973.4 4976.4 5021.6 5010.6 5035.9 5048.1 5055.3 5079.8 4718.3 4841.3 4869.3 4890.0 4913.9 4863.1 4896.2 4927.4 4943.0 4963.4 4744.7 4749.4 4786.1 4810.9 4851.2 4716.8 4724.7 4819.4 4940.9 4983.1 4725.5 4774.1 4784.0 4813.5 4821.3 12:42:18 INFO - 2015-12-10 12:42:18,075 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,075 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42493.11 Median:19341.60 stddev:69619.39 (359.9%) stddev-sans-first:69743.51 12:42:18 INFO - 2015-12-10 12:42:18,075 DEBUG : BROWSER_OUTPUT: Values: 563.7 567.9 571.1 572.3 583.7 26983.5 27238.7 35152.8 35954.6 36024.1 39517.2 40022.0 40145.6 40153.6 40358.4 7867.0 7989.5 8005.9 8012.5 8015.5 7767.5 7796.6 7894.1 7897.1 7910.8 7822.6 7881.6 7914.8 7940.1 7942.8 7810.5 7813.0 7825.2 7870.0 7902.0 7767.2 7850.2 7904.8 7907.2 7908.2 36548.0 36623.5 36984.3 37033.4 37192.3 2863.3 2871.9 2879.4 3142.2 3445.3 27278.9 27665.0 27913.0 28008.7 28036.3 3396.9 3530.4 3563.3 3568.1 3577.2 214490.0 214558.0 215010.0 215089.0 215443.0 51652.2 53249.5 56641.2 56919.4 61660.1 55142.0 55236.0 55339.2 55679.7 55697.0 13544.7 13791.8 13792.8 13800.1 13842.7 10093.8 10144.6 10152.5 10239.7 10240.5 19246.4 19319.0 19363.4 19571.6 19667.8 7605.7 7819.1 7938.4 7969.5 8030.3 812.9 818.0 836.4 868.2 897.9 23341.9 24516.3 24535.0 24627.6 25050.9 908.5 962.1 963.2 998.1 1071.0 13202.3 13343.7 13419.9 13467.1 15295.7 318986.0 321400.0 324645.0 324747.0 325336.0 48809.3 49253.8 49401.3 49449.8 49544.8 1076.5 1083.2 1090.2 1180.6 1190.6 26625.3 27000.0 27181.3 27369.0 27545.2 39636.4 40272.2 40335.0 40345.4 40411.8 247223.0 248499.0 248827.0 249086.0 249584.0 29535.4 41792.2 42552.0 43132.8 43169.1 44793.1 45753.0 46029.5 46405.5 46484.8 32541.4 32713.3 33091.7 33681.6 33889.2 33485.3 33616.5 33652.3 33805.7 33816.3 32528.2 32696.7 33010.8 33088.5 33096.3 27849.7 29533.6 30273.0 30290.0 30319.5 23634.8 23820.0 23862.2 23943.3 24074.0 12:42:18 INFO - 2015-12-10 12:42:18,075 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,076 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27357.33 Median:347.56 stddev:79247.80 (22801.5%) stddev-sans-first:79444.15 12:42:18 INFO - 2015-12-10 12:42:18,077 DEBUG : BROWSER_OUTPUT: Values: 342.6 343.7 346.1 347.4 347.7 10282.2 10734.2 15071.5 15266.5 16904.2 16053.4 16091.4 16246.1 16337.8 16723.6 5506.2 6002.7 6187.1 6197.4 6211.3 6123.4 6341.4 6416.9 6432.8 6432.8 5987.8 6169.8 6197.7 6234.4 6319.6 6179.9 6248.5 6286.6 6605.4 6621.7 6289.5 6345.6 6346.5 6347.7 6354.0 19269.7 20082.1 20493.2 20580.0 20677.6 576.5 577.6 577.6 1882.1 1887.4 13598.0 13615.3 13827.2 14022.1 14238.1 1836.0 1867.6 1879.8 1884.6 1909.8 61533.4 63759.0 64534.0 64582.4 65328.3 14822.0 15353.2 15498.2 15534.8 15596.5 22900.4 23115.0 23305.8 23429.3 23586.3 8394.2 8414.8 8494.4 8517.2 8567.4 7120.6 7147.2 7193.2 7226.6 7239.5 7520.5 7537.8 7608.7 7614.7 7742.6 1720.6 1785.0 2993.3 3003.6 3137.7 769.3 825.6 826.2 833.8 921.2 14802.9 14849.6 14977.6 14978.8 15154.5 815.9 817.8 827.6 832.7 848.5 8827.7 9072.8 9082.2 9136.5 9178.7 475031.0 482233.0 486745.0 492048.0 492382.0 69862.0 71484.3 71769.0 72270.3 73926.0 1010.7 1013.9 1016.5 1024.4 1027.0 13698.0 14250.9 14276.4 14322.3 14526.3 32513.0 32701.0 33473.8 34360.0 34567.8 39964.8 40230.2 40428.2 40670.2 40993.6 8619.4 8641.3 8659.0 8667.7 8731.8 10273.8 10295.1 11839.6 15411.5 16727.7 13230.3 13268.5 13374.2 13706.2 13882.6 12832.6 13215.3 13225.4 13681.8 13858.9 13988.4 14100.2 14644.9 14792.8 14878.9 8692.7 8802.0 8952.5 8992.1 9001.4 13334.5 13376.2 13542.5 13911.2 14002.2 12:42:18 INFO - 2015-12-10 12:42:18,077 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,077 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4804.68 Median:6232.83 stddev:2456.33 (39.4%) stddev-sans-first:2450.60 12:42:18 INFO - 2015-12-10 12:42:18,077 DEBUG : BROWSER_OUTPUT: Values: 1491.0 1596.7 1631.2 1650.1 1669.0 6391.1 6657.0 6864.6 6873.8 6969.3 6959.5 7004.1 7070.7 7071.4 7078.3 2072.7 2114.5 2121.5 2121.5 2122.3 2053.6 2055.7 2060.4 2104.8 2114.0 1756.5 1781.7 2054.1 2059.6 2102.6 2054.6 2066.6 2067.4 2068.8 2070.6 2030.7 2041.3 2090.9 2095.5 2105.8 6833.0 6877.7 6890.4 6892.7 6898.2 3985.0 4013.4 4032.2 4032.7 4040.6 2621.8 2637.8 2655.9 2728.5 2754.8 876.1 890.1 890.9 909.3 916.6 9156.5 9269.7 9288.6 9305.6 9312.4 3708.5 3770.5 3805.8 3833.5 3833.9 7287.1 7295.9 7303.3 7320.3 7335.0 4112.5 4116.8 4134.4 4153.9 4155.4 3782.5 3807.6 3821.1 3826.6 3844.2 3147.8 3170.0 3186.8 3198.9 3210.2 933.6 939.6 942.3 942.4 948.5 803.9 828.5 909.6 954.7 1170.9 6346.9 6640.5 6664.0 6664.4 6672.2 920.1 923.3 1032.9 1038.5 1049.0 4899.6 4937.0 5009.3 5016.5 5016.6 8113.9 8356.9 8370.3 8393.3 8424.4 8127.9 8213.3 8239.1 8252.6 8305.4 6510.6 6528.8 6590.8 6609.1 6618.5 6303.7 6317.1 6337.0 6339.3 6341.3 7190.1 7310.2 7333.8 7352.7 7381.6 7343.3 7585.7 7625.4 7647.0 7647.4 5787.1 5798.5 5852.7 6037.8 6047.9 6371.5 6438.0 6464.5 6474.9 6500.9 6225.8 6260.0 6338.1 6351.1 6478.0 5648.7 5942.4 6137.6 6194.1 6233.3 6215.0 6232.4 6237.8 6283.9 6313.1 5618.5 5712.1 5713.4 5718.4 5733.7 5827.8 5869.0 5875.8 5877.0 5878.4 12:42:18 INFO - 2015-12-10 12:42:18,077 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,077 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4808.35 Median:5508.63 stddev:1430.63 (26.0%) stddev-sans-first:1394.15 12:42:18 INFO - 2015-12-10 12:42:18,078 DEBUG : BROWSER_OUTPUT: Values: 305.8 311.2 318.1 319.1 323.1 5388.6 5518.1 5538.9 5610.9 5615.0 5341.1 5487.4 5499.2 5521.3 5522.4 4288.5 4307.2 4317.1 4326.4 4330.2 4281.5 4290.9 4305.2 4312.2 4326.5 4047.6 4145.3 4272.1 4274.6 4277.5 4134.2 4140.7 4141.0 4153.0 4158.2 3625.5 3641.8 3642.1 3656.9 3659.4 5247.3 5349.3 5365.1 5375.2 5384.9 4856.0 4866.4 4871.8 4873.8 4883.2 5790.1 5835.7 5839.0 5848.6 5849.2 3873.4 3995.4 4002.2 4014.4 4026.6 5994.8 6079.1 6079.5 6091.8 6107.4 4060.6 4065.7 4069.6 4072.4 4075.3 4128.2 4133.0 4135.0 4139.9 4141.5 4691.9 4781.4 4782.4 4788.9 4791.2 5771.7 5778.9 5783.6 5785.9 5808.7 1459.7 1462.1 1464.7 1464.9 1465.5 988.9 990.3 993.6 1008.6 1016.1 5035.4 5040.4 5058.6 5073.4 5076.5 5978.5 5979.9 6032.2 6040.2 6040.3 5029.5 5040.5 5044.5 5049.0 5050.1 3176.6 3240.0 3266.6 3267.7 3275.9 5590.6 5652.1 5665.2 5668.7 5675.0 6245.5 6263.9 6276.3 6277.6 6297.2 5641.5 5672.0 5692.8 5703.8 5723.1 6071.1 6072.6 6080.4 6082.4 6103.3 5790.7 5973.6 5974.5 5979.2 5987.8 5711.8 5718.8 5730.3 5736.8 5743.4 4113.1 4154.5 4164.7 4166.6 4168.0 5913.9 6005.6 6057.3 6087.0 6097.1 5827.2 5827.5 5829.1 5842.4 5844.3 5784.2 5800.5 5815.8 5838.4 5857.8 5571.9 5688.6 5696.7 5698.3 5709.3 5814.7 5871.6 5883.7 5886.7 5890.8 5739.8 5740.2 5746.0 5747.0 5748.3 12:42:18 INFO - 2015-12-10 12:42:18,079 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,079 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12103.22 Median:22104.95 stddev:13571.16 (61.4%) stddev-sans-first:13582.18 12:42:18 INFO - 2015-12-10 12:42:18,079 DEBUG : BROWSER_OUTPUT: Values: 693.6 732.4 740.7 745.9 753.6 10258.7 10392.6 11584.8 11802.3 11826.2 11833.2 12123.2 12274.2 12430.4 12439.6 803.5 823.3 823.8 823.8 827.6 819.2 826.6 830.4 830.8 832.4 855.2 859.5 863.8 866.1 867.4 854.9 855.9 859.4 859.7 860.3 630.3 630.9 632.8 633.2 637.4 11578.7 12645.0 12855.0 12954.7 13000.5 10470.5 11531.9 11806.2 11966.6 12475.2 9315.4 9433.5 9484.7 9567.6 9617.7 3184.6 3211.1 3253.2 3269.7 3309.1 37416.3 38101.2 38245.6 38369.4 38398.2 8268.9 8446.4 8522.1 8577.7 8601.4 17265.7 17335.7 17337.2 17355.7 17356.0 6573.5 6593.6 6677.4 6687.8 6691.5 3695.2 3695.6 3707.5 3732.1 3738.5 2746.5 2771.9 2784.9 2789.7 2791.1 479.0 488.9 497.6 502.2 503.6 427.8 444.1 477.3 505.9 596.5 7327.0 7672.0 7784.1 7884.3 8031.3 404.0 483.4 486.5 489.5 507.0 3317.0 3337.0 3338.3 3372.6 3387.4 46352.8 49587.5 49636.5 49677.8 49967.3 42224.6 43314.6 43318.8 43385.1 43765.8 38811.2 39786.8 39893.4 40259.0 40282.0 7357.0 7489.3 7542.9 7569.2 7604.5 32022.7 32157.5 32464.0 33955.2 33984.7 38467.8 38913.6 39489.6 39638.4 39726.4 10067.5 10133.7 10235.0 10331.5 10400.6 10699.7 10792.5 10905.5 10956.8 11025.1 10613.0 10667.2 10692.6 10784.2 10838.7 9413.7 9630.5 9786.6 9984.5 10520.5 9766.6 9958.8 10098.1 10278.4 10739.2 8527.5 8548.0 9010.0 9275.8 9397.8 8356.6 8372.2 8459.9 8467.7 8608.9 12:42:18 INFO - 2015-12-10 12:42:18,079 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:42:18 INFO - 2015-12-10 12:42:18,079 DEBUG : BROWSER_OUTPUT: 12:42:18 INFO - 2015-12-10 12:42:18,812 INFO : Browser exited with error code: 0 12:42:18 INFO - 2015-12-10 12:42:18,833 INFO : Completed test dromaeo_css (00:18:34) 12:42:18 INFO - 2015-12-10 12:42:18,833 INFO : Starting test kraken 12:42:18 INFO - 2015-12-10 12:42:18,833 DEBUG : operating with platform_type : linux_ 12:42:18 INFO - 2015-12-10 12:42:18,833 INFO : Initialising browser for kraken test... 12:42:18 INFO - 2015-12-10 12:42:18,839 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprSF3TC/profile http://localhost:39092/getInfo.html 12:42:23 INFO - 2015-12-10 12:42:23,277 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:42:23 INFO - 2015-12-10 12:42:23,277 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:42:23 INFO - 2015-12-10 12:42:23,277 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:42:23 INFO - 2015-12-10 12:42:23,277 DEBUG : BROWSER_OUTPUT: __metrics 12:42:23 INFO - 2015-12-10 12:42:23,419 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] 12:42:24 INFO - 2015-12-10 12:42:24,304 INFO : Browser initialized. 12:42:24 INFO - 2015-12-10 12:42:24,304 INFO : Running cycle 1/1 for kraken test... 12:42:24 INFO - 2015-12-10 12:42:24,305 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprSF3TC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:42:36 INFO - 2015-12-10 12:42:36,419 DEBUG : BROWSER_OUTPUT: RSS: Main: 142987264 12:42:36 INFO - 2015-12-10 12:42:36,419 DEBUG : BROWSER_OUTPUT: 12:42:39 INFO - 2015-12-10 12:42:39,000 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39092/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:39 INFO - 2015-12-10 12:42:39,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 142860288 12:42:39 INFO - 2015-12-10 12:42:39,622 DEBUG : BROWSER_OUTPUT: 12:42:42 INFO - 2015-12-10 12:42:42,344 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39092/tests/kraken/driver.html?testName=audio-dft) 12:42:42 INFO - 2015-12-10 12:42:42,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 143458304 12:42:42 INFO - 2015-12-10 12:42:42,938 DEBUG : BROWSER_OUTPUT: 12:42:45 INFO - 2015-12-10 12:42:45,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39092/tests/kraken/driver.html?testName=audio-fft) 12:42:46 INFO - 2015-12-10 12:42:46,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 138760192 12:42:46 INFO - 2015-12-10 12:42:46,409 DEBUG : BROWSER_OUTPUT: 12:42:48 INFO - 2015-12-10 12:42:48,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39092/tests/kraken/driver.html?testName=audio-oscillator) 12:42:49 INFO - 2015-12-10 12:42:49,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 138817536 12:42:49 INFO - 2015-12-10 12:42:49,117 DEBUG : BROWSER_OUTPUT: 12:42:51 INFO - 2015-12-10 12:42:51,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39092/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:51 INFO - 2015-12-10 12:42:51,952 DEBUG : BROWSER_OUTPUT: RSS: Main: 140312576 12:42:51 INFO - 2015-12-10 12:42:51,952 DEBUG : BROWSER_OUTPUT: 12:42:55 INFO - 2015-12-10 12:42:55,032 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39092/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:55 INFO - 2015-12-10 12:42:55,633 DEBUG : BROWSER_OUTPUT: RSS: Main: 139395072 12:42:55 INFO - 2015-12-10 12:42:55,633 DEBUG : BROWSER_OUTPUT: 12:42:59 INFO - 2015-12-10 12:42:59,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39092/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:59 INFO - 2015-12-10 12:42:59,671 DEBUG : BROWSER_OUTPUT: RSS: Main: 140185600 12:42:59 INFO - 2015-12-10 12:42:59,671 DEBUG : BROWSER_OUTPUT: 12:43:02 INFO - 2015-12-10 12:43:02,570 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39092/tests/kraken/driver.html?testName=json-parse-financial) 12:43:03 INFO - 2015-12-10 12:43:03,156 DEBUG : BROWSER_OUTPUT: RSS: Main: 139681792 12:43:03 INFO - 2015-12-10 12:43:03,156 DEBUG : BROWSER_OUTPUT: 12:43:04 INFO - 2015-12-10 12:43:04,946 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39092/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:05 INFO - 2015-12-10 12:43:05,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 141615104 12:43:05 INFO - 2015-12-10 12:43:05,532 DEBUG : BROWSER_OUTPUT: 12:43:07 INFO - 2015-12-10 12:43:07,428 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:08 INFO - 2015-12-10 12:43:08,050 DEBUG : BROWSER_OUTPUT: RSS: Main: 141950976 12:43:08 INFO - 2015-12-10 12:43:08,050 DEBUG : BROWSER_OUTPUT: 12:43:10 INFO - 2015-12-10 12:43:10,235 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:10 INFO - 2015-12-10 12:43:10,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 141770752 12:43:10 INFO - 2015-12-10 12:43:10,824 DEBUG : BROWSER_OUTPUT: 12:43:13 INFO - 2015-12-10 12:43:13,406 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:14 INFO - 2015-12-10 12:43:14,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 141983744 12:43:14 INFO - 2015-12-10 12:43:14,008 DEBUG : BROWSER_OUTPUT: 12:43:16 INFO - 2015-12-10 12:43:16,632 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:17 INFO - 2015-12-10 12:43:17,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 141414400 12:43:17 INFO - 2015-12-10 12:43:17,234 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,007 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39092/tests/kraken/driver.html?testName=ai-astar) 12:43:19 INFO - 2015-12-10 12:43:19,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 141164544 12:43:19 INFO - 2015-12-10 12:43:19,258 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,258 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:43:19 INFO - 2015-12-10 12:43:19,259 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:43:19 INFO - 2015-12-10 12:43:19,259 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |0;ai-astar;116;108;116;112;114;125;113;111;114;113 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;129;157;171;139;150;156;135;167;134 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;164;165;162;164;167;166;161;163;164 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;73;74;78;72;152;72;72;72 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;129;95;95;96;132;95;96;141 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;109;111;110;110;111;110 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;147;147;149;148;147;147;150;148 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;97;97;95;96;97;96;97;97;96 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;75;73;73;76;75;74;75;77;72 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;67;69;69;70;68;67;70;69 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;84;85;85;93;85;85;84;85;95 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;121;123;134;131;123;131;134;139;124 12:43:19 INFO - 2015-12-10 12:43:19,260 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;150;148;150;149;151;148;136;145;139 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;72;73;71;70;70;69;70;69;69 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,784 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: __startTimestamp1449780199257__endTimestamp 12:43:19 INFO - 2015-12-10 12:43:19,261 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,264 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:43:19 INFO - 2015-12-10 12:43:19,264 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:43:19 INFO - 2015-12-10 12:43:19,264 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,264 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:4.47 (3.9%) stddev-sans-first:4.69 12:43:19 INFO - 2015-12-10 12:43:19,264 DEBUG : BROWSER_OUTPUT: Values: 116.0 108.0 116.0 112.0 114.0 125.0 113.0 111.0 114.0 113.0 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.20 Median:155.00 stddev:14.41 (9.3%) stddev-sans-first:15.17 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: Values: 154.0 129.0 157.0 171.0 139.0 150.0 156.0 135.0 167.0 134.0 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:1.87 (1.1%) stddev-sans-first:1.87 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: Values: 162.0 164.0 165.0 162.0 164.0 167.0 166.0 161.0 163.0 164.0 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.00 Median:72.50 stddev:25.02 (34.5%) stddev-sans-first:26.32 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 73.0 74.0 78.0 72.0 152.0 72.0 72.0 72.0 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:95.00 stddev:19.01 (20.0%) stddev-sans-first:19.59 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 129.0 95.0 95.0 96.0 132.0 95.0 96.0 141.0 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 109.0 111.0 110.0 110.0 111.0 110.0 12:43:19 INFO - 2015-12-10 12:43:19,265 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 147.0 147.0 149.0 148.0 147.0 147.0 150.0 148.0 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.50 Median:97.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: Values: 97.0 97.0 97.0 95.0 96.0 97.0 96.0 97.0 97.0 96.0 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,266 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.40 Median:75.00 stddev:1.51 (2.0%) stddev-sans-first:1.59 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: Values: 74.0 75.0 73.0 73.0 76.0 75.0 74.0 75.0 77.0 72.0 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:1.07 (1.6%) stddev-sans-first:1.12 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 67.0 69.0 69.0 70.0 68.0 67.0 70.0 69.0 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:85.00 stddev:4.01 (4.7%) stddev-sans-first:4.15 12:43:19 INFO - 2015-12-10 12:43:19,267 DEBUG : BROWSER_OUTPUT: Values: 84.0 84.0 85.0 85.0 93.0 85.0 85.0 84.0 85.0 95.0 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.60 Median:131.00 stddev:6.02 (4.6%) stddev-sans-first:6.31 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: Values: 126.0 121.0 123.0 134.0 131.0 123.0 131.0 134.0 139.0 124.0 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.30 Median:148.50 stddev:4.99 (3.4%) stddev-sans-first:5.29 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: Values: 147.0 150.0 148.0 150.0 149.0 151.0 148.0 136.0 145.0 139.0 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: 12:43:19 INFO - 2015-12-10 12:43:19,268 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:70.50 stddev:1.35 (1.9%) stddev-sans-first:1.41 12:43:19 INFO - 2015-12-10 12:43:19,269 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 73.0 71.0 70.0 70.0 69.0 70.0 69.0 69.0 12:43:19 INFO - 2015-12-10 12:43:19,269 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:43:19 INFO - 2015-12-10 12:43:19,269 DEBUG : BROWSER_OUTPUT: 12:43:20 INFO - 2015-12-10 12:43:20,160 INFO : Browser exited with error code: 0 12:43:20 INFO - 2015-12-10 12:43:20,211 INFO : Completed test kraken (00:01:01) 12:43:20 INFO - 2015-12-10 12:43:20,211 INFO : Starting test v8_7 12:43:20 INFO - 2015-12-10 12:43:20,211 DEBUG : operating with platform_type : linux_ 12:43:20 INFO - 2015-12-10 12:43:20,211 INFO : Initialising browser for v8_7 test... 12:43:20 INFO - 2015-12-10 12:43:20,217 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5LxirG/profile http://localhost:39092/getInfo.html 12:43:24 INFO - 2015-12-10 12:43:24,708 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:43:24 INFO - 2015-12-10 12:43:24,708 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:43:24 INFO - 2015-12-10 12:43:24,708 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:43:24 INFO - 2015-12-10 12:43:24,708 DEBUG : BROWSER_OUTPUT: __metrics 12:43:24 INFO - 2015-12-10 12:43:24,845 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] 12:43:25 INFO - 2015-12-10 12:43:25,756 INFO : Browser initialized. 12:43:25 INFO - 2015-12-10 12:43:25,756 INFO : Running cycle 1/1 for v8_7 test... 12:43:25 INFO - 2015-12-10 12:43:25,756 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5LxirG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:43:37 INFO - 2015-12-10 12:43:37,837 DEBUG : BROWSER_OUTPUT: RSS: Main: 142581760 12:43:37 INFO - 2015-12-10 12:43:37,838 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,241 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39092/tests/v8_7/run.html (next: http://localhost:39092/tests/v8_7/run.html) 12:43:59 INFO - 2015-12-10 12:43:59,485 DEBUG : BROWSER_OUTPUT: RSS: Main: 137658368 12:43:59 INFO - 2015-12-10 12:43:59,485 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,486 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: |0;Richards;164.52780519907864 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.34468166955185 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.79854504756577 12:43:59 INFO - 2015-12-10 12:43:59,487 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1075.268817204301 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: |5;Earley;696.8641114982578 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: |6;Boyer;12243.90243902439 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: |7;RegExp;26500 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: |8;Splay;610.1281269066504 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 12:43:59 INFO - 2015-12-10 12:43:59,488 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:43:59 INFO - 2015-12-10 12:43:59,489 DEBUG : BROWSER_OUTPUT: __startTimestamp1449780239486__endTimestamp 12:43:59 INFO - 2015-12-10 12:43:59,489 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,489 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:43:59 INFO - 2015-12-10 12:43:59,489 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:43:59 INFO - 2015-12-10 12:43:59,489 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,490 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.53 Median:164.53 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,490 DEBUG : BROWSER_OUTPUT: Values: 164.5 12:43:59 INFO - 2015-12-10 12:43:59,490 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,490 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.34 Median:192.34 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,491 DEBUG : BROWSER_OUTPUT: Values: 192.3 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.80 Median:279.80 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: Values: 279.8 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: Values: 5133.3 12:43:59 INFO - 2015-12-10 12:43:59,492 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1075.27 Median:1075.27 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: Values: 1075.3 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:696.86 Median:696.86 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: Values: 696.9 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,493 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12243.90 Median:12243.90 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: Values: 12243.9 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26500.00 Median:26500.00 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: Values: 26500.0 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:610.13 Median:610.13 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,494 DEBUG : BROWSER_OUTPUT: Values: 610.1 12:43:59 INFO - 2015-12-10 12:43:59,495 DEBUG : BROWSER_OUTPUT: 12:43:59 INFO - 2015-12-10 12:43:59,495 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 12:43:59 INFO - 2015-12-10 12:43:59,495 DEBUG : BROWSER_OUTPUT: Values: 4835.7 12:43:59 INFO - 2015-12-10 12:43:59,495 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:43:59 INFO - 2015-12-10 12:43:59,495 DEBUG : BROWSER_OUTPUT: 12:44:00 INFO - 2015-12-10 12:44:00,396 INFO : Browser exited with error code: 0 12:44:00 INFO - 2015-12-10 12:44:00,410 INFO : Completed test v8_7 (00:00:40) 12:44:00 INFO - 2015-12-10 12:44:00,495 INFO : Completed test suite (00:20:15) 12:44:00 INFO - 2015-12-10 12:44:00,495 DEBUG : Working with test: dromaeo_css 12:44:00 INFO - 2015-12-10 12:44:00,495 DEBUG : Generating results file: dromaeo_css 12:44:00 INFO - 2015-12-10 12:44:00,496 DEBUG : Working with test: kraken 12:44:00 INFO - 2015-12-10 12:44:00,496 DEBUG : Generating results file: kraken 12:44:00 INFO - 2015-12-10 12:44:00,497 INFO : javascript benchmark 12:44:00 INFO - 2015-12-10 12:44:00,497 DEBUG : Working with test: v8_7 12:44:00 INFO - 2015-12-10 12:44:00,497 DEBUG : Generating results file: v8_7 12:44:00 INFO - 2015-12-10 12:44:00,497 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:44:00 INFO - 2015-12-10 12:44:00,546 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 12:44:00 INFO - 2015-12-10 12:44:00,546 DEBUG : process_Request line: dromaeo_css 6406.52 graph.html#tests=[[72,132,43]] 12:44:00 INFO - 2015-12-10 12:44:00,546 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:44:00 INFO - 2015-12-10 12:44:00,573 DEBUG : process_Request line: kraken 1544.20 graph.html#tests=[[232,132,43]] 12:44:00 INFO - 2015-12-10 12:44:00,574 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:44:00 INFO - 2015-12-10 12:44:00,602 DEBUG : process_Request line: v8_7 21290.23 graph.html#tests=[[230,132,43]] 12:44:00 INFO - 2015-12-10 12:44:00,603 INFO : javascript benchmark 12:44:00 INFO - 2015-12-10 12:44:00,607 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2610.5170543449462, 2649.9470010599803, 2688.722856643095, 2705.675318961724, 2714.063648041425, 4513.751743654106, 4701.2242979908315, 4715.575598196162, 4727.172744769665, 4749.501302363254, 4667.533246675331, 4673.813047478106, 4682.016776476944, 4752.857414277569, 4766.9274413257, 4335.588119128685, 4391.5827996340295, 4434.889127771799, 4442.422485076935, 4450.2434586120435, 4369.060651959828, 4400.295952647561, 4423.225935461298, 4426.402435671192, 4468.240399132155, 4223.66210703143, 4232.3016702244095, 4330.610245077926, 4334.176506463762, 4371.934420983672, 4341.41390912226, 4344.6524278058, 4347.347897815322, 4348.173846969065, 4384.846530371422, 4339.262325404688, 4345.608895199415, 4348.478182618074, 4356.629686476654, 4401.383806267125, 4441.800118994653, 4515.977420112911, 4554.0, 4650.627949764044, 4670.0, 4944.134776414149, 4969.254611808222, 4993.101241776479, 4998.875927359906, 5036.597072234213, 5042.1428357179, 5043.873903152414, 5043.899122017576, 5044.924326135111, 5090.490950904917, 4532.909341813178, 4647.256438969816, 4655.255159174516, 4658.277966915066, 4661.696989695659, 5117.641765076483, 5182.663126896739, 5190.532852043334, 5196.428392876781, 5207.062728708869, 4969.304297398367, 4979.601631869442, 4993.101241776515, 4996.7001979881325, 5042.243663450512, 4893.535114164149, 4950.925736113961, 4994.2508623706735, 5063.291139240509, 5065.8733531662, 4997.075541024922, 4998.075356059141, 5000.349954505964, 5007.524285192901, 5051.924221136685, 4969.552740253394, 4987.875303117451, 4989.351384320015, 5002.799888004512, 5047.848564543069, 4050.18996200761, 4107.0, 4139.8965025874595, 4163.417121602978, 4203.243416185116, 3630.3465376232, 3635.690966267844, 3645.1251699592435, 3649.708023358125, 3679.025058359537, 4175.874723758292, 4318.78406079701, 4325.41606883073, 4338.848140315074, 4350.695451318377, 4937.308776771255, 4956.107900577858, 4958.355413796184, 4968.304437378698, 5096.9490305097215, 4290.742555446621, 4301.182775272689, 4320.719153255029, 4356.803943822536, 4363.5418281080665, 4418.779061046935, 4456.554344565518, 4466.620337271368, 4470.821167153277, 4500.3249512573375, 4547.113312903952, 4663.160631086369, 4719.197736384892, 4751.0, 4845.3216549682365, 4934.9753251233515, 5028.698278103326, 5040.0, 5040.924386134284, 5086.847394578169, 4596.655250856133, 4770.833020844254, 4780.450248221499, 4781.976090119527, 4826.758662066953, 4885.853424397132, 4913.336699545515, 4973.378327709036, 4976.427710813303, 5021.6484846061885, 5010.574101201439, 5035.924461133012, 5048.091343558195, 5055.34280543527, 5079.796808127633, 4718.268668356411, 4841.273808928689, 4869.293952376815, 4889.997550502209, 4913.901721965469, 4863.076015557032, 4896.167651499182, 4927.408710954708, 4942.950570494249, 4963.429205641253, 4744.715317080986, 4749.430068391677, 4786.090642778, 4810.880571825728, 4851.175300198904, 4716.764161791965, 4724.740139292235, 4819.421669399694, 4940.901181976412, 4983.053219888209, 4725.527447255247, 4774.092922344744, 4783.995360974234, 4813.470518242921, 4821.300911367762], "unit": "ms", "name": "dojo.html", "value": 4600.981356344631}, {"lowerIsBetter": false, "replicates": [563.6533531877893, 567.9067794495596, 571.1033677126907, 572.2732130194653, 583.7139801497262, 26983.460330793398, 27238.727612723866, 35152.82423587885, 35954.64045359546, 36024.09939751508, 39517.209655806866, 40022.0, 40145.59854401455, 40153.59846401543, 40358.39462408059, 7867.0, 7989.480683755566, 8005.919940800605, 8012.479188852735, 8015.519068855858, 7767.495112817677, 7796.610169491502, 7894.131645518988, 7897.091834439056, 7910.841783164333, 7822.608869556528, 7881.566513841736, 7914.841703165933, 7940.1265860755275, 7942.801429964239, 7810.453268271223, 7812.960935195344, 7825.178356272595, 7870.016247969004, 7902.0, 7767.184445633184, 7850.175731548163, 7904.802379940547, 7907.209279072104, 7908.248716371935, 36548.0, 36623.4506482403, 36984.26031479382, 37033.444498332545, 37192.256154877025, 2863.2984918694847, 2871.913842584726, 2879.3521457672064, 3142.1516190628404, 3445.3453843769853, 27278.90884364623, 27665.0, 27913.0, 28008.719912800814, 28036.29909252285, 3396.932061358759, 3530.38218311797, 3563.3051554946796, 3568.091915637959, 3577.2130131371055, 214490.0, 214558.0, 215010.0, 215089.0, 215443.0, 51652.22521662178, 53249.46750532523, 56641.150382744294, 56919.43080569224, 61660.07509887355, 55142.0, 55236.0, 55339.16991245135, 55679.721601391735, 55697.0, 13544.729105417837, 13791.793123103162, 13792.793108103386, 13800.102993305403, 13842.723145537235, 10093.848592271122, 10144.594216231268, 10152.543135558917, 10239.692809215736, 10240.463930410499, 19246.4226073218, 19319.0, 19363.4190974271, 19571.608567828847, 19667.803321966883, 7605.733799317108, 7819.077831647427, 7938.444308898437, 7969.481983671043, 8030.277275045274, 812.8821320908553, 817.979550511242, 836.3768992100923, 868.2229404682773, 897.9281657467387, 23341.883290583777, 24516.264512064667, 24535.0, 24627.630585541232, 25050.87271072806, 908.4549270437684, 962.1148543340144, 963.2342287881152, 998.1366118307611, 1070.9571617135227, 13202.33988300581, 13343.66640833987, 13419.86580134186, 13467.057305988486, 15295.694086118216, 318986.0, 321400.0, 324645.0, 324747.0, 325336.0, 48809.267860982116, 49253.75373123112, 49401.25898111531, 49449.75275123601, 49544.75227623911, 1076.5370890516997, 1083.2254937719567, 1090.2095980414135, 1180.557291015869, 1190.5654436130924, 26625.33436664099, 27000.0, 27181.320466988484, 27369.0, 27545.173644790688, 39636.405453917636, 40272.1945561093, 40335.0, 40345.39481907833, 40411.7979410101, 247223.0, 248499.0, 248827.0, 249086.0, 249584.0, 29535.409291813612, 41792.16415671731, 42552.0, 43132.78433607812, 43169.13661726811, 44793.10413791771, 45753.0, 46029.539704602525, 46405.53594464012, 46484.7675761619, 32541.396688265355, 32713.34573308568, 33091.66908330982, 33681.621389219246, 33889.15277117993, 33485.33029339351, 33616.49575256324, 33652.3269534603, 33805.66194338025, 33816.32367352688, 32528.18679532936, 32696.673033269362, 33010.83494582512, 33088.50367244445, 33096.338073237916, 27849.721502785524, 29533.556996645497, 30273.0, 30290.0, 30319.545206821473, 23634.763652363257, 23819.98077909425, 23862.16482423107, 23943.281701548283, 24074.0], "unit": "ms", "name": "ext.html", "value": 17071.742505934064}, {"lowerIsBetter": false, "replicates": [342.6436506033725, 343.6580602300711, 346.1191268222374, 347.44929287079987, 347.6610304952671, 10282.228832837529, 10734.248602597801, 15071.547853564396, 15266.465673701423, 16904.239309231085, 16053.438129665465, 16091.356345746186, 16246.106464144468, 16337.83662163378, 16723.581910452245, 5506.174073888918, 6002.669853158074, 6187.07193920911, 6197.380261973812, 6211.3167551569295, 6123.387661233885, 6341.365863413668, 6416.935830641703, 6432.77485288015, 6432.839179020539, 5987.760489580412, 6169.784057557987, 6197.721102550396, 6234.407731265545, 6319.6208227506495, 6179.938200618004, 6248.500119990413, 6286.622802631834, 6605.405513503808, 6621.668916554201, 6289.465395441393, 6345.587536810139, 6346.540295731961, 6347.682615869234, 6354.0, 19269.710954335555, 20082.09630566628, 20493.18027278907, 20580.0, 20677.58644827095, 576.4926864359337, 577.5812535911473, 577.6418620455303, 1882.129752128002, 1887.3582981786258, 13598.0, 13615.31923403826, 13827.170369777745, 14022.088564243393, 14238.145711257357, 1835.9541011474687, 1867.5526466988058, 1879.8120187981228, 1884.5853912139307, 1909.7517322748092, 61533.38466615366, 63759.04361434582, 64534.0, 64582.3541764581, 65328.3467165327, 14822.0, 15353.155576443403, 15498.225088745517, 15534.844651553565, 15596.454124105601, 22900.4274893129, 23115.0, 23305.766942330698, 23429.297121086394, 23586.292411227692, 8394.24451799341, 8414.78963025917, 8494.447860889022, 8517.190866867637, 8567.357448191411, 7120.6439678015895, 7147.2138064812825, 7193.208747037822, 7226.566406015656, 7239.493235473572, 7520.511166774142, 7537.8115547111765, 7608.695652173962, 7614.695412183451, 7742.574158421228, 1720.5698575356162, 1784.9910750446165, 2993.2965753048015, 3003.5945147404823, 3137.6862313768443, 769.253823790922, 825.5542007316102, 826.1573195340839, 833.7832163637498, 921.239977018962, 14802.851971480148, 14849.628759281104, 14977.550673479813, 14978.775318370233, 15154.545363638888, 815.8980127484065, 817.824167803925, 827.6234313387395, 832.6877420967137, 848.5163456829548, 8827.69103081402, 9072.773180670403, 9082.18260356558, 9136.451812891248, 9178.724638260863, 475031.0, 482233.0, 486745.0, 492048.0, 492382.0, 69862.0, 71484.2851571488, 71769.0, 72270.27729722635, 73926.0, 1010.7372083258406, 1013.852991316255, 1016.4866742295094, 1024.3853687787416, 1027.019196667181, 13698.0, 14250.928745356206, 14276.357563909856, 14322.283885805877, 14526.273686315853, 32513.0, 32701.0, 33473.83263083669, 34360.0, 34567.827160864035, 39964.800175998935, 40230.19539609133, 40428.19143617052, 40670.186596268504, 40993.59006409898, 8619.396642234984, 8641.274583022156, 8658.956705216433, 8667.739967800977, 8731.825363492822, 10273.84589231177, 10295.07344338983, 11839.58561450345, 15411.460598878986, 16727.74908376351, 13230.338483076, 13268.53560125391, 13374.197548147142, 13706.177629342272, 13882.583522494342, 12832.615021549369, 13215.339233038118, 13225.404856781275, 13681.79477307861, 13858.86141138616, 13988.440462381392, 14100.153990760587, 14644.926775366055, 14792.778108328168, 14878.85121148818, 8692.695755648521, 8801.98777140637, 8952.462852228888, 8992.055834137322, 9001.414908030956, 13334.533291334757, 13376.197428154343, 13542.526011589547, 13911.234882081179, 14002.229877356844], "unit": "ms", "name": "jquery.html", "value": 9036.270461060274}, {"lowerIsBetter": false, "replicates": [1490.9861294319858, 1596.712591733488, 1631.1681042668242, 1650.1171873047915, 1669.0319614623515, 6391.10524526566, 6657.0, 6864.553804002736, 6873.793786186417, 6969.337912898279, 6959.547629404098, 7004.089468369105, 7070.717171313141, 7071.398931090864, 7078.256783037771, 2072.730545029148, 2114.450242936832, 2121.501447159924, 2121.533262682215, 2122.320857325649, 2053.640612892745, 2055.6916462530667, 2060.3509894383296, 2104.8000439958178, 2113.968290475644, 1756.5432987423235, 1781.732740088984, 2054.075665950328, 2059.5983783162287, 2102.5899949509853, 2054.5685406064667, 2066.5763518478752, 2067.4211220858183, 2068.7931206879343, 2070.647989841723, 2030.736004319429, 2041.2549419461939, 2090.9477263068393, 2095.4656562576597, 2105.799949004842, 6832.965835170842, 6877.724891004354, 6890.37986581205, 6892.724291028353, 6898.241193468714, 3985.0, 4013.4180543821276, 4032.1532478179615, 4032.6572241359518, 4040.5555388907196, 2621.829581077243, 2637.815352925296, 2655.946881062368, 2728.5361488546996, 2754.807163498556, 876.1282523888708, 890.1098901098901, 890.9020007799137, 909.3134683314122, 916.5692124701379, 9156.496392698398, 9269.72190834276, 9288.582013809395, 9305.627774888997, 9312.394694344846, 3708.517892673935, 3770.490983717218, 3805.771653700787, 3833.4824798651825, 3833.884983450501, 7287.089113860768, 7295.8540829184185, 7303.269673032761, 7320.304571065847, 7335.0, 4112.547619761824, 4116.794160291974, 4134.400511925754, 4153.875383738493, 4155.397467367215, 3782.508273924427, 3807.638274363931, 3821.082940094357, 3826.598207188261, 3844.1927195289304, 3147.763917706181, 3169.952450713241, 3186.824724640167, 3198.856051477689, 3210.181403742031, 933.5658918602883, 939.6053657463882, 942.2556180617372, 942.4157022646003, 948.5209968965628, 803.9477433966773, 828.5360198288978, 909.6361455417886, 954.708813811794, 1170.8829117088223, 6346.873062538723, 6640.535162538673, 6664.033715111282, 6664.400203981665, 6672.165979252593, 920.089111779347, 923.3398120343986, 1032.9018743219383, 1038.4755698372426, 1049.034887903131, 4899.608031357483, 4936.950630493721, 5009.298698182258, 5016.498350164954, 5016.648834581617, 8113.945187125636, 8356.916430835736, 8370.3303735701, 8393.328533717287, 8424.368172387096, 8127.878081828777, 8213.34293256538, 8239.052508961546, 8252.628631711588, 8305.37709671777, 6510.641914694737, 6528.804135875931, 6590.7693230736695, 6609.074729537869, 6618.4705223582, 6303.684815759286, 6317.052442133717, 6336.968315158395, 6339.270983836734, 6341.27075386318, 7190.1371835377995, 7310.159331676925, 7333.779986600411, 7352.705891764484, 7381.557106573623, 7343.302386273295, 7585.6965721372735, 7625.351845093007, 7647.0, 7647.388208943383, 5787.102999035156, 5798.536117110705, 5852.678102704393, 6037.849053773691, 6047.909281360871, 6371.522135839923, 6438.034294855622, 6464.4828413725845, 6474.870502589828, 6500.869982600416, 6225.782097626561, 6259.96870015647, 6338.144350512806, 6351.110844481684, 6477.967610161919, 5648.661080335193, 5942.405759424196, 6137.570370074052, 6194.132821404917, 6233.283172435046, 6214.9689251553455, 6232.376762323732, 6237.75048998035, 6283.905741413791, 6313.147725057059, 5618.4662457066515, 5712.11462223356, 5713.371529131709, 5718.428157184248, 5733.713314334183, 5827.825165245049, 5869.0, 5875.823725288248, 5877.0, 5878.382769809108], "unit": "ms", "name": "mootools.html", "value": 3960.4956394273786}, {"lowerIsBetter": false, "replicates": [305.78594983511533, 311.1536620392532, 318.1156385249009, 319.076274186231, 323.0872784384115, 5388.595855310848, 5518.089515229987, 5538.889222215554, 5610.915836262459, 5615.045442274818, 5341.092014357559, 5487.42382049885, 5499.175123731432, 5521.309836270466, 5522.447755224475, 4288.463942007249, 4307.224699554079, 4317.050248945227, 4326.372675961999, 4330.17726631939, 4281.486221653406, 4290.935635965448, 4305.182015417076, 4312.202242585116, 4326.480822301328, 4047.5547689754103, 4145.2952997990415, 4272.081502476967, 4274.636655884254, 4277.465316835395, 4134.235166494178, 4140.730852494593, 4141.047559061432, 4153.003279212998, 4158.168366326717, 3625.456181572759, 3641.7814931104217, 3642.1076836175043, 3656.926861462756, 3659.414493681025, 5247.344081989751, 5349.3045904032615, 5365.114756065244, 5375.16684913839, 5384.946150538483, 4856.004519073582, 4866.416030076378, 4871.8294859679745, 4873.756312184412, 4883.1698611235815, 5790.13148027795, 5835.737391817378, 5839.036558967765, 5848.64908105515, 5849.210356601847, 3873.360895452247, 3995.4206640037323, 4002.2195671844, 4014.3777714454304, 4026.577209393001, 5994.760209591611, 6079.088136779475, 6079.544034197453, 6091.756329746805, 6107.389261073857, 4060.573639767841, 4065.6950728695465, 4069.593040695966, 4072.4095006223934, 4075.2664520386616, 4128.174365126986, 4133.0, 4135.028268356934, 4139.91720165595, 4141.544430112685, 4691.859244222679, 4781.378420805343, 4782.402199725035, 4788.928166077511, 4791.185498465269, 5771.740271687784, 5778.913316300259, 5783.624064435799, 5785.942140578541, 5808.709564521757, 1459.6569806095558, 1462.1154201708025, 1464.7070585882866, 1464.875485583716, 1465.5383554180398, 988.8714467119229, 990.3265779270022, 993.6373223773415, 1008.5562352564848, 1016.0905989139678, 5035.420926593415, 5040.36995375578, 5058.620603454756, 5073.441921388644, 5076.543111120017, 5978.491828194565, 5979.850503737354, 6032.1856549365275, 6040.154378387029, 6040.305364883095, 5029.4719054498755, 5040.47075057121, 5044.520770526794, 5049.040682270357, 5050.090983622909, 3176.634687010977, 3239.967600324014, 3266.6406695263504, 3267.7059064684295, 3275.901722948315, 5590.608657394025, 5652.067408877571, 5665.178549110356, 5668.716564171775, 5675.0068737971515, 6245.531585131133, 6263.937360626426, 6276.278228003838, 6277.591956522811, 6297.2128483939505, 5641.464060914206, 5671.971640141938, 5692.7722891083895, 5703.771849126071, 5723.084306510938, 6071.119687645355, 6072.605280656743, 6080.422359875804, 6082.391760823883, 6103.298120716175, 5790.710464476843, 5973.611715238496, 5974.522038237017, 5979.22270104901, 5987.760489580368, 5711.8000869969355, 5718.771249150153, 5730.312362516524, 5736.799212027559, 5743.368229494668, 4113.115680128787, 4154.5222299434745, 4164.687648426319, 4166.625013745517, 4168.041350489344, 5913.881722365614, 6005.609635373687, 6057.303410107894, 6086.969565152146, 6097.085437184458, 5827.213326200908, 5827.504662103769, 5829.067349224104, 5842.38654941242, 5844.327902291121, 5784.21913041734, 5800.477956983892, 5815.79644712433, 5838.4453476919625, 5857.765689372548, 5571.860703482445, 5688.63023903445, 5696.658200507983, 5698.2592263005545, 5709.286339207599, 5814.73833677494, 5871.6183448075735, 5883.735231914488, 5886.705664716833, 5890.793822216373, 5739.827805165852, 5740.167675687086, 5745.994450970979, 5747.0, 5748.310202775527], "unit": "ms", "name": "prototype.html", "value": 4347.962777477975}, {"lowerIsBetter": false, "replicates": [693.6185098195992, 732.4177279063144, 740.655595148256, 745.9030326057615, 753.5930597477362, 10258.692239232816, 10392.636257730981, 11584.826227606582, 11802.291862488257, 11826.172167948236, 11833.171677982524, 12123.151379403424, 12274.202176858498, 12430.44063017164, 12439.564615238469, 803.533950308821, 823.3166471828368, 823.7899335669396, 823.8393513264916, 827.5572568675759, 819.1767273889733, 826.6197549127379, 830.4311546590575, 830.8338332333527, 832.3798769916391, 855.170484629908, 859.4928991894781, 863.7797361672765, 866.0603245478663, 867.4405008769371, 854.918782715641, 855.8545047341967, 859.3769517100114, 859.6905114158898, 860.2730692564772, 630.3192552043828, 630.8801327747759, 632.8449529865167, 633.2306247908813, 637.378555907986, 11578.652640420802, 12645.0, 12855.0, 12954.740905181938, 13000.479980800757, 10470.476476176236, 11531.942340288246, 11806.173567850254, 11966.581169659106, 12475.189112707645, 9315.39449935752, 9433.528323583794, 9484.715458536255, 9567.569459374345, 9617.663381781604, 3184.5860038194887, 3211.0848408203783, 3253.170441537393, 3269.738420926344, 3309.1396236978317, 37416.25167496635, 38101.23797524062, 38245.61754382448, 38369.424458633424, 38398.232035359135, 8268.917310826935, 8446.366522510836, 8522.10517895612, 8577.742667719978, 8601.39790214679, 17265.741013884803, 17335.653286934445, 17337.2198251079, 17355.652886942444, 17356.0, 6573.474122070223, 6593.604383736895, 6677.399034086956, 6687.832804179934, 6691.464682825363, 3695.2055308108884, 3695.5750088740137, 3707.5057650821777, 3732.122951106488, 3738.4953031340965, 2746.4507098580366, 2771.8752656130523, 2784.888604455799, 2789.6512935883015, 2791.1486996466274, 478.9712617242977, 488.8948875991679, 497.64169797745564, 502.23660036743945, 503.60215429810773, 427.8032105231566, 444.12064113055686, 477.2962455005936, 505.94181669108525, 596.4780816785313, 7327.0, 7671.961640191764, 7784.143744188134, 7884.329831964347, 8031.317338026216, 403.9838406463709, 483.35230790740576, 486.5353587324097, 489.4615922485238, 507.01132791056847, 3317.038058962874, 3336.966630333714, 3338.332333533303, 3372.5952885653387, 3387.3733359328603, 46352.768236158605, 49587.50412495829, 49636.503634963185, 49677.751611241714, 49967.25049124266, 42224.57775422268, 43314.566854331686, 43318.7834060834, 43385.13229735451, 43765.78117109394, 38811.22377552433, 39786.80106599507, 39893.40159897604, 40259.0, 40281.992950175896, 7357.0, 7489.325960663567, 7542.924570754222, 7569.1673915870315, 7604.5437273763755, 32022.6797732029, 32157.517637234992, 32464.0, 33955.15112122118, 33984.66015339914, 38467.80766096152, 38913.61086389213, 39489.60510394859, 39638.40542391924, 39726.40410393904, 10067.496625168858, 10133.695989120339, 10235.0, 10331.483425828828, 10400.58397664085, 10699.732506687395, 10792.460376981277, 10905.454727263765, 10956.835647465135, 11025.117990560897, 10612.991765782239, 10667.199960002876, 10692.62575809843, 10784.19118566095, 10838.729031774268, 9413.717588472357, 9630.518474076409, 9786.559604817658, 9984.500774961078, 10520.473976301308, 9766.56050477716, 9958.751031223988, 10098.091171794575, 10278.434686092038, 10739.194560407845, 8527.488350698977, 8547.993660792818, 9009.954950225207, 9275.814483710423, 9397.812043759222, 8356.623951922056, 8372.162783721822, 8459.91540084616, 8467.66129354819, 8608.870866937126], "unit": "ms", "name": "yui.html", "value": 5656.333961839902}], "name": "dromaeo_css", "value": 6406.525635300813}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 108.0, 116.0, 112.0, 114.0, 125.0, 113.0, 111.0, 114.0, 113.0], "unit": "ms", "name": "ai-astar", "value": 114.2}, {"lowerIsBetter": true, "replicates": [154.0, 129.0, 157.0, 171.0, 139.0, 150.0, 156.0, 135.0, 167.0, 134.0], "unit": "ms", "name": "audio-beat-detection", "value": 149.2}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 165.0, 162.0, 164.0, 167.0, 166.0, 161.0, 163.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 163.8}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 73.0, 74.0, 78.0, 72.0, 152.0, 72.0, 72.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 81.0}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 129.0, 95.0, 95.0, 96.0, 132.0, 95.0, 96.0, 141.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 109.0, 111.0, 110.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 147.0, 147.0, 149.0, 148.0, 147.0, 147.0, 150.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.9}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 97.0, 95.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.5}, {"lowerIsBetter": true, "replicates": [74.0, 75.0, 73.0, 73.0, 76.0, 75.0, 74.0, 75.0, 77.0, 72.0], "unit": "ms", "name": "json-parse-financial", "value": 74.4}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 67.0, 69.0, 69.0, 70.0, 68.0, 67.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.6}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 85.0, 85.0, 93.0, 85.0, 85.0, 84.0, 85.0, 95.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.5}, {"lowerIsBetter": true, "replicates": [126.0, 121.0, 123.0, 134.0, 131.0, 123.0, 131.0, 134.0, 139.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 128.6}, {"lowerIsBetter": true, "replicates": [147.0, 150.0, 148.0, 150.0, 149.0, 151.0, 148.0, 136.0, 145.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 73.0, 71.0, 70.0, 70.0, 69.0, 70.0, 69.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.4}], "name": "kraken", "value": 1544.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.52780519907864], "unit": "score", "name": "Richards", "value": 214.56555600000002}, {"lowerIsBetter": false, "replicates": [192.34468166955185], "unit": "score", "name": "DeltaBlue", "value": 343.7474820000001}, {"lowerIsBetter": false, "replicates": [279.79854504756577], "unit": "score", "name": "Encrypt", "value": 951.330894}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [1075.268817204301], "unit": "score", "name": "RayTrace", "value": 688.1897699999998}, {"lowerIsBetter": false, "replicates": [696.8641114982578], "unit": "score", "name": "Earley", "value": 956.374405}, {"lowerIsBetter": false, "replicates": [12243.90243902439], "unit": "score", "name": "Boyer", "value": 54.4322370517928}, {"lowerIsBetter": false, "replicates": [26500.0], "unit": "score", "name": "RegExp", "value": 34.37679245283018}, {"lowerIsBetter": false, "replicates": [610.1281269066504], "unit": "score", "name": "Splay", "value": 133.56374900000003}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21290.228528474036}]} 12:44:00 INFO - RETURN: dromaeo_css: 6406.52 12:44:00 INFO - RETURN: kraken: 1544.20 12:44:00 INFO - RETURN: v8_7: 21290.23 12:44:00 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21290.23"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1544.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6406.52"}}} 12:44:00 INFO - Return code: 0 12:44:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:44:00 INFO - # TBPL SUCCESS # 12:44:00 INFO - Running post-action listener: _resource_record_post_action 12:44:00 INFO - Running post-run listener: _resource_record_post_run 12:44:01 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 0; Write bytes: 506466304; Read time: 0; Write time: 456356 12:44:01 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 231481344; Read time: 0; Write time: 267892 12:44:01 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 0; Write bytes: 274092032; Read time: 0; Write time: 188408 12:44:01 INFO - Running post-run listener: _upload_blobber_files 12:44:01 INFO - Blob upload gear active. 12:44:01 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:44:01 INFO - Copying logs to upload dir... 12:44:01 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1272.663298 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-10 12:44:00.902986) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:44:00.906950) ========= 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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009938 build_url: 'https://queue.taskcluster.net/v1/task/pthj8n_QSwq8HEoTOUagyw/artifacts/public/build/firefox-45.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-10 12:44:00.960546) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:44:00.961292) ========= 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=8e92d6ac3b8b0dffa3775d33000001f9-1449778867.374961-28305114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:44:01.014079) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-10 12:44:01.014745) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-10 12:44:01.015527) ========= ========= Total master_lag: 0.69 =========