builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1450274913.29 results: success (0) buildid: 20151216053720 builduid: a51b9edb21ff452b80b697adb3ebd586 revision: f8840e2aec5afdebf63216dc219e103b5d4c391a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.288469) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.289211) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.289711) ========= 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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006402 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.315712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.316054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.339719) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.340044) ========= 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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.394355) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.394947) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.395692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.396285) ========= 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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 06:08:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2015-12-16 06:08:33 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.590235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.590585) ========= 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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015756 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:33.659984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-16 06:08:33.660442) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f8840e2aec5afdebf63216dc219e103b5d4c391a --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 f8840e2aec5afdebf63216dc219e103b5d4c391a --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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 06:08:33,935 truncating revision to first 12 chars 2015-12-16 06:08:33,935 Setting DEBUG logging. 2015-12-16 06:08:33,935 attempt 1/10 2015-12-16 06:08:33,935 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f8840e2aec5a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 06:08:34,434 unpacking tar archive at: fx-team-f8840e2aec5a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.124005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-16 06:08:34.802186) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:34.802785) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:34.812875) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:34.813168) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 06:08:34.813625) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2015-12-16 06:08:34.813953) ========= /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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:08:35 INFO - MultiFileLogger online at 20151216 06:08:35 in /builds/slave/test 06:08:35 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 06:08:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:08:35 INFO - {'append_to_log': False, 06:08:35 INFO - 'base_work_dir': '/builds/slave/test', 06:08:35 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 06:08:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:08:35 INFO - 'branch': 'Fx-Team-Non-PGO', 06:08:35 INFO - 'buildbot_json_path': 'buildprops.json', 06:08:35 INFO - 'config_files': ('talos/linux_config.py',), 06:08:35 INFO - 'default_actions': ('clobber', 06:08:35 INFO - 'read-buildbot-config', 06:08:35 INFO - 'download-and-extract', 06:08:35 INFO - 'populate-webroot', 06:08:35 INFO - 'create-virtualenv', 06:08:35 INFO - 'install', 06:08:35 INFO - 'run-tests'), 06:08:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:08:35 INFO - 'download_minidump_stackwalk': True, 06:08:35 INFO - 'download_symbols': 'ondemand', 06:08:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:08:35 INFO - 'tooltool.py': '/tools/tooltool.py', 06:08:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:08:35 INFO - '/tools/misc-python/virtualenv.py')}, 06:08:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:08:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:08:35 INFO - 'installer_path': 'installer.exe', 06:08:35 INFO - 'log_level': 'info', 06:08:35 INFO - 'log_name': 'talos', 06:08:35 INFO - 'log_to_console': True, 06:08:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:08:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:08:35 INFO - 'opt_config_files': (), 06:08:35 INFO - 'pip_index': False, 06:08:35 INFO - 'sps_profile': False, 06:08:35 INFO - 'sps_profile_interval': 0, 06:08:35 INFO - 'suite': 'dromaeojs-e10s', 06:08:35 INFO - 'system_bits': '64', 06:08:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:08:35 INFO - 'title': 'talos-linux64-ix-003', 06:08:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:08:35 INFO - 'use_talos_json': True, 06:08:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:08:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:08:35 INFO - 'work_dir': 'build'} 06:08:35 INFO - ##### 06:08:35 INFO - ##### Running clobber step. 06:08:35 INFO - ##### 06:08:35 INFO - Running pre-action listener: _resource_record_pre_action 06:08:35 INFO - Running main action method: clobber 06:08:35 INFO - rmtree: /builds/slave/test/build 06:08:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:08:36 INFO - Running post-action listener: _resource_record_post_action 06:08:36 INFO - ##### 06:08:36 INFO - ##### Running read-buildbot-config step. 06:08:36 INFO - ##### 06:08:36 INFO - Running pre-action listener: _resource_record_pre_action 06:08:36 INFO - Running main action method: read_buildbot_config 06:08:36 INFO - Using buildbot properties: 06:08:36 INFO - { 06:08:36 INFO - "properties": { 06:08:36 INFO - "buildnumber": 586, 06:08:36 INFO - "product": "firefox", 06:08:36 INFO - "script_repo_revision": "production", 06:08:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 06:08:36 INFO - "repository": "", 06:08:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 06:08:36 INFO - "buildid": "20151216053720", 06:08:36 INFO - "slavename": "talos-linux64-ix-003", 06:08:36 INFO - "pgo_build": "False", 06:08:36 INFO - "basedir": "/builds/slave/test", 06:08:36 INFO - "project": "", 06:08:36 INFO - "platform": "ubuntu64_hw", 06:08:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:08:36 INFO - "slavebuilddir": "test", 06:08:36 INFO - "scheduler": "tests-fx-team-linux64-talos", 06:08:36 INFO - "branch": "fx-team", 06:08:36 INFO - "repo_path": "integration/fx-team", 06:08:36 INFO - "stage_platform": "linux64", 06:08:36 INFO - "builduid": "a51b9edb21ff452b80b697adb3ebd586", 06:08:36 INFO - "revision": "f8840e2aec5afdebf63216dc219e103b5d4c391a" 06:08:36 INFO - }, 06:08:36 INFO - "sourcestamp": { 06:08:36 INFO - "repository": "", 06:08:36 INFO - "hasPatch": false, 06:08:36 INFO - "project": "", 06:08:36 INFO - "branch": "fx-team-linux64-talos", 06:08:36 INFO - "changes": [ 06:08:36 INFO - { 06:08:36 INFO - "category": null, 06:08:36 INFO - "files": [ 06:08:36 INFO - { 06:08:36 INFO - "url": null, 06:08:36 INFO - "name": "https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 06:08:36 INFO - } 06:08:36 INFO - ], 06:08:36 INFO - "repository": "", 06:08:36 INFO - "rev": "f8840e2aec5afdebf63216dc219e103b5d4c391a", 06:08:36 INFO - "who": "gijskruitbosch@gmail.com", 06:08:36 INFO - "when": 1450274869, 06:08:36 INFO - "number": 7366237, 06:08:36 INFO - "comments": "Bug 1231114 - fix labels of unnamed groups as well as group ordering on the tab migration page, r=mconley", 06:08:36 INFO - "project": "", 06:08:36 INFO - "at": "Wed 16 Dec 2015 06:07:49", 06:08:36 INFO - "branch": "fx-team-linux64-talos", 06:08:36 INFO - "revlink": "", 06:08:36 INFO - "properties": [ 06:08:36 INFO - [ 06:08:36 INFO - "buildid", 06:08:36 INFO - "20151216053720", 06:08:36 INFO - "Change" 06:08:36 INFO - ], 06:08:36 INFO - [ 06:08:36 INFO - "builduid", 06:08:36 INFO - "a51b9edb21ff452b80b697adb3ebd586", 06:08:36 INFO - "Change" 06:08:36 INFO - ], 06:08:36 INFO - [ 06:08:36 INFO - "pgo_build", 06:08:36 INFO - "False", 06:08:36 INFO - "Change" 06:08:36 INFO - ] 06:08:36 INFO - ], 06:08:36 INFO - "revision": "f8840e2aec5afdebf63216dc219e103b5d4c391a" 06:08:36 INFO - } 06:08:36 INFO - ], 06:08:36 INFO - "revision": "f8840e2aec5afdebf63216dc219e103b5d4c391a" 06:08:36 INFO - } 06:08:36 INFO - } 06:08:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 06:08:36 INFO - Running post-action listener: _resource_record_post_action 06:08:36 INFO - ##### 06:08:36 INFO - ##### Running download-and-extract step. 06:08:36 INFO - ##### 06:08:36 INFO - Running pre-action listener: _resource_record_pre_action 06:08:36 INFO - Running main action method: download_and_extract 06:08:36 INFO - mkdir: /builds/slave/test/build/tests 06:08:36 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')]} 06:08:36 INFO - https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:08:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/test_packages.json 06:08:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/test_packages.json 06:08:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 06:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 06:08:46 INFO - Downloaded 1302 bytes. 06:08:46 INFO - Reading from file /builds/slave/test/build/test_packages.json 06:08:46 INFO - Using the following test package requirements: 06:08:46 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 06:08:46 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:08:46 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'jsshell-linux-x86_64.zip'], 06:08:46 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:08:46 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 06:08:46 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:08:46 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:08:46 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:08:46 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 06:08:46 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:08:46 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:08:46 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:08:46 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')]} 06:08:46 INFO - https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:08:54 INFO - Downloaded 21869080 bytes. 06:08:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 06:08:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 06:08:55 INFO - Return code: 0 06:08:55 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:08:55 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')]} 06:08:55 INFO - https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:08:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:08:58 INFO - Downloaded 21869080 bytes. 06:08:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 06:08:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:08:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 06:08:59 INFO - Return code: 0 06:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:08:59 INFO - https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:08:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06:08:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06:08:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:09:00 INFO - Downloaded 11132052 bytes. 06:09:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 06:09:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06:09:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 06:09:00 INFO - Return code: 0 06:09:00 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')]} 06:09:00 INFO - https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:09:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:09:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:09:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:09:01 INFO - Downloaded 57742175 bytes. 06:09:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:09:01 INFO - mkdir: /builds/slave/test/properties 06:09:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:09:01 INFO - Writing to file /builds/slave/test/properties/build_url 06:09:01 INFO - Contents: 06:09:01 INFO - build_url:https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:09:01 INFO - Running post-action listener: _resource_record_post_action 06:09:01 INFO - Running post-action listener: set_extra_try_arguments 06:09:01 INFO - ##### 06:09:01 INFO - ##### Running populate-webroot step. 06:09:01 INFO - ##### 06:09:01 INFO - Running pre-action listener: _resource_record_pre_action 06:09:01 INFO - Running main action method: populate_webroot 06:09:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:09:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:09:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:09:01 INFO - '--tppagecycles', 06:09:01 INFO - '7'], 06:09:01 INFO - 'tests': ['tsvgm']}}, 06:09:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:09:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:09:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:09:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:09:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:09:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:09:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'tests': ['damp', 'tps']}, 06:09:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['damp', 'tps']}, 06:09:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:09:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:09:01 INFO - 'other': {'tests': ['a11yr', 06:09:01 INFO - 'ts_paint', 06:09:01 INFO - 'tpaint', 06:09:01 INFO - 'sessionrestore', 06:09:01 INFO - 'sessionrestore_no_auto_restore']}, 06:09:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['a11yr', 06:09:01 INFO - 'ts_paint', 06:09:01 INFO - 'tpaint', 06:09:01 INFO - 'sessionrestore', 06:09:01 INFO - 'sessionrestore_no_auto_restore']}, 06:09:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['a11yr', 06:09:01 INFO - 'ts_paint', 06:09:01 INFO - 'tpaint', 06:09:01 INFO - 'sessionrestore', 06:09:01 INFO - 'sessionrestore_no_auto_restore']}, 06:09:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['a11yr', 06:09:01 INFO - 'ts_paint', 06:09:01 INFO - 'tpaint', 06:09:01 INFO - 'sessionrestore', 06:09:01 INFO - 'sessionrestore_no_auto_restore']}, 06:09:01 INFO - 'other_l64': {'tests': ['a11yr', 06:09:01 INFO - 'ts_paint', 06:09:01 INFO - 'tpaint', 06:09:01 INFO - 'sessionrestore', 06:09:01 INFO - 'sessionrestore_no_auto_restore']}, 06:09:01 INFO - 'other_nol64': {'tests': ['a11yr', 06:09:01 INFO - 'ts_paint', 06:09:01 INFO - 'tpaint', 06:09:01 INFO - 'sessionrestore', 06:09:01 INFO - 'sessionrestore_no_auto_restore']}, 06:09:01 INFO - 'svgr': {'tests': ['tsvgx', 06:09:01 INFO - 'tsvgr_opacity', 06:09:01 INFO - 'tart', 06:09:01 INFO - 'tscrollx', 06:09:01 INFO - 'cart']}, 06:09:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['tsvgx', 06:09:01 INFO - 'tsvgr_opacity', 06:09:01 INFO - 'tart', 06:09:01 INFO - 'tscrollx', 06:09:01 INFO - 'cart']}, 06:09:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'tests': ['tp5o']}, 06:09:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'talos_options': ['--e10s'], 06:09:01 INFO - 'tests': ['tp5o']}, 06:09:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'talos_options': ['--xperf_path', 06:09:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:09:01 INFO - 'tests': ['tp5n']}, 06:09:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:09:01 INFO - 'talos_options': ['--e10s', 06:09:01 INFO - '--xperf_path', 06:09:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:09:01 INFO - 'tests': ['tp5n']}}, 06:09:01 INFO - 'talos.zip': {'path': '', 06:09:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:09:01 INFO - Running post-action listener: _resource_record_post_action 06:09:01 INFO - ##### 06:09:01 INFO - ##### Running create-virtualenv step. 06:09:01 INFO - ##### 06:09:01 INFO - Running pre-action listener: _resource_record_pre_action 06:09:01 INFO - Running main action method: create_virtualenv 06:09:01 INFO - Creating virtualenv /builds/slave/test/build/venv 06:09:01 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 06:09:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:09:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:09:01 INFO - Using real prefix '/usr' 06:09:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:09:02 INFO - Installing distribute.............................................................................................................................................................................................done. 06:09:04 INFO - Installing pip.................done. 06:09:04 INFO - Return code: 0 06:09:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:04 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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:04 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 06:09:04 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 06:09:04 INFO - Using env: {'DISPLAY': ':0', 06:09:04 INFO - 'HOME': '/home/cltbld', 06:09:04 INFO - 'LANG': 'en_US.UTF-8', 06:09:04 INFO - 'LANGUAGE': 'en_US:en', 06:09:04 INFO - 'LOGNAME': 'cltbld', 06:09:04 INFO - 'MAIL': '/var/mail/cltbld', 06:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:04 INFO - 'MOZ_NO_REMOTE': '1', 06:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:04 INFO - 'NO_EM_RESTART': '1', 06:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:04 INFO - 'PWD': '/builds/slave/test', 06:09:04 INFO - 'SHELL': '/bin/bash', 06:09:04 INFO - 'SHLVL': '1', 06:09:04 INFO - 'TERM': 'linux', 06:09:04 INFO - 'TMOUT': '86400', 06:09:04 INFO - 'USER': 'cltbld', 06:09:04 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:04 INFO - '_': '/tools/buildbot/bin/python'} 06:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:04 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 06:09:04 INFO - Cleaning up... 06:09:04 INFO - Return code: 0 06:09:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:04 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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:04 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 06:09:04 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 06:09:04 INFO - Using env: {'DISPLAY': ':0', 06:09:04 INFO - 'HOME': '/home/cltbld', 06:09:04 INFO - 'LANG': 'en_US.UTF-8', 06:09:04 INFO - 'LANGUAGE': 'en_US:en', 06:09:04 INFO - 'LOGNAME': 'cltbld', 06:09:04 INFO - 'MAIL': '/var/mail/cltbld', 06:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:04 INFO - 'MOZ_NO_REMOTE': '1', 06:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:04 INFO - 'NO_EM_RESTART': '1', 06:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:04 INFO - 'PWD': '/builds/slave/test', 06:09:04 INFO - 'SHELL': '/bin/bash', 06:09:04 INFO - 'SHLVL': '1', 06:09:04 INFO - 'TERM': 'linux', 06:09:04 INFO - 'TMOUT': '86400', 06:09:04 INFO - 'USER': 'cltbld', 06:09:04 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:04 INFO - '_': '/tools/buildbot/bin/python'} 06:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:04 INFO - Downloading/unpacking psutil>=0.7.1 06:09:04 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 06:09:04 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 06:09:04 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 06:09:04 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 06:09:04 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 06:09:04 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 06:09:06 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 06:09:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 06:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:09:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:09:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:09:06 INFO - Installing collected packages: psutil 06:09:06 INFO - Running setup.py install for psutil 06:09:06 INFO - building 'psutil._psutil_linux' extension 06:09:06 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 06:09:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:09:06 INFO - building 'psutil._psutil_posix' extension 06:09:06 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 06:09:07 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 06:09:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:09:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:09:07 INFO - Successfully installed psutil 06:09:07 INFO - Cleaning up... 06:09:07 INFO - Return code: 0 06:09:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:09:07 INFO - Using env: {'DISPLAY': ':0', 06:09:07 INFO - 'HOME': '/home/cltbld', 06:09:07 INFO - 'LANG': 'en_US.UTF-8', 06:09:07 INFO - 'LANGUAGE': 'en_US:en', 06:09:07 INFO - 'LOGNAME': 'cltbld', 06:09:07 INFO - 'MAIL': '/var/mail/cltbld', 06:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:07 INFO - 'MOZ_NO_REMOTE': '1', 06:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:07 INFO - 'NO_EM_RESTART': '1', 06:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:07 INFO - 'PWD': '/builds/slave/test', 06:09:07 INFO - 'SHELL': '/bin/bash', 06:09:07 INFO - 'SHLVL': '1', 06:09:07 INFO - 'TERM': 'linux', 06:09:07 INFO - 'TMOUT': '86400', 06:09:07 INFO - 'USER': 'cltbld', 06:09:07 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:07 INFO - '_': '/tools/buildbot/bin/python'} 06:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:09:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 06:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:09:09 INFO - Installing collected packages: mozsystemmonitor 06:09:09 INFO - Running setup.py install for mozsystemmonitor 06:09:09 INFO - Successfully installed mozsystemmonitor 06:09:09 INFO - Cleaning up... 06:09:09 INFO - Return code: 0 06:09:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:09:09 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')]} 06:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:09 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')]} 06:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:09 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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:09 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 06:09:09 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 06:09:09 INFO - Using env: {'DISPLAY': ':0', 06:09:09 INFO - 'HOME': '/home/cltbld', 06:09:09 INFO - 'LANG': 'en_US.UTF-8', 06:09:09 INFO - 'LANGUAGE': 'en_US:en', 06:09:09 INFO - 'LOGNAME': 'cltbld', 06:09:09 INFO - 'MAIL': '/var/mail/cltbld', 06:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:09 INFO - 'MOZ_NO_REMOTE': '1', 06:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:09 INFO - 'NO_EM_RESTART': '1', 06:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:09 INFO - 'PWD': '/builds/slave/test', 06:09:09 INFO - 'SHELL': '/bin/bash', 06:09:09 INFO - 'SHLVL': '1', 06:09:09 INFO - 'TERM': 'linux', 06:09:09 INFO - 'TMOUT': '86400', 06:09:09 INFO - 'USER': 'cltbld', 06:09:09 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:09 INFO - '_': '/tools/buildbot/bin/python'} 06:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:09 INFO - Downloading/unpacking blobuploader==1.2.4 06:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:11 INFO - Downloading blobuploader-1.2.4.tar.gz 06:09:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 06:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:09:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 06:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:09:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:12 INFO - Downloading docopt-0.6.1.tar.gz 06:09:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 06:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:09:12 INFO - Installing collected packages: blobuploader, requests, docopt 06:09:12 INFO - Running setup.py install for blobuploader 06:09:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:09:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:09:12 INFO - Running setup.py install for requests 06:09:12 INFO - Running setup.py install for docopt 06:09:12 INFO - Successfully installed blobuploader requests docopt 06:09:12 INFO - Cleaning up... 06:09:12 INFO - Return code: 0 06:09:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:12 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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:12 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 06:09:12 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 06:09:12 INFO - Using env: {'DISPLAY': ':0', 06:09:12 INFO - 'HOME': '/home/cltbld', 06:09:12 INFO - 'LANG': 'en_US.UTF-8', 06:09:12 INFO - 'LANGUAGE': 'en_US:en', 06:09:12 INFO - 'LOGNAME': 'cltbld', 06:09:12 INFO - 'MAIL': '/var/mail/cltbld', 06:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:12 INFO - 'MOZ_NO_REMOTE': '1', 06:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:12 INFO - 'NO_EM_RESTART': '1', 06:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:12 INFO - 'PWD': '/builds/slave/test', 06:09:12 INFO - 'SHELL': '/bin/bash', 06:09:12 INFO - 'SHLVL': '1', 06:09:12 INFO - 'TERM': 'linux', 06:09:12 INFO - 'TMOUT': '86400', 06:09:12 INFO - 'USER': 'cltbld', 06:09:12 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:12 INFO - '_': '/tools/buildbot/bin/python'} 06:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:09:13 INFO - Running setup.py (path:/tmp/pip-reIQVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:09:13 INFO - Running setup.py (path:/tmp/pip-ma8p5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:09:13 INFO - Running setup.py (path:/tmp/pip-MPEFUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:09:13 INFO - Running setup.py (path:/tmp/pip-PZkSBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:09:13 INFO - Running setup.py (path:/tmp/pip-obyCVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:09:13 INFO - Running setup.py (path:/tmp/pip-ApEJZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:09:13 INFO - Running setup.py (path:/tmp/pip-kRj0Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:09:13 INFO - Running setup.py (path:/tmp/pip-TdN9dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:09:13 INFO - Running setup.py (path:/tmp/pip-LICaKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:09:13 INFO - Running setup.py (path:/tmp/pip-sxlktd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:09:13 INFO - Running setup.py (path:/tmp/pip-DW4eQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:09:13 INFO - Running setup.py (path:/tmp/pip-nAypRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:09:13 INFO - Running setup.py (path:/tmp/pip-aXz4tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:09:13 INFO - Running setup.py (path:/tmp/pip-BwfLes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:14 INFO - Running setup.py (path:/tmp/pip-pommWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:09:14 INFO - Running setup.py (path:/tmp/pip-Bhiyfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:09:14 INFO - Running setup.py (path:/tmp/pip-W1mnz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:09:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:09:14 INFO - Running setup.py install for manifestparser 06:09:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:09:14 INFO - Running setup.py install for mozcrash 06:09:14 INFO - Running setup.py install for mozdebug 06:09:14 INFO - Running setup.py install for mozdevice 06:09:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:09:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:09:14 INFO - Running setup.py install for mozfile 06:09:14 INFO - Running setup.py install for mozhttpd 06:09:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:09:14 INFO - Running setup.py install for mozinfo 06:09:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:09:14 INFO - Running setup.py install for mozInstall 06:09:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Running setup.py install for mozleak 06:09:15 INFO - Running setup.py install for mozlog 06:09:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Running setup.py install for moznetwork 06:09:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Running setup.py install for mozprocess 06:09:15 INFO - Running setup.py install for mozprofile 06:09:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Running setup.py install for mozrunner 06:09:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:09:15 INFO - Running setup.py install for mozscreenshot 06:09:15 INFO - Running setup.py install for moztest 06:09:15 INFO - Running setup.py install for mozversion 06:09:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:09:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:09:16 INFO - Cleaning up... 06:09:16 INFO - Return code: 0 06:09:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:16 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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:16 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 06:09:16 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 06:09:16 INFO - Using env: {'DISPLAY': ':0', 06:09:16 INFO - 'HOME': '/home/cltbld', 06:09:16 INFO - 'LANG': 'en_US.UTF-8', 06:09:16 INFO - 'LANGUAGE': 'en_US:en', 06:09:16 INFO - 'LOGNAME': 'cltbld', 06:09:16 INFO - 'MAIL': '/var/mail/cltbld', 06:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:16 INFO - 'MOZ_NO_REMOTE': '1', 06:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:16 INFO - 'NO_EM_RESTART': '1', 06:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:16 INFO - 'PWD': '/builds/slave/test', 06:09:16 INFO - 'SHELL': '/bin/bash', 06:09:16 INFO - 'SHLVL': '1', 06:09:16 INFO - 'TERM': 'linux', 06:09:16 INFO - 'TMOUT': '86400', 06:09:16 INFO - 'USER': 'cltbld', 06:09:16 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:16 INFO - '_': '/tools/buildbot/bin/python'} 06:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:09:16 INFO - Running setup.py (path:/tmp/pip-qAxKv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:09:16 INFO - Running setup.py (path:/tmp/pip-HfaVgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:09:16 INFO - Running setup.py (path:/tmp/pip-xtul00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:09:16 INFO - Running setup.py (path:/tmp/pip-M8J3S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:09:16 INFO - Running setup.py (path:/tmp/pip-cWzrpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:09:16 INFO - Running setup.py (path:/tmp/pip-ngOf_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:09:16 INFO - Running setup.py (path:/tmp/pip-6tMTfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:09:16 INFO - Running setup.py (path:/tmp/pip-Id9XOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:09:16 INFO - Running setup.py (path:/tmp/pip-La4a90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:09:16 INFO - Running setup.py (path:/tmp/pip-91zORP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:09:16 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)) 06:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:09:16 INFO - Running setup.py (path:/tmp/pip-PPFtFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:09:17 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)) 06:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:09:17 INFO - Running setup.py (path:/tmp/pip-tVUAbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:09:17 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)) 06:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:09:17 INFO - Running setup.py (path:/tmp/pip-gd0OFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:09:17 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)) 06:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:09:17 INFO - Running setup.py (path:/tmp/pip-zff0ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:09:17 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)) 06:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:17 INFO - Running setup.py (path:/tmp/pip-q4UPfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:17 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)) 06:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:09:17 INFO - Running setup.py (path:/tmp/pip-sc97Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:09:17 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)) 06:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:09:17 INFO - Running setup.py (path:/tmp/pip-zWTDKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:09:17 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)) 06:09:17 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)) 06:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:09:17 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)) 06:09:17 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)) 06:09:17 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)) 06:09:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:09:17 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 06:09:17 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 06:09:17 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 06:09:17 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 06:09:17 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 06:09:17 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 06:09:19 INFO - Downloading blessings-1.5.1.tar.gz 06:09:19 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 06:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:09:19 INFO - Installing collected packages: blessings 06:09:19 INFO - Running setup.py install for blessings 06:09:19 INFO - Successfully installed blessings 06:09:19 INFO - Cleaning up... 06:09:19 INFO - Return code: 0 06:09:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:09:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:09:19 INFO - Reading from file tmpfile_stdout 06:09:19 INFO - Current package versions: 06:09:19 INFO - argparse == 1.2.1 06:09:19 INFO - blessings == 1.5.1 06:09:19 INFO - blobuploader == 1.2.4 06:09:19 INFO - docopt == 0.6.1 06:09:19 INFO - manifestparser == 1.1 06:09:19 INFO - mozInstall == 1.12 06:09:19 INFO - mozcrash == 0.16 06:09:19 INFO - mozdebug == 0.1 06:09:19 INFO - mozdevice == 0.47 06:09:19 INFO - mozfile == 1.2 06:09:19 INFO - mozhttpd == 0.7 06:09:19 INFO - mozinfo == 0.9 06:09:19 INFO - mozleak == 0.1 06:09:19 INFO - mozlog == 3.1 06:09:19 INFO - moznetwork == 0.27 06:09:19 INFO - mozprocess == 0.22 06:09:19 INFO - mozprofile == 0.27 06:09:19 INFO - mozrunner == 6.11 06:09:19 INFO - mozscreenshot == 0.1 06:09:19 INFO - mozsystemmonitor == 0.0 06:09:19 INFO - moztest == 0.7 06:09:19 INFO - mozversion == 1.4 06:09:19 INFO - psutil == 3.1.1 06:09:19 INFO - requests == 1.2.3 06:09:19 INFO - wsgiref == 0.1.2 06:09:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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/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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09: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/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 06:09: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/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 06:09:19 INFO - Using env: {'DISPLAY': ':0', 06:09:19 INFO - 'HOME': '/home/cltbld', 06:09:19 INFO - 'LANG': 'en_US.UTF-8', 06:09:19 INFO - 'LANGUAGE': 'en_US:en', 06:09:19 INFO - 'LOGNAME': 'cltbld', 06:09:19 INFO - 'MAIL': '/var/mail/cltbld', 06:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:19 INFO - 'MOZ_NO_REMOTE': '1', 06:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:19 INFO - 'NO_EM_RESTART': '1', 06:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:19 INFO - 'PWD': '/builds/slave/test', 06:09:19 INFO - 'SHELL': '/bin/bash', 06:09:19 INFO - 'SHLVL': '1', 06:09:19 INFO - 'TERM': 'linux', 06:09:19 INFO - 'TMOUT': '86400', 06:09:19 INFO - 'USER': 'cltbld', 06:09:19 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:19 INFO - '_': '/tools/buildbot/bin/python'} 06:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:09:19 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)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:09:19 INFO - Cleaning up... 06:09:19 INFO - Return code: 0 06:09:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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', '--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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:19 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 06:09:19 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 06:09:19 INFO - Using env: {'DISPLAY': ':0', 06:09:19 INFO - 'HOME': '/home/cltbld', 06:09:19 INFO - 'LANG': 'en_US.UTF-8', 06:09:19 INFO - 'LANGUAGE': 'en_US:en', 06:09:19 INFO - 'LOGNAME': 'cltbld', 06:09:19 INFO - 'MAIL': '/var/mail/cltbld', 06:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:19 INFO - 'MOZ_NO_REMOTE': '1', 06:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:19 INFO - 'NO_EM_RESTART': '1', 06:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:19 INFO - 'PWD': '/builds/slave/test', 06:09:19 INFO - 'SHELL': '/bin/bash', 06:09:19 INFO - 'SHLVL': '1', 06:09:19 INFO - 'TERM': 'linux', 06:09:19 INFO - 'TMOUT': '86400', 06:09:19 INFO - 'USER': 'cltbld', 06:09:19 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:19 INFO - '_': '/tools/buildbot/bin/python'} 06:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:20 INFO - Downloading/unpacking jsonschema 06:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:09:21 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 06:09:21 INFO - Downloading/unpacking functools32 (from jsonschema) 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:21 INFO - Downloading functools32-3.2.3-2.tar.gz 06:09:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 06:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:09:21 INFO - warning: no files found matching '*.txt' 06:09:21 INFO - no previously-included directories found matching 'build' 06:09:21 INFO - no previously-included directories found matching 'dist' 06:09:21 INFO - no previously-included directories found matching '.git*' 06:09:21 INFO - Installing collected packages: jsonschema, functools32 06:09:22 INFO - Running setup.py install for functools32 06:09:22 INFO - warning: no files found matching '*.txt' 06:09:22 INFO - no previously-included directories found matching 'build' 06:09:22 INFO - no previously-included directories found matching 'dist' 06:09:22 INFO - no previously-included directories found matching '.git*' 06:09:22 INFO - Successfully installed jsonschema functools32 06:09:22 INFO - Cleaning up... 06:09:22 INFO - Return code: 0 06:09:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:22 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 0x7f06b3e6ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, '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': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', '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 06:09:22 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 06:09:22 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 06:09:22 INFO - Using env: {'DISPLAY': ':0', 06:09:22 INFO - 'HOME': '/home/cltbld', 06:09:22 INFO - 'LANG': 'en_US.UTF-8', 06:09:22 INFO - 'LANGUAGE': 'en_US:en', 06:09:22 INFO - 'LOGNAME': 'cltbld', 06:09:22 INFO - 'MAIL': '/var/mail/cltbld', 06:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:22 INFO - 'MOZ_NO_REMOTE': '1', 06:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:22 INFO - 'NO_EM_RESTART': '1', 06:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:22 INFO - 'PWD': '/builds/slave/test', 06:09:22 INFO - 'SHELL': '/bin/bash', 06:09:22 INFO - 'SHLVL': '1', 06:09:22 INFO - 'TERM': 'linux', 06:09:22 INFO - 'TMOUT': '86400', 06:09:22 INFO - 'USER': 'cltbld', 06:09:22 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:22 INFO - '_': '/tools/buildbot/bin/python'} 06:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:22 INFO - Downloading/unpacking flake8 06:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:09:24 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 06:09:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:09:24 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 06:09:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 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 06:09:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 06:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:09:24 INFO - no previously-included directories found matching 'docs/_build' 06:09:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:09:24 INFO - Running setup.py install for pep8 06:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:09:24 INFO - no previously-included directories found matching 'docs/_build' 06:09:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:09:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:09:24 INFO - Cleaning up... 06:09:24 INFO - Return code: 0 06:09:24 INFO - Running post-action listener: _resource_record_post_action 06:09:24 INFO - Running post-action listener: _start_resource_monitoring 06:09:24 INFO - Starting resource monitoring. 06:09:24 INFO - ##### 06:09:24 INFO - ##### Running install step. 06:09:24 INFO - ##### 06:09:24 INFO - Running pre-action listener: _resource_record_pre_action 06:09:24 INFO - Running main action method: install 06:09:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:09:25 INFO - Reading from file tmpfile_stdout 06:09:25 INFO - Detecting whether we're running mozinstall >=1.0... 06:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:09:25 INFO - Reading from file tmpfile_stdout 06:09:25 INFO - Output received: 06:09:25 INFO - Usage: mozinstall [options] installer 06:09:25 INFO - Options: 06:09:25 INFO - -h, --help show this help message and exit 06:09:25 INFO - -d DEST, --destination=DEST 06:09:25 INFO - Directory to install application into. [default: 06:09:25 INFO - "/builds/slave/test"] 06:09:25 INFO - --app=APP Application being installed. [default: firefox] 06:09:25 INFO - mkdir: /builds/slave/test/build/application 06:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:09:39 INFO - Reading from file tmpfile_stdout 06:09:39 INFO - Output received: 06:09:39 INFO - /builds/slave/test/build/application/firefox/firefox 06:09:39 INFO - Running post-action listener: _resource_record_post_action 06:09:39 INFO - ##### 06:09:39 INFO - ##### Running run-tests step. 06:09:39 INFO - ##### 06:09:39 INFO - Running pre-action listener: _resource_record_pre_action 06:09:39 INFO - Running main action method: run_tests 06:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:09:39 INFO - Python 2.7.3 06:09:39 INFO - Return code: 0 06:09:39 INFO - grabbing minidump binary from tooltool 06:09:39 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')]} 06:09:39 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 0x26fd0b0>, '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 0x26fdfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fe440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:09:39 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 06:09:39 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 06:09:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:09:39 INFO - Return code: 0 06:09:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:09:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:09:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:09:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:09:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:09:40 INFO - Return code: 0 06:09:40 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/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] in /builds/slave/test/build 06:09:40 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/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost 06:09:40 INFO - Using env: {'DISPLAY': ':0', 06:09:40 INFO - 'HOME': '/home/cltbld', 06:09:40 INFO - 'LANG': 'en_US.UTF-8', 06:09:40 INFO - 'LANGUAGE': 'en_US:en', 06:09:40 INFO - 'LOGNAME': 'cltbld', 06:09:40 INFO - 'MAIL': '/var/mail/cltbld', 06:09:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:09:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:40 INFO - 'MOZ_NO_REMOTE': '1', 06:09:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:09:40 INFO - 'NO_EM_RESTART': '1', 06:09:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:40 INFO - 'PWD': '/builds/slave/test', 06:09:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:09:40 INFO - 'SHELL': '/bin/bash', 06:09:40 INFO - 'SHLVL': '1', 06:09:40 INFO - 'TERM': 'linux', 06:09:40 INFO - 'TMOUT': '86400', 06:09:40 INFO - 'USER': 'cltbld', 06:09:40 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1450273727.750528-1062671518', 06:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:40 INFO - '_': '/tools/buildbot/bin/python'} 06:09:40 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/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] with output_timeout 3600 06:09:41 INFO - mozversion INFO | application_buildid: 20151216053720 06:09:41 INFO - mozversion INFO | application_changeset: f8840e2aec5afdebf63216dc219e103b5d4c391a 06:09:41 INFO - mozversion INFO | application_display_name: Nightly 06:09:41 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:09:41 INFO - mozversion INFO | application_name: Firefox 06:09:41 INFO - mozversion INFO | application_remotingname: firefox 06:09:41 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 06:09:41 INFO - mozversion INFO | application_vendor: Mozilla 06:09:41 INFO - mozversion INFO | application_version: 46.0a1 06:09:41 INFO - mozversion INFO | platform_buildid: 20151216053720 06:09:41 INFO - mozversion INFO | platform_changeset: f8840e2aec5afdebf63216dc219e103b5d4c391a 06:09:41 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 06:09:41 INFO - mozversion INFO | platform_version: 46.0a1 06:09:41 INFO - 2015-12-16 06:09:41,009 DEBUG : using testdate: 1450274981 06:09:41 INFO - 2015-12-16 06:09:41,009 DEBUG : actual date: 1450274981 06:09:41 INFO - 2015-12-16 06:09:41,020 INFO : starting webserver on 'localhost:37484' 06:09:41 INFO - 2015-12-16 06:09:41,021 INFO : Starting test suite talos-linux64-ix-003.e 06:09:41 INFO - 2015-12-16 06:09:41,022 INFO : Starting test dromaeo_css 06:09:41 INFO - 2015-12-16 06:09:41,022 DEBUG : operating with platform_type : linux_ 06:09:41 INFO - 2015-12-16 06:09:41,023 INFO : Initialising browser for dromaeo_css test... 06:09:41 INFO - 2015-12-16 06:09:41,039 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP_z4fs/profile http://localhost:37484/getInfo.html 06:09:45 INFO - 2015-12-16 06:09:45,520 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:09:45 INFO - 2015-12-16 06:09:45,520 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:09:45 INFO - 2015-12-16 06:09:45,520 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:09:45 INFO - 2015-12-16 06:09:45,520 DEBUG : BROWSER_OUTPUT: __metrics 06:09:45 INFO - 2015-12-16 06:09:45,661 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] 06:09:46 INFO - 2015-12-16 06:09:46,645 INFO : Browser initialized. 06:09:46 INFO - 2015-12-16 06:09:46,646 INFO : Running cycle 1/1 for dromaeo_css test... 06:09:46 INFO - 2015-12-16 06:09:46,646 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP_z4fs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:09:58 INFO - 2015-12-16 06:09:58,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 143114240 06:09:58 INFO - 2015-12-16 06:09:58,766 DEBUG : BROWSER_OUTPUT: 06:10:48 INFO - 2015-12-16 06:10:48,383 DEBUG : BROWSER_OUTPUT: 1450275048382 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] 06:13:00 INFO - 2015-12-16 06:13:00,151 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37484/tests/dromaeo/cssquery-ext.html) 06:13:01 INFO - 2015-12-16 06:13:01,240 DEBUG : BROWSER_OUTPUT: RSS: Main: 146374656 06:13:01 INFO - 2015-12-16 06:13:01,240 DEBUG : BROWSER_OUTPUT: 06:16:03 INFO - 2015-12-16 06:16:03,242 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/dromaeo/cssquery-ext.html (next: http://localhost:37484/tests/dromaeo/cssquery-jquery.html) 06:16:03 INFO - 2015-12-16 06:16:03,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 145272832 06:16:03 INFO - 2015-12-16 06:16:03,844 DEBUG : BROWSER_OUTPUT: 06:19:05 INFO - 2015-12-16 06:19:05,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37484/tests/dromaeo/cssquery-mootools.html) 06:19:06 INFO - 2015-12-16 06:19:06,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 146485248 06:19:06 INFO - 2015-12-16 06:19:06,221 DEBUG : BROWSER_OUTPUT: 06:22:07 INFO - 2015-12-16 06:22:07,971 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37484/tests/dromaeo/cssquery-prototype.html) 06:22:08 INFO - 2015-12-16 06:22:08,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 144502784 06:22:08 INFO - 2015-12-16 06:22:08,577 DEBUG : BROWSER_OUTPUT: 06:25:11 INFO - 2015-12-16 06:25:11,215 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37484/tests/dromaeo/cssquery-yui.html) 06:25:11 INFO - 2015-12-16 06:25:11,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 143933440 06:25:11 INFO - 2015-12-16 06:25:11,841 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,136 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/dromaeo/cssquery-yui.html (next: http://localhost:37484/tests/dromaeo/cssquery-dojo.html) 06:28:14 INFO - 2015-12-16 06:28:14,380 DEBUG : BROWSER_OUTPUT: RSS: Main: 147300352 06:28:14 INFO - 2015-12-16 06:28:14,380 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,382 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:28:14 INFO - 2015-12-16 06:28:14,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:28:14 INFO - 2015-12-16 06:28:14,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:28:14 INFO - 2015-12-16 06:28:14,382 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:28:14 INFO - 2015-12-16 06:28:14,383 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2680.7453291937263;2732.5764506501473;2748.0107161421897;2755.2285360099168;2775.1258353618614;4714.151452738506;4753.263244197151;4786.042791441712;4792.760361981896;4800.303955926389;4691.788869500872;4766.833160839371;4787.162246606838;4808.615310775138;4843.878903027426;4344.608985191332;4410.603045725884;4487.46150461945;4492.573205545485;4508.504064552896;4265.210935976838;4437.046035102452;4474.597286244238;4518.480374756896;4581.496035436099;4349.652027837781;4357.651387888961;4499.167653983991;4511.548845115495;4530.75080870552;4496.752678602708;4510.210713125222;4517.570830771071;4522.819087236507;4531.116432295704;4244;4336.869893903188;4456.131054444352;4463.620592249663;4484.035932274576;4603.608693261043;4655.068986202773;4685.835995740167;4702.3181638662545;4707.176244157293;5132.127538318495;5147.150720131174;5163.251328557338;5183.429822719498;5185.0148471790235;4956.1822299320565;5054.368203974504;5066.721330326831;5203.557697595709;5267.710275934822;4832.903341933177;4835.081334546425;4852.417709874767;4863.440704319013;4903.190973489405;5303.628745987821;5329.973350133225;5333.5199832015305;5334.653247538898;5336.146216605324;4828.203346447867;4944.184209605447;4951.801927922843;5101.056304583664;5111.87220319495;5157.819476318386;5170.97414512925;5187.870303242374;5189.221616757516;5201.375834899836;5126.077306084866;5129.179331306973;5131.589472842164;5137.974310128426;5183.196604526229;4878.487758785347;4904.558589726942;5001.324821149171;5003.3495645565845;5017.347744793226;4263.786810659454;4314.374415709704;4314.633256173198;4316.093620339701;4351.282038463682;3757.830897609614;3764.7741135531955;3783.621637836194;3788.5264341957254;3806.314863324573;4282.507511636201;4362.17118747437;4364.323529852878;4437.955620443754;4463.12968971054;5163.096458119814;5224.738763061831;5225.111731005738;5227.2420499028185;5251.474852514794;4222.767747773841;4444.73331600105;4478.865634030984;4484.260097083945;4526.479454862667;4462.933056004163;4514.887127821765;4569.360289559464;4608.677392582554;4647.18674232008;4932.309476673268;4953.603711702983;4956;4959.528844759742;4975.875603109951;4973.179425394914;5091.898162036812;5221.843344699665;5238.371395432572;5287.550558202596;4824.782884770124;4867.245576935581;4875.853724388274;4890.144224760724;4927.014597080527;5085.6185786066935;5122.974385128199;5129.410117836496;5130.384353877558;5181.06740786647;4898.093852637274;5012.699238045729;5016.197408414636;5199.090159222045;5227.372715274191;4879.121758083437;4893.266010098514;4926.137925862888;4994.175960966545;5035.471275516018;4931.654784165074;4981.153203955409;4987.376577927759;4994.600431965507;5024.42219144803;4734.479207287264;4885.609151267961;4898.853034408973;4913.975430122827;4920.753962301942;4901.681390709592;4979.3526841510375;4991.975040124777;5019.52314530119;5042.571381432473;4878.756062196947;4913.361263035782;4919.385076865392;4924.581410580141;4964.851054468371 06:28:14 INFO - 2015-12-16 06:28:14,385 DEBUG : BROWSER_OUTPUT: |1;ext.html;547.4853637580674;559.9748011339491;574.2592056247444;589.2545929399308;616.260487415102;35020.64979350206;35558;35727.82136089316;35742.463863042045;35765.46351804725;36341.81829090851;36782.632173678256;36870.63129368705;36912.630873691254;36922.07694807631;7508.624568771567;7755.1469338372735;7765.107012693527;7775.066991960973;7781.961090194541;7510.774676759706;7545.056867891513;7580.014598102239;7580.05249343832;7605.543667379948;7584.089909210902;7697.961510192441;7724.9613751931165;7733.7293194738195;7740.187280335568;7558.508696934709;7591;7598.620068996555;7599.506032107895;7607.543547387175;7394.37147842434;7462.10454745434;7503.249675032508;7553;7577.962110189414;33321.50017749736;33754.49368259478;33931.15172120692;34007.31985360279;34178.48732269018;2809.2836326736756;2932.208303757973;2955.807872488303;3028.3186283086347;3515.2793677296095;26112.086076987212;26120;26470.86764566165;26647.86676066607;27408.17775466739;3430.5368775215525;3436.0894362993827;3479.165000399911;3489.860405583799;3547.0245682437535;211054;211787;212418.99999999846;213734;213949;50444.24333634999;51885.22172167421;56232;57661.71169144211;57688.42311576915;48616;48727.269090963666;48863;48902.510974889796;48907.51092489101;13193.274369909746;13569.457221711218;13574.117682350616;13575.38910748999;13587;10641.574337026586;10878.782424351468;10894.891051089387;10906.400147991779;10969.23215374916;18941.147648355858;19191.136398862083;19221.90389048046;19292.710609340873;19349.226030958886;7622.885656715153;7856.685732570748;7921.405894557931;7933.841323173504;8043.316318112909;802.1617409806765;854.073330436471;922.9630814767335;992.1467537917385;1013.7921726046143;26453.86773066161;26691.599626005627;26975;26978.865105674347;27223.591646125325;945.1635302756972;969.2294625772387;1059.1897198643044;1064.1539975719322;1085.79369919715;13708.3831227595;13829.723405531833;13970.580882573538;14061.78907316391;14081.014328997077;254983;257030;258034;258554;260511;44943.32585011228;45784;45838;45974.77012614982;46024.539754602694;1227.766724322376;1229.6434034130002;1234.3149551998727;1239.3555351217497;1239.367922359585;25475.87262063678;25927.870360648078;26265.343366415993;26415.339616508973;26602.20193394201;39482;39623.207535848545;39678;39682.80158599189;39857.40213896852;243013;245529;247105;247636;247782;29163.7083629161;29830.403391932476;29836.85081574665;29935.850320748257;29996.400071998003;38608;38873.80563097166;38918.80540597392;39691;39846.40230396488;32630.184245394055;33283.66716332806;33461.83269083639;33934.490982635776;34187.829060854536;33610.49584256287;33804.32391352209;34017.82991085029;34248.82875585606;34427.4835877457;33455.83272083624;34013;34075.65924340824;34200;34318.65681343254;28652.854654441722;31056.534151987285;31065.069537102787;31138.22154446157;31300.843495782374;24262.393440164138;24464.287499874838;24519.63220551729;24610;27102.186934392 06:28:14 INFO - 2015-12-16 06:28:14,385 DEBUG : BROWSER_OUTPUT: |2;jquery.html;340.22089415239105;344.3027868566154;344.59682171858924;344.6880573081361;344.8051850704352;15704.371825126995;16007.439739609115;16018.75971860423;16487.91756041221;17373.218205180765;9583.329166958316;9679.467629280387;9682;9785.412875227492;10017.148542373907;4498.4376952880975;4603.516630753773;4606.861794146181;4613.331066995283;4624;4524.705894116872;4983.327250821129;5119;5125.461826508219;5131.486851314876;3513.455414410758;3593.6047034826147;5090.898182036357;5140.691558506483;5173.637845350827;5310.9734451327495;5336.332958380202;5357.357117145967;5357.785688572452;5367.436419176009;4673.953260467386;4809.422869255677;4812.230043193107;5138.511841375063;5181.740912954338;18033.368832090815;18163.818361816346;18274.086295685163;18293.451196464128;18412.263509459604;1707.6670049340385;1799.7750281214849;1831.8534517238588;1837.2283640870867;1940.5439721665398;8752.956235218782;8921.598528066253;9123.635054597808;11188.38463884486;12812.55156069533;1685.5364774686936;1692.8814982951199;1724.5343757185615;1741.5471977285997;1758.5955230297104;53070;53539.19691204635;53734.19398709023;54669.72665136649;55757.44242557563;9550.522473876279;10169.288149829588;10170.593176273014;10209.081182693593;10222.6422075227;12667.30329831868;12673.809892851614;12763.808542871864;12764.744705105846;12825.102242842911;6928.44572434204;6977.51157418986;7013.2986701329455;7155.141383033965;7289.489735718449;6685.6322902240845;6717.496187785936;6733.259341472434;6807.047013418125;6941.618210998444;4279.700420970565;4288.50682171548;4336.34954756789;4369.584889435499;4379.3430985351815;1656.1139790212178;1780.2967827707982;1798.9910050449664;1817.2276782367442;1821.3625231168985;380.3420083255983;381.47547122706277;398.8683734367652;849.864021756516;905.9320550958704;9827;10111.140553052926;10161.708923936883;10405.427701476492;10425.11386532138;397.14613580801506;825.7925867704039;896.551724137931;932.776133727914;952.9618815247451;8632.223099920911;8817.426867253607;8908.688195913111;8923.41997770143;9006.59470323837;452186;460021;461481;462554;463435;69439;70086.299137009;72471;72999.63500182464;73500;1111.7387413957713;1126.2622981946838;1126.8929451702074;1137.7655244060122;1138.4023387721513;13534.526291579747;13586.048976571941;13787.103838250481;13856.515021974183;13930.233837139054;29210.269743256587;30594.847025764728;30705.078847634606;30707.539386909662;30744.231394215323;41795.7910210447;42072.36891446692;43402;43449.782751086044;43751;8950.910490895008;9013.709995851597;9101;9159.49622770754;9189.77025574366;16463.34146634121;16522.339106435607;16732.330706771594;16823.495295141165;16993;11564.363959982282;13892.930535347663;14086.718265634425;14102.435902563782;14465.204413756923;13441.932790335986;13611.11527750693;13740.381682824096;13896.791548126988;14131.15213087218;13337.333133343489;13846.307684615931;14017.088889222168;14049.438022478986;14510.419583216975;8871.467711937305;8957.641694332153;9029.322800789836;9220.769480763034;9229.077092290718;11753.882461175279;13848.723025539633;14110.78833817473;14277.928610356881;14325.212113333866 06:28:14 INFO - 2015-12-16 06:28:14,388 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1462.4588902106223;1692.9238184281705;1730.1176400035986;1744.5028166972415;1756.4203812741794;6112.572119951606;6318.336574659664;6854.82862928427;7386.298301661341;7421.5176013559085;6892.241853396123;7121.181064177621;7173.139223293199;7189.424846012306;7191.065161529007;2053.127420846138;2082.094288984289;2090.0490276923965;2092.5187206942414;2094.0890712540017;2071.513194399315;2089.968650470244;2110.641190997534;2137.9144834206613;2142.003968154808;2095.5913596848623;2098.2026829804654;2104.3686893931836;2127.1278775701967;2142.646463333548;2084.0725876984748;2099.1603358656507;2099.653557163066;2106.7577228618675;2107.083418712857;2044.1210279579775;2063.886486243256;2075.63676356637;2092.435042538506;2094.5077906691913;7160.4271658267735;7195.6042417667;7214.9278507214785;7230.421566274686;7241.203467618558;4148.170365926827;4186.037211441386;4198.853040143611;4211.873643790691;4212.68404869636;2656.2296933889147;2678.1162216468715;2708.214617760847;2733.070755942969;2734.644496215479;852.1393392673383;870.0038455967945;876.1282523888772;876.3383645347785;917.655879045358;9806.852897206547;9825.950870245602;9867.358621689567;9882.11061004513;9898.455584942825;3892.922141557153;3905.863294784669;3931.941020884689;3953.723239373216;4043.5147782265726;8462.492250464991;8524.70163544273;8534.87197692035;8553.101924297984;8576.485410875368;4202.159568086395;4204.096119334357;4210.59999300066;4214.704970652025;4217.641500472494;3190.7128358447385;3274.099622603803;3694.9076273093387;3764.5858955514873;3793.6206379362393;3470.8264586771065;3478.0435380270123;3495.8426870790368;3503.6146023937345;3509.859605615798;1070.8447275145215;1076.687760549432;1081.1891081688734;1090.8091084060259;1090.9345439273668;1220.700928272579;1220.9084318676134;1230.864604893461;1235.610782603477;1242.658268976039;6707.932920670731;6742.6291553965;6758.155230596176;6884.483663725241;7104.43164546835;1030.1295405382439;1037.113268155716;1043.3114144664485;1045.4981608827652;1055.7888422315568;5460.399356070829;5515.090010148361;5518.172274158908;5525.696086715189;5537.252470916455;8643.697470588624;8673.089325620846;8799.384043117048;8929.910700892908;8967.237784788236;8752.606132724042;8794.34042446819;8813.867791983126;8816.162464565856;8856.202941735275;6659.833504162435;6759.5944243344575;6768.849295619756;6770;6770.93229067703;6543.803685889431;6543.869122617616;6563.638999855054;6571.112899758471;6577.276499585138;7568.924310756822;7736.574488402966;7823.882641760492;7877.7636670899965;7943.84112317762;7787.571683557232;8074.1522140176785;8107.878381824159;8155.347572194091;8157.79605509867;5348.786048558014;5410.540104091197;5800.187973683772;6162.383761623802;6250.499960003098;6582.670866456754;6789.660516974231;6841.794746157623;6892.207396149306;6898.8620227596175;6089.604175728564;6211.0372892201785;6235;6320.715567799368;6335.018072198816;6349.333320001518;6351.872962540816;6433.967830160819;6499.122618446449;6510.023496475377;6536.117624120873;6549.443297319781;6559.672016399066;6586.209654841449;6605.636689982097;5955.612885162451;5973.253343332083;6004.8799024020145;6011.128386384037;6026.909596356146;5928.110783382527;5935.762569497172;5960.284765828128;5971.522278217645;5982.641041537521 06:28:14 INFO - 2015-12-16 06:28:14,390 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.9174023013786;308.61731452998276;310.6924145096355;312.91082041618114;316.7830036425048;5569.108942569184;5587.972060139698;5692.0323544997345;5708.315002199732;5803.477687008169;5615.775368985246;5673.943260567393;5699.971500142494;5703.714814259291;5705.828825135242;4197.412362269285;4323.178596066753;4330.177266319405;4332.06860524987;4334.45819272591;4291;4312.568743125694;4328.285832837578;4344.152890186415;4360.280553708634;4269.252880745873;4278.4438023056955;4290.6138447539715;4291.506476755182;4292.1630282095;4271.466066741657;4290.334998075304;4292.0342922842265;4299.419578356944;4308.784560771949;3647.1429214134655;3738.831752571141;3744.8314825832904;3745.6441638044344;3746.587875333711;5249.8425047248265;5414.945850541483;5415.350157981067;5426.891462170774;5434.510894019518;4871.805127794884;4878.902421951541;4894.632902532324;4903.117438861003;4903.289023091632;5943.108533719934;5943.840951014554;5958.13607026983;5971.761129554812;5976.282846058457;3901.161250331164;4010.3382941814566;4023.718339716221;4033.193361327746;4070.409790580349;6212.06818977157;6276.780312689079;6284.497240220817;6297.559170858041;6301;4059.8579049733116;4081.2653722329674;4087.4686290782006;4094.467719196485;4100.91798164035;4154.086101057763;4158.438610787565;4159.043420013414;4162.439085342997;4168.686863637944;4726.078414709167;4733.502982186889;4829.203181475087;4838.395200599925;4842.104210721028;5878;5895.174675545427;5914.852128696731;5931.1992880960515;5934.8813023739285;1474.7050589882067;1474.9336279867432;1484.9331780069922;1486.7769834524909;1487.7693957436616;970.5923512124924;972.0619602084023;974.6345120579782;1002.5538635307219;1024.3239461955075;5133.255677926754;5136;5145.099607568735;5159.561437277798;5159.948400516022;6150.169727086875;6161.045038019114;6161.815145545641;6176.320604733476;6176.505879529716;5113.744312784346;5130.384353877484;5152.433232344439;5160.045391602565;5167.173252279617;3230.725388342017;3294.2917272786467;3328.21786880083;3334.0831271400075;3336.9833150834093;5882.23530940975;5884.911726324109;5888.087346461305;5889.087191485327;5899.705014749331;6416.615003099829;6421.614703117828;6425.5502114851515;6428.453581445629;6438.806835794933;5733.827985160368;5864.824055278348;5877.177195192677;5878.559108066826;5880.14737863016;6192.287886893065;6203.65879876593;6206.193194884636;6211.689415529115;6221.875562488815;6032.517398608188;6042;6042.788502402394;6061.909071364022;6071.666058366655;5742.28221472316;5833.445822646841;5836.562257830765;5877.706114694333;5888.823335299948;4047.8178481967793;4133.855315063958;4134.152498737812;4140.37894315843;4144.33690609501;5938.138969849261;6093.9390606093375;6213.627182369072;6218.160548325918;6218.906716399348;5751.1085781703905;5852.912206316993;5920;5924.763009479572;5938.851528711817;5901.527877769682;5928.288605367211;5933.169356290036;5935.584509084322;5937.079752638348;5684.857878553069;5786.3056433228285;5797.565182611237;5798.159266906359;5802.419758024333;5955.76176952917;5957.553183511167;5972.462478376793;5978.8206353809455;6009.489193418608;5793.710314484175;5806.1000544915605;5839.240898683314;5854.707264636836;5868.09044598088 06:28:14 INFO - 2015-12-16 06:28:14,391 DEBUG : BROWSER_OUTPUT: |5;yui.html;659.2254101430817;677.451264475775;736.6537727268183;737.0050431916912;776.8135647444611;11585.884141158585;11765.647030589074;11790.410479476035;11807.881921180786;11830.822537661941;10863.565457381696;10875.945620271888;10894.074003709675;10932.289401188917;11062.834057489144;776.8446310737875;804.8752443371287;821.2280456371022;832.9583520823965;841.5035129273728;816.5713000674634;825.9793505162359;827.3587969323792;827.735124760077;832.305025303872;856;865.5412631305387;866.1468453573225;868.9913100869005;870.4907629037021;856.7044369692446;862.1637012098317;862.5040601654074;863.6718047142077;867.7396780965717;639.178655427773;639.4979940746491;640.9903851442232;641.8940874755659;642.0337392224718;11406.087512999024;11422.371769552754;11441.370724610144;11455.942720286344;11462.083033357394;10856.728581785439;10911;11049.28179668319;11285.266457680225;11625.651230463016;9102.954485227598;9351.90648093524;9430.811383772287;9494.287928405398;9565.521723913846;3166.145140811991;3202.503611940153;3208.3102133041507;3210.887618933326;3216.887408940676;39843.60156398428;40285.39571906424;40405;40444;40491;8221.876671849926;8728.650853965897;8957.820843583091;8980.46117232968;9097.13577210164;15480.612984675474;15494;15537.611559711098;15561.68876622484;15561.922190388976;5963.254593175853;6049.3950604939155;6053.4854537363935;6219.720112594944;6269.435750782361;3324.1357247115925;3425.0752296879705;3449.223924616991;3477.3914564951538;3527.894163175109;2529.2538701083226;2561.5261176682;2569.460413313188;2572.305477521059;2576.420305431263;465.08146410839;465.60656245472524;470.6399604302711;478.01528850567246;479.49652864492145;579.3829571506417;584.7281014328357;592.9809000353803;598.4703537369372;600.666630020343;7972.202779721982;7977.48146370484;8000.959995199987;8022.438429309892;8107.797305067303;587.759018802289;598.2671227746064;608.4523928464417;618.245740196968;623.1213988276509;3512.8946131616094;3722.664960153599;3801.50580424543;3865.729398942047;3868.671162951124;47905.281420778716;48019;48179.518204817505;48439.757801211475;48791;43144.352834707504;43159;43177;43527.34708979368;43551.34672979908;39591.40612890809;39830.60169398327;39847;40114.59885401167;40174.59825401767;7511.3615342696485;7617.923820761722;7690.500117492345;7704.6918123274445;7705.845883082195;30071;30633.693663063084;30674.386512269182;31359.21601959969;31515.52726709147;36196.63803362038;37160;38222.23555528929;39657.40513892352;39901.401478977255;10195.23535734808;10264.486775661337;10287.588496460057;10421.843672344768;10581.62964296246;11265.324080554865;11302.208845380745;11476.827847582459;11504.309741415542;11809.23239989398;10388.896111038794;10623.468826558796;10963.616273430393;10966.06788422993;11091.833622495831;10598.417087060287;10690.198235132551;10700.83948740754;10745.785084298113;11156.442177891235;10518.579256829642;10552.314099583502;10564.418956957441;10600.893991059991;10647.520861561094;9353.064693530592;9427.952860235655;9444.197243234403;9611.807763844823;9717.076877696607;9111.17999380059;9410.529473526432;9478.099580539838;9560.468088505571;9614.230861530921 06:28:14 INFO - 2015-12-16 06:28:14,391 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:28:14 INFO - 2015-12-16 06:28:14,391 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:28:14 INFO - 2015-12-16 06:28:14,391 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,334 06:28:14 INFO - 2015-12-16 06:28:14,391 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: __startTimestamp1450276094381__endTimestamp 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4743.66 Median:4885.61 stddev:494.12 (10.1%) stddev-sans-first:468.38 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: Values: 2680.7 2732.6 2748.0 2755.2 2775.1 4714.2 4753.3 4786.0 4792.8 4800.3 4691.8 4766.8 4787.2 4808.6 4843.9 4344.6 4410.6 4487.5 4492.6 4508.5 4265.2 4437.0 4474.6 4518.5 4581.5 4349.7 4357.7 4499.2 4511.5 4530.8 4496.8 4510.2 4517.6 4522.8 4531.1 4244.0 4336.9 4456.1 4463.6 4484.0 4603.6 4655.1 4685.8 4702.3 4707.2 5132.1 5147.2 5163.3 5183.4 5185.0 4956.2 5054.4 5066.7 5203.6 5267.7 4832.9 4835.1 4852.4 4863.4 4903.2 5303.6 5330.0 5333.5 5334.7 5336.1 4828.2 4944.2 4951.8 5101.1 5111.9 5157.8 5171.0 5187.9 5189.2 5201.4 5126.1 5129.2 5131.6 5138.0 5183.2 4878.5 4904.6 5001.3 5003.3 5017.3 4263.8 4314.4 4314.6 4316.1 4351.3 3757.8 3764.8 3783.6 3788.5 3806.3 4282.5 4362.2 4364.3 4438.0 4463.1 5163.1 5224.7 5225.1 5227.2 5251.5 4222.8 4444.7 4478.9 4484.3 4526.5 4462.9 4514.9 4569.4 4608.7 4647.2 4932.3 4953.6 4956.0 4959.5 4975.9 4973.2 5091.9 5221.8 5238.4 5287.6 4824.8 4867.2 4875.9 4890.1 4927.0 5085.6 5123.0 5129.4 5130.4 5181.1 4898.1 5012.7 5016.2 5199.1 5227.4 4879.1 4893.3 4926.1 4994.2 5035.5 4931.7 4981.2 4987.4 4994.6 5024.4 4734.5 4885.6 4898.9 4914.0 4920.8 4901.7 4979.4 4992.0 5019.5 5042.6 4878.8 4913.4 4919.4 4924.6 4964.9 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39753.47 Median:34046.74 stddev:62309.79 (183.0%) stddev-sans-first:62415.05 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: Values: 547.5 560.0 574.3 589.3 616.3 35020.6 35558.0 35727.8 35742.5 35765.5 36341.8 36782.6 36870.6 36912.6 36922.1 7508.6 7755.1 7765.1 7775.1 7782.0 7510.8 7545.1 7580.0 7580.1 7605.5 7584.1 7698.0 7725.0 7733.7 7740.2 7558.5 7591.0 7598.6 7599.5 7607.5 7394.4 7462.1 7503.2 7553.0 7578.0 33321.5 33754.5 33931.2 34007.3 34178.5 2809.3 2932.2 2955.8 3028.3 3515.3 26112.1 26120.0 26470.9 26647.9 27408.2 3430.5 3436.1 3479.2 3489.9 3547.0 211054.0 211787.0 212419.0 213734.0 213949.0 50444.2 51885.2 56232.0 57661.7 57688.4 48616.0 48727.3 48863.0 48902.5 48907.5 13193.3 13569.5 13574.1 13575.4 13587.0 10641.6 10878.8 10894.9 10906.4 10969.2 18941.1 19191.1 19221.9 19292.7 19349.2 7622.9 7856.7 7921.4 7933.8 8043.3 802.2 854.1 923.0 992.1 1013.8 26453.9 26691.6 26975.0 26978.9 27223.6 945.2 969.2 1059.2 1064.2 1085.8 13708.4 13829.7 13970.6 14061.8 14081.0 254983.0 257030.0 258034.0 258554.0 260511.0 44943.3 45784.0 45838.0 45974.8 46024.5 1227.8 1229.6 1234.3 1239.4 1239.4 25475.9 25927.9 26265.3 26415.3 26602.2 39482.0 39623.2 39678.0 39682.8 39857.4 243013.0 245529.0 247105.0 247636.0 247782.0 29163.7 29830.4 29836.9 29935.9 29996.4 38608.0 38873.8 38918.8 39691.0 39846.4 32630.2 33283.7 33461.8 33934.5 34187.8 33610.5 33804.3 34017.8 34248.8 34427.5 33455.8 34013.0 34075.7 34200.0 34318.7 28652.9 31056.5 31065.1 31138.2 31300.8 24262.4 24464.3 24519.6 24610.0 27102.2 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,392 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25268.64 Median:398.01 stddev:75171.26 (18886.9%) stddev-sans-first:75358.83 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: Values: 340.2 344.3 344.6 344.7 344.8 15704.4 16007.4 16018.8 16487.9 17373.2 9583.3 9679.5 9682.0 9785.4 10017.1 4498.4 4603.5 4606.9 4613.3 4624.0 4524.7 4983.3 5119.0 5125.5 5131.5 3513.5 3593.6 5090.9 5140.7 5173.6 5311.0 5336.3 5357.4 5357.8 5367.4 4674.0 4809.4 4812.2 5138.5 5181.7 18033.4 18163.8 18274.1 18293.5 18412.3 1707.7 1799.8 1831.9 1837.2 1940.5 8753.0 8921.6 9123.6 11188.4 12812.6 1685.5 1692.9 1724.5 1741.5 1758.6 53070.0 53539.2 53734.2 54669.7 55757.4 9550.5 10169.3 10170.6 10209.1 10222.6 12667.3 12673.8 12763.8 12764.7 12825.1 6928.4 6977.5 7013.3 7155.1 7289.5 6685.6 6717.5 6733.3 6807.0 6941.6 4279.7 4288.5 4336.3 4369.6 4379.3 1656.1 1780.3 1799.0 1817.2 1821.4 380.3 381.5 398.9 849.9 905.9 9827.0 10111.1 10161.7 10405.4 10425.1 397.1 825.8 896.6 932.8 953.0 8632.2 8817.4 8908.7 8923.4 9006.6 452186.0 460021.0 461481.0 462554.0 463435.0 69439.0 70086.3 72471.0 72999.6 73500.0 1111.7 1126.3 1126.9 1137.8 1138.4 13534.5 13586.0 13787.1 13856.5 13930.2 29210.3 30594.8 30705.1 30707.5 30744.2 41795.8 42072.4 43402.0 43449.8 43751.0 8950.9 9013.7 9101.0 9159.5 9189.8 16463.3 16522.3 16732.3 16823.5 16993.0 11564.4 13892.9 14086.7 14102.4 14465.2 13441.9 13611.1 13740.4 13896.8 14131.2 13337.3 13846.3 14017.1 14049.4 14510.4 8871.5 8957.6 9029.3 9220.8 9229.1 11753.9 13848.7 14110.8 14277.9 14325.2 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5024.84 Median:6008.00 stddev:2609.65 (43.4%) stddev-sans-first:2603.24 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: Values: 1462.5 1692.9 1730.1 1744.5 1756.4 6112.6 6318.3 6854.8 7386.3 7421.5 6892.2 7121.2 7173.1 7189.4 7191.1 2053.1 2082.1 2090.0 2092.5 2094.1 2071.5 2090.0 2110.6 2137.9 2142.0 2095.6 2098.2 2104.4 2127.1 2142.6 2084.1 2099.2 2099.7 2106.8 2107.1 2044.1 2063.9 2075.6 2092.4 2094.5 7160.4 7195.6 7214.9 7230.4 7241.2 4148.2 4186.0 4198.9 4211.9 4212.7 2656.2 2678.1 2708.2 2733.1 2734.6 852.1 870.0 876.1 876.3 917.7 9806.9 9826.0 9867.4 9882.1 9898.5 3892.9 3905.9 3931.9 3953.7 4043.5 8462.5 8524.7 8534.9 8553.1 8576.5 4202.2 4204.1 4210.6 4214.7 4217.6 3190.7 3274.1 3694.9 3764.6 3793.6 3470.8 3478.0 3495.8 3503.6 3509.9 1070.8 1076.7 1081.2 1090.8 1090.9 1220.7 1220.9 1230.9 1235.6 1242.7 6707.9 6742.6 6758.2 6884.5 7104.4 1030.1 1037.1 1043.3 1045.5 1055.8 5460.4 5515.1 5518.2 5525.7 5537.3 8643.7 8673.1 8799.4 8929.9 8967.2 8752.6 8794.3 8813.9 8816.2 8856.2 6659.8 6759.6 6768.8 6770.0 6770.9 6543.8 6543.9 6563.6 6571.1 6577.3 7568.9 7736.6 7823.9 7877.8 7943.8 7787.6 8074.2 8107.9 8155.3 8157.8 5348.8 5410.5 5800.2 6162.4 6250.5 6582.7 6789.7 6841.8 6892.2 6898.9 6089.6 6211.0 6235.0 6320.7 6335.0 6349.3 6351.9 6434.0 6499.1 6510.0 6536.1 6549.4 6559.7 6586.2 6605.6 5955.6 5973.3 6004.9 6011.1 6026.9 5928.1 5935.8 5960.3 5971.5 5982.6 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4894.28 Median:5679.40 stddev:1473.37 (25.9%) stddev-sans-first:1436.69 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: Values: 305.9 308.6 310.7 312.9 316.8 5569.1 5588.0 5692.0 5708.3 5803.5 5615.8 5673.9 5700.0 5703.7 5705.8 4197.4 4323.2 4330.2 4332.1 4334.5 4291.0 4312.6 4328.3 4344.2 4360.3 4269.3 4278.4 4290.6 4291.5 4292.2 4271.5 4290.3 4292.0 4299.4 4308.8 3647.1 3738.8 3744.8 3745.6 3746.6 5249.8 5414.9 5415.4 5426.9 5434.5 4871.8 4878.9 4894.6 4903.1 4903.3 5943.1 5943.8 5958.1 5971.8 5976.3 3901.2 4010.3 4023.7 4033.2 4070.4 6212.1 6276.8 6284.5 6297.6 6301.0 4059.9 4081.3 4087.5 4094.5 4100.9 4154.1 4158.4 4159.0 4162.4 4168.7 4726.1 4733.5 4829.2 4838.4 4842.1 5878.0 5895.2 5914.9 5931.2 5934.9 1474.7 1474.9 1484.9 1486.8 1487.8 970.6 972.1 974.6 1002.6 1024.3 5133.3 5136.0 5145.1 5159.6 5159.9 6150.2 6161.0 6161.8 6176.3 6176.5 5113.7 5130.4 5152.4 5160.0 5167.2 3230.7 3294.3 3328.2 3334.1 3337.0 5882.2 5884.9 5888.1 5889.1 5899.7 6416.6 6421.6 6425.6 6428.5 6438.8 5733.8 5864.8 5877.2 5878.6 5880.1 6192.3 6203.7 6206.2 6211.7 6221.9 6032.5 6042.0 6042.8 6061.9 6071.7 5742.3 5833.4 5836.6 5877.7 5888.8 4047.8 4133.9 4134.2 4140.4 4144.3 5938.1 6093.9 6213.6 6218.2 6218.9 5751.1 5852.9 5920.0 5924.8 5938.9 5901.5 5928.3 5933.2 5935.6 5937.1 5684.9 5786.3 5797.6 5798.2 5802.4 5955.8 5957.6 5972.5 5978.8 6009.5 5793.7 5806.1 5839.2 5854.7 5868.1 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: 06:28:14 INFO - 2015-12-16 06:28:14,394 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12052.75 Median:43151.68 stddev:13472.90 (31.2%) stddev-sans-first:13483.52 06:28:14 INFO - 2015-12-16 06:28:14,395 DEBUG : BROWSER_OUTPUT: Values: 659.2 677.5 736.7 737.0 776.8 11585.9 11765.6 11790.4 11807.9 11830.8 10863.6 10875.9 10894.1 10932.3 11062.8 776.8 804.9 821.2 833.0 841.5 816.6 826.0 827.4 827.7 832.3 856.0 865.5 866.1 869.0 870.5 856.7 862.2 862.5 863.7 867.7 639.2 639.5 641.0 641.9 642.0 11406.1 11422.4 11441.4 11455.9 11462.1 10856.7 10911.0 11049.3 11285.3 11625.7 9103.0 9351.9 9430.8 9494.3 9565.5 3166.1 3202.5 3208.3 3210.9 3216.9 39843.6 40285.4 40405.0 40444.0 40491.0 8221.9 8728.7 8957.8 8980.5 9097.1 15480.6 15494.0 15537.6 15561.7 15561.9 5963.3 6049.4 6053.5 6219.7 6269.4 3324.1 3425.1 3449.2 3477.4 3527.9 2529.3 2561.5 2569.5 2572.3 2576.4 465.1 465.6 470.6 478.0 479.5 579.4 584.7 593.0 598.5 600.7 7972.2 7977.5 8001.0 8022.4 8107.8 587.8 598.3 608.5 618.2 623.1 3512.9 3722.7 3801.5 3865.7 3868.7 47905.3 48019.0 48179.5 48439.8 48791.0 43144.4 43159.0 43177.0 43527.3 43551.3 39591.4 39830.6 39847.0 40114.6 40174.6 7511.4 7617.9 7690.5 7704.7 7705.8 30071.0 30633.7 30674.4 31359.2 31515.5 36196.6 37160.0 38222.2 39657.4 39901.4 10195.2 10264.5 10287.6 10421.8 10581.6 11265.3 11302.2 11476.8 11504.3 11809.2 10388.9 10623.5 10963.6 10966.1 11091.8 10598.4 10690.2 10700.8 10745.8 11156.4 10518.6 10552.3 10564.4 10600.9 10647.5 9353.1 9428.0 9444.2 9611.8 9717.1 9111.2 9410.5 9478.1 9560.5 9614.2 06:28:14 INFO - 2015-12-16 06:28:14,396 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:28:14 INFO - 2015-12-16 06:28:14,396 DEBUG : BROWSER_OUTPUT: 06:28:15 INFO - 2015-12-16 06:28:15,015 INFO : Browser exited with error code: 0 06:28:15 INFO - 2015-12-16 06:28:15,033 INFO : Completed test dromaeo_css (00:18:34) 06:28:15 INFO - 2015-12-16 06:28:15,033 INFO : Starting test kraken 06:28:15 INFO - 2015-12-16 06:28:15,033 DEBUG : operating with platform_type : linux_ 06:28:15 INFO - 2015-12-16 06:28:15,033 INFO : Initialising browser for kraken test... 06:28:15 INFO - 2015-12-16 06:28:15,039 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY3dXtP/profile http://localhost:37484/getInfo.html 06:28:19 INFO - 2015-12-16 06:28:19,476 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:28:19 INFO - 2015-12-16 06:28:19,476 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:28:19 INFO - 2015-12-16 06:28:19,477 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:28:19 INFO - 2015-12-16 06:28:19,477 DEBUG : BROWSER_OUTPUT: __metrics 06:28:19 INFO - 2015-12-16 06:28:19,613 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] 06:28:20 INFO - 2015-12-16 06:28:20,541 INFO : Browser initialized. 06:28:20 INFO - 2015-12-16 06:28:20,542 INFO : Running cycle 1/1 for kraken test... 06:28:20 INFO - 2015-12-16 06:28:20,542 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY3dXtP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:28:32 INFO - 2015-12-16 06:28:32,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 143015936 06:28:32 INFO - 2015-12-16 06:28:32,599 DEBUG : BROWSER_OUTPUT: 06:28:35 INFO - 2015-12-16 06:28:35,178 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37484/tests/kraken/driver.html?testName=audio-beat-detection) 06:28:35 INFO - 2015-12-16 06:28:35,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 143306752 06:28:35 INFO - 2015-12-16 06:28:35,800 DEBUG : BROWSER_OUTPUT: 06:28:38 INFO - 2015-12-16 06:28:38,548 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37484/tests/kraken/driver.html?testName=audio-dft) 06:28:39 INFO - 2015-12-16 06:28:39,151 DEBUG : BROWSER_OUTPUT: RSS: Main: 143761408 06:28:39 INFO - 2015-12-16 06:28:39,152 DEBUG : BROWSER_OUTPUT: 06:28:42 INFO - 2015-12-16 06:28:42,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37484/tests/kraken/driver.html?testName=audio-fft) 06:28:42 INFO - 2015-12-16 06:28:42,645 DEBUG : BROWSER_OUTPUT: RSS: Main: 138407936 06:28:42 INFO - 2015-12-16 06:28:42,645 DEBUG : BROWSER_OUTPUT: 06:28:44 INFO - 2015-12-16 06:28:44,746 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37484/tests/kraken/driver.html?testName=audio-oscillator) 06:28:45 INFO - 2015-12-16 06:28:45,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 137936896 06:28:45 INFO - 2015-12-16 06:28:45,328 DEBUG : BROWSER_OUTPUT: 06:28:47 INFO - 2015-12-16 06:28:47,587 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37484/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:28:48 INFO - 2015-12-16 06:28:48,172 DEBUG : BROWSER_OUTPUT: RSS: Main: 138625024 06:28:48 INFO - 2015-12-16 06:28:48,173 DEBUG : BROWSER_OUTPUT: 06:28:51 INFO - 2015-12-16 06:28:51,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37484/tests/kraken/driver.html?testName=imaging-darkroom) 06:28:51 INFO - 2015-12-16 06:28:51,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 139931648 06:28:51 INFO - 2015-12-16 06:28:51,846 DEBUG : BROWSER_OUTPUT: 06:28:55 INFO - 2015-12-16 06:28:55,328 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37484/tests/kraken/driver.html?testName=imaging-desaturate) 06:28:55 INFO - 2015-12-16 06:28:55,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 138936320 06:28:55 INFO - 2015-12-16 06:28:55,924 DEBUG : BROWSER_OUTPUT: 06:28:58 INFO - 2015-12-16 06:28:58,828 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37484/tests/kraken/driver.html?testName=json-parse-financial) 06:28:59 INFO - 2015-12-16 06:28:59,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 139214848 06:28:59 INFO - 2015-12-16 06:28:59,421 DEBUG : BROWSER_OUTPUT: 06:29:01 INFO - 2015-12-16 06:29:01,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37484/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:29:01 INFO - 2015-12-16 06:29:01,793 DEBUG : BROWSER_OUTPUT: RSS: Main: 140279808 06:29:01 INFO - 2015-12-16 06:29:01,794 DEBUG : BROWSER_OUTPUT: 06:29:03 INFO - 2015-12-16 06:29:03,729 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:29:04 INFO - 2015-12-16 06:29:04,351 DEBUG : BROWSER_OUTPUT: RSS: Main: 139309056 06:29:04 INFO - 2015-12-16 06:29:04,351 DEBUG : BROWSER_OUTPUT: 06:29:06 INFO - 2015-12-16 06:29:06,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:29:07 INFO - 2015-12-16 06:29:07,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 139550720 06:29:07 INFO - 2015-12-16 06:29:07,155 DEBUG : BROWSER_OUTPUT: 06:29:09 INFO - 2015-12-16 06:29:09,764 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:29:10 INFO - 2015-12-16 06:29:10,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 140595200 06:29:10 INFO - 2015-12-16 06:29:10,378 DEBUG : BROWSER_OUTPUT: 06:29:13 INFO - 2015-12-16 06:29:13,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:29:13 INFO - 2015-12-16 06:29:13,642 DEBUG : BROWSER_OUTPUT: RSS: Main: 140492800 06:29:13 INFO - 2015-12-16 06:29:13,643 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,398 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37484/tests/kraken/driver.html?testName=ai-astar) 06:29:15 INFO - 2015-12-16 06:29:15,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 139784192 06:29:15 INFO - 2015-12-16 06:29:15,656 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,656 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;109;115;113;115;125;114;112;114;114 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;140;154;161;133;157;161;134;173;132 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;164;165;163;162;164;171;162;166;163 06:29:15 INFO - 2015-12-16 06:29:15,657 DEBUG : BROWSER_OUTPUT: |3;audio-fft;77;72;72;73;148;75;79;71;72;150 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;97;137;94;96;96;134;95;95;135 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;111;109;110;109;110;110;110;110 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;146;147;148;148;147;148;148;148 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;96;97;100;96;96;95;96;96;95 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;72;75;75;76;72;72;73;73;77 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;67;68;68;68;69;69;68;68;68 06:29:15 INFO - 2015-12-16 06:29:15,658 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;88;85;85;85;86;87;91;86;105;101 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;138;121;132;122;123;122;132;129;135;131 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;166;150;154;154;138;156;150;154;146 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;72;70;72;71;72;74;71;70;73 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,788 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:29:15 INFO - 2015-12-16 06:29:15,659 DEBUG : BROWSER_OUTPUT: __startTimestamp1450276155651__endTimestamp 06:29:15 INFO - 2015-12-16 06:29:15,660 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,661 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:29:15 INFO - 2015-12-16 06:29:15,661 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:29:15 INFO - 2015-12-16 06:29:15,661 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,661 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.50 Median:114.00 stddev:4.09 (3.6%) stddev-sans-first:4.33 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: Values: 114.0 109.0 115.0 113.0 115.0 125.0 114.0 112.0 114.0 114.0 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.60 Median:155.50 stddev:14.16 (9.1%) stddev-sans-first:15.01 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: Values: 151.0 140.0 154.0 161.0 133.0 157.0 161.0 134.0 173.0 132.0 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:2.63 (1.6%) stddev-sans-first:2.79 06:29:15 INFO - 2015-12-16 06:29:15,662 DEBUG : BROWSER_OUTPUT: Values: 164.0 164.0 165.0 163.0 162.0 164.0 171.0 162.0 166.0 163.0 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.90 Median:72.50 stddev:31.78 (43.8%) stddev-sans-first:33.41 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: Values: 77.0 72.0 72.0 73.0 148.0 75.0 79.0 71.0 72.0 150.0 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.40 Median:95.00 stddev:19.31 (20.3%) stddev-sans-first:19.95 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: Values: 95.0 97.0 137.0 94.0 96.0 96.0 134.0 95.0 95.0 135.0 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,663 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 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 111.0 109.0 110.0 109.0 110.0 110.0 110.0 110.0 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.71 (0.5%) stddev-sans-first:0.73 06:29:15 INFO - 2015-12-16 06:29:15,663 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 146.0 147.0 148.0 148.0 147.0 148.0 148.0 148.0 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:1.43 (1.5%) stddev-sans-first:1.50 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 97.0 100.0 96.0 96.0 95.0 96.0 96.0 95.0 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.00 Median:75.00 stddev:1.83 (2.4%) stddev-sans-first:1.90 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: Values: 75.0 72.0 75.0 75.0 76.0 72.0 72.0 73.0 73.0 77.0 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: Values: 69.0 67.0 68.0 68.0 68.0 69.0 69.0 68.0 68.0 68.0 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:86.00 stddev:7.20 (8.4%) stddev-sans-first:7.61 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: Values: 88.0 85.0 85.0 85.0 86.0 87.0 91.0 86.0 105.0 101.0 06:29:15 INFO - 2015-12-16 06:29:15,664 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:131.50 stddev:6.10 (4.6%) stddev-sans-first:5.41 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: Values: 138.0 121.0 132.0 122.0 123.0 122.0 132.0 129.0 135.0 131.0 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.90 Median:154.00 stddev:7.98 (5.2%) stddev-sans-first:7.62 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: Values: 141.0 166.0 150.0 154.0 154.0 138.0 156.0 150.0 154.0 146.0 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:72.00 stddev:1.51 (2.1%) stddev-sans-first:1.32 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: Values: 69.0 72.0 70.0 72.0 71.0 72.0 74.0 71.0 70.0 73.0 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:29:15 INFO - 2015-12-16 06:29:15,665 DEBUG : BROWSER_OUTPUT: 06:29:16 INFO - 2015-12-16 06:29:16,626 INFO : Browser exited with error code: 0 06:29:16 INFO - 2015-12-16 06:29:16,674 INFO : Completed test kraken (00:01:01) 06:29:16 INFO - 2015-12-16 06:29:16,674 INFO : Starting test v8_7 06:29:16 INFO - 2015-12-16 06:29:16,674 DEBUG : operating with platform_type : linux_ 06:29:16 INFO - 2015-12-16 06:29:16,674 INFO : Initialising browser for v8_7 test... 06:29:16 INFO - 2015-12-16 06:29:16,680 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzzfefc/profile http://localhost:37484/getInfo.html 06:29:21 INFO - 2015-12-16 06:29:21,205 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:29:21 INFO - 2015-12-16 06:29:21,206 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:29:21 INFO - 2015-12-16 06:29:21,206 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:29:21 INFO - 2015-12-16 06:29:21,206 DEBUG : BROWSER_OUTPUT: __metrics 06:29:21 INFO - 2015-12-16 06:29:21,370 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] 06:29:22 INFO - 2015-12-16 06:29:22,297 INFO : Browser initialized. 06:29:22 INFO - 2015-12-16 06:29:22,298 INFO : Running cycle 1/1 for v8_7 test... 06:29:22 INFO - 2015-12-16 06:29:22,298 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzzfefc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:29:34 INFO - 2015-12-16 06:29:34,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 143187968 06:29:34 INFO - 2015-12-16 06:29:34,305 DEBUG : BROWSER_OUTPUT: 06:29:55 INFO - 2015-12-16 06:29:55,771 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37484/tests/v8_7/run.html (next: http://localhost:37484/tests/v8_7/run.html) 06:29:56 INFO - 2015-12-16 06:29:56,029 DEBUG : BROWSER_OUTPUT: RSS: Main: 138534912 06:29:56 INFO - 2015-12-16 06:29:56,029 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,029 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:29:56 INFO - 2015-12-16 06:29:56,029 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:29:56 INFO - 2015-12-16 06:29:56,029 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:29:56 INFO - 2015-12-16 06:29:56,029 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |0;Richards;165.45334215751157 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.8281220026856 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.00945232137894 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1069.51871657754 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |5;Earley;718.3908045977012 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |6;Boyer;12530.864197530864 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |7;RegExp;26102.5641025641 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |8;Splay;610.5006105006105 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:29:56 INFO - 2015-12-16 06:29:56,030 DEBUG : BROWSER_OUTPUT: __startTimestamp1450276196023__endTimestamp 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.45 Median:165.45 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: Values: 165.5 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.83 Median:191.83 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: Values: 191.8 06:29:56 INFO - 2015-12-16 06:29:56,031 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.01 Median:278.01 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: Values: 278.0 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: Values: 5191.7 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1069.52 Median:1069.52 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: Values: 1069.5 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:718.39 Median:718.39 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: Values: 718.4 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12530.86 Median:12530.86 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,032 DEBUG : BROWSER_OUTPUT: Values: 12530.9 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26102.56 Median:26102.56 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: Values: 26102.6 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:610.50 Median:610.50 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: Values: 610.5 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: Values: 4840.6 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:29:56 INFO - 2015-12-16 06:29:56,033 DEBUG : BROWSER_OUTPUT: 06:29:56 INFO - 2015-12-16 06:29:56,950 INFO : Browser exited with error code: 0 06:29:56 INFO - 2015-12-16 06:29:56,968 INFO : Completed test v8_7 (00:00:40) 06:29:56 INFO - 2015-12-16 06:29:56,974 INFO : Completed test suite (00:20:15) 06:29:56 INFO - 2015-12-16 06:29:56,975 DEBUG : Working with test: dromaeo_css 06:29:56 INFO - 2015-12-16 06:29:56,975 DEBUG : Generating results file: dromaeo_css 06:29:56 INFO - 2015-12-16 06:29:56,976 DEBUG : Working with test: kraken 06:29:56 INFO - 2015-12-16 06:29:56,976 DEBUG : Generating results file: kraken 06:29:56 INFO - 2015-12-16 06:29:56,976 INFO : javascript benchmark 06:29:56 INFO - 2015-12-16 06:29:56,976 DEBUG : Working with test: v8_7 06:29:56 INFO - 2015-12-16 06:29:56,977 DEBUG : Generating results file: v8_7 06:29:56 INFO - 2015-12-16 06:29:56,977 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:29:57 INFO - 2015-12-16 06:29:57,035 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 06:29:57 INFO - 2015-12-16 06:29:57,036 DEBUG : process_Request line: dromaeo_css 6352.27 graph.html#tests=[[72,132,43]] 06:29:57 INFO - 2015-12-16 06:29:57,036 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:29:57 INFO - 2015-12-16 06:29:57,068 DEBUG : process_Request line: kraken 1561.60 graph.html#tests=[[232,132,43]] 06:29:57 INFO - 2015-12-16 06:29:57,068 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:29:57 INFO - 2015-12-16 06:29:57,108 DEBUG : process_Request line: v8_7 21199.98 graph.html#tests=[[230,132,43]] 06:29:57 INFO - 2015-12-16 06:29:57,109 INFO : javascript benchmark 06:29:57 INFO - 2015-12-16 06:29:57,113 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2680.7453291937263, 2732.5764506501473, 2748.0107161421897, 2755.2285360099168, 2775.1258353618614, 4714.151452738506, 4753.263244197151, 4786.042791441712, 4792.760361981896, 4800.303955926389, 4691.788869500872, 4766.833160839371, 4787.162246606838, 4808.615310775138, 4843.878903027426, 4344.608985191332, 4410.603045725884, 4487.46150461945, 4492.573205545485, 4508.504064552896, 4265.210935976838, 4437.046035102452, 4474.597286244238, 4518.480374756896, 4581.496035436099, 4349.652027837781, 4357.651387888961, 4499.167653983991, 4511.548845115495, 4530.75080870552, 4496.752678602708, 4510.210713125222, 4517.570830771071, 4522.819087236507, 4531.116432295704, 4244.0, 4336.869893903188, 4456.131054444352, 4463.620592249663, 4484.035932274576, 4603.608693261043, 4655.068986202773, 4685.835995740167, 4702.3181638662545, 4707.176244157293, 5132.127538318495, 5147.150720131174, 5163.251328557338, 5183.429822719498, 5185.0148471790235, 4956.1822299320565, 5054.368203974504, 5066.721330326831, 5203.557697595709, 5267.710275934822, 4832.903341933177, 4835.081334546425, 4852.417709874767, 4863.440704319013, 4903.190973489405, 5303.628745987821, 5329.973350133225, 5333.5199832015305, 5334.653247538898, 5336.146216605324, 4828.203346447867, 4944.184209605447, 4951.801927922843, 5101.056304583664, 5111.87220319495, 5157.819476318386, 5170.97414512925, 5187.870303242374, 5189.221616757516, 5201.375834899836, 5126.077306084866, 5129.179331306973, 5131.589472842164, 5137.974310128426, 5183.196604526229, 4878.487758785347, 4904.558589726942, 5001.324821149171, 5003.3495645565845, 5017.347744793226, 4263.786810659454, 4314.374415709704, 4314.633256173198, 4316.093620339701, 4351.282038463682, 3757.830897609614, 3764.7741135531955, 3783.621637836194, 3788.5264341957254, 3806.314863324573, 4282.507511636201, 4362.17118747437, 4364.323529852878, 4437.955620443754, 4463.12968971054, 5163.096458119814, 5224.738763061831, 5225.111731005738, 5227.2420499028185, 5251.474852514794, 4222.767747773841, 4444.73331600105, 4478.865634030984, 4484.260097083945, 4526.479454862667, 4462.933056004163, 4514.887127821765, 4569.360289559464, 4608.677392582554, 4647.18674232008, 4932.309476673268, 4953.603711702983, 4956.0, 4959.528844759742, 4975.875603109951, 4973.179425394914, 5091.898162036812, 5221.843344699665, 5238.371395432572, 5287.550558202596, 4824.782884770124, 4867.245576935581, 4875.853724388274, 4890.144224760724, 4927.014597080527, 5085.6185786066935, 5122.974385128199, 5129.410117836496, 5130.384353877558, 5181.06740786647, 4898.093852637274, 5012.699238045729, 5016.197408414636, 5199.090159222045, 5227.372715274191, 4879.121758083437, 4893.266010098514, 4926.137925862888, 4994.175960966545, 5035.471275516018, 4931.654784165074, 4981.153203955409, 4987.376577927759, 4994.600431965507, 5024.42219144803, 4734.479207287264, 4885.609151267961, 4898.853034408973, 4913.975430122827, 4920.753962301942, 4901.681390709592, 4979.3526841510375, 4991.975040124777, 5019.52314530119, 5042.571381432473, 4878.756062196947, 4913.361263035782, 4919.385076865392, 4924.581410580141, 4964.851054468371], "unit": "score", "name": "dojo.html", "value": 4712.962408585349}, {"lowerIsBetter": false, "replicates": [547.4853637580674, 559.9748011339491, 574.2592056247444, 589.2545929399308, 616.260487415102, 35020.64979350206, 35558.0, 35727.82136089316, 35742.463863042045, 35765.46351804725, 36341.81829090851, 36782.632173678256, 36870.63129368705, 36912.630873691254, 36922.07694807631, 7508.624568771567, 7755.1469338372735, 7765.107012693527, 7775.066991960973, 7781.961090194541, 7510.774676759706, 7545.056867891513, 7580.014598102239, 7580.05249343832, 7605.543667379948, 7584.089909210902, 7697.961510192441, 7724.9613751931165, 7733.7293194738195, 7740.187280335568, 7558.508696934709, 7591.0, 7598.620068996555, 7599.506032107895, 7607.543547387175, 7394.37147842434, 7462.10454745434, 7503.249675032508, 7553.0, 7577.962110189414, 33321.50017749736, 33754.49368259478, 33931.15172120692, 34007.31985360279, 34178.48732269018, 2809.2836326736756, 2932.208303757973, 2955.807872488303, 3028.3186283086347, 3515.2793677296095, 26112.086076987212, 26120.0, 26470.86764566165, 26647.86676066607, 27408.17775466739, 3430.5368775215525, 3436.0894362993827, 3479.165000399911, 3489.860405583799, 3547.0245682437535, 211054.0, 211787.0, 212418.99999999846, 213734.0, 213949.0, 50444.24333634999, 51885.22172167421, 56232.0, 57661.71169144211, 57688.42311576915, 48616.0, 48727.269090963666, 48863.0, 48902.510974889796, 48907.51092489101, 13193.274369909746, 13569.457221711218, 13574.117682350616, 13575.38910748999, 13587.0, 10641.574337026586, 10878.782424351468, 10894.891051089387, 10906.400147991779, 10969.23215374916, 18941.147648355858, 19191.136398862083, 19221.90389048046, 19292.710609340873, 19349.226030958886, 7622.885656715153, 7856.685732570748, 7921.405894557931, 7933.841323173504, 8043.316318112909, 802.1617409806765, 854.073330436471, 922.9630814767335, 992.1467537917385, 1013.7921726046143, 26453.86773066161, 26691.599626005627, 26975.0, 26978.865105674347, 27223.591646125325, 945.1635302756972, 969.2294625772387, 1059.1897198643044, 1064.1539975719322, 1085.79369919715, 13708.3831227595, 13829.723405531833, 13970.580882573538, 14061.78907316391, 14081.014328997077, 254983.0, 257030.0, 258034.0, 258554.0, 260511.0, 44943.32585011228, 45784.0, 45838.0, 45974.77012614982, 46024.539754602694, 1227.766724322376, 1229.6434034130002, 1234.3149551998727, 1239.3555351217497, 1239.367922359585, 25475.87262063678, 25927.870360648078, 26265.343366415993, 26415.339616508973, 26602.20193394201, 39482.0, 39623.207535848545, 39678.0, 39682.80158599189, 39857.40213896852, 243013.0, 245529.0, 247105.0, 247636.0, 247782.0, 29163.7083629161, 29830.403391932476, 29836.85081574665, 29935.850320748257, 29996.400071998003, 38608.0, 38873.80563097166, 38918.80540597392, 39691.0, 39846.40230396488, 32630.184245394055, 33283.66716332806, 33461.83269083639, 33934.490982635776, 34187.829060854536, 33610.49584256287, 33804.32391352209, 34017.82991085029, 34248.82875585606, 34427.4835877457, 33455.83272083624, 34013.0, 34075.65924340824, 34200.0, 34318.65681343254, 28652.854654441722, 31056.534151987285, 31065.069537102787, 31138.22154446157, 31300.843495782374, 24262.393440164138, 24464.287499874838, 24519.63220551729, 24610.0, 27102.186934392], "unit": "score", "name": "ext.html", "value": 16704.227322947492}, {"lowerIsBetter": false, "replicates": [340.22089415239105, 344.3027868566154, 344.59682171858924, 344.6880573081361, 344.8051850704352, 15704.371825126995, 16007.439739609115, 16018.75971860423, 16487.91756041221, 17373.218205180765, 9583.329166958316, 9679.467629280387, 9682.0, 9785.412875227492, 10017.148542373907, 4498.4376952880975, 4603.516630753773, 4606.861794146181, 4613.331066995283, 4624.0, 4524.705894116872, 4983.327250821129, 5119.0, 5125.461826508219, 5131.486851314876, 3513.455414410758, 3593.6047034826147, 5090.898182036357, 5140.691558506483, 5173.637845350827, 5310.9734451327495, 5336.332958380202, 5357.357117145967, 5357.785688572452, 5367.436419176009, 4673.953260467386, 4809.422869255677, 4812.230043193107, 5138.511841375063, 5181.740912954338, 18033.368832090815, 18163.818361816346, 18274.086295685163, 18293.451196464128, 18412.263509459604, 1707.6670049340385, 1799.7750281214849, 1831.8534517238588, 1837.2283640870867, 1940.5439721665398, 8752.956235218782, 8921.598528066253, 9123.635054597808, 11188.38463884486, 12812.55156069533, 1685.5364774686936, 1692.8814982951199, 1724.5343757185615, 1741.5471977285997, 1758.5955230297104, 53070.0, 53539.19691204635, 53734.19398709023, 54669.72665136649, 55757.44242557563, 9550.522473876279, 10169.288149829588, 10170.593176273014, 10209.081182693593, 10222.6422075227, 12667.30329831868, 12673.809892851614, 12763.808542871864, 12764.744705105846, 12825.102242842911, 6928.44572434204, 6977.51157418986, 7013.2986701329455, 7155.141383033965, 7289.489735718449, 6685.6322902240845, 6717.496187785936, 6733.259341472434, 6807.047013418125, 6941.618210998444, 4279.700420970565, 4288.50682171548, 4336.34954756789, 4369.584889435499, 4379.3430985351815, 1656.1139790212178, 1780.2967827707982, 1798.9910050449664, 1817.2276782367442, 1821.3625231168985, 380.3420083255983, 381.47547122706277, 398.8683734367652, 849.864021756516, 905.9320550958704, 9827.0, 10111.140553052926, 10161.708923936883, 10405.427701476492, 10425.11386532138, 397.14613580801506, 825.7925867704039, 896.551724137931, 932.776133727914, 952.9618815247451, 8632.223099920911, 8817.426867253607, 8908.688195913111, 8923.41997770143, 9006.59470323837, 452186.0, 460021.0, 461481.0, 462554.0, 463435.0, 69439.0, 70086.299137009, 72471.0, 72999.63500182464, 73500.0, 1111.7387413957713, 1126.2622981946838, 1126.8929451702074, 1137.7655244060122, 1138.4023387721513, 13534.526291579747, 13586.048976571941, 13787.103838250481, 13856.515021974183, 13930.233837139054, 29210.269743256587, 30594.847025764728, 30705.078847634606, 30707.539386909662, 30744.231394215323, 41795.7910210447, 42072.36891446692, 43402.0, 43449.782751086044, 43751.0, 8950.910490895008, 9013.709995851597, 9101.0, 9159.49622770754, 9189.77025574366, 16463.34146634121, 16522.339106435607, 16732.330706771594, 16823.495295141165, 16993.0, 11564.363959982282, 13892.930535347663, 14086.718265634425, 14102.435902563782, 14465.204413756923, 13441.932790335986, 13611.11527750693, 13740.381682824096, 13896.791548126988, 14131.15213087218, 13337.333133343489, 13846.307684615931, 14017.088889222168, 14049.438022478986, 14510.419583216975, 8871.467711937305, 8957.641694332153, 9029.322800789836, 9220.769480763034, 9229.077092290718, 11753.882461175279, 13848.723025539633, 14110.78833817473, 14277.928610356881, 14325.212113333866], "unit": "score", "name": "jquery.html", "value": 7986.926438747141}, {"lowerIsBetter": false, "replicates": [1462.4588902106223, 1692.9238184281705, 1730.1176400035986, 1744.5028166972415, 1756.4203812741794, 6112.572119951606, 6318.336574659664, 6854.82862928427, 7386.298301661341, 7421.5176013559085, 6892.241853396123, 7121.181064177621, 7173.139223293199, 7189.424846012306, 7191.065161529007, 2053.127420846138, 2082.094288984289, 2090.0490276923965, 2092.5187206942414, 2094.0890712540017, 2071.513194399315, 2089.968650470244, 2110.641190997534, 2137.9144834206613, 2142.003968154808, 2095.5913596848623, 2098.2026829804654, 2104.3686893931836, 2127.1278775701967, 2142.646463333548, 2084.0725876984748, 2099.1603358656507, 2099.653557163066, 2106.7577228618675, 2107.083418712857, 2044.1210279579775, 2063.886486243256, 2075.63676356637, 2092.435042538506, 2094.5077906691913, 7160.4271658267735, 7195.6042417667, 7214.9278507214785, 7230.421566274686, 7241.203467618558, 4148.170365926827, 4186.037211441386, 4198.853040143611, 4211.873643790691, 4212.68404869636, 2656.2296933889147, 2678.1162216468715, 2708.214617760847, 2733.070755942969, 2734.644496215479, 852.1393392673383, 870.0038455967945, 876.1282523888772, 876.3383645347785, 917.655879045358, 9806.852897206547, 9825.950870245602, 9867.358621689567, 9882.11061004513, 9898.455584942825, 3892.922141557153, 3905.863294784669, 3931.941020884689, 3953.723239373216, 4043.5147782265726, 8462.492250464991, 8524.70163544273, 8534.87197692035, 8553.101924297984, 8576.485410875368, 4202.159568086395, 4204.096119334357, 4210.59999300066, 4214.704970652025, 4217.641500472494, 3190.7128358447385, 3274.099622603803, 3694.9076273093387, 3764.5858955514873, 3793.6206379362393, 3470.8264586771065, 3478.0435380270123, 3495.8426870790368, 3503.6146023937345, 3509.859605615798, 1070.8447275145215, 1076.687760549432, 1081.1891081688734, 1090.8091084060259, 1090.9345439273668, 1220.700928272579, 1220.9084318676134, 1230.864604893461, 1235.610782603477, 1242.658268976039, 6707.932920670731, 6742.6291553965, 6758.155230596176, 6884.483663725241, 7104.43164546835, 1030.1295405382439, 1037.113268155716, 1043.3114144664485, 1045.4981608827652, 1055.7888422315568, 5460.399356070829, 5515.090010148361, 5518.172274158908, 5525.696086715189, 5537.252470916455, 8643.697470588624, 8673.089325620846, 8799.384043117048, 8929.910700892908, 8967.237784788236, 8752.606132724042, 8794.34042446819, 8813.867791983126, 8816.162464565856, 8856.202941735275, 6659.833504162435, 6759.5944243344575, 6768.849295619756, 6770.0, 6770.93229067703, 6543.803685889431, 6543.869122617616, 6563.638999855054, 6571.112899758471, 6577.276499585138, 7568.924310756822, 7736.574488402966, 7823.882641760492, 7877.7636670899965, 7943.84112317762, 7787.571683557232, 8074.1522140176785, 8107.878381824159, 8155.347572194091, 8157.79605509867, 5348.786048558014, 5410.540104091197, 5800.187973683772, 6162.383761623802, 6250.499960003098, 6582.670866456754, 6789.660516974231, 6841.794746157623, 6892.207396149306, 6898.8620227596175, 6089.604175728564, 6211.0372892201785, 6235.0, 6320.715567799368, 6335.018072198816, 6349.333320001518, 6351.872962540816, 6433.967830160819, 6499.122618446449, 6510.023496475377, 6536.117624120873, 6549.443297319781, 6559.672016399066, 6586.209654841449, 6605.636689982097, 5955.612885162451, 5973.253343332083, 6004.8799024020145, 6011.128386384037, 6026.909596356146, 5928.110783382527, 5935.762569497172, 5960.284765828128, 5971.522278217645, 5982.641041537521], "unit": "score", "name": "mootools.html", "value": 4145.296945583724}, {"lowerIsBetter": false, "replicates": [305.9174023013786, 308.61731452998276, 310.6924145096355, 312.91082041618114, 316.7830036425048, 5569.108942569184, 5587.972060139698, 5692.0323544997345, 5708.315002199732, 5803.477687008169, 5615.775368985246, 5673.943260567393, 5699.971500142494, 5703.714814259291, 5705.828825135242, 4197.412362269285, 4323.178596066753, 4330.177266319405, 4332.06860524987, 4334.45819272591, 4291.0, 4312.568743125694, 4328.285832837578, 4344.152890186415, 4360.280553708634, 4269.252880745873, 4278.4438023056955, 4290.6138447539715, 4291.506476755182, 4292.1630282095, 4271.466066741657, 4290.334998075304, 4292.0342922842265, 4299.419578356944, 4308.784560771949, 3647.1429214134655, 3738.831752571141, 3744.8314825832904, 3745.6441638044344, 3746.587875333711, 5249.8425047248265, 5414.945850541483, 5415.350157981067, 5426.891462170774, 5434.510894019518, 4871.805127794884, 4878.902421951541, 4894.632902532324, 4903.117438861003, 4903.289023091632, 5943.108533719934, 5943.840951014554, 5958.13607026983, 5971.761129554812, 5976.282846058457, 3901.161250331164, 4010.3382941814566, 4023.718339716221, 4033.193361327746, 4070.409790580349, 6212.06818977157, 6276.780312689079, 6284.497240220817, 6297.559170858041, 6301.0, 4059.8579049733116, 4081.2653722329674, 4087.4686290782006, 4094.467719196485, 4100.91798164035, 4154.086101057763, 4158.438610787565, 4159.043420013414, 4162.439085342997, 4168.686863637944, 4726.078414709167, 4733.502982186889, 4829.203181475087, 4838.395200599925, 4842.104210721028, 5878.0, 5895.174675545427, 5914.852128696731, 5931.1992880960515, 5934.8813023739285, 1474.7050589882067, 1474.9336279867432, 1484.9331780069922, 1486.7769834524909, 1487.7693957436616, 970.5923512124924, 972.0619602084023, 974.6345120579782, 1002.5538635307219, 1024.3239461955075, 5133.255677926754, 5136.0, 5145.099607568735, 5159.561437277798, 5159.948400516022, 6150.169727086875, 6161.045038019114, 6161.815145545641, 6176.320604733476, 6176.505879529716, 5113.744312784346, 5130.384353877484, 5152.433232344439, 5160.045391602565, 5167.173252279617, 3230.725388342017, 3294.2917272786467, 3328.21786880083, 3334.0831271400075, 3336.9833150834093, 5882.23530940975, 5884.911726324109, 5888.087346461305, 5889.087191485327, 5899.705014749331, 6416.615003099829, 6421.614703117828, 6425.5502114851515, 6428.453581445629, 6438.806835794933, 5733.827985160368, 5864.824055278348, 5877.177195192677, 5878.559108066826, 5880.14737863016, 6192.287886893065, 6203.65879876593, 6206.193194884636, 6211.689415529115, 6221.875562488815, 6032.517398608188, 6042.0, 6042.788502402394, 6061.909071364022, 6071.666058366655, 5742.28221472316, 5833.445822646841, 5836.562257830765, 5877.706114694333, 5888.823335299948, 4047.8178481967793, 4133.855315063958, 4134.152498737812, 4140.37894315843, 4144.33690609501, 5938.138969849261, 6093.9390606093375, 6213.627182369072, 6218.160548325918, 6218.906716399348, 5751.1085781703905, 5852.912206316993, 5920.0, 5924.763009479572, 5938.851528711817, 5901.527877769682, 5928.288605367211, 5933.169356290036, 5935.584509084322, 5937.079752638348, 5684.857878553069, 5786.3056433228285, 5797.565182611237, 5798.159266906359, 5802.419758024333, 5955.76176952917, 5957.553183511167, 5972.462478376793, 5978.8206353809455, 6009.489193418608, 5793.710314484175, 5806.1000544915605, 5839.240898683314, 5854.707264636836, 5868.09044598088], "unit": "score", "name": "prototype.html", "value": 4415.445808942754}, {"lowerIsBetter": false, "replicates": [659.2254101430817, 677.451264475775, 736.6537727268183, 737.0050431916912, 776.8135647444611, 11585.884141158585, 11765.647030589074, 11790.410479476035, 11807.881921180786, 11830.822537661941, 10863.565457381696, 10875.945620271888, 10894.074003709675, 10932.289401188917, 11062.834057489144, 776.8446310737875, 804.8752443371287, 821.2280456371022, 832.9583520823965, 841.5035129273728, 816.5713000674634, 825.9793505162359, 827.3587969323792, 827.735124760077, 832.305025303872, 856.0, 865.5412631305387, 866.1468453573225, 868.9913100869005, 870.4907629037021, 856.7044369692446, 862.1637012098317, 862.5040601654074, 863.6718047142077, 867.7396780965717, 639.178655427773, 639.4979940746491, 640.9903851442232, 641.8940874755659, 642.0337392224718, 11406.087512999024, 11422.371769552754, 11441.370724610144, 11455.942720286344, 11462.083033357394, 10856.728581785439, 10911.0, 11049.28179668319, 11285.266457680225, 11625.651230463016, 9102.954485227598, 9351.90648093524, 9430.811383772287, 9494.287928405398, 9565.521723913846, 3166.145140811991, 3202.503611940153, 3208.3102133041507, 3210.887618933326, 3216.887408940676, 39843.60156398428, 40285.39571906424, 40405.0, 40444.0, 40491.0, 8221.876671849926, 8728.650853965897, 8957.820843583091, 8980.46117232968, 9097.13577210164, 15480.612984675474, 15494.0, 15537.611559711098, 15561.68876622484, 15561.922190388976, 5963.254593175853, 6049.3950604939155, 6053.4854537363935, 6219.720112594944, 6269.435750782361, 3324.1357247115925, 3425.0752296879705, 3449.223924616991, 3477.3914564951538, 3527.894163175109, 2529.2538701083226, 2561.5261176682, 2569.460413313188, 2572.305477521059, 2576.420305431263, 465.08146410839, 465.60656245472524, 470.6399604302711, 478.01528850567246, 479.49652864492145, 579.3829571506417, 584.7281014328357, 592.9809000353803, 598.4703537369372, 600.666630020343, 7972.202779721982, 7977.48146370484, 8000.959995199987, 8022.438429309892, 8107.797305067303, 587.759018802289, 598.2671227746064, 608.4523928464417, 618.245740196968, 623.1213988276509, 3512.8946131616094, 3722.664960153599, 3801.50580424543, 3865.729398942047, 3868.671162951124, 47905.281420778716, 48019.0, 48179.518204817505, 48439.757801211475, 48791.0, 43144.352834707504, 43159.0, 43177.0, 43527.34708979368, 43551.34672979908, 39591.40612890809, 39830.60169398327, 39847.0, 40114.59885401167, 40174.59825401767, 7511.3615342696485, 7617.923820761722, 7690.500117492345, 7704.6918123274445, 7705.845883082195, 30071.0, 30633.693663063084, 30674.386512269182, 31359.21601959969, 31515.52726709147, 36196.63803362038, 37160.0, 38222.23555528929, 39657.40513892352, 39901.401478977255, 10195.23535734808, 10264.486775661337, 10287.588496460057, 10421.843672344768, 10581.62964296246, 11265.324080554865, 11302.208845380745, 11476.827847582459, 11504.309741415542, 11809.23239989398, 10388.896111038794, 10623.468826558796, 10963.616273430393, 10966.06788422993, 11091.833622495831, 10598.417087060287, 10690.198235132551, 10700.83948740754, 10745.785084298113, 11156.442177891235, 10518.579256829642, 10552.314099583502, 10564.418956957441, 10600.893991059991, 10647.520861561094, 9353.064693530592, 9427.952860235655, 9444.197243234403, 9611.807763844823, 9717.076877696607, 9111.17999380059, 9410.529473526432, 9478.099580539838, 9560.468088505571, 9614.230861530921], "unit": "score", "name": "yui.html", "value": 5708.247828500515}], "name": "dromaeo_css", "value": 6352.272172055682}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 109.0, 115.0, 113.0, 115.0, 125.0, 114.0, 112.0, 114.0, 114.0], "unit": "score", "name": "ai-astar", "value": 114.5}, {"lowerIsBetter": true, "replicates": [151.0, 140.0, 154.0, 161.0, 133.0, 157.0, 161.0, 134.0, 173.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 149.6}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 165.0, 163.0, 162.0, 164.0, 171.0, 162.0, 166.0, 163.0], "unit": "score", "name": "audio-dft", "value": 164.4}, {"lowerIsBetter": true, "replicates": [77.0, 72.0, 72.0, 73.0, 148.0, 75.0, 79.0, 71.0, 72.0, 150.0], "unit": "score", "name": "audio-fft", "value": 88.9}, {"lowerIsBetter": true, "replicates": [95.0, 97.0, 137.0, 94.0, 96.0, 96.0, 134.0, 95.0, 95.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 107.4}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 111.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 146.0, 147.0, 148.0, 148.0, 147.0, 148.0, 148.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.5}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 97.0, 100.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [75.0, 72.0, 75.0, 75.0, 76.0, 72.0, 72.0, 73.0, 73.0, 77.0], "unit": "score", "name": "json-parse-financial", "value": 74.0}, {"lowerIsBetter": true, "replicates": [69.0, 67.0, 68.0, 68.0, 68.0, 69.0, 69.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.2}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 85.0, 85.0, 86.0, 87.0, 91.0, 86.0, 105.0, 101.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.9}, {"lowerIsBetter": true, "replicates": [138.0, 121.0, 132.0, 122.0, 123.0, 122.0, 132.0, 129.0, 135.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 128.5}, {"lowerIsBetter": true, "replicates": [141.0, 166.0, 150.0, 154.0, 154.0, 138.0, 156.0, 150.0, 154.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.9}, {"lowerIsBetter": true, "replicates": [69.0, 72.0, 70.0, 72.0, 71.0, 72.0, 74.0, 71.0, 70.0, 73.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.4}], "name": "kraken", "value": 1561.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.45334215751157], "unit": "score", "name": "Richards", "value": 213.36528799999996}, {"lowerIsBetter": false, "replicates": [191.8281220026856], "unit": "score", "name": "DeltaBlue", "value": 344.6731340000001}, {"lowerIsBetter": false, "replicates": [278.00945232137894], "unit": "score", "name": "Encrypt", "value": 957.4530570000002}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1069.51871657754], "unit": "score", "name": "RayTrace", "value": 691.889715}, {"lowerIsBetter": false, "replicates": [718.3908045977012], "unit": "score", "name": "Earley", "value": 927.7164960000002}, {"lowerIsBetter": false, "replicates": [12530.864197530864], "unit": "score", "name": "Boyer", "value": 53.185717241379315}, {"lowerIsBetter": false, "replicates": [26102.5641025641], "unit": "score", "name": "RegExp", "value": 34.90021119842827}, {"lowerIsBetter": false, "replicates": [610.5006105006105], "unit": "score", "name": "Splay", "value": 133.48225799999997}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21199.978314274707}]} 06:29:57 INFO - RETURN: dromaeo_css: 6352.27 06:29:57 INFO - RETURN: kraken: 1561.60 06:29:57 INFO - RETURN: v8_7: 21199.98 06:29:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21199.98"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1561.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6352.27"}}} 06:29:57 INFO - Return code: 0 06:29:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:29:57 INFO - # TBPL SUCCESS # 06:29:57 INFO - Running post-action listener: _resource_record_post_action 06:29:57 INFO - Running post-run listener: _resource_record_post_run 06:29:58 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 0; Write bytes: 509939712; Read time: 0; Write time: 412496 06:29:58 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 232308736; Read time: 0; Write time: 272696 06:29:58 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 0; Write bytes: 277630976; Read time: 0; Write time: 139800 06:29:58 INFO - Running post-run listener: _upload_blobber_files 06:29:58 INFO - Blob upload gear active. 06:29:58 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:29:58 INFO - Copying logs to upload dir... 06:29:58 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1283.316628 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2015-12-16 06:29:58.151567) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 06:29:58.154823) ========= 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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010090 build_url: 'https://queue.taskcluster.net/v1/task/acJ7vidKQDqnHtsJxi4p6w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 06:29:58.206472) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:29:58.206803) ========= 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=21869f6c619b95355032545b000001da-1450273727.750528-1062671518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004613 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 06:29:58.257757) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 06:29:58.258248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 06:29:58.258732) ========= ========= Total master_lag: 0.27 =========