builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-028 starttime: 1449834324.47 results: success (0) buildid: 20151211023240 builduid: e66f4db348bc43348f6c71b4c6727bea revision: 5562d39c90da9eb2de1ab212098cd5f1af1a6066 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.468372) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.468867) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.469258) ========= 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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006334 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.503637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.503996) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.546540) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.546837) ========= 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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004622 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.615496) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.615803) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.616192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.616530) ========= 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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-11 03:45:26-- 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% 43.4M=0s 2015-12-11 03:45:27 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.317569 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.951862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:24.952179) ========= 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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021073 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.005796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.006603) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5562d39c90da9eb2de1ab212098cd5f1af1a6066 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5562d39c90da9eb2de1ab212098cd5f1af1a6066 --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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-11 03:45:27,285 truncating revision to first 12 chars 2015-12-11 03:45:27,285 Setting DEBUG logging. 2015-12-11 03:45:27,285 attempt 1/10 2015-12-11 03:45:27,285 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5562d39c90da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-11 03:45:27,536 unpacking tar archive at: mozilla-central-5562d39c90da/testing/mozharness/ program finished with exit code 0 elapsedTime=0.549036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.573107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.573497) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.584136) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.584852) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-11 03:45:25.585723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-12-11 03:45:25.586540) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:45:27 INFO - MultiFileLogger online at 20151211 03:45:27 in /builds/slave/test 03:45:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:45:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:45:27 INFO - {'append_to_log': False, 03:45:27 INFO - 'base_work_dir': '/builds/slave/test', 03:45:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:45:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:45:27 INFO - 'branch': 'Firefox-Non-PGO', 03:45:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:45:27 INFO - 'config_files': ('talos/linux_config.py',), 03:45:27 INFO - 'default_actions': ('clobber', 03:45:27 INFO - 'read-buildbot-config', 03:45:27 INFO - 'download-and-extract', 03:45:27 INFO - 'populate-webroot', 03:45:27 INFO - 'create-virtualenv', 03:45:27 INFO - 'install', 03:45:27 INFO - 'run-tests'), 03:45:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:45:27 INFO - 'download_minidump_stackwalk': True, 03:45:27 INFO - 'download_symbols': 'ondemand', 03:45:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:45:27 INFO - 'tooltool.py': '/tools/tooltool.py', 03:45:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:45:27 INFO - '/tools/misc-python/virtualenv.py')}, 03:45:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:45:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:45:27 INFO - 'installer_path': 'installer.exe', 03:45:27 INFO - 'log_level': 'info', 03:45:27 INFO - 'log_name': 'talos', 03:45:27 INFO - 'log_to_console': True, 03:45:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:45:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:45:27 INFO - 'opt_config_files': (), 03:45:27 INFO - 'pip_index': False, 03:45:27 INFO - 'sps_profile': False, 03:45:27 INFO - 'sps_profile_interval': 0, 03:45:27 INFO - 'suite': 'dromaeojs-e10s', 03:45:27 INFO - 'system_bits': '64', 03:45:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:45:27 INFO - 'title': 'talos-linux64-ix-028', 03:45:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:45:27 INFO - 'use_talos_json': True, 03:45:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:45:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:45:27 INFO - 'work_dir': 'build'} 03:45:27 INFO - ##### 03:45:27 INFO - ##### Running clobber step. 03:45:27 INFO - ##### 03:45:27 INFO - Running pre-action listener: _resource_record_pre_action 03:45:27 INFO - Running main action method: clobber 03:45:27 INFO - rmtree: /builds/slave/test/build 03:45:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:45:28 INFO - Running post-action listener: _resource_record_post_action 03:45:28 INFO - ##### 03:45:28 INFO - ##### Running read-buildbot-config step. 03:45:28 INFO - ##### 03:45:28 INFO - Running pre-action listener: _resource_record_pre_action 03:45:28 INFO - Running main action method: read_buildbot_config 03:45:28 INFO - Using buildbot properties: 03:45:28 INFO - { 03:45:28 INFO - "properties": { 03:45:28 INFO - "buildnumber": 144, 03:45:28 INFO - "product": "firefox", 03:45:28 INFO - "script_repo_revision": "production", 03:45:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:45:28 INFO - "repository": "", 03:45:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:45:28 INFO - "buildid": "20151211023240", 03:45:28 INFO - "slavename": "talos-linux64-ix-028", 03:45:28 INFO - "pgo_build": "False", 03:45:28 INFO - "basedir": "/builds/slave/test", 03:45:28 INFO - "project": "", 03:45:28 INFO - "platform": "ubuntu64_hw", 03:45:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:45:28 INFO - "slavebuilddir": "test", 03:45:28 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 03:45:28 INFO - "branch": "mozilla-central", 03:45:28 INFO - "repo_path": "mozilla-central", 03:45:28 INFO - "stage_platform": "linux64", 03:45:28 INFO - "builduid": "e66f4db348bc43348f6c71b4c6727bea", 03:45:28 INFO - "revision": "5562d39c90da9eb2de1ab212098cd5f1af1a6066" 03:45:28 INFO - }, 03:45:28 INFO - "sourcestamp": { 03:45:28 INFO - "repository": "", 03:45:28 INFO - "hasPatch": false, 03:45:28 INFO - "project": "", 03:45:28 INFO - "branch": "mozilla-central-linux64-talos", 03:45:28 INFO - "changes": [ 03:45:28 INFO - { 03:45:28 INFO - "category": null, 03:45:28 INFO - "files": [ 03:45:28 INFO - { 03:45:28 INFO - "url": null, 03:45:28 INFO - "name": "https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 03:45:28 INFO - } 03:45:28 INFO - ], 03:45:28 INFO - "repository": "", 03:45:28 INFO - "rev": "5562d39c90da9eb2de1ab212098cd5f1af1a6066", 03:45:28 INFO - "who": "cbook@mozilla.com", 03:45:28 INFO - "when": 1449834279, 03:45:28 INFO - "number": 7326627, 03:45:28 INFO - "comments": "Bumping manifests a=b2g-bump", 03:45:28 INFO - "project": "", 03:45:28 INFO - "at": "Fri 11 Dec 2015 03:44:39", 03:45:28 INFO - "branch": "mozilla-central-linux64-talos", 03:45:28 INFO - "revlink": "", 03:45:28 INFO - "properties": [ 03:45:28 INFO - [ 03:45:28 INFO - "buildid", 03:45:28 INFO - "20151211023240", 03:45:28 INFO - "Change" 03:45:28 INFO - ], 03:45:28 INFO - [ 03:45:28 INFO - "builduid", 03:45:28 INFO - "e66f4db348bc43348f6c71b4c6727bea", 03:45:28 INFO - "Change" 03:45:28 INFO - ], 03:45:28 INFO - [ 03:45:28 INFO - "pgo_build", 03:45:28 INFO - "False", 03:45:28 INFO - "Change" 03:45:28 INFO - ] 03:45:28 INFO - ], 03:45:28 INFO - "revision": "5562d39c90da9eb2de1ab212098cd5f1af1a6066" 03:45:28 INFO - } 03:45:28 INFO - ], 03:45:28 INFO - "revision": "5562d39c90da9eb2de1ab212098cd5f1af1a6066" 03:45:28 INFO - } 03:45:28 INFO - } 03:45:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 03:45:28 INFO - Running post-action listener: _resource_record_post_action 03:45:28 INFO - ##### 03:45:28 INFO - ##### Running download-and-extract step. 03:45:28 INFO - ##### 03:45:28 INFO - Running pre-action listener: _resource_record_pre_action 03:45:28 INFO - Running main action method: download_and_extract 03:45:28 INFO - mkdir: /builds/slave/test/build/tests 03:45:28 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')]} 03:45:28 INFO - https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:45:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/test_packages.json 03:45:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/test_packages.json 03:45:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 03:45:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 03:45:34 INFO - Downloaded 1302 bytes. 03:45:34 INFO - Reading from file /builds/slave/test/build/test_packages.json 03:45:34 INFO - Using the following test package requirements: 03:45:34 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:45:34 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:45:34 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'jsshell-linux-x86_64.zip'], 03:45:34 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:45:34 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:45:34 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:45:34 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:45:34 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:45:34 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:45:34 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:45:34 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:45:34 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:45:34 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')]} 03:45:34 INFO - https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:45:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:45:36 INFO - Downloaded 21866764 bytes. 03:45:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 03:45:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:45:37 INFO - Return code: 0 03:45:37 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:37 INFO - https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:45:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:45:44 INFO - Downloaded 21866764 bytes. 03:45:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 03:45:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:45:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:45:45 INFO - Return code: 0 03:45:45 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')]} 03:45:45 INFO - https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:45:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:45:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:45:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:45:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:45:46 INFO - Downloaded 11132038 bytes. 03:45:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 03:45:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:45:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 03:45:46 INFO - Return code: 0 03:45: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')]} 03:45:46 INFO - https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:45:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:45:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:45:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:45:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:45:48 INFO - Downloaded 57727215 bytes. 03:45:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:45:48 INFO - mkdir: /builds/slave/test/properties 03:45:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:45:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:45:48 INFO - Contents: 03:45:48 INFO - build_url:https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:45:48 INFO - Running post-action listener: _resource_record_post_action 03:45:48 INFO - Running post-action listener: set_extra_try_arguments 03:45:48 INFO - ##### 03:45:48 INFO - ##### Running populate-webroot step. 03:45:48 INFO - ##### 03:45:48 INFO - Running pre-action listener: _resource_record_pre_action 03:45:48 INFO - Running main action method: populate_webroot 03:45:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:45:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:45:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:45:48 INFO - '--tppagecycles', 03:45:48 INFO - '7'], 03:45:48 INFO - 'tests': ['tsvgm']}}, 03:45:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:45:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:45:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:45:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:45:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'tests': ['damp', 'tps']}, 03:45:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['damp', 'tps']}, 03:45:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:45:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:45:48 INFO - 'other': {'tests': ['a11yr', 03:45:48 INFO - 'ts_paint', 03:45:48 INFO - 'tpaint', 03:45:48 INFO - 'sessionrestore', 03:45:48 INFO - 'sessionrestore_no_auto_restore']}, 03:45:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['a11yr', 03:45:48 INFO - 'ts_paint', 03:45:48 INFO - 'tpaint', 03:45:48 INFO - 'sessionrestore', 03:45:48 INFO - 'sessionrestore_no_auto_restore']}, 03:45:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['a11yr', 03:45:48 INFO - 'ts_paint', 03:45:48 INFO - 'tpaint', 03:45:48 INFO - 'sessionrestore', 03:45:48 INFO - 'sessionrestore_no_auto_restore']}, 03:45:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['a11yr', 03:45:48 INFO - 'ts_paint', 03:45:48 INFO - 'tpaint', 03:45:48 INFO - 'sessionrestore', 03:45:48 INFO - 'sessionrestore_no_auto_restore']}, 03:45:48 INFO - 'other_l64': {'tests': ['a11yr', 03:45:48 INFO - 'ts_paint', 03:45:48 INFO - 'tpaint', 03:45:48 INFO - 'sessionrestore', 03:45:48 INFO - 'sessionrestore_no_auto_restore']}, 03:45:48 INFO - 'other_nol64': {'tests': ['a11yr', 03:45:48 INFO - 'ts_paint', 03:45:48 INFO - 'tpaint', 03:45:48 INFO - 'sessionrestore', 03:45:48 INFO - 'sessionrestore_no_auto_restore']}, 03:45:48 INFO - 'svgr': {'tests': ['tsvgx', 03:45:48 INFO - 'tsvgr_opacity', 03:45:48 INFO - 'tart', 03:45:48 INFO - 'tscrollx', 03:45:48 INFO - 'cart']}, 03:45:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['tsvgx', 03:45:48 INFO - 'tsvgr_opacity', 03:45:48 INFO - 'tart', 03:45:48 INFO - 'tscrollx', 03:45:48 INFO - 'cart']}, 03:45:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'tests': ['tp5o']}, 03:45:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'talos_options': ['--e10s'], 03:45:48 INFO - 'tests': ['tp5o']}, 03:45:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'talos_options': ['--xperf_path', 03:45:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:48 INFO - 'tests': ['tp5n']}, 03:45:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:48 INFO - 'talos_options': ['--e10s', 03:45:48 INFO - '--xperf_path', 03:45:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:48 INFO - 'tests': ['tp5n']}}, 03:45:48 INFO - 'talos.zip': {'path': '', 03:45:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:45:48 INFO - Running post-action listener: _resource_record_post_action 03:45:48 INFO - ##### 03:45:48 INFO - ##### Running create-virtualenv step. 03:45:48 INFO - ##### 03:45:48 INFO - Running pre-action listener: _resource_record_pre_action 03:45:48 INFO - Running main action method: create_virtualenv 03:45:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:45:48 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 03:45:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:45:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:45:48 INFO - Using real prefix '/usr' 03:45:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:45:48 INFO - Installing distribute.............................................................................................................................................................................................done. 03:45:50 INFO - Installing pip.................done. 03:45:50 INFO - Return code: 0 03:45:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:45:50 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')]} 03:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:50 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')]} 03:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:50 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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:45:50 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 03:45:50 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 03:45:50 INFO - Using env: {'DISPLAY': ':0', 03:45:50 INFO - 'HOME': '/home/cltbld', 03:45:50 INFO - 'LANG': 'en_US.UTF-8', 03:45:50 INFO - 'LANGUAGE': 'en_US:en', 03:45:50 INFO - 'LOGNAME': 'cltbld', 03:45:50 INFO - 'MAIL': '/var/mail/cltbld', 03:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:50 INFO - 'MOZ_NO_REMOTE': '1', 03:45:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:50 INFO - 'NO_EM_RESTART': '1', 03:45:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:50 INFO - 'PWD': '/builds/slave/test', 03:45:50 INFO - 'SHELL': '/bin/bash', 03:45:50 INFO - 'SHLVL': '1', 03:45:50 INFO - 'TERM': 'linux', 03:45:50 INFO - 'TMOUT': '86400', 03:45:50 INFO - 'USER': 'cltbld', 03:45:50 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:50 INFO - '_': '/tools/buildbot/bin/python'} 03:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:50 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 03:45:50 INFO - Cleaning up... 03:45:51 INFO - Return code: 0 03:45:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:51 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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:45:51 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 03:45:51 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 03:45:51 INFO - Using env: {'DISPLAY': ':0', 03:45:51 INFO - 'HOME': '/home/cltbld', 03:45:51 INFO - 'LANG': 'en_US.UTF-8', 03:45:51 INFO - 'LANGUAGE': 'en_US:en', 03:45:51 INFO - 'LOGNAME': 'cltbld', 03:45:51 INFO - 'MAIL': '/var/mail/cltbld', 03:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:51 INFO - 'MOZ_NO_REMOTE': '1', 03:45:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:51 INFO - 'NO_EM_RESTART': '1', 03:45:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:51 INFO - 'PWD': '/builds/slave/test', 03:45:51 INFO - 'SHELL': '/bin/bash', 03:45:51 INFO - 'SHLVL': '1', 03:45:51 INFO - 'TERM': 'linux', 03:45:51 INFO - 'TMOUT': '86400', 03:45:51 INFO - 'USER': 'cltbld', 03:45:51 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:51 INFO - '_': '/tools/buildbot/bin/python'} 03:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:51 INFO - Downloading/unpacking psutil>=0.7.1 03:45:51 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 03:45:51 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 03:45:51 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 03:45:51 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 03:45:51 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 03:45:51 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 03:45:52 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 03:45:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:45:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:53 INFO - Installing collected packages: psutil 03:45:53 INFO - Running setup.py install for psutil 03:45:53 INFO - building 'psutil._psutil_linux' extension 03:45:53 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 03:45:53 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 03:45:53 INFO - building 'psutil._psutil_posix' extension 03:45:53 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 03:45:53 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 03:45:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:53 INFO - Successfully installed psutil 03:45:53 INFO - Cleaning up... 03:45:53 INFO - Return code: 0 03:45:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:45:53 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')]} 03:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:53 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')]} 03:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:53 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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:45:53 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 03:45:53 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 03:45:53 INFO - Using env: {'DISPLAY': ':0', 03:45:53 INFO - 'HOME': '/home/cltbld', 03:45:53 INFO - 'LANG': 'en_US.UTF-8', 03:45:53 INFO - 'LANGUAGE': 'en_US:en', 03:45:53 INFO - 'LOGNAME': 'cltbld', 03:45:53 INFO - 'MAIL': '/var/mail/cltbld', 03:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:53 INFO - 'MOZ_NO_REMOTE': '1', 03:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:53 INFO - 'NO_EM_RESTART': '1', 03:45:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:53 INFO - 'PWD': '/builds/slave/test', 03:45:53 INFO - 'SHELL': '/bin/bash', 03:45:53 INFO - 'SHLVL': '1', 03:45:53 INFO - 'TERM': 'linux', 03:45:53 INFO - 'TMOUT': '86400', 03:45:53 INFO - 'USER': 'cltbld', 03:45:53 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:53 INFO - '_': '/tools/buildbot/bin/python'} 03:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:45:53 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 03:45:53 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 03:45:53 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 03:45:53 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 03:45:53 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 03:45:53 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 03:45:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:45:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:45:55 INFO - Installing collected packages: mozsystemmonitor 03:45:55 INFO - Running setup.py install for mozsystemmonitor 03:45:55 INFO - Successfully installed mozsystemmonitor 03:45:55 INFO - Cleaning up... 03:45:55 INFO - Return code: 0 03:45:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:45: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')]} 03:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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')]} 03:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:55 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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:45:55 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 03:45:55 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 03:45:55 INFO - Using env: {'DISPLAY': ':0', 03:45:55 INFO - 'HOME': '/home/cltbld', 03:45:55 INFO - 'LANG': 'en_US.UTF-8', 03:45:55 INFO - 'LANGUAGE': 'en_US:en', 03:45:55 INFO - 'LOGNAME': 'cltbld', 03:45:55 INFO - 'MAIL': '/var/mail/cltbld', 03:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:55 INFO - 'MOZ_NO_REMOTE': '1', 03:45:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:55 INFO - 'NO_EM_RESTART': '1', 03:45:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:55 INFO - 'PWD': '/builds/slave/test', 03:45:55 INFO - 'SHELL': '/bin/bash', 03:45:55 INFO - 'SHLVL': '1', 03:45:55 INFO - 'TERM': 'linux', 03:45:55 INFO - 'TMOUT': '86400', 03:45:55 INFO - 'USER': 'cltbld', 03:45:55 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:55 INFO - '_': '/tools/buildbot/bin/python'} 03:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:55 INFO - Downloading/unpacking blobuploader==1.2.4 03:45:55 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 03:45:55 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 03:45:55 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 03:45:55 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 03:45:55 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 03:45:55 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 03:45:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:45:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:45:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:45:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 INFO - Downloading docopt-0.6.1.tar.gz 03:45:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:45:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:45:58 INFO - Running setup.py install for blobuploader 03:45:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:45:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:45:58 INFO - Running setup.py install for requests 03:45:58 INFO - Running setup.py install for docopt 03:45:58 INFO - Successfully installed blobuploader requests docopt 03:45:58 INFO - Cleaning up... 03:45:58 INFO - Return code: 0 03:45:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:58 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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:45:58 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 03:45:58 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 03:45:58 INFO - Using env: {'DISPLAY': ':0', 03:45:58 INFO - 'HOME': '/home/cltbld', 03:45:58 INFO - 'LANG': 'en_US.UTF-8', 03:45:58 INFO - 'LANGUAGE': 'en_US:en', 03:45:58 INFO - 'LOGNAME': 'cltbld', 03:45:58 INFO - 'MAIL': '/var/mail/cltbld', 03:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:58 INFO - 'MOZ_NO_REMOTE': '1', 03:45:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:58 INFO - 'NO_EM_RESTART': '1', 03:45:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:58 INFO - 'PWD': '/builds/slave/test', 03:45:58 INFO - 'SHELL': '/bin/bash', 03:45:58 INFO - 'SHLVL': '1', 03:45:58 INFO - 'TERM': 'linux', 03:45:58 INFO - 'TMOUT': '86400', 03:45:58 INFO - 'USER': 'cltbld', 03:45:58 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:58 INFO - '_': '/tools/buildbot/bin/python'} 03:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:59 INFO - Running setup.py (path:/tmp/pip-B5omTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:59 INFO - Running setup.py (path:/tmp/pip-oNPx36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:59 INFO - Running setup.py (path:/tmp/pip-t5r7eC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:59 INFO - Running setup.py (path:/tmp/pip-7spjtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:59 INFO - Running setup.py (path:/tmp/pip-Q2nUDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:59 INFO - Running setup.py (path:/tmp/pip-zJDUhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:59 INFO - Running setup.py (path:/tmp/pip-ctnVFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:59 INFO - Running setup.py (path:/tmp/pip-Zkrukg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:59 INFO - Running setup.py (path:/tmp/pip-J2OBeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:59 INFO - Running setup.py (path:/tmp/pip-VERy5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:59 INFO - Running setup.py (path:/tmp/pip-22W1C2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:59 INFO - Running setup.py (path:/tmp/pip-IxQx4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:59 INFO - Running setup.py (path:/tmp/pip-2FqLu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:00 INFO - Running setup.py (path:/tmp/pip-DhyHC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:00 INFO - Running setup.py (path:/tmp/pip-k6wEyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:00 INFO - Running setup.py (path:/tmp/pip-e14uq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:00 INFO - Running setup.py (path:/tmp/pip-Y98gF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:46:00 INFO - Running setup.py install for manifestparser 03:46:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:46:00 INFO - Running setup.py install for mozcrash 03:46:00 INFO - Running setup.py install for mozdebug 03:46:00 INFO - Running setup.py install for mozdevice 03:46:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:46:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:46:00 INFO - Running setup.py install for mozfile 03:46:00 INFO - Running setup.py install for mozhttpd 03:46:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:46:00 INFO - Running setup.py install for mozinfo 03:46:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Running setup.py install for mozInstall 03:46:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Running setup.py install for mozleak 03:46:01 INFO - Running setup.py install for mozlog 03:46:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Running setup.py install for moznetwork 03:46:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Running setup.py install for mozprocess 03:46:01 INFO - Running setup.py install for mozprofile 03:46:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Running setup.py install for mozrunner 03:46:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:46:01 INFO - Running setup.py install for mozscreenshot 03:46:01 INFO - Running setup.py install for moztest 03:46:01 INFO - Running setup.py install for mozversion 03:46:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:46:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:46:02 INFO - Cleaning up... 03:46:02 INFO - Return code: 0 03:46:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:46:02 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 03:46:02 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 03:46:02 INFO - Using env: {'DISPLAY': ':0', 03:46:02 INFO - 'HOME': '/home/cltbld', 03:46:02 INFO - 'LANG': 'en_US.UTF-8', 03:46:02 INFO - 'LANGUAGE': 'en_US:en', 03:46:02 INFO - 'LOGNAME': 'cltbld', 03:46:02 INFO - 'MAIL': '/var/mail/cltbld', 03:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:02 INFO - 'MOZ_NO_REMOTE': '1', 03:46:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:02 INFO - 'NO_EM_RESTART': '1', 03:46:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:02 INFO - 'PWD': '/builds/slave/test', 03:46:02 INFO - 'SHELL': '/bin/bash', 03:46:02 INFO - 'SHLVL': '1', 03:46:02 INFO - 'TERM': 'linux', 03:46:02 INFO - 'TMOUT': '86400', 03:46:02 INFO - 'USER': 'cltbld', 03:46:02 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:02 INFO - '_': '/tools/buildbot/bin/python'} 03:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:02 INFO - Running setup.py (path:/tmp/pip-a7JlgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:02 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)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:02 INFO - Running setup.py (path:/tmp/pip-kOUk14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:02 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)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:02 INFO - Running setup.py (path:/tmp/pip-f_VRgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:02 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)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:02 INFO - Running setup.py (path:/tmp/pip-YB6vmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:02 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)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:02 INFO - Running setup.py (path:/tmp/pip-vl0mOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:02 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)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:02 INFO - Running setup.py (path:/tmp/pip-FkGAjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:02 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)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:02 INFO - Running setup.py (path:/tmp/pip-7ZkYH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:02 INFO - Running setup.py (path:/tmp/pip-H9s0QM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:02 INFO - Running setup.py (path:/tmp/pip-1huCPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:02 INFO - Running setup.py (path:/tmp/pip-39YZSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:03 INFO - Running setup.py (path:/tmp/pip-QQddmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:03 INFO - Running setup.py (path:/tmp/pip-NgElB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:03 INFO - Running setup.py (path:/tmp/pip-_0VQpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:03 INFO - Running setup.py (path:/tmp/pip-mPFUXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:03 INFO - Running setup.py (path:/tmp/pip-8BW3Gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:03 INFO - Running setup.py (path:/tmp/pip-1GtcsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:03 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)) 03:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:03 INFO - Running setup.py (path:/tmp/pip-s1_sxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:03 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)) 03:46:03 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)) 03:46:03 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)) 03:46:03 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)) 03:46:03 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)) 03:46:03 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)) 03:46:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:05 INFO - Downloading blessings-1.5.1.tar.gz 03:46:05 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 03:46:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:46:05 INFO - Installing collected packages: blessings 03:46:05 INFO - Running setup.py install for blessings 03:46:05 INFO - Successfully installed blessings 03:46:05 INFO - Cleaning up... 03:46:05 INFO - Return code: 0 03:46:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:46:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:05 INFO - Reading from file tmpfile_stdout 03:46:05 INFO - Current package versions: 03:46:05 INFO - argparse == 1.2.1 03:46:05 INFO - blessings == 1.5.1 03:46:05 INFO - blobuploader == 1.2.4 03:46:05 INFO - docopt == 0.6.1 03:46:05 INFO - manifestparser == 1.1 03:46:05 INFO - mozInstall == 1.12 03:46:05 INFO - mozcrash == 0.16 03:46:05 INFO - mozdebug == 0.1 03:46:05 INFO - mozdevice == 0.47 03:46:05 INFO - mozfile == 1.2 03:46:05 INFO - mozhttpd == 0.7 03:46:05 INFO - mozinfo == 0.9 03:46:05 INFO - mozleak == 0.1 03:46:05 INFO - mozlog == 3.1 03:46:05 INFO - moznetwork == 0.27 03:46:05 INFO - mozprocess == 0.22 03:46:05 INFO - mozprofile == 0.27 03:46:05 INFO - mozrunner == 6.11 03:46:05 INFO - mozscreenshot == 0.1 03:46:05 INFO - mozsystemmonitor == 0.0 03:46:05 INFO - moztest == 0.7 03:46:05 INFO - mozversion == 1.4 03:46:05 INFO - psutil == 3.1.1 03:46:05 INFO - requests == 1.2.3 03:46:05 INFO - wsgiref == 0.1.2 03:46:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:46:05 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 03:46:05 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 03:46:05 INFO - Using env: {'DISPLAY': ':0', 03:46:05 INFO - 'HOME': '/home/cltbld', 03:46:05 INFO - 'LANG': 'en_US.UTF-8', 03:46:05 INFO - 'LANGUAGE': 'en_US:en', 03:46:05 INFO - 'LOGNAME': 'cltbld', 03:46:05 INFO - 'MAIL': '/var/mail/cltbld', 03:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:05 INFO - 'MOZ_NO_REMOTE': '1', 03:46:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:05 INFO - 'NO_EM_RESTART': '1', 03:46:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:05 INFO - 'PWD': '/builds/slave/test', 03:46:05 INFO - 'SHELL': '/bin/bash', 03:46:05 INFO - 'SHLVL': '1', 03:46:05 INFO - 'TERM': 'linux', 03:46:05 INFO - 'TMOUT': '86400', 03:46:05 INFO - 'USER': 'cltbld', 03:46:05 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:05 INFO - '_': '/tools/buildbot/bin/python'} 03:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:46:05 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)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:46:05 INFO - Cleaning up... 03:46:05 INFO - Return code: 0 03:46:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:46:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:46:05 INFO - Using env: {'DISPLAY': ':0', 03:46:05 INFO - 'HOME': '/home/cltbld', 03:46:05 INFO - 'LANG': 'en_US.UTF-8', 03:46:05 INFO - 'LANGUAGE': 'en_US:en', 03:46:05 INFO - 'LOGNAME': 'cltbld', 03:46:05 INFO - 'MAIL': '/var/mail/cltbld', 03:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:05 INFO - 'MOZ_NO_REMOTE': '1', 03:46:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:05 INFO - 'NO_EM_RESTART': '1', 03:46:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:05 INFO - 'PWD': '/builds/slave/test', 03:46:05 INFO - 'SHELL': '/bin/bash', 03:46:05 INFO - 'SHLVL': '1', 03:46:05 INFO - 'TERM': 'linux', 03:46:05 INFO - 'TMOUT': '86400', 03:46:05 INFO - 'USER': 'cltbld', 03:46:05 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:05 INFO - '_': '/tools/buildbot/bin/python'} 03:46:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:06 INFO - Downloading/unpacking jsonschema 03:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:07 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 03:46:07 INFO - Downloading/unpacking functools32 (from jsonschema) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:07 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 03:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:08 INFO - warning: no files found matching '*.txt' 03:46:08 INFO - no previously-included directories found matching 'build' 03:46:08 INFO - no previously-included directories found matching 'dist' 03:46:08 INFO - no previously-included directories found matching '.git*' 03:46:08 INFO - Installing collected packages: jsonschema, functools32 03:46:08 INFO - Running setup.py install for functools32 03:46:08 INFO - warning: no files found matching '*.txt' 03:46:08 INFO - no previously-included directories found matching 'build' 03:46:08 INFO - no previously-included directories found matching 'dist' 03:46:08 INFO - no previously-included directories found matching '.git*' 03:46:08 INFO - Successfully installed jsonschema functools32 03:46:08 INFO - Cleaning up... 03:46:08 INFO - Return code: 0 03:46:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d90066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1400240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, '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': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', '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 03:46:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:46:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:46:08 INFO - Using env: {'DISPLAY': ':0', 03:46:08 INFO - 'HOME': '/home/cltbld', 03:46:08 INFO - 'LANG': 'en_US.UTF-8', 03:46:08 INFO - 'LANGUAGE': 'en_US:en', 03:46:08 INFO - 'LOGNAME': 'cltbld', 03:46:08 INFO - 'MAIL': '/var/mail/cltbld', 03:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:08 INFO - 'MOZ_NO_REMOTE': '1', 03:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:08 INFO - 'NO_EM_RESTART': '1', 03:46:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:08 INFO - 'PWD': '/builds/slave/test', 03:46:08 INFO - 'SHELL': '/bin/bash', 03:46:08 INFO - 'SHLVL': '1', 03:46:08 INFO - 'TERM': 'linux', 03:46:08 INFO - 'TMOUT': '86400', 03:46:08 INFO - 'USER': 'cltbld', 03:46:08 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:08 INFO - '_': '/tools/buildbot/bin/python'} 03:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:08 INFO - Downloading/unpacking flake8 03:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:46:10 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 03:46:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:46:10 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 03:46:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 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 03:46:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 03:46:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:46:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:10 INFO - no previously-included directories found matching 'docs/_build' 03:46:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:46:10 INFO - Running setup.py install for pep8 03:46:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:10 INFO - no previously-included directories found matching 'docs/_build' 03:46:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:46:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:46:10 INFO - Cleaning up... 03:46:11 INFO - Return code: 0 03:46:11 INFO - Running post-action listener: _resource_record_post_action 03:46:11 INFO - Running post-action listener: _start_resource_monitoring 03:46:11 INFO - Starting resource monitoring. 03:46:11 INFO - ##### 03:46:11 INFO - ##### Running install step. 03:46:11 INFO - ##### 03:46:11 INFO - Running pre-action listener: _resource_record_pre_action 03:46:11 INFO - Running main action method: install 03:46:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:11 INFO - Reading from file tmpfile_stdout 03:46:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:46:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:46:11 INFO - Reading from file tmpfile_stdout 03:46:11 INFO - Output received: 03:46:11 INFO - Usage: mozinstall [options] installer 03:46:11 INFO - Options: 03:46:11 INFO - -h, --help show this help message and exit 03:46:11 INFO - -d DEST, --destination=DEST 03:46:11 INFO - Directory to install application into. [default: 03:46:11 INFO - "/builds/slave/test"] 03:46:11 INFO - --app=APP Application being installed. [default: firefox] 03:46:11 INFO - mkdir: /builds/slave/test/build/application 03:46:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:46:25 INFO - Reading from file tmpfile_stdout 03:46:25 INFO - Output received: 03:46:25 INFO - /builds/slave/test/build/application/firefox/firefox 03:46:25 INFO - Running post-action listener: _resource_record_post_action 03:46:25 INFO - ##### 03:46:25 INFO - ##### Running run-tests step. 03:46:25 INFO - ##### 03:46:25 INFO - Running pre-action listener: _resource_record_pre_action 03:46:25 INFO - Running main action method: run_tests 03:46:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:46:25 INFO - Python 2.7.3 03:46:25 INFO - Return code: 0 03:46:25 INFO - grabbing minidump binary from tooltool 03:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:25 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 0x148f0b0>, '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 0x148ffb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1490440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:46:25 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 03:46:25 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 03:46:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:46:25 INFO - Return code: 0 03:46:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:46:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:46:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:46:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:46:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:46:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:46:26 INFO - Return code: 0 03:46:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost'] in /builds/slave/test/build 03:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost 03:46:26 INFO - Using env: {'DISPLAY': ':0', 03:46:26 INFO - 'HOME': '/home/cltbld', 03:46:26 INFO - 'LANG': 'en_US.UTF-8', 03:46:26 INFO - 'LANGUAGE': 'en_US:en', 03:46:26 INFO - 'LOGNAME': 'cltbld', 03:46:26 INFO - 'MAIL': '/var/mail/cltbld', 03:46:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:46:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:26 INFO - 'MOZ_NO_REMOTE': '1', 03:46:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:26 INFO - 'NO_EM_RESTART': '1', 03:46:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:26 INFO - 'PWD': '/builds/slave/test', 03:46:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:46:26 INFO - 'SHELL': '/bin/bash', 03:46:26 INFO - 'SHLVL': '1', 03:46:26 INFO - 'TERM': 'linux', 03:46:26 INFO - 'TMOUT': '86400', 03:46:26 INFO - 'USER': 'cltbld', 03:46:26 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577', 03:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:26 INFO - '_': '/tools/buildbot/bin/python'} 03:46:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost'] with output_timeout 3600 03:46:27 INFO - mozversion INFO | application_buildid: 20151211023240 03:46:27 INFO - mozversion INFO | application_changeset: 5562d39c90da9eb2de1ab212098cd5f1af1a6066 03:46:27 INFO - mozversion INFO | application_display_name: Nightly 03:46:27 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:46:27 INFO - mozversion INFO | application_name: Firefox 03:46:27 INFO - mozversion INFO | application_remotingname: firefox 03:46:27 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 03:46:27 INFO - mozversion INFO | application_vendor: Mozilla 03:46:27 INFO - mozversion INFO | application_version: 45.0a1 03:46:27 INFO - mozversion INFO | platform_buildid: 20151211023240 03:46:27 INFO - mozversion INFO | platform_changeset: 5562d39c90da9eb2de1ab212098cd5f1af1a6066 03:46:27 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 03:46:27 INFO - mozversion INFO | platform_version: 45.0a1 03:46:27 INFO - 2015-12-11 03:46:27,063 DEBUG : using testdate: 1449834387 03:46:27 INFO - 2015-12-11 03:46:27,063 DEBUG : actual date: 1449834387 03:46:27 INFO - 2015-12-11 03:46:27,112 INFO : starting webserver on 'localhost:51219' 03:46:27 INFO - 2015-12-11 03:46:27,113 INFO : Starting test suite talos-linux64-ix-028.e 03:46:27 INFO - 2015-12-11 03:46:27,113 INFO : Starting test dromaeo_css 03:46:27 INFO - 2015-12-11 03:46:27,113 DEBUG : operating with platform_type : linux_ 03:46:27 INFO - 2015-12-11 03:46:27,114 INFO : Initialising browser for dromaeo_css test... 03:46:27 INFO - 2015-12-11 03:46:27,130 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAfnHjt/profile http://localhost:51219/getInfo.html 03:46:31 INFO - 2015-12-11 03:46:31,590 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:46:31 INFO - 2015-12-11 03:46:31,590 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:46:31 INFO - 2015-12-11 03:46:31,590 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:46:31 INFO - 2015-12-11 03:46:31,590 DEBUG : BROWSER_OUTPUT: __metrics 03:46:31 INFO - 2015-12-11 03:46:31,759 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] 03:46:32 INFO - 2015-12-11 03:46:32,665 INFO : Browser initialized. 03:46:32 INFO - 2015-12-11 03:46:32,666 INFO : Running cycle 1/1 for dromaeo_css test... 03:46:32 INFO - 2015-12-11 03:46:32,666 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAfnHjt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:46:44 INFO - 2015-12-11 03:46:44,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 143212544 03:46:44 INFO - 2015-12-11 03:46:44,785 DEBUG : BROWSER_OUTPUT: 03:47:34 INFO - 2015-12-11 03:47:34,396 DEBUG : BROWSER_OUTPUT: 1449834454396 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] 03:49:45 INFO - 2015-12-11 03:49:45,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51219/tests/dromaeo/cssquery-ext.html) 03:49:47 INFO - 2015-12-11 03:49:47,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 147726336 03:49:47 INFO - 2015-12-11 03:49:47,017 DEBUG : BROWSER_OUTPUT: 03:52:48 INFO - 2015-12-11 03:52:48,977 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/dromaeo/cssquery-ext.html (next: http://localhost:51219/tests/dromaeo/cssquery-jquery.html) 03:52:49 INFO - 2015-12-11 03:52:49,564 DEBUG : BROWSER_OUTPUT: RSS: Main: 144486400 03:52:49 INFO - 2015-12-11 03:52:49,564 DEBUG : BROWSER_OUTPUT: 03:55:51 INFO - 2015-12-11 03:55:51,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51219/tests/dromaeo/cssquery-mootools.html) 03:55:51 INFO - 2015-12-11 03:55:51,897 DEBUG : BROWSER_OUTPUT: RSS: Main: 145711104 03:55:51 INFO - 2015-12-11 03:55:51,897 DEBUG : BROWSER_OUTPUT: 03:58:53 INFO - 2015-12-11 03:58:53,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51219/tests/dromaeo/cssquery-prototype.html) 03:58:54 INFO - 2015-12-11 03:58:54,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 142876672 03:58:54 INFO - 2015-12-11 03:58:54,255 DEBUG : BROWSER_OUTPUT: 04:01:56 INFO - 2015-12-11 04:01:56,942 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51219/tests/dromaeo/cssquery-yui.html) 04:01:57 INFO - 2015-12-11 04:01:57,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 144461824 04:01:57 INFO - 2015-12-11 04:01:57,569 DEBUG : BROWSER_OUTPUT: 04:04:59 INFO - 2015-12-11 04:04:59,887 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/dromaeo/cssquery-yui.html (next: http://localhost:51219/tests/dromaeo/cssquery-dojo.html) 04:05:00 INFO - 2015-12-11 04:05:00,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 147718144 04:05:00 INFO - 2015-12-11 04:05:00,137 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,139 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:05:00 INFO - 2015-12-11 04:05:00,139 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:05:00 INFO - 2015-12-11 04:05:00,139 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:05:00 INFO - 2015-12-11 04:05:00,139 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:05:00 INFO - 2015-12-11 04:05:00,140 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2634.815562910596;2663.267601409611;2698.7840972722183;2707.6073969274457;2714.4299697063616;4607.285870690049;4672.953270467295;4681.180793361161;4694.6244300455965;4709.4348678158585;4593.954060459394;4613.331066995292;4653.348531205634;4702.600278976282;4716.669833111683;4319.2441322768545;4344.348347747831;4348.89127771805;4386.254336762742;4397;4143.502779666429;4353.477582690081;4360.878338750891;4440.55594440555;4440.777961101948;4224.683148763825;4258.6380157686635;4290.978545107255;4296.699231053827;4297.505786834523;4332.826686932519;4360.869173924787;4361.323994780782;4364.323529852878;4396.230659634552;4135.545090040093;4219.177260434217;4333.544977777351;4345.217860785057;4398.318260669601;4459.888502787457;4486.170058539148;4537.568930951555;4562.110388474249;4569.977150114261;4980.452150263468;4983.626228032912;4986.127427700138;4990.800367985276;5014.52362025607;4886.60907127429;4967.428745694219;5020.723860187688;5029.396472423333;5032.798688052473;4561.338605902126;4577.33628623851;4596.49438561758;4606.078784243165;4653.2554791233915;5143.4085080216255;5196.142636465017;5197.8180763673845;5209.53114219722;5217.113090774577;4741.66808323421;4836;4975.303457515951;4982.576480999156;5028.497150284942;4878.829240976549;4891.755412229374;4908.8772780680765;4925.458199598042;5007.549320561167;4937.308776771255;5005.6996580205305;5007.37407824022;5025.170846810309;5063.493650634907;4784.760761961888;4824.831130910471;4945.67853089548;4966.925496117561;5035.0685123252315;4164.354525048622;4165.354370072643;4168.666506679519;4169.91660166795;4187.120704651997;3619.511365965561;3630.582483014487;3632.3098611264304;3662.780233185964;3670.4127339625534;4175.874723758292;4284.635805956528;4286.978565107217;4288.571142885687;4375.6280716138845;4993.425756038029;5023.221400682827;5071.619628527875;5077.441481437039;5121.28302037715;4381.430414046154;4398.274284742982;4409.977950110293;4430.158269928703;4437.622802061796;4240.427542281815;4333.804978775963;4399.186150562156;4481.596656300949;4485.573870482299;4859.708417494961;4859.7327147006545;4872.878178045577;4880.389951256093;4910.361652985232;5067.138586440387;5069.493050694901;5078.162103252922;5079;5079.796808127633;4672.602828759594;4716.693414928019;4805.038992201504;4829.855104346875;4904.019196160853;4941.65408421407;5005.024020316004;5010.624203184703;5024.723640199824;5036.899262014813;5106.565941894981;5106.872328191825;5107.846764597068;5122.974385128051;5151.124308867426;4661.580457758818;4762.047590481987;4818.277258411266;4932.4327702314695;4946.406431228275;4868.123737727243;4918.311436398979;4922.778474968563;4927.531884470969;4963.677360971526;4816.253480710496;4817.205161148371;4820.40926494249;4832.710037397767;4840.201366774583;4656.8137274508645;4784.856454306377;4811.206150985048;4911.607071434348;4935.309056732132;4829.66192366531;4839.951600484091;4862.610991120772;4864.294677271847;4907.214845624683 04:05:00 INFO - 2015-12-11 04:05:00,141 DEBUG : BROWSER_OUTPUT: |1;ext.html;560.2464684998678;560.7168379968116;564.438383808111;566.0659911146607;568.8037627018679;35217.11957201071;35561.288774224566;35736.10659733505;35809.64190358099;35974.280514389764;39529.40705889414;39919;39985.800070999605;39988.600113998924;40192.79903600478;7885.960570197155;7977.202279772006;8007.879881801778;8018.959905200466;8032.799180020488;7923.445358824884;7982.082060563022;8045.477043992151;8046.557439340834;8061;7887.763367098996;7907.762767116996;7942.046954365484;7942.841143177133;7987.600619969007;7931.6034198290145;7932.405069619773;7960.9203907960755;7981.561014144219;7989.32090772285;7807;7919.722809701633;7969.402294827911;7981.640826162837;7989.201079892023;37106.81446592777;37476.43785343222;37498.81250593757;37673.81163094194;37781.62218377808;2812.985935070332;2994.8802047918057;3477.8434970426392;3499.1602015516346;3567.518385018015;27471.45057098847;27680.031198908142;27904.860475697693;27972.860135699393;28499.57250641242;3293.8353082346025;3368.949465758016;3416.3167366526545;3442.3115376924716;3455.706264967456;224628;225924;226092;226239;226364.99999999834;56646;57110.714446427504;57127.71436142793;57226.427735722944;57388.713056435285;66296;68056.31943680599;68104.31895681079;68331.31668683249;68358.31641683618;13045.739085218242;13120.212787232796;13127.803082953764;13174.670633234246;13294.734105318033;10163;10311.639092631722;10338.0695737384;10340;10364.637237696645;19524.8047519523;19793.62476502829;19835.404937851887;19840.801591983894;19920.804751714943;7783.649735761905;8090.069641991128;8147.637185216339;8154.469959452616;8300.543470109202;846.4540371460497;879.8108406692592;892.7366426903949;962.4273557233574;1064.435849000031;25876.48247035049;25891.0938117165;26057.348566285993;26194.476110477684;26474.6028809568;966.7583104223944;972.7324985629009;1006.8489726540931;1067.5462928255615;1082.929609575375;13557.86442135566;13732.519361822287;13963.162210267417;14028.649283767783;14034.298285085704;254600;258455.7077214602;260572;260984;261557;45112.77443612827;45394.31908521375;45525.31712024322;45628.54371456243;45791.77104114525;1162.3607016141088;1191.6722901202065;1197.6407077876777;1199.196538319328;1209.989658635032;25132.62301065522;25608.615870762325;25640.487190256463;25696.229113126636;25803.612945806202;39741.403878942416;40188.1962360757;40241.79879100586;40242.798786005886;40282;243424;243866;244787;245166;245304;29608.851955740083;41920.16159676851;42674.57325426706;42750.144997100506;42837.5716242846;38178.61821381862;38320.42519362156;38398.42402363911;39144.80427597958;39364;33091.8345408281;33382.332353352314;33811.4928276081;33896.66103338935;34285.65714342924;33158.502622461165;33232.501512477815;33342.83328583342;33507;33614.663853361155;37637;37675;38340.23319533538;38677.613223868524;39006;27659.58510622302;28627;30484.695153048186;30514;30910.845445772626;23481.765182347957;24064.51870962606;24069.157579484636;24072.87963560171;24161.15435959733 04:05:00 INFO - 2015-12-11 04:05:00,142 DEBUG : BROWSER_OUTPUT: |2;jquery.html;337.95437615921844;340.2701205913316;340.59639327397036;341.47924415266715;341.88546836809667;15038.473653422132;15175.39298428063;15999.680006399865;16219.675606487877;16560.503184904443;15144.621384465394;15208.087514749095;15373.38506459743;15468.381264749396;15496.380144794195;4269.167512335088;4443.155800397914;4444.688871778977;4448.488423831269;4453.777311134447;3564.269324788412;4353.0423306872435;4460.1525710114975;4518.344839998198;4524.954750452486;4344.543822898598;4350.934735978963;4966;5114.795408183687;5130.5126013028885;5022.6233032522705;5064.139096353629;5069.9493005070035;5074.264231686403;5094.821681241138;5100.642954993152;5129.538341549279;5130.794768209267;5134.589232861362;5146.614003949719;10467.476626168662;10700.357978521235;12105.45525451348;17637.823621763746;17646.823531764774;1737.8435940765266;1806.7831860176864;1811.8007019227875;1860.1470675678263;1882.3882238272615;8522.446041007315;8836.911630883738;8936;9027.458352498805;9196.770080747969;1722.1389305347327;1794.9551261218444;1811.3750755989163;1829.7438358629802;1836.6877630802796;50276;54892.72553637246;55245.72377138129;55879.16181257284;57070.71464642692;8759.868601970975;9262.768430789285;9904.41529355314;10005;10079.798404031879;12592.307423091635;12596;12603.684907877194;12605.495780168874;12607.369631518572;7090.645467726593;7138.857222855514;7151.249118842551;7193.89209161863;7276.599787011659;6826.692798824065;6955.930440695528;6967.268436814163;6968.059311992917;6996.160460744743;4276.5295817460055;4280.72175308604;4286.39990401344;4307.504636966726;4331.545187755303;1637.87715921304;1648.5878530367409;1775.946721598354;1793.2557988434653;1827.5248435406625;718.8454482286334;758.7571976967317;803.0042746993686;813.1380736419414;833.2375876073446;15332.770008449883;15715.21423928799;15726.685466290839;15954.840451595568;16156.676866462605;777.149021821105;822.0546371672505;857.7426771968385;870.5560164316117;916.2852974679761;9073.59168837404;9146.2225710814;9179.908200918038;9185.954070229605;9221.953890230507;457227;464532;468239;487283;487719;68082;69267.65366173237;69480.3051969484;70423.2957670427;70587.29412705808;1009.7475631092227;1012.9797404051877;1024.1039090795555;1030.8247597908223;1030.9587616495255;13542.864571354161;14317.64205894861;14376.496822611158;14390.928045359706;14497;32121;33601.66398335985;33808.3238335227;34430.82784586061;34900.47649285314;39630.40554391744;40425.191496169326;40597;41589.79205103955;43390.783046084565;8505.23452889243;9173.954130229573;9200.6779762708;9205.158968206251;9216;10629.89370106289;10638;15289.235538223267;15418.691626167638;17039.14804259807;11589.362585057566;13119.934400327937;13145;13157.473701051851;13802.930985345009;13020.218786872818;13216.471341146247;13353.465861365437;13709.520166794115;13858;13263.877779555225;13632.113912595649;13684.93157534206;13959.626863433037;14128.43486260538;8954.731358059009;9114.316426267924;9162.267018638626;9196;9226.584803683985;11770.352630605057;13650.658733531742;13705;13905.930470347988;14424.855751442352 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1436.5331267338104;1692.610699539106;1719.8452139307467;1731.8441340279373;1761.137042849004;6081.726322315495;6138.355472675372;6755;6945.027696122541;6973.546719463237;6578.5066120041065;6804.931950680491;6837.863242735142;6847.178338599362;6852.82867928302;2011.2558353409186;2039.2352867674622;2041.4181958141933;2041.540653352991;2041.765197002349;1958.0699167895207;1979.732736080625;1988.532694816717;1994.7207390965277;2023.6863286190664;2065.834733221339;2076.7611724651706;2106.6734656128247;2108.3569511299047;2110.947226319339;1908.055512521297;1923.4614307993713;1923.7114432835117;1931.9034048297528;1932.4299331697043;2007.638625047491;2019.0712272354735;2034.511717187879;2050.0774651406928;2053.3531937439657;6822.727090916357;6850.520463567552;6865.244823069458;6905.067815844847;6912.447004239699;4056.3509838426007;4113.71204015716;4116;4122.237386083584;4130.215259100762;2553.3488960315;2580.329114430243;2598.883050262724;2703.148508219904;2719.2250208690625;839.890814194157;840.1892174052008;879.7316818370394;894.959726812295;1600.4718442913927;9334.439933604006;9444.952775236148;9454.33819632619;9457.810843783154;9460.85808712863;3642.2715456908722;3697.8890633280507;3758.2671379080816;3764.171882185915;3775.1505911169766;7330;7347.8163045924275;7361.558306501627;7361.668724907392;7374.557526548424;4109.034376921421;4122.979385103055;4128.793560322032;4147.108371700099;4155.335146376565;3722.218334149805;3813.6758375537834;3851.826667799956;3856.807159642007;3861.0540417597867;3198.760092992988;3258.250602361423;3264.918377040593;3274.9345013099605;3286.0306209668206;992.2359782967075;993.7614972406715;995.3977843404781;996.5814357969671;997.9600815967425;1117.6311817100338;1149.4425203776161;1149.683836944847;1160.1009217856229;1173.4602083041896;6256.155419018374;6366.172397588284;6566.540342176002;6601.207855057424;6624;376.63090171631956;381.66985557493126;382.128746458076;382.60782697734487;388.279741080292;4860.270959356054;4925.556699897026;4928.457869634337;4935.728534930541;4940.6788558743565;8472.110428404929;8509.064002959669;8701.129887011248;8708.69519566812;8757.124287571192;8506.829863402698;8548.016978047479;8582.742517724479;8608.69869554575;8628.827423451496;6702.363275488919;6728.932710672929;6744;6786.660666966632;6789.0834737310815;6489.513286503626;6509.804705858832;6516.283208847118;6526.967365163334;6574.638394888327;7199.604021778643;7328.706851725871;7373.410127189919;7503.9249607503225;7559.168491465824;7666.271704188093;7714.768556943301;7818.491798033109;8011.6795328188555;8036.276735093869;5433.157860531624;5552.972235138798;6157.261128664589;6203.100550420254;6241.500679945502;6631.568948018364;6708.22855371619;6743.966280168764;6762.966185169043;6784.423324017514;5788.334341550775;5898.9115163273445;6060.787872424444;6221.813345599639;6256.687165641791;6194.349593292639;6207.937920620736;6211.192544969306;6352.618842869442;6364.07720880479;6336;6381.904271436025;6401.8719625608155;6406.13517175194;6429.871402571829;5939.138824870456;5957.49361304294;6000.669963152069;6000.909986350295;6023.548233882389;5760.0783874579865;5924.11138329254;5932.0508718604815;6003.639781612943;6046.2139921809885 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: |4;prototype.html;310.6163887598816;312.4720118619949;322.5822559785078;323.047011316616;324.02630096559835;5421.403645598986;5456.427075157111;5467.753951072202;5476.780928762854;5623.100303951369;5426.294581704373;5471.781128754845;5472.945270547303;5476.972615136919;5482.821193443408;4043.110515686544;4139.130782535671;4161.542230354658;4223.936640950388;4228.76741779202;4130.215259100776;4155.812988415513;4163.604457576525;4175.958240417587;4186.874393768192;4087.0804069084516;4091.0386059276193;4113.280175969208;4129.6696264298935;4135.648469880064;4131.256373852721;4131.338985762263;4132.152908653734;4135.0902801383645;4143.751374917515;3536.876209332687;3598.5501812273465;3601.009722326355;3605.06268370223;3647.197616524387;5094.974525127388;5209.843704688866;5225.320708307933;5233.581313494911;5238.109521381336;4742.3123647071325;4769.475357710649;4774.618030557547;4775.761211939389;4777.163996300634;5733.942660573382;5738.0532212184935;5741.25363702716;5741.311042674905;5763.971180144114;3800.5819359870393;3920.6863450924143;3921.901952451183;3923.5684074751753;3927.725059245854;5946.405359464062;5983.940160598426;6028.125921741323;6029.155918171416;6029.728662210167;4042.3532234842282;4045.8988525287104;4069.4709687740406;4071.2875246831686;4081.142959978438;4142.06803469217;4150.27370210218;4151.875443736692;4153.875383738493;4159.5008598967715;4605.769711514478;4726;4731.2903064539905;4745.952540474551;4765.642576806753;5525.4474552545225;5625.5092400513895;5628.268325117709;5630.268065151504;5637.3235211774845;1433.6702558411623;1463.370750577262;1463.4512057978259;1465.545680838922;1465.5823090419178;970.4759429907771;982.9262805289632;984.8030393921244;997.3218211616171;1002.1882275356984;4932.53140951609;4959.801607935714;4964.751762411937;5013.49865013503;5023.547880690755;5890.4993075589055;5934.762609495659;5938.317093534212;5938.406159384027;5945.3757355477055;4993.525615066563;4996.525330093636;4999.02519008797;5003.924941125886;5009.423916249678;3115.922101947424;3157.542156387311;3191.0426871938325;3198.6321573018936;3199.728023118061;5619.8876022480135;5642.689652069175;5648.661080335193;5654.208410822488;5669.518090962314;6051.546134039964;6074.3925607438905;6086.2087928569;6092.573519853568;6104.450599445982;5485.780568777204;5597.552195824324;5623.212750214973;5647.858803529944;5657.886842263049;5909.970450147722;5915.91126133117;5922.318933322723;5922.55580831448;5922.940770592411;5785.4214578541805;5796.623219490888;5802.738876750472;5803.651780892991;5810.099434587646;5623.662580245198;5624.437556244343;5641.520470759867;5648.039833228443;5651.406602306699;3962.841486340629;4028.3957406389277;4040.2323558523785;4106.486689163855;4113.280175969134;5860.208871802422;5940.1683764272175;5957.731902064331;5961.463468287875;5993.730282137227;5717.714114294352;5725.828225153252;5747.712614369349;5760.683162425939;5765.827025189251;5703.7433315500075;5729.598928075162;5744.913826292693;5761.5102716269585;5763.789604183168;5497.5601951842955;5578.414266502121;5608.68195973954;5631.6902570359025;5655.575831812713;5747.396523365153;5799.3040835100055;5811.4479124483105;5824.70876456167;5857.033589457692;5660.235868157746;5664.773409063591;5665.971670141623;5670.943290567042;5684.204211410489 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: |5;yui.html;659.577870163096;775.3099741230302;776.5030380556443;779.4777499075619;782.8669126248539;11603.65189044329;11911.940440297798;11954.521819127225;11967.64097077088;12011.519539218443;10887.401192934405;10998.450077496133;11018.44907754613;11520.423978801047;11558.30650160991;803.4817542684965;809.8339840332718;810.4084018666363;827.6317038917684;827.6978902700502;779.7076096463826;784.6861255497789;784.7881072110526;793.1196372027046;809.9271065604095;833.7540425574471;836.6318819719335;838.5220424358118;844.307667712476;855.2260204514924;827.0365024746163;831.3432388413146;831.8419500294924;836.2264904962915;859.1108203009886;629.0846817879974;634.9904751428733;637.1907677249867;638.2086213095728;638.3648269971408;12114.152009359348;12182.51269949201;12228.510859565607;12230.388480575935;12236.694082647917;11079.778404431867;11084;11354.091672666167;11368.772624547462;11373.431328433628;9471.66849160276;9495.857562136573;9588.66439674608;9651.276154288385;9688;3273.214428537158;3300.818454984975;3324.3683700096904;3326.966730332714;3361.411752943225;39013;39038;39219.21561568781;39322.606773932464;39553.80223098866;9192.63229470827;9240.676576319931;9256;9364.391314564531;9409.623615055458;15777.921110394374;15839.604009899846;15855.445059422866;15860.52418427449;15866.603334916488;6182.196314479089;6183.505319574423;6221;6228.719707613168;6292.528060395489;3659.14010207601;3676.8713095041544;3681.5398075240596;3696.5933747287777;3697.445383192489;2664.174106027138;2683.034107721219;2689.0991517841353;2689.5831146172372;2696.44722831819;502.9723365214948;509.38364578859256;512.9589632829365;514.1259858241006;514.4880843560682;527.5515811560143;528.1734086155155;530.5225297232445;533.1496263459776;543.5081251467428;6922.688479018356;6941.965290173517;7012.824679383056;7021.368076873106;7137.643117844191;425.7977460706202;469.27965572845517;472.9314249433813;496.5952748509953;527.4857014410919;3404.676555727202;3449.0515108345407;3540.539729835105;3709.888703338904;3766.7363284570365;43791.34312985308;45240;45434.09131817411;45629.31556026662;45716.77141614271;37748.433773493416;37877.62122378796;37907;38078.238435231695;38894.6110538891;39890;40809.79595102005;40884.18231635351;41290;41797.58202417937;7309.963450182715;7633.274838890296;7707.768766937001;7708.961455192688;7940.523568585903;30888.382232355678;31532.684673152973;31711.524327135572;31747;32119.518207226443;38256.426153607164;41143.588564115176;41396;41502.79248603858;41626.791866040476;9841.311108222642;9890.307678462439;9894.752631184278;9912.15746661542;10202.744931376776;10854.312424006806;11134.665960021212;11201.327920324806;11270.436478176221;11300.94349528247;10155.84766228522;10292.897071029194;10435.478226088513;10438.843417348899;10440.947795260976;9636.807263854824;10582.576696932036;10965.287256328313;10979.231453798158;11050.557977680803;10539.94730026345;10636.202284828514;10671.94664026675;10692.358458492516;10698.304610200312;8489.278411335106;8706.347023973101;8726.869096963677;8852.159044890725;8936.151065648754;8270.669173233244;8435.481613309417;8694.608742606724;8741.519216442903;8821.221315285664 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,338 04:05:00 INFO - 2015-12-11 04:05:00,145 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: __startTimestamp1449835500138__endTimestamp 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4628.93 Median:4784.86 stddev:489.05 (10.2%) stddev-sans-first:464.86 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: Values: 2634.8 2663.3 2698.8 2707.6 2714.4 4607.3 4673.0 4681.2 4694.6 4709.4 4594.0 4613.3 4653.3 4702.6 4716.7 4319.2 4344.3 4348.9 4386.3 4397.0 4143.5 4353.5 4360.9 4440.6 4440.8 4224.7 4258.6 4291.0 4296.7 4297.5 4332.8 4360.9 4361.3 4364.3 4396.2 4135.5 4219.2 4333.5 4345.2 4398.3 4459.9 4486.2 4537.6 4562.1 4570.0 4980.5 4983.6 4986.1 4990.8 5014.5 4886.6 4967.4 5020.7 5029.4 5032.8 4561.3 4577.3 4596.5 4606.1 4653.3 5143.4 5196.1 5197.8 5209.5 5217.1 4741.7 4836.0 4975.3 4982.6 5028.5 4878.8 4891.8 4908.9 4925.5 5007.5 4937.3 5005.7 5007.4 5025.2 5063.5 4784.8 4824.8 4945.7 4966.9 5035.1 4164.4 4165.4 4168.7 4169.9 4187.1 3619.5 3630.6 3632.3 3662.8 3670.4 4175.9 4284.6 4287.0 4288.6 4375.6 4993.4 5023.2 5071.6 5077.4 5121.3 4381.4 4398.3 4410.0 4430.2 4437.6 4240.4 4333.8 4399.2 4481.6 4485.6 4859.7 4859.7 4872.9 4880.4 4910.4 5067.1 5069.5 5078.2 5079.0 5079.8 4672.6 4716.7 4805.0 4829.9 4904.0 4941.7 5005.0 5010.6 5024.7 5036.9 5106.6 5106.9 5107.8 5123.0 5151.1 4661.6 4762.0 4818.3 4932.4 4946.4 4868.1 4918.3 4922.8 4927.5 4963.7 4816.3 4817.2 4820.4 4832.7 4840.2 4656.8 4784.9 4811.2 4911.6 4935.3 4829.7 4840.0 4862.6 4864.3 4907.2 04:05:00 INFO - 2015-12-11 04:05:00,146 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,147 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:41272.06 Median:19564.40 stddev:63459.36 (324.4%) stddev-sans-first:63563.75 04:05:00 INFO - 2015-12-11 04:05:00,147 DEBUG : BROWSER_OUTPUT: Values: 560.2 560.7 564.4 566.1 568.8 35217.1 35561.3 35736.1 35809.6 35974.3 39529.4 39919.0 39985.8 39988.6 40192.8 7886.0 7977.2 8007.9 8019.0 8032.8 7923.4 7982.1 8045.5 8046.6 8061.0 7887.8 7907.8 7942.0 7942.8 7987.6 7931.6 7932.4 7960.9 7981.6 7989.3 7807.0 7919.7 7969.4 7981.6 7989.2 37106.8 37476.4 37498.8 37673.8 37781.6 2813.0 2994.9 3477.8 3499.2 3567.5 27471.5 27680.0 27904.9 27972.9 28499.6 3293.8 3368.9 3416.3 3442.3 3455.7 224628.0 225924.0 226092.0 226239.0 226365.0 56646.0 57110.7 57127.7 57226.4 57388.7 66296.0 68056.3 68104.3 68331.3 68358.3 13045.7 13120.2 13127.8 13174.7 13294.7 10163.0 10311.6 10338.1 10340.0 10364.6 19524.8 19793.6 19835.4 19840.8 19920.8 7783.6 8090.1 8147.6 8154.5 8300.5 846.5 879.8 892.7 962.4 1064.4 25876.5 25891.1 26057.3 26194.5 26474.6 966.8 972.7 1006.8 1067.5 1082.9 13557.9 13732.5 13963.2 14028.6 14034.3 254600.0 258455.7 260572.0 260984.0 261557.0 45112.8 45394.3 45525.3 45628.5 45791.8 1162.4 1191.7 1197.6 1199.2 1210.0 25132.6 25608.6 25640.5 25696.2 25803.6 39741.4 40188.2 40241.8 40242.8 40282.0 243424.0 243866.0 244787.0 245166.0 245304.0 29608.9 41920.2 42674.6 42750.1 42837.6 38178.6 38320.4 38398.4 39144.8 39364.0 33091.8 33382.3 33811.5 33896.7 34285.7 33158.5 33232.5 33342.8 33507.0 33614.7 37637.0 37675.0 38340.2 38677.6 39006.0 27659.6 28627.0 30484.7 30514.0 30910.8 23481.8 24064.5 24069.2 24072.9 24161.2 04:05:00 INFO - 2015-12-11 04:05:00,147 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,147 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25647.37 Median:41093.40 stddev:77269.69 (188.0%) stddev-sans-first:77463.08 04:05:00 INFO - 2015-12-11 04:05:00,148 DEBUG : BROWSER_OUTPUT: Values: 338.0 340.3 340.6 341.5 341.9 15038.5 15175.4 15999.7 16219.7 16560.5 15144.6 15208.1 15373.4 15468.4 15496.4 4269.2 4443.2 4444.7 4448.5 4453.8 3564.3 4353.0 4460.2 4518.3 4525.0 4344.5 4350.9 4966.0 5114.8 5130.5 5022.6 5064.1 5069.9 5074.3 5094.8 5100.6 5129.5 5130.8 5134.6 5146.6 10467.5 10700.4 12105.5 17637.8 17646.8 1737.8 1806.8 1811.8 1860.1 1882.4 8522.4 8836.9 8936.0 9027.5 9196.8 1722.1 1795.0 1811.4 1829.7 1836.7 50276.0 54892.7 55245.7 55879.2 57070.7 8759.9 9262.8 9904.4 10005.0 10079.8 12592.3 12596.0 12603.7 12605.5 12607.4 7090.6 7138.9 7151.2 7193.9 7276.6 6826.7 6955.9 6967.3 6968.1 6996.2 4276.5 4280.7 4286.4 4307.5 4331.5 1637.9 1648.6 1775.9 1793.3 1827.5 718.8 758.8 803.0 813.1 833.2 15332.8 15715.2 15726.7 15954.8 16156.7 777.1 822.1 857.7 870.6 916.3 9073.6 9146.2 9179.9 9186.0 9222.0 457227.0 464532.0 468239.0 487283.0 487719.0 68082.0 69267.7 69480.3 70423.3 70587.3 1009.7 1013.0 1024.1 1030.8 1031.0 13542.9 14317.6 14376.5 14390.9 14497.0 32121.0 33601.7 33808.3 34430.8 34900.5 39630.4 40425.2 40597.0 41589.8 43390.8 8505.2 9174.0 9200.7 9205.2 9216.0 10629.9 10638.0 15289.2 15418.7 17039.1 11589.4 13119.9 13145.0 13157.5 13802.9 13020.2 13216.5 13353.5 13709.5 13858.0 13263.9 13632.1 13684.9 13959.6 14128.4 8954.7 9114.3 9162.3 9196.0 9226.6 11770.4 13650.7 13705.0 13905.9 14424.9 04:05:00 INFO - 2015-12-11 04:05:00,148 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4843.43 Median:6071.26 stddev:2527.30 (41.6%) stddev-sans-first:2521.42 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: Values: 1436.5 1692.6 1719.8 1731.8 1761.1 6081.7 6138.4 6755.0 6945.0 6973.5 6578.5 6804.9 6837.9 6847.2 6852.8 2011.3 2039.2 2041.4 2041.5 2041.8 1958.1 1979.7 1988.5 1994.7 2023.7 2065.8 2076.8 2106.7 2108.4 2110.9 1908.1 1923.5 1923.7 1931.9 1932.4 2007.6 2019.1 2034.5 2050.1 2053.4 6822.7 6850.5 6865.2 6905.1 6912.4 4056.4 4113.7 4116.0 4122.2 4130.2 2553.3 2580.3 2598.9 2703.1 2719.2 839.9 840.2 879.7 895.0 1600.5 9334.4 9445.0 9454.3 9457.8 9460.9 3642.3 3697.9 3758.3 3764.2 3775.2 7330.0 7347.8 7361.6 7361.7 7374.6 4109.0 4123.0 4128.8 4147.1 4155.3 3722.2 3813.7 3851.8 3856.8 3861.1 3198.8 3258.3 3264.9 3274.9 3286.0 992.2 993.8 995.4 996.6 998.0 1117.6 1149.4 1149.7 1160.1 1173.5 6256.2 6366.2 6566.5 6601.2 6624.0 376.6 381.7 382.1 382.6 388.3 4860.3 4925.6 4928.5 4935.7 4940.7 8472.1 8509.1 8701.1 8708.7 8757.1 8506.8 8548.0 8582.7 8608.7 8628.8 6702.4 6728.9 6744.0 6786.7 6789.1 6489.5 6509.8 6516.3 6527.0 6574.6 7199.6 7328.7 7373.4 7503.9 7559.2 7666.3 7714.8 7818.5 8011.7 8036.3 5433.2 5553.0 6157.3 6203.1 6241.5 6631.6 6708.2 6744.0 6763.0 6784.4 5788.3 5898.9 6060.8 6221.8 6256.7 6194.3 6207.9 6211.2 6352.6 6364.1 6336.0 6381.9 6401.9 6406.1 6429.9 5939.1 5957.5 6000.7 6000.9 6023.5 5760.1 5924.1 5932.1 6003.6 6046.2 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4732.09 Median:5472.36 stddev:1405.27 (25.7%) stddev-sans-first:1369.47 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: Values: 310.6 312.5 322.6 323.0 324.0 5421.4 5456.4 5467.8 5476.8 5623.1 5426.3 5471.8 5472.9 5477.0 5482.8 4043.1 4139.1 4161.5 4223.9 4228.8 4130.2 4155.8 4163.6 4176.0 4186.9 4087.1 4091.0 4113.3 4129.7 4135.6 4131.3 4131.3 4132.2 4135.1 4143.8 3536.9 3598.6 3601.0 3605.1 3647.2 5095.0 5209.8 5225.3 5233.6 5238.1 4742.3 4769.5 4774.6 4775.8 4777.2 5733.9 5738.1 5741.3 5741.3 5764.0 3800.6 3920.7 3921.9 3923.6 3927.7 5946.4 5983.9 6028.1 6029.2 6029.7 4042.4 4045.9 4069.5 4071.3 4081.1 4142.1 4150.3 4151.9 4153.9 4159.5 4605.8 4726.0 4731.3 4746.0 4765.6 5525.4 5625.5 5628.3 5630.3 5637.3 1433.7 1463.4 1463.5 1465.5 1465.6 970.5 982.9 984.8 997.3 1002.2 4932.5 4959.8 4964.8 5013.5 5023.5 5890.5 5934.8 5938.3 5938.4 5945.4 4993.5 4996.5 4999.0 5003.9 5009.4 3115.9 3157.5 3191.0 3198.6 3199.7 5619.9 5642.7 5648.7 5654.2 5669.5 6051.5 6074.4 6086.2 6092.6 6104.5 5485.8 5597.6 5623.2 5647.9 5657.9 5910.0 5915.9 5922.3 5922.6 5922.9 5785.4 5796.6 5802.7 5803.7 5810.1 5623.7 5624.4 5641.5 5648.0 5651.4 3962.8 4028.4 4040.2 4106.5 4113.3 5860.2 5940.2 5957.7 5961.5 5993.7 5717.7 5725.8 5747.7 5760.7 5765.8 5703.7 5729.6 5744.9 5761.5 5763.8 5497.6 5578.4 5608.7 5631.7 5655.6 5747.4 5799.3 5811.4 5824.7 5857.0 5660.2 5664.8 5666.0 5670.9 5684.2 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,149 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11874.56 Median:22108.57 stddev:13127.28 (59.4%) stddev-sans-first:13137.09 04:05:00 INFO - 2015-12-11 04:05:00,151 DEBUG : BROWSER_OUTPUT: Values: 659.6 775.3 776.5 779.5 782.9 11603.7 11911.9 11954.5 11967.6 12011.5 10887.4 10998.5 11018.4 11520.4 11558.3 803.5 809.8 810.4 827.6 827.7 779.7 784.7 784.8 793.1 809.9 833.8 836.6 838.5 844.3 855.2 827.0 831.3 831.8 836.2 859.1 629.1 635.0 637.2 638.2 638.4 12114.2 12182.5 12228.5 12230.4 12236.7 11079.8 11084.0 11354.1 11368.8 11373.4 9471.7 9495.9 9588.7 9651.3 9688.0 3273.2 3300.8 3324.4 3327.0 3361.4 39013.0 39038.0 39219.2 39322.6 39553.8 9192.6 9240.7 9256.0 9364.4 9409.6 15777.9 15839.6 15855.4 15860.5 15866.6 6182.2 6183.5 6221.0 6228.7 6292.5 3659.1 3676.9 3681.5 3696.6 3697.4 2664.2 2683.0 2689.1 2689.6 2696.4 503.0 509.4 513.0 514.1 514.5 527.6 528.2 530.5 533.1 543.5 6922.7 6942.0 7012.8 7021.4 7137.6 425.8 469.3 472.9 496.6 527.5 3404.7 3449.1 3540.5 3709.9 3766.7 43791.3 45240.0 45434.1 45629.3 45716.8 37748.4 37877.6 37907.0 38078.2 38894.6 39890.0 40809.8 40884.2 41290.0 41797.6 7310.0 7633.3 7707.8 7709.0 7940.5 30888.4 31532.7 31711.5 31747.0 32119.5 38256.4 41143.6 41396.0 41502.8 41626.8 9841.3 9890.3 9894.8 9912.2 10202.7 10854.3 11134.7 11201.3 11270.4 11300.9 10155.8 10292.9 10435.5 10438.8 10440.9 9636.8 10582.6 10965.3 10979.2 11050.6 10539.9 10636.2 10671.9 10692.4 10698.3 8489.3 8706.3 8726.9 8852.2 8936.2 8270.7 8435.5 8694.6 8741.5 8821.2 04:05:00 INFO - 2015-12-11 04:05:00,151 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:05:00 INFO - 2015-12-11 04:05:00,151 DEBUG : BROWSER_OUTPUT: 04:05:00 INFO - 2015-12-11 04:05:00,854 INFO : Browser exited with error code: 0 04:05:00 INFO - 2015-12-11 04:05:00,877 INFO : Completed test dromaeo_css (00:18:33) 04:05:00 INFO - 2015-12-11 04:05:00,877 INFO : Starting test kraken 04:05:00 INFO - 2015-12-11 04:05:00,877 DEBUG : operating with platform_type : linux_ 04:05:00 INFO - 2015-12-11 04:05:00,877 INFO : Initialising browser for kraken test... 04:05:00 INFO - 2015-12-11 04:05:00,883 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV41z0n/profile http://localhost:51219/getInfo.html 04:05:05 INFO - 2015-12-11 04:05:05,414 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:05:05 INFO - 2015-12-11 04:05:05,414 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:05:05 INFO - 2015-12-11 04:05:05,414 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:05:05 INFO - 2015-12-11 04:05:05,415 DEBUG : BROWSER_OUTPUT: __metrics 04:05:05 INFO - 2015-12-11 04:05:05,539 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:05:06 INFO - 2015-12-11 04:05:06,405 INFO : Browser initialized. 04:05:06 INFO - 2015-12-11 04:05:06,406 INFO : Running cycle 1/1 for kraken test... 04:05:06 INFO - 2015-12-11 04:05:06,406 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV41z0n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:05:18 INFO - 2015-12-11 04:05:18,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 143126528 04:05:18 INFO - 2015-12-11 04:05:18,588 DEBUG : BROWSER_OUTPUT: 04:05:21 INFO - 2015-12-11 04:05:21,135 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51219/tests/kraken/driver.html?testName=audio-beat-detection) 04:05:21 INFO - 2015-12-11 04:05:21,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 143745024 04:05:21 INFO - 2015-12-11 04:05:21,765 DEBUG : BROWSER_OUTPUT: 04:05:24 INFO - 2015-12-11 04:05:24,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51219/tests/kraken/driver.html?testName=audio-dft) 04:05:25 INFO - 2015-12-11 04:05:25,124 DEBUG : BROWSER_OUTPUT: RSS: Main: 142454784 04:05:25 INFO - 2015-12-11 04:05:25,124 DEBUG : BROWSER_OUTPUT: 04:05:28 INFO - 2015-12-11 04:05:28,010 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51219/tests/kraken/driver.html?testName=audio-fft) 04:05:28 INFO - 2015-12-11 04:05:28,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 137383936 04:05:28 INFO - 2015-12-11 04:05:28,600 DEBUG : BROWSER_OUTPUT: 04:05:30 INFO - 2015-12-11 04:05:30,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51219/tests/kraken/driver.html?testName=audio-oscillator) 04:05:31 INFO - 2015-12-11 04:05:31,294 DEBUG : BROWSER_OUTPUT: RSS: Main: 137768960 04:05:31 INFO - 2015-12-11 04:05:31,294 DEBUG : BROWSER_OUTPUT: 04:05:33 INFO - 2015-12-11 04:05:33,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:05:34 INFO - 2015-12-11 04:05:34,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 139026432 04:05:34 INFO - 2015-12-11 04:05:34,155 DEBUG : BROWSER_OUTPUT: 04:05:37 INFO - 2015-12-11 04:05:37,226 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51219/tests/kraken/driver.html?testName=imaging-darkroom) 04:05:37 INFO - 2015-12-11 04:05:37,820 DEBUG : BROWSER_OUTPUT: RSS: Main: 140857344 04:05:37 INFO - 2015-12-11 04:05:37,820 DEBUG : BROWSER_OUTPUT: 04:05:41 INFO - 2015-12-11 04:05:41,303 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51219/tests/kraken/driver.html?testName=imaging-desaturate) 04:05:41 INFO - 2015-12-11 04:05:41,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 138330112 04:05:41 INFO - 2015-12-11 04:05:41,909 DEBUG : BROWSER_OUTPUT: 04:05:44 INFO - 2015-12-11 04:05:44,827 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51219/tests/kraken/driver.html?testName=json-parse-financial) 04:05:45 INFO - 2015-12-11 04:05:45,420 DEBUG : BROWSER_OUTPUT: RSS: Main: 138829824 04:05:45 INFO - 2015-12-11 04:05:45,420 DEBUG : BROWSER_OUTPUT: 04:05:47 INFO - 2015-12-11 04:05:47,238 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:05:47 INFO - 2015-12-11 04:05:47,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 139780096 04:05:47 INFO - 2015-12-11 04:05:47,824 DEBUG : BROWSER_OUTPUT: 04:05:49 INFO - 2015-12-11 04:05:49,694 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:05:50 INFO - 2015-12-11 04:05:50,308 DEBUG : BROWSER_OUTPUT: RSS: Main: 138670080 04:05:50 INFO - 2015-12-11 04:05:50,308 DEBUG : BROWSER_OUTPUT: 04:05:52 INFO - 2015-12-11 04:05:52,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:05:53 INFO - 2015-12-11 04:05:53,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 139128832 04:05:53 INFO - 2015-12-11 04:05:53,065 DEBUG : BROWSER_OUTPUT: 04:05:55 INFO - 2015-12-11 04:05:55,661 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:05:56 INFO - 2015-12-11 04:05:56,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 139776000 04:05:56 INFO - 2015-12-11 04:05:56,263 DEBUG : BROWSER_OUTPUT: 04:05:58 INFO - 2015-12-11 04:05:58,898 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:05:59 INFO - 2015-12-11 04:05:59,493 DEBUG : BROWSER_OUTPUT: RSS: Main: 139116544 04:05:59 INFO - 2015-12-11 04:05:59,494 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,247 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51219/tests/kraken/driver.html?testName=ai-astar) 04:06:01 INFO - 2015-12-11 04:06:01,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 139481088 04:06:01 INFO - 2015-12-11 04:06:01,499 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,499 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:06:01 INFO - 2015-12-11 04:06:01,499 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:06:01 INFO - 2015-12-11 04:06:01,499 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:06:01 INFO - 2015-12-11 04:06:01,499 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:06:01 INFO - 2015-12-11 04:06:01,499 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;108;113;114;113;125;112;112;113;116 04:06:01 INFO - 2015-12-11 04:06:01,500 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;132;158;171;130;171;156;131;169;133 04:06:01 INFO - 2015-12-11 04:06:01,500 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;164;167;163;165;168;166;162;165;163 04:06:01 INFO - 2015-12-11 04:06:01,500 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;74;73;74;74;79;134;72;71;80 04:06:01 INFO - 2015-12-11 04:06:01,500 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;96;95;134;94;95;96;133;96;95;134 04:06:01 INFO - 2015-12-11 04:06:01,500 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;110;110;110;110;110;110;110 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;149;147;149;148;148;147;147;147 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;97;96;96;96;96;97;96;95 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;77;76;74;76;77;75;81;75;82;73 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;68;68;68;67;68;68;68;68;67 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;84;84;85;83;89;87;84;84;86 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;135;127;124;121;137;129;125;128;124;129 04:06:01 INFO - 2015-12-11 04:06:01,501 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;146;150;146;147;148;147;149;153;148;150 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;68;68;70;71;70;69;69;71;69 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,795 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: __startTimestamp1449835561497__endTimestamp 04:06:01 INFO - 2015-12-11 04:06:01,502 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.80 Median:113.00 stddev:4.42 (3.9%) stddev-sans-first:4.64 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: Values: 112.0 108.0 113.0 114.0 113.0 125.0 112.0 112.0 113.0 116.0 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.90 Median:158.00 stddev:17.55 (11.1%) stddev-sans-first:18.43 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: Values: 158.0 132.0 158.0 171.0 130.0 171.0 156.0 131.0 169.0 133.0 04:06:01 INFO - 2015-12-11 04:06:01,510 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:1.89 (1.1%) stddev-sans-first:1.99 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: Values: 164.0 164.0 167.0 163.0 165.0 168.0 166.0 162.0 165.0 163.0 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.40 Median:74.00 stddev:19.05 (25.7%) stddev-sans-first:20.02 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: Values: 73.0 74.0 73.0 74.0 74.0 79.0 134.0 72.0 71.0 80.0 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:95.00 stddev:18.55 (19.5%) stddev-sans-first:19.26 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 134.0 94.0 95.0 96.0 133.0 96.0 95.0 134.0 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 149.0 147.0 149.0 148.0 148.0 147.0 147.0 147.0 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,511 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 97.0 96.0 96.0 96.0 96.0 97.0 96.0 95.0 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:76.60 Median:76.50 stddev:2.88 (3.8%) stddev-sans-first:3.05 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 74.0 76.0 77.0 75.0 81.0 75.0 82.0 73.0 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 68.0 68.0 67.0 68.0 68.0 68.0 68.0 67.0 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,512 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:1.79 (2.1%) stddev-sans-first:1.90 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: Values: 85.0 84.0 84.0 85.0 83.0 89.0 87.0 84.0 84.0 86.0 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:128.50 stddev:4.98 (3.9%) stddev-sans-first:4.57 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: Values: 135.0 127.0 124.0 121.0 137.0 129.0 125.0 128.0 124.0 129.0 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.40 Median:148.50 stddev:2.17 (1.5%) stddev-sans-first:2.12 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: Values: 146.0 150.0 146.0 147.0 148.0 147.0 149.0 153.0 148.0 150.0 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:1.07 (1.5%) stddev-sans-first:1.13 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 68.0 70.0 71.0 70.0 69.0 69.0 71.0 69.0 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:06:01 INFO - 2015-12-11 04:06:01,513 DEBUG : BROWSER_OUTPUT: 04:06:02 INFO - 2015-12-11 04:06:02,413 INFO : Browser exited with error code: 0 04:06:02 INFO - 2015-12-11 04:06:02,462 INFO : Completed test kraken (00:01:01) 04:06:02 INFO - 2015-12-11 04:06:02,463 INFO : Starting test v8_7 04:06:02 INFO - 2015-12-11 04:06:02,463 DEBUG : operating with platform_type : linux_ 04:06:02 INFO - 2015-12-11 04:06:02,463 INFO : Initialising browser for v8_7 test... 04:06:02 INFO - 2015-12-11 04:06:02,469 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoLx_rH/profile http://localhost:51219/getInfo.html 04:06:06 INFO - 2015-12-11 04:06:06,750 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:06:06 INFO - 2015-12-11 04:06:06,751 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:06:06 INFO - 2015-12-11 04:06:06,751 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:06:06 INFO - 2015-12-11 04:06:06,751 DEBUG : BROWSER_OUTPUT: __metrics 04:06:06 INFO - 2015-12-11 04:06:06,875 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:06:07 INFO - 2015-12-11 04:06:07,825 INFO : Browser initialized. 04:06:07 INFO - 2015-12-11 04:06:07,826 INFO : Running cycle 1/1 for v8_7 test... 04:06:07 INFO - 2015-12-11 04:06:07,826 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoLx_rH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:06:19 INFO - 2015-12-11 04:06:19,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 142917632 04:06:19 INFO - 2015-12-11 04:06:19,865 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,341 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51219/tests/v8_7/run.html (next: http://localhost:51219/tests/v8_7/run.html) 04:06:41 INFO - 2015-12-11 04:06:41,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 138330112 04:06:41 INFO - 2015-12-11 04:06:41,599 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: |0;Richards;165.371258475277 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.88095683049897 04:06:41 INFO - 2015-12-11 04:06:41,600 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.623542476402 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1075.268817204301 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |5;Earley;707.9207920792079 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |6;Boyer;12345.67901234568 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |7;RegExp;26394.736842105263 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |8;Splay;592.0663114268798 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 04:06:41 INFO - 2015-12-11 04:06:41,601 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: __startTimestamp1449835601592__endTimestamp 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.37 Median:165.37 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: Values: 165.4 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.88 Median:186.88 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: Values: 186.9 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.62 Median:277.62 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: Values: 277.6 04:06:41 INFO - 2015-12-11 04:06:41,602 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: Values: 5170.1 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1075.27 Median:1075.27 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: Values: 1075.3 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:707.92 Median:707.92 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: Values: 707.9 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12345.68 Median:12345.68 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,603 DEBUG : BROWSER_OUTPUT: Values: 12345.7 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26394.74 Median:26394.74 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: Values: 26394.7 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:592.07 Median:592.07 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: Values: 592.1 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: Values: 4835.7 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:06:41 INFO - 2015-12-11 04:06:41,604 DEBUG : BROWSER_OUTPUT: 04:06:42 INFO - 2015-12-11 04:06:42,509 INFO : Browser exited with error code: 0 04:06:42 INFO - 2015-12-11 04:06:42,526 INFO : Completed test v8_7 (00:00:40) 04:06:42 INFO - 2015-12-11 04:06:42,610 INFO : Completed test suite (00:20:15) 04:06:42 INFO - 2015-12-11 04:06:42,610 DEBUG : Working with test: dromaeo_css 04:06:42 INFO - 2015-12-11 04:06:42,610 DEBUG : Generating results file: dromaeo_css 04:06:42 INFO - 2015-12-11 04:06:42,611 DEBUG : Working with test: kraken 04:06:42 INFO - 2015-12-11 04:06:42,611 DEBUG : Generating results file: kraken 04:06:42 INFO - 2015-12-11 04:06:42,612 INFO : javascript benchmark 04:06:42 INFO - 2015-12-11 04:06:42,612 DEBUG : Working with test: v8_7 04:06:42 INFO - 2015-12-11 04:06:42,612 DEBUG : Generating results file: v8_7 04:06:42 INFO - 2015-12-11 04:06:42,612 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:06:42 INFO - 2015-12-11 04:06:42,654 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 04:06:42 INFO - 2015-12-11 04:06:42,654 DEBUG : process_Request line: dromaeo_css 6263.87 graph.html#tests=[[72,94,43]] 04:06:42 INFO - 2015-12-11 04:06:42,654 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:06:42 INFO - 2015-12-11 04:06:42,690 DEBUG : process_Request line: kraken 1545.20 graph.html#tests=[[232,94,43]] 04:06:42 INFO - 2015-12-11 04:06:42,691 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:06:42 INFO - 2015-12-11 04:06:42,730 DEBUG : process_Request line: v8_7 21363.69 graph.html#tests=[[230,94,43]] 04:06:42 INFO - 2015-12-11 04:06:42,732 INFO : javascript benchmark 04:06:42 INFO - 2015-12-11 04:06:42,735 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2634.815562910596, 2663.267601409611, 2698.7840972722183, 2707.6073969274457, 2714.4299697063616, 4607.285870690049, 4672.953270467295, 4681.180793361161, 4694.6244300455965, 4709.4348678158585, 4593.954060459394, 4613.331066995292, 4653.348531205634, 4702.600278976282, 4716.669833111683, 4319.2441322768545, 4344.348347747831, 4348.89127771805, 4386.254336762742, 4397.0, 4143.502779666429, 4353.477582690081, 4360.878338750891, 4440.55594440555, 4440.777961101948, 4224.683148763825, 4258.6380157686635, 4290.978545107255, 4296.699231053827, 4297.505786834523, 4332.826686932519, 4360.869173924787, 4361.323994780782, 4364.323529852878, 4396.230659634552, 4135.545090040093, 4219.177260434217, 4333.544977777351, 4345.217860785057, 4398.318260669601, 4459.888502787457, 4486.170058539148, 4537.568930951555, 4562.110388474249, 4569.977150114261, 4980.452150263468, 4983.626228032912, 4986.127427700138, 4990.800367985276, 5014.52362025607, 4886.60907127429, 4967.428745694219, 5020.723860187688, 5029.396472423333, 5032.798688052473, 4561.338605902126, 4577.33628623851, 4596.49438561758, 4606.078784243165, 4653.2554791233915, 5143.4085080216255, 5196.142636465017, 5197.8180763673845, 5209.53114219722, 5217.113090774577, 4741.66808323421, 4836.0, 4975.303457515951, 4982.576480999156, 5028.497150284942, 4878.829240976549, 4891.755412229374, 4908.8772780680765, 4925.458199598042, 5007.549320561167, 4937.308776771255, 5005.6996580205305, 5007.37407824022, 5025.170846810309, 5063.493650634907, 4784.760761961888, 4824.831130910471, 4945.67853089548, 4966.925496117561, 5035.0685123252315, 4164.354525048622, 4165.354370072643, 4168.666506679519, 4169.91660166795, 4187.120704651997, 3619.511365965561, 3630.582483014487, 3632.3098611264304, 3662.780233185964, 3670.4127339625534, 4175.874723758292, 4284.635805956528, 4286.978565107217, 4288.571142885687, 4375.6280716138845, 4993.425756038029, 5023.221400682827, 5071.619628527875, 5077.441481437039, 5121.28302037715, 4381.430414046154, 4398.274284742982, 4409.977950110293, 4430.158269928703, 4437.622802061796, 4240.427542281815, 4333.804978775963, 4399.186150562156, 4481.596656300949, 4485.573870482299, 4859.708417494961, 4859.7327147006545, 4872.878178045577, 4880.389951256093, 4910.361652985232, 5067.138586440387, 5069.493050694901, 5078.162103252922, 5079.0, 5079.796808127633, 4672.602828759594, 4716.693414928019, 4805.038992201504, 4829.855104346875, 4904.019196160853, 4941.65408421407, 5005.024020316004, 5010.624203184703, 5024.723640199824, 5036.899262014813, 5106.565941894981, 5106.872328191825, 5107.846764597068, 5122.974385128051, 5151.124308867426, 4661.580457758818, 4762.047590481987, 4818.277258411266, 4932.4327702314695, 4946.406431228275, 4868.123737727243, 4918.311436398979, 4922.778474968563, 4927.531884470969, 4963.677360971526, 4816.253480710496, 4817.205161148371, 4820.40926494249, 4832.710037397767, 4840.201366774583, 4656.8137274508645, 4784.856454306377, 4811.206150985048, 4911.607071434348, 4935.309056732132, 4829.66192366531, 4839.951600484091, 4862.610991120772, 4864.294677271847, 4907.214845624683], "unit": "ms", "name": "dojo.html", "value": 4598.237590701471}, {"lowerIsBetter": false, "replicates": [560.2464684998678, 560.7168379968116, 564.438383808111, 566.0659911146607, 568.8037627018679, 35217.11957201071, 35561.288774224566, 35736.10659733505, 35809.64190358099, 35974.280514389764, 39529.40705889414, 39919.0, 39985.800070999605, 39988.600113998924, 40192.79903600478, 7885.960570197155, 7977.202279772006, 8007.879881801778, 8018.959905200466, 8032.799180020488, 7923.445358824884, 7982.082060563022, 8045.477043992151, 8046.557439340834, 8061.0, 7887.763367098996, 7907.762767116996, 7942.046954365484, 7942.841143177133, 7987.600619969007, 7931.6034198290145, 7932.405069619773, 7960.9203907960755, 7981.561014144219, 7989.32090772285, 7807.0, 7919.722809701633, 7969.402294827911, 7981.640826162837, 7989.201079892023, 37106.81446592777, 37476.43785343222, 37498.81250593757, 37673.81163094194, 37781.62218377808, 2812.985935070332, 2994.8802047918057, 3477.8434970426392, 3499.1602015516346, 3567.518385018015, 27471.45057098847, 27680.031198908142, 27904.860475697693, 27972.860135699393, 28499.57250641242, 3293.8353082346025, 3368.949465758016, 3416.3167366526545, 3442.3115376924716, 3455.706264967456, 224628.0, 225924.0, 226092.0, 226239.0, 226364.99999999834, 56646.0, 57110.714446427504, 57127.71436142793, 57226.427735722944, 57388.713056435285, 66296.0, 68056.31943680599, 68104.31895681079, 68331.31668683249, 68358.31641683618, 13045.739085218242, 13120.212787232796, 13127.803082953764, 13174.670633234246, 13294.734105318033, 10163.0, 10311.639092631722, 10338.0695737384, 10340.0, 10364.637237696645, 19524.8047519523, 19793.62476502829, 19835.404937851887, 19840.801591983894, 19920.804751714943, 7783.649735761905, 8090.069641991128, 8147.637185216339, 8154.469959452616, 8300.543470109202, 846.4540371460497, 879.8108406692592, 892.7366426903949, 962.4273557233574, 1064.435849000031, 25876.48247035049, 25891.0938117165, 26057.348566285993, 26194.476110477684, 26474.6028809568, 966.7583104223944, 972.7324985629009, 1006.8489726540931, 1067.5462928255615, 1082.929609575375, 13557.86442135566, 13732.519361822287, 13963.162210267417, 14028.649283767783, 14034.298285085704, 254600.0, 258455.7077214602, 260572.0, 260984.0, 261557.0, 45112.77443612827, 45394.31908521375, 45525.31712024322, 45628.54371456243, 45791.77104114525, 1162.3607016141088, 1191.6722901202065, 1197.6407077876777, 1199.196538319328, 1209.989658635032, 25132.62301065522, 25608.615870762325, 25640.487190256463, 25696.229113126636, 25803.612945806202, 39741.403878942416, 40188.1962360757, 40241.79879100586, 40242.798786005886, 40282.0, 243424.0, 243866.0, 244787.0, 245166.0, 245304.0, 29608.851955740083, 41920.16159676851, 42674.57325426706, 42750.144997100506, 42837.5716242846, 38178.61821381862, 38320.42519362156, 38398.42402363911, 39144.80427597958, 39364.0, 33091.8345408281, 33382.332353352314, 33811.4928276081, 33896.66103338935, 34285.65714342924, 33158.502622461165, 33232.501512477815, 33342.83328583342, 33507.0, 33614.663853361155, 37637.0, 37675.0, 38340.23319533538, 38677.613223868524, 39006.0, 27659.58510622302, 28627.0, 30484.695153048186, 30514.0, 30910.845445772626, 23481.765182347957, 24064.51870962606, 24069.157579484636, 24072.87963560171, 24161.15435959733], "unit": "ms", "name": "ext.html", "value": 17237.648138145392}, {"lowerIsBetter": false, "replicates": [337.95437615921844, 340.2701205913316, 340.59639327397036, 341.47924415266715, 341.88546836809667, 15038.473653422132, 15175.39298428063, 15999.680006399865, 16219.675606487877, 16560.503184904443, 15144.621384465394, 15208.087514749095, 15373.38506459743, 15468.381264749396, 15496.380144794195, 4269.167512335088, 4443.155800397914, 4444.688871778977, 4448.488423831269, 4453.777311134447, 3564.269324788412, 4353.0423306872435, 4460.1525710114975, 4518.344839998198, 4524.954750452486, 4344.543822898598, 4350.934735978963, 4966.0, 5114.795408183687, 5130.5126013028885, 5022.6233032522705, 5064.139096353629, 5069.9493005070035, 5074.264231686403, 5094.821681241138, 5100.642954993152, 5129.538341549279, 5130.794768209267, 5134.589232861362, 5146.614003949719, 10467.476626168662, 10700.357978521235, 12105.45525451348, 17637.823621763746, 17646.823531764774, 1737.8435940765266, 1806.7831860176864, 1811.8007019227875, 1860.1470675678263, 1882.3882238272615, 8522.446041007315, 8836.911630883738, 8936.0, 9027.458352498805, 9196.770080747969, 1722.1389305347327, 1794.9551261218444, 1811.3750755989163, 1829.7438358629802, 1836.6877630802796, 50276.0, 54892.72553637246, 55245.72377138129, 55879.16181257284, 57070.71464642692, 8759.868601970975, 9262.768430789285, 9904.41529355314, 10005.0, 10079.798404031879, 12592.307423091635, 12596.0, 12603.684907877194, 12605.495780168874, 12607.369631518572, 7090.645467726593, 7138.857222855514, 7151.249118842551, 7193.89209161863, 7276.599787011659, 6826.692798824065, 6955.930440695528, 6967.268436814163, 6968.059311992917, 6996.160460744743, 4276.5295817460055, 4280.72175308604, 4286.39990401344, 4307.504636966726, 4331.545187755303, 1637.87715921304, 1648.5878530367409, 1775.946721598354, 1793.2557988434653, 1827.5248435406625, 718.8454482286334, 758.7571976967317, 803.0042746993686, 813.1380736419414, 833.2375876073446, 15332.770008449883, 15715.21423928799, 15726.685466290839, 15954.840451595568, 16156.676866462605, 777.149021821105, 822.0546371672505, 857.7426771968385, 870.5560164316117, 916.2852974679761, 9073.59168837404, 9146.2225710814, 9179.908200918038, 9185.954070229605, 9221.953890230507, 457227.0, 464532.0, 468239.0, 487283.0, 487719.0, 68082.0, 69267.65366173237, 69480.3051969484, 70423.2957670427, 70587.29412705808, 1009.7475631092227, 1012.9797404051877, 1024.1039090795555, 1030.8247597908223, 1030.9587616495255, 13542.864571354161, 14317.64205894861, 14376.496822611158, 14390.928045359706, 14497.0, 32121.0, 33601.66398335985, 33808.3238335227, 34430.82784586061, 34900.47649285314, 39630.40554391744, 40425.191496169326, 40597.0, 41589.79205103955, 43390.783046084565, 8505.23452889243, 9173.954130229573, 9200.6779762708, 9205.158968206251, 9216.0, 10629.89370106289, 10638.0, 15289.235538223267, 15418.691626167638, 17039.14804259807, 11589.362585057566, 13119.934400327937, 13145.0, 13157.473701051851, 13802.930985345009, 13020.218786872818, 13216.471341146247, 13353.465861365437, 13709.520166794115, 13858.0, 13263.877779555225, 13632.113912595649, 13684.93157534206, 13959.626863433037, 14128.43486260538, 8954.731358059009, 9114.316426267924, 9162.267018638626, 9196.0, 9226.584803683985, 11770.352630605057, 13650.658733531742, 13705.0, 13905.930470347988, 14424.855751442352], "unit": "ms", "name": "jquery.html", "value": 8073.684983357138}, {"lowerIsBetter": false, "replicates": [1436.5331267338104, 1692.610699539106, 1719.8452139307467, 1731.8441340279373, 1761.137042849004, 6081.726322315495, 6138.355472675372, 6755.0, 6945.027696122541, 6973.546719463237, 6578.5066120041065, 6804.931950680491, 6837.863242735142, 6847.178338599362, 6852.82867928302, 2011.2558353409186, 2039.2352867674622, 2041.4181958141933, 2041.540653352991, 2041.765197002349, 1958.0699167895207, 1979.732736080625, 1988.532694816717, 1994.7207390965277, 2023.6863286190664, 2065.834733221339, 2076.7611724651706, 2106.6734656128247, 2108.3569511299047, 2110.947226319339, 1908.055512521297, 1923.4614307993713, 1923.7114432835117, 1931.9034048297528, 1932.4299331697043, 2007.638625047491, 2019.0712272354735, 2034.511717187879, 2050.0774651406928, 2053.3531937439657, 6822.727090916357, 6850.520463567552, 6865.244823069458, 6905.067815844847, 6912.447004239699, 4056.3509838426007, 4113.71204015716, 4116.0, 4122.237386083584, 4130.215259100762, 2553.3488960315, 2580.329114430243, 2598.883050262724, 2703.148508219904, 2719.2250208690625, 839.890814194157, 840.1892174052008, 879.7316818370394, 894.959726812295, 1600.4718442913927, 9334.439933604006, 9444.952775236148, 9454.33819632619, 9457.810843783154, 9460.85808712863, 3642.2715456908722, 3697.8890633280507, 3758.2671379080816, 3764.171882185915, 3775.1505911169766, 7330.0, 7347.8163045924275, 7361.558306501627, 7361.668724907392, 7374.557526548424, 4109.034376921421, 4122.979385103055, 4128.793560322032, 4147.108371700099, 4155.335146376565, 3722.218334149805, 3813.6758375537834, 3851.826667799956, 3856.807159642007, 3861.0540417597867, 3198.760092992988, 3258.250602361423, 3264.918377040593, 3274.9345013099605, 3286.0306209668206, 992.2359782967075, 993.7614972406715, 995.3977843404781, 996.5814357969671, 997.9600815967425, 1117.6311817100338, 1149.4425203776161, 1149.683836944847, 1160.1009217856229, 1173.4602083041896, 6256.155419018374, 6366.172397588284, 6566.540342176002, 6601.207855057424, 6624.0, 376.63090171631956, 381.66985557493126, 382.128746458076, 382.60782697734487, 388.279741080292, 4860.270959356054, 4925.556699897026, 4928.457869634337, 4935.728534930541, 4940.6788558743565, 8472.110428404929, 8509.064002959669, 8701.129887011248, 8708.69519566812, 8757.124287571192, 8506.829863402698, 8548.016978047479, 8582.742517724479, 8608.69869554575, 8628.827423451496, 6702.363275488919, 6728.932710672929, 6744.0, 6786.660666966632, 6789.0834737310815, 6489.513286503626, 6509.804705858832, 6516.283208847118, 6526.967365163334, 6574.638394888327, 7199.604021778643, 7328.706851725871, 7373.410127189919, 7503.9249607503225, 7559.168491465824, 7666.271704188093, 7714.768556943301, 7818.491798033109, 8011.6795328188555, 8036.276735093869, 5433.157860531624, 5552.972235138798, 6157.261128664589, 6203.100550420254, 6241.500679945502, 6631.568948018364, 6708.22855371619, 6743.966280168764, 6762.966185169043, 6784.423324017514, 5788.334341550775, 5898.9115163273445, 6060.787872424444, 6221.813345599639, 6256.687165641791, 6194.349593292639, 6207.937920620736, 6211.192544969306, 6352.618842869442, 6364.07720880479, 6336.0, 6381.904271436025, 6401.8719625608155, 6406.13517175194, 6429.871402571829, 5939.138824870456, 5957.49361304294, 6000.669963152069, 6000.909986350295, 6023.548233882389, 5760.0783874579865, 5924.11138329254, 5932.0508718604815, 6003.639781612943, 6046.2139921809885], "unit": "ms", "name": "mootools.html", "value": 3917.625257334484}, {"lowerIsBetter": false, "replicates": [310.6163887598816, 312.4720118619949, 322.5822559785078, 323.047011316616, 324.02630096559835, 5421.403645598986, 5456.427075157111, 5467.753951072202, 5476.780928762854, 5623.100303951369, 5426.294581704373, 5471.781128754845, 5472.945270547303, 5476.972615136919, 5482.821193443408, 4043.110515686544, 4139.130782535671, 4161.542230354658, 4223.936640950388, 4228.76741779202, 4130.215259100776, 4155.812988415513, 4163.604457576525, 4175.958240417587, 4186.874393768192, 4087.0804069084516, 4091.0386059276193, 4113.280175969208, 4129.6696264298935, 4135.648469880064, 4131.256373852721, 4131.338985762263, 4132.152908653734, 4135.0902801383645, 4143.751374917515, 3536.876209332687, 3598.5501812273465, 3601.009722326355, 3605.06268370223, 3647.197616524387, 5094.974525127388, 5209.843704688866, 5225.320708307933, 5233.581313494911, 5238.109521381336, 4742.3123647071325, 4769.475357710649, 4774.618030557547, 4775.761211939389, 4777.163996300634, 5733.942660573382, 5738.0532212184935, 5741.25363702716, 5741.311042674905, 5763.971180144114, 3800.5819359870393, 3920.6863450924143, 3921.901952451183, 3923.5684074751753, 3927.725059245854, 5946.405359464062, 5983.940160598426, 6028.125921741323, 6029.155918171416, 6029.728662210167, 4042.3532234842282, 4045.8988525287104, 4069.4709687740406, 4071.2875246831686, 4081.142959978438, 4142.06803469217, 4150.27370210218, 4151.875443736692, 4153.875383738493, 4159.5008598967715, 4605.769711514478, 4726.0, 4731.2903064539905, 4745.952540474551, 4765.642576806753, 5525.4474552545225, 5625.5092400513895, 5628.268325117709, 5630.268065151504, 5637.3235211774845, 1433.6702558411623, 1463.370750577262, 1463.4512057978259, 1465.545680838922, 1465.5823090419178, 970.4759429907771, 982.9262805289632, 984.8030393921244, 997.3218211616171, 1002.1882275356984, 4932.53140951609, 4959.801607935714, 4964.751762411937, 5013.49865013503, 5023.547880690755, 5890.4993075589055, 5934.762609495659, 5938.317093534212, 5938.406159384027, 5945.3757355477055, 4993.525615066563, 4996.525330093636, 4999.02519008797, 5003.924941125886, 5009.423916249678, 3115.922101947424, 3157.542156387311, 3191.0426871938325, 3198.6321573018936, 3199.728023118061, 5619.8876022480135, 5642.689652069175, 5648.661080335193, 5654.208410822488, 5669.518090962314, 6051.546134039964, 6074.3925607438905, 6086.2087928569, 6092.573519853568, 6104.450599445982, 5485.780568777204, 5597.552195824324, 5623.212750214973, 5647.858803529944, 5657.886842263049, 5909.970450147722, 5915.91126133117, 5922.318933322723, 5922.55580831448, 5922.940770592411, 5785.4214578541805, 5796.623219490888, 5802.738876750472, 5803.651780892991, 5810.099434587646, 5623.662580245198, 5624.437556244343, 5641.520470759867, 5648.039833228443, 5651.406602306699, 3962.841486340629, 4028.3957406389277, 4040.2323558523785, 4106.486689163855, 4113.280175969134, 5860.208871802422, 5940.1683764272175, 5957.731902064331, 5961.463468287875, 5993.730282137227, 5717.714114294352, 5725.828225153252, 5747.712614369349, 5760.683162425939, 5765.827025189251, 5703.7433315500075, 5729.598928075162, 5744.913826292693, 5761.5102716269585, 5763.789604183168, 5497.5601951842955, 5578.414266502121, 5608.68195973954, 5631.6902570359025, 5655.575831812713, 5747.396523365153, 5799.3040835100055, 5811.4479124483105, 5824.70876456167, 5857.033589457692, 5660.235868157746, 5664.773409063591, 5665.971670141623, 5670.943290567042, 5684.204211410489], "unit": "ms", "name": "prototype.html", "value": 4283.1960112979805}, {"lowerIsBetter": false, "replicates": [659.577870163096, 775.3099741230302, 776.5030380556443, 779.4777499075619, 782.8669126248539, 11603.65189044329, 11911.940440297798, 11954.521819127225, 11967.64097077088, 12011.519539218443, 10887.401192934405, 10998.450077496133, 11018.44907754613, 11520.423978801047, 11558.30650160991, 803.4817542684965, 809.8339840332718, 810.4084018666363, 827.6317038917684, 827.6978902700502, 779.7076096463826, 784.6861255497789, 784.7881072110526, 793.1196372027046, 809.9271065604095, 833.7540425574471, 836.6318819719335, 838.5220424358118, 844.307667712476, 855.2260204514924, 827.0365024746163, 831.3432388413146, 831.8419500294924, 836.2264904962915, 859.1108203009886, 629.0846817879974, 634.9904751428733, 637.1907677249867, 638.2086213095728, 638.3648269971408, 12114.152009359348, 12182.51269949201, 12228.510859565607, 12230.388480575935, 12236.694082647917, 11079.778404431867, 11084.0, 11354.091672666167, 11368.772624547462, 11373.431328433628, 9471.66849160276, 9495.857562136573, 9588.66439674608, 9651.276154288385, 9688.0, 3273.214428537158, 3300.818454984975, 3324.3683700096904, 3326.966730332714, 3361.411752943225, 39013.0, 39038.0, 39219.21561568781, 39322.606773932464, 39553.80223098866, 9192.63229470827, 9240.676576319931, 9256.0, 9364.391314564531, 9409.623615055458, 15777.921110394374, 15839.604009899846, 15855.445059422866, 15860.52418427449, 15866.603334916488, 6182.196314479089, 6183.505319574423, 6221.0, 6228.719707613168, 6292.528060395489, 3659.14010207601, 3676.8713095041544, 3681.5398075240596, 3696.5933747287777, 3697.445383192489, 2664.174106027138, 2683.034107721219, 2689.0991517841353, 2689.5831146172372, 2696.44722831819, 502.9723365214948, 509.38364578859256, 512.9589632829365, 514.1259858241006, 514.4880843560682, 527.5515811560143, 528.1734086155155, 530.5225297232445, 533.1496263459776, 543.5081251467428, 6922.688479018356, 6941.965290173517, 7012.824679383056, 7021.368076873106, 7137.643117844191, 425.7977460706202, 469.27965572845517, 472.9314249433813, 496.5952748509953, 527.4857014410919, 3404.676555727202, 3449.0515108345407, 3540.539729835105, 3709.888703338904, 3766.7363284570365, 43791.34312985308, 45240.0, 45434.09131817411, 45629.31556026662, 45716.77141614271, 37748.433773493416, 37877.62122378796, 37907.0, 38078.238435231695, 38894.6110538891, 39890.0, 40809.79595102005, 40884.18231635351, 41290.0, 41797.58202417937, 7309.963450182715, 7633.274838890296, 7707.768766937001, 7708.961455192688, 7940.523568585903, 30888.382232355678, 31532.684673152973, 31711.524327135572, 31747.0, 32119.518207226443, 38256.426153607164, 41143.588564115176, 41396.0, 41502.79248603858, 41626.791866040476, 9841.311108222642, 9890.307678462439, 9894.752631184278, 9912.15746661542, 10202.744931376776, 10854.312424006806, 11134.665960021212, 11201.327920324806, 11270.436478176221, 11300.94349528247, 10155.84766228522, 10292.897071029194, 10435.478226088513, 10438.843417348899, 10440.947795260976, 9636.807263854824, 10582.576696932036, 10965.287256328313, 10979.231453798158, 11050.557977680803, 10539.94730026345, 10636.202284828514, 10671.94664026675, 10692.358458492516, 10698.304610200312, 8489.278411335106, 8706.347023973101, 8726.869096963677, 8852.159044890725, 8936.151065648754, 8270.669173233244, 8435.481613309417, 8694.608742606724, 8741.519216442903, 8821.221315285664], "unit": "ms", "name": "yui.html", "value": 5624.423142567097}], "name": "dromaeo_css", "value": 6263.871247707621}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 113.0, 114.0, 113.0, 125.0, 112.0, 112.0, 113.0, 116.0], "unit": "ms", "name": "ai-astar", "value": 113.8}, {"lowerIsBetter": true, "replicates": [158.0, 132.0, 158.0, 171.0, 130.0, 171.0, 156.0, 131.0, 169.0, 133.0], "unit": "ms", "name": "audio-beat-detection", "value": 150.9}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 167.0, 163.0, 165.0, 168.0, 166.0, 162.0, 165.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 164.7}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 73.0, 74.0, 74.0, 79.0, 134.0, 72.0, 71.0, 80.0], "unit": "ms", "name": "audio-fft", "value": 80.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 134.0, 94.0, 95.0, 96.0, 133.0, 96.0, 95.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 149.0, 147.0, 149.0, 148.0, 148.0, 147.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 74.0, 76.0, 77.0, 75.0, 81.0, 75.0, 82.0, 73.0], "unit": "ms", "name": "json-parse-financial", "value": 76.6}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 84.0, 85.0, 83.0, 89.0, 87.0, 84.0, 84.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.1}, {"lowerIsBetter": true, "replicates": [135.0, 127.0, 124.0, 121.0, 137.0, 129.0, 125.0, 128.0, 124.0, 129.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 127.9}, {"lowerIsBetter": true, "replicates": [146.0, 150.0, 146.0, 147.0, 148.0, 147.0, 149.0, 153.0, 148.0, 150.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.4}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 70.0, 71.0, 70.0, 69.0, 69.0, 71.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 69.4}], "name": "kraken", "value": 1545.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.371258475277], "unit": "score", "name": "Richards", "value": 213.47119400000008}, {"lowerIsBetter": false, "replicates": [186.88095683049897], "unit": "score", "name": "DeltaBlue", "value": 353.79741800000016}, {"lowerIsBetter": false, "replicates": [277.623542476402], "unit": "score", "name": "Encrypt", "value": 958.7839620000002}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [1075.268817204301], "unit": "score", "name": "RayTrace", "value": 688.1897699999998}, {"lowerIsBetter": false, "replicates": [707.9207920792079], "unit": "score", "name": "Earley", "value": 941.437244755245}, {"lowerIsBetter": false, "replicates": [12345.67901234568], "unit": "score", "name": "Boyer", "value": 53.98350299999995}, {"lowerIsBetter": false, "replicates": [26394.736842105263], "unit": "score", "name": "RegExp", "value": 34.51388833499503}, {"lowerIsBetter": false, "replicates": [592.0663114268798], "unit": "score", "name": "Splay", "value": 137.63829900000002}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21363.68800339323}]} 04:06:42 INFO - RETURN: dromaeo_css: 6263.87 04:06:42 INFO - RETURN: kraken: 1545.20 04:06:42 INFO - RETURN: v8_7: 21363.69 04:06:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21363.69"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1545.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6263.87"}}} 04:06:42 INFO - Return code: 0 04:06:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:06:42 INFO - # TBPL SUCCESS # 04:06:42 INFO - Running post-action listener: _resource_record_post_action 04:06:42 INFO - Running post-run listener: _resource_record_post_run 04:06:43 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506843136; Read time: 16; Write time: 332432 04:06:43 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 231055360; Read time: 0; Write time: 187780 04:06:43 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 4096; Write bytes: 275787776; Read time: 16; Write time: 144652 04:06:43 INFO - Running post-run listener: _upload_blobber_files 04:06:43 INFO - Blob upload gear active. 04:06:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:06:43 INFO - Copying logs to upload dir... 04:06:43 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1276.174964 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-12-11 04:06:41.778315) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-11 04:06:41.782025) ========= 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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009965 build_url: 'https://queue.taskcluster.net/v1/task/92Hv94vGQOewZq5tqGSJig/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-11 04:06:41.834182) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 04:06:41.834578) ========= 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=b00de3c228505fac2445e70d000001bb-1449828311.373533-1469279577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004549 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-11 04:06:41.884955) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-11 04:06:41.885310) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-11 04:06:41.885653) ========= ========= Total master_lag: 0.26 =========