builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-029 starttime: 1450147057.66 results: success (0) buildid: 20151214180411 builduid: bca6f7cedca446dc9008fdb9d992709a revision: 063c2822231032a310ad6508daf7a52486ec2e5c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.662915) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.663416) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.663761) ========= 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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006345 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.712646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.713034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.782130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.782419) ========= 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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004595 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.832813) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.833153) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.833570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:37.833881) ========= 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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-14 18:37:38-- 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% 38.6M=0s 2015-12-14 18:37:38 (38.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168449 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:38.017382) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:38.017969) ========= 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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014821 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:38.107512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-14 18:37:38.107957) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 063c2822231032a310ad6508daf7a52486ec2e5c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 063c2822231032a310ad6508daf7a52486ec2e5c --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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-14 18:37:38,464 truncating revision to first 12 chars 2015-12-14 18:37:38,464 Setting DEBUG logging. 2015-12-14 18:37:38,464 attempt 1/10 2015-12-14 18:37:38,464 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/063c28222310?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-14 18:37:39,462 unpacking tar archive at: fx-team-063c28222310/testing/mozharness/ program finished with exit code 0 elapsedTime=1.276069 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-14 18:37:39.399369) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:39.399940) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:39.411576) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:39.412223) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-14 18:37:39.413163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-14 18:37:39.413920) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:37:39 INFO - MultiFileLogger online at 20151214 18:37:39 in /builds/slave/test 18:37:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 18:37:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:37:39 INFO - {'append_to_log': False, 18:37:39 INFO - 'base_work_dir': '/builds/slave/test', 18:37:39 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:37:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:37:39 INFO - 'branch': 'Fx-Team-Non-PGO', 18:37:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:37:39 INFO - 'config_files': ('talos/linux_config.py',), 18:37:39 INFO - 'default_actions': ('clobber', 18:37:39 INFO - 'read-buildbot-config', 18:37:39 INFO - 'download-and-extract', 18:37:39 INFO - 'populate-webroot', 18:37:39 INFO - 'create-virtualenv', 18:37:39 INFO - 'install', 18:37:39 INFO - 'run-tests'), 18:37:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:37:39 INFO - 'download_minidump_stackwalk': True, 18:37:39 INFO - 'download_symbols': 'ondemand', 18:37:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:37:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:37:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:37:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:37:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:37:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:37:39 INFO - 'installer_path': 'installer.exe', 18:37:39 INFO - 'log_level': 'info', 18:37:39 INFO - 'log_name': 'talos', 18:37:39 INFO - 'log_to_console': True, 18:37:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:37:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:37:39 INFO - 'opt_config_files': (), 18:37:39 INFO - 'pip_index': False, 18:37:39 INFO - 'sps_profile': False, 18:37:39 INFO - 'sps_profile_interval': 0, 18:37:39 INFO - 'suite': 'dromaeojs-e10s', 18:37:39 INFO - 'system_bits': '64', 18:37:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:37:39 INFO - 'title': 'talos-linux64-ix-029', 18:37:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:37:39 INFO - 'use_talos_json': True, 18:37:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:37:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:37:39 INFO - 'work_dir': 'build'} 18:37:39 INFO - ##### 18:37:39 INFO - ##### Running clobber step. 18:37:39 INFO - ##### 18:37:39 INFO - Running pre-action listener: _resource_record_pre_action 18:37:39 INFO - Running main action method: clobber 18:37:39 INFO - rmtree: /builds/slave/test/build 18:37:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:37:40 INFO - Running post-action listener: _resource_record_post_action 18:37:40 INFO - ##### 18:37:40 INFO - ##### Running read-buildbot-config step. 18:37:40 INFO - ##### 18:37:40 INFO - Running pre-action listener: _resource_record_pre_action 18:37:40 INFO - Running main action method: read_buildbot_config 18:37:40 INFO - Using buildbot properties: 18:37:40 INFO - { 18:37:40 INFO - "properties": { 18:37:40 INFO - "buildnumber": 817, 18:37:40 INFO - "product": "firefox", 18:37:40 INFO - "script_repo_revision": "production", 18:37:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 18:37:40 INFO - "repository": "", 18:37:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 18:37:40 INFO - "buildid": "20151214180411", 18:37:40 INFO - "slavename": "talos-linux64-ix-029", 18:37:40 INFO - "pgo_build": "False", 18:37:40 INFO - "basedir": "/builds/slave/test", 18:37:40 INFO - "project": "", 18:37:40 INFO - "platform": "ubuntu64_hw", 18:37:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:37:40 INFO - "slavebuilddir": "test", 18:37:40 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:37:40 INFO - "branch": "fx-team", 18:37:40 INFO - "repo_path": "integration/fx-team", 18:37:40 INFO - "stage_platform": "linux64", 18:37:40 INFO - "builduid": "bca6f7cedca446dc9008fdb9d992709a", 18:37:40 INFO - "revision": "063c2822231032a310ad6508daf7a52486ec2e5c" 18:37:40 INFO - }, 18:37:40 INFO - "sourcestamp": { 18:37:40 INFO - "repository": "", 18:37:40 INFO - "hasPatch": false, 18:37:40 INFO - "project": "", 18:37:40 INFO - "branch": "fx-team-linux64-talos", 18:37:40 INFO - "changes": [ 18:37:40 INFO - { 18:37:40 INFO - "category": null, 18:37:40 INFO - "files": [ 18:37:40 INFO - { 18:37:40 INFO - "url": null, 18:37:40 INFO - "name": "https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:37:40 INFO - } 18:37:40 INFO - ], 18:37:40 INFO - "repository": "", 18:37:40 INFO - "rev": "063c2822231032a310ad6508daf7a52486ec2e5c", 18:37:40 INFO - "who": "bgrinstead@mozilla.com", 18:37:40 INFO - "when": 1450147035, 18:37:40 INFO - "number": 7360522, 18:37:40 INFO - "comments": "Bug 1224726 - Do not attempt to parse source file when searching in debugger if text > 1MB;r=jlongster", 18:37:40 INFO - "project": "", 18:37:40 INFO - "at": "Mon 14 Dec 2015 18:37:15", 18:37:40 INFO - "branch": "fx-team-linux64-talos", 18:37:40 INFO - "revlink": "", 18:37:40 INFO - "properties": [ 18:37:40 INFO - [ 18:37:40 INFO - "buildid", 18:37:40 INFO - "20151214180411", 18:37:40 INFO - "Change" 18:37:40 INFO - ], 18:37:40 INFO - [ 18:37:40 INFO - "builduid", 18:37:40 INFO - "bca6f7cedca446dc9008fdb9d992709a", 18:37:40 INFO - "Change" 18:37:40 INFO - ], 18:37:40 INFO - [ 18:37:40 INFO - "pgo_build", 18:37:40 INFO - "False", 18:37:40 INFO - "Change" 18:37:40 INFO - ] 18:37:40 INFO - ], 18:37:40 INFO - "revision": "063c2822231032a310ad6508daf7a52486ec2e5c" 18:37:40 INFO - } 18:37:40 INFO - ], 18:37:40 INFO - "revision": "063c2822231032a310ad6508daf7a52486ec2e5c" 18:37:40 INFO - } 18:37:40 INFO - } 18:37:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:37:40 INFO - Running post-action listener: _resource_record_post_action 18:37:40 INFO - ##### 18:37:40 INFO - ##### Running download-and-extract step. 18:37:40 INFO - ##### 18:37:40 INFO - Running pre-action listener: _resource_record_pre_action 18:37:40 INFO - Running main action method: download_and_extract 18:37:40 INFO - mkdir: /builds/slave/test/build/tests 18:37:40 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')]} 18:37:40 INFO - https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:37:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/test_packages.json 18:37:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/test_packages.json 18:37:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 18:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 18:37:41 INFO - Downloaded 1302 bytes. 18:37:41 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:37:41 INFO - Using the following test package requirements: 18:37:41 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:37:41 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:37:41 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'jsshell-linux-x86_64.zip'], 18:37:41 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:37:41 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:37:41 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:37:41 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:37:41 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:37:41 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:37:41 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:37:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:37:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:41 INFO - https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:37:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:37:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:37:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:42 INFO - Downloaded 21869889 bytes. 18:37:42 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 18:37:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:37:42 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 18:37:43 INFO - Return code: 0 18:37:43 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 18:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:43 INFO - https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:37:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:37:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:37:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:45 INFO - Downloaded 21869889 bytes. 18:37:45 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 18:37:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:37:45 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 18:37:46 INFO - Return code: 0 18:37: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')]} 18:37:46 INFO - https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:37:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:37:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:37:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:48 INFO - Downloaded 11132038 bytes. 18:37:48 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 18:37:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:37:48 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 18:37:48 INFO - Return code: 0 18:37:48 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')]} 18:37:48 INFO - https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:37:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:37:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:37:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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 18:37:50 INFO - Downloaded 57741665 bytes. 18:37:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:37:50 INFO - mkdir: /builds/slave/test/properties 18:37:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:37:50 INFO - Writing to file /builds/slave/test/properties/build_url 18:37:50 INFO - Contents: 18:37:50 INFO - build_url:https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:37:50 INFO - Running post-action listener: _resource_record_post_action 18:37:50 INFO - Running post-action listener: set_extra_try_arguments 18:37:50 INFO - ##### 18:37:50 INFO - ##### Running populate-webroot step. 18:37:50 INFO - ##### 18:37:50 INFO - Running pre-action listener: _resource_record_pre_action 18:37:50 INFO - Running main action method: populate_webroot 18:37:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:37:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:37:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:37:50 INFO - '--tppagecycles', 18:37:50 INFO - '7'], 18:37:50 INFO - 'tests': ['tsvgm']}}, 18:37:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:37:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:37:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:37:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:37:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:37:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:37:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'tests': ['damp', 'tps']}, 18:37:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['damp', 'tps']}, 18:37:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:37:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:37:50 INFO - 'other': {'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore']}, 18:37:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore']}, 18:37:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore']}, 18:37:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore']}, 18:37:50 INFO - 'other_l64': {'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore']}, 18:37:50 INFO - 'other_nol64': {'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore']}, 18:37:50 INFO - 'svgr': {'tests': ['tsvgx', 18:37:50 INFO - 'tsvgr_opacity', 18:37:50 INFO - 'tart', 18:37:50 INFO - 'tscrollx', 18:37:50 INFO - 'cart']}, 18:37:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['tsvgx', 18:37:50 INFO - 'tsvgr_opacity', 18:37:50 INFO - 'tart', 18:37:50 INFO - 'tscrollx', 18:37:50 INFO - 'cart']}, 18:37:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'tests': ['tp5o']}, 18:37:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--e10s'], 18:37:50 INFO - 'tests': ['tp5o']}, 18:37:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--xperf_path', 18:37:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:37:50 INFO - 'tests': ['tp5n']}, 18:37:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--e10s', 18:37:50 INFO - '--xperf_path', 18:37:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:37:50 INFO - 'tests': ['tp5n']}}, 18:37:50 INFO - 'talos.zip': {'path': '', 18:37:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:37:50 INFO - Running post-action listener: _resource_record_post_action 18:37:50 INFO - ##### 18:37:50 INFO - ##### Running create-virtualenv step. 18:37:50 INFO - ##### 18:37:50 INFO - Running pre-action listener: _resource_record_pre_action 18:37:50 INFO - Running main action method: create_virtualenv 18:37:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:37:50 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 18:37:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:37:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:37:50 INFO - Using real prefix '/usr' 18:37:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:37:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:37:52 INFO - Installing pip.................done. 18:37:52 INFO - Return code: 0 18:37:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:52 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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:37:52 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 18:37:52 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 18:37:52 INFO - Using env: {'DISPLAY': ':0', 18:37:52 INFO - 'HOME': '/home/cltbld', 18:37:52 INFO - 'LANG': 'en_US.UTF-8', 18:37:52 INFO - 'LANGUAGE': 'en_US:en', 18:37:52 INFO - 'LOGNAME': 'cltbld', 18:37:52 INFO - 'MAIL': '/var/mail/cltbld', 18:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:52 INFO - 'MOZ_NO_REMOTE': '1', 18:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:52 INFO - 'NO_EM_RESTART': '1', 18:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:52 INFO - 'PWD': '/builds/slave/test', 18:37:52 INFO - 'SHELL': '/bin/bash', 18:37:52 INFO - 'SHLVL': '1', 18:37:52 INFO - 'TERM': 'linux', 18:37:52 INFO - 'TMOUT': '86400', 18:37:52 INFO - 'USER': 'cltbld', 18:37:52 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:52 INFO - '_': '/tools/buildbot/bin/python'} 18:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:52 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 18:37:52 INFO - Cleaning up... 18:37:52 INFO - Return code: 0 18:37:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:52 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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:37:52 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 18:37:52 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 18:37:52 INFO - Using env: {'DISPLAY': ':0', 18:37:52 INFO - 'HOME': '/home/cltbld', 18:37:52 INFO - 'LANG': 'en_US.UTF-8', 18:37:52 INFO - 'LANGUAGE': 'en_US:en', 18:37:52 INFO - 'LOGNAME': 'cltbld', 18:37:52 INFO - 'MAIL': '/var/mail/cltbld', 18:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:52 INFO - 'MOZ_NO_REMOTE': '1', 18:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:52 INFO - 'NO_EM_RESTART': '1', 18:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:52 INFO - 'PWD': '/builds/slave/test', 18:37:52 INFO - 'SHELL': '/bin/bash', 18:37:52 INFO - 'SHLVL': '1', 18:37:52 INFO - 'TERM': 'linux', 18:37:52 INFO - 'TMOUT': '86400', 18:37:52 INFO - 'USER': 'cltbld', 18:37:52 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:52 INFO - '_': '/tools/buildbot/bin/python'} 18:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:53 INFO - Downloading/unpacking psutil>=0.7.1 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:54 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:37:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:37:54 INFO - Installing collected packages: psutil 18:37:54 INFO - Running setup.py install for psutil 18:37:54 INFO - building 'psutil._psutil_linux' extension 18:37:54 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 18:37:55 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 18:37:55 INFO - building 'psutil._psutil_posix' extension 18:37:55 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 18:37:55 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 18:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:37:55 INFO - Successfully installed psutil 18:37:55 INFO - Cleaning up... 18:37:55 INFO - Return code: 0 18:37:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:37: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')]} 18:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37: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')]} 18:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37: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', '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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:37: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:37: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 mozsystemmonitor==0.0.0 18:37:55 INFO - Using env: {'DISPLAY': ':0', 18:37:55 INFO - 'HOME': '/home/cltbld', 18:37:55 INFO - 'LANG': 'en_US.UTF-8', 18:37:55 INFO - 'LANGUAGE': 'en_US:en', 18:37:55 INFO - 'LOGNAME': 'cltbld', 18:37:55 INFO - 'MAIL': '/var/mail/cltbld', 18:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:55 INFO - 'MOZ_NO_REMOTE': '1', 18:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:55 INFO - 'NO_EM_RESTART': '1', 18:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:55 INFO - 'PWD': '/builds/slave/test', 18:37:55 INFO - 'SHELL': '/bin/bash', 18:37:55 INFO - 'SHLVL': '1', 18:37:55 INFO - 'TERM': 'linux', 18:37:55 INFO - 'TMOUT': '86400', 18:37:55 INFO - 'USER': 'cltbld', 18:37:55 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:55 INFO - '_': '/tools/buildbot/bin/python'} 18:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:37:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:37:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:37:57 INFO - Installing collected packages: mozsystemmonitor 18:37:57 INFO - Running setup.py install for mozsystemmonitor 18:37:57 INFO - Successfully installed mozsystemmonitor 18:37:57 INFO - Cleaning up... 18:37:57 INFO - Return code: 0 18:37:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:57 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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:37:57 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 18:37:57 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 18:37:57 INFO - Using env: {'DISPLAY': ':0', 18:37:57 INFO - 'HOME': '/home/cltbld', 18:37:57 INFO - 'LANG': 'en_US.UTF-8', 18:37:57 INFO - 'LANGUAGE': 'en_US:en', 18:37:57 INFO - 'LOGNAME': 'cltbld', 18:37:57 INFO - 'MAIL': '/var/mail/cltbld', 18:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:57 INFO - 'MOZ_NO_REMOTE': '1', 18:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:57 INFO - 'NO_EM_RESTART': '1', 18:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:57 INFO - 'PWD': '/builds/slave/test', 18:37:57 INFO - 'SHELL': '/bin/bash', 18:37:57 INFO - 'SHLVL': '1', 18:37:57 INFO - 'TERM': 'linux', 18:37:57 INFO - 'TMOUT': '86400', 18:37:57 INFO - 'USER': 'cltbld', 18:37:57 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:57 INFO - '_': '/tools/buildbot/bin/python'} 18:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:57 INFO - Downloading/unpacking blobuploader==1.2.4 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:37:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:37:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:37:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:38:00 INFO - Downloading docopt-0.6.1.tar.gz 18:38:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:38:00 INFO - Installing collected packages: blobuploader, requests, docopt 18:38:00 INFO - Running setup.py install for blobuploader 18:38:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:38:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:38:00 INFO - Running setup.py install for requests 18:38:00 INFO - Running setup.py install for docopt 18:38:00 INFO - Successfully installed blobuploader requests docopt 18:38:00 INFO - Cleaning up... 18:38:00 INFO - Return code: 0 18:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:00 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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:38:00 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 18:38:00 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 18:38:00 INFO - Using env: {'DISPLAY': ':0', 18:38:00 INFO - 'HOME': '/home/cltbld', 18:38:00 INFO - 'LANG': 'en_US.UTF-8', 18:38:00 INFO - 'LANGUAGE': 'en_US:en', 18:38:00 INFO - 'LOGNAME': 'cltbld', 18:38:00 INFO - 'MAIL': '/var/mail/cltbld', 18:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:00 INFO - 'MOZ_NO_REMOTE': '1', 18:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:00 INFO - 'NO_EM_RESTART': '1', 18:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:00 INFO - 'PWD': '/builds/slave/test', 18:38:00 INFO - 'SHELL': '/bin/bash', 18:38:00 INFO - 'SHLVL': '1', 18:38:00 INFO - 'TERM': 'linux', 18:38:00 INFO - 'TMOUT': '86400', 18:38:00 INFO - 'USER': 'cltbld', 18:38:00 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:00 INFO - '_': '/tools/buildbot/bin/python'} 18:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:00 INFO - Running setup.py (path:/tmp/pip-vomSGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:00 INFO - Running setup.py (path:/tmp/pip-EoJeY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:01 INFO - Running setup.py (path:/tmp/pip-Gcvcfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:01 INFO - Running setup.py (path:/tmp/pip-HK2SiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:01 INFO - Running setup.py (path:/tmp/pip-JojKPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:01 INFO - Running setup.py (path:/tmp/pip-Pi5yI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:01 INFO - Running setup.py (path:/tmp/pip-6N6RbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:01 INFO - Running setup.py (path:/tmp/pip-GhshlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:01 INFO - Running setup.py (path:/tmp/pip-G5qqbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:01 INFO - Running setup.py (path:/tmp/pip-hrU7qP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:01 INFO - Running setup.py (path:/tmp/pip-mOgA7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:01 INFO - Running setup.py (path:/tmp/pip-gtiMGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:38:01 INFO - Running setup.py (path:/tmp/pip-_rQMTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:38:01 INFO - Running setup.py (path:/tmp/pip-i0ZloU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:01 INFO - Running setup.py (path:/tmp/pip-GwWUNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:38:01 INFO - Running setup.py (path:/tmp/pip-VbJLdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:38:02 INFO - Running setup.py (path:/tmp/pip-9jxQLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:38:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:38:02 INFO - Running setup.py install for manifestparser 18:38:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Running setup.py install for mozcrash 18:38:02 INFO - Running setup.py install for mozdebug 18:38:02 INFO - Running setup.py install for mozdevice 18:38:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Running setup.py install for mozfile 18:38:02 INFO - Running setup.py install for mozhttpd 18:38:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Running setup.py install for mozinfo 18:38:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Running setup.py install for mozInstall 18:38:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:38:02 INFO - Running setup.py install for mozleak 18:38:03 INFO - Running setup.py install for mozlog 18:38:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Running setup.py install for moznetwork 18:38:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Running setup.py install for mozprocess 18:38:03 INFO - Running setup.py install for mozprofile 18:38:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Running setup.py install for mozrunner 18:38:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Running setup.py install for mozscreenshot 18:38:03 INFO - Running setup.py install for moztest 18:38:03 INFO - Running setup.py install for mozversion 18:38:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:38:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:38:03 INFO - Cleaning up... 18:38:03 INFO - Return code: 0 18:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:03 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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:38:03 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 18:38:03 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 18:38:03 INFO - Using env: {'DISPLAY': ':0', 18:38:03 INFO - 'HOME': '/home/cltbld', 18:38:03 INFO - 'LANG': 'en_US.UTF-8', 18:38:03 INFO - 'LANGUAGE': 'en_US:en', 18:38:03 INFO - 'LOGNAME': 'cltbld', 18:38:03 INFO - 'MAIL': '/var/mail/cltbld', 18:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:03 INFO - 'MOZ_NO_REMOTE': '1', 18:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:03 INFO - 'NO_EM_RESTART': '1', 18:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:03 INFO - 'PWD': '/builds/slave/test', 18:38:03 INFO - 'SHELL': '/bin/bash', 18:38:03 INFO - 'SHLVL': '1', 18:38:03 INFO - 'TERM': 'linux', 18:38:03 INFO - 'TMOUT': '86400', 18:38:03 INFO - 'USER': 'cltbld', 18:38:03 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:03 INFO - '_': '/tools/buildbot/bin/python'} 18:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:04 INFO - Running setup.py (path:/tmp/pip-UPKVzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:04 INFO - Running setup.py (path:/tmp/pip-g6Ayfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:04 INFO - Running setup.py (path:/tmp/pip-IcpA8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:04 INFO - Running setup.py (path:/tmp/pip-cwwTZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:04 INFO - Running setup.py (path:/tmp/pip-V1KkrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:04 INFO - Running setup.py (path:/tmp/pip-TAsMot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:04 INFO - Running setup.py (path:/tmp/pip-jcswx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:04 INFO - Running setup.py (path:/tmp/pip-Iot4IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:04 INFO - Running setup.py (path:/tmp/pip-Am4z9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:04 INFO - Running setup.py (path:/tmp/pip-06aj0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:04 INFO - Running setup.py (path:/tmp/pip-byvAEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:04 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)) 18:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:04 INFO - Running setup.py (path:/tmp/pip-czgZ7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:38:05 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)) 18:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:38:05 INFO - Running setup.py (path:/tmp/pip-LsMFtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:38:05 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)) 18:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:38:05 INFO - Running setup.py (path:/tmp/pip-HpWOgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:38:05 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)) 18:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:05 INFO - Running setup.py (path:/tmp/pip-I79PFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:05 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)) 18:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:38:05 INFO - Running setup.py (path:/tmp/pip-BkrNuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:38:05 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)) 18:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:38:05 INFO - Running setup.py (path:/tmp/pip-eS4Xy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:38:05 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)) 18:38:05 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)) 18:38: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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:38:05 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)) 18:38:05 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)) 18:38:05 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)) 18:38:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:07 INFO - Downloading blessings-1.5.1.tar.gz 18:38:07 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 18:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:38:07 INFO - Installing collected packages: blessings 18:38:07 INFO - Running setup.py install for blessings 18:38:07 INFO - Successfully installed blessings 18:38:07 INFO - Cleaning up... 18:38:07 INFO - Return code: 0 18:38:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:38:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:38:07 INFO - Reading from file tmpfile_stdout 18:38:07 INFO - Current package versions: 18:38:07 INFO - argparse == 1.2.1 18:38:07 INFO - blessings == 1.5.1 18:38:07 INFO - blobuploader == 1.2.4 18:38:07 INFO - docopt == 0.6.1 18:38:07 INFO - manifestparser == 1.1 18:38:07 INFO - mozInstall == 1.12 18:38:07 INFO - mozcrash == 0.16 18:38:07 INFO - mozdebug == 0.1 18:38:07 INFO - mozdevice == 0.47 18:38:07 INFO - mozfile == 1.2 18:38:07 INFO - mozhttpd == 0.7 18:38:07 INFO - mozinfo == 0.9 18:38:07 INFO - mozleak == 0.1 18:38:07 INFO - mozlog == 3.1 18:38:07 INFO - moznetwork == 0.27 18:38:07 INFO - mozprocess == 0.22 18:38:07 INFO - mozprofile == 0.27 18:38:07 INFO - mozrunner == 6.11 18:38:07 INFO - mozscreenshot == 0.1 18:38:07 INFO - mozsystemmonitor == 0.0 18:38:07 INFO - moztest == 0.7 18:38:07 INFO - mozversion == 1.4 18:38:07 INFO - psutil == 3.1.1 18:38:07 INFO - requests == 1.2.3 18:38:07 INFO - wsgiref == 0.1.2 18:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:38:07 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 18:38:07 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 18:38:07 INFO - Using env: {'DISPLAY': ':0', 18:38:07 INFO - 'HOME': '/home/cltbld', 18:38:07 INFO - 'LANG': 'en_US.UTF-8', 18:38:07 INFO - 'LANGUAGE': 'en_US:en', 18:38:07 INFO - 'LOGNAME': 'cltbld', 18:38:07 INFO - 'MAIL': '/var/mail/cltbld', 18:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:07 INFO - 'MOZ_NO_REMOTE': '1', 18:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:07 INFO - 'NO_EM_RESTART': '1', 18:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:07 INFO - 'PWD': '/builds/slave/test', 18:38:07 INFO - 'SHELL': '/bin/bash', 18:38:07 INFO - 'SHLVL': '1', 18:38:07 INFO - 'TERM': 'linux', 18:38:07 INFO - 'TMOUT': '86400', 18:38:07 INFO - 'USER': 'cltbld', 18:38:07 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:07 INFO - '_': '/tools/buildbot/bin/python'} 18:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 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)) 18:38:07 INFO - Cleaning up... 18:38:07 INFO - Return code: 0 18:38:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:38:07 INFO - Using env: {'DISPLAY': ':0', 18:38:07 INFO - 'HOME': '/home/cltbld', 18:38:07 INFO - 'LANG': 'en_US.UTF-8', 18:38:07 INFO - 'LANGUAGE': 'en_US:en', 18:38:07 INFO - 'LOGNAME': 'cltbld', 18:38:07 INFO - 'MAIL': '/var/mail/cltbld', 18:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:07 INFO - 'MOZ_NO_REMOTE': '1', 18:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:07 INFO - 'NO_EM_RESTART': '1', 18:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:07 INFO - 'PWD': '/builds/slave/test', 18:38:07 INFO - 'SHELL': '/bin/bash', 18:38:07 INFO - 'SHLVL': '1', 18:38:07 INFO - 'TERM': 'linux', 18:38:07 INFO - 'TMOUT': '86400', 18:38:07 INFO - 'USER': 'cltbld', 18:38:07 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:07 INFO - '_': '/tools/buildbot/bin/python'} 18:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:07 INFO - Downloading/unpacking jsonschema 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:38:09 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 18:38:09 INFO - Downloading/unpacking functools32 (from jsonschema) 18:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:09 INFO - Downloading functools32-3.2.3-2.tar.gz 18:38:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:38:09 INFO - warning: no files found matching '*.txt' 18:38:09 INFO - no previously-included directories found matching 'build' 18:38:09 INFO - no previously-included directories found matching 'dist' 18:38:09 INFO - no previously-included directories found matching '.git*' 18:38:09 INFO - Installing collected packages: jsonschema, functools32 18:38:09 INFO - Running setup.py install for functools32 18:38:10 INFO - warning: no files found matching '*.txt' 18:38:10 INFO - no previously-included directories found matching 'build' 18:38:10 INFO - no previously-included directories found matching 'dist' 18:38:10 INFO - no previously-included directories found matching '.git*' 18:38:10 INFO - Successfully installed jsonschema functools32 18:38:10 INFO - Cleaning up... 18:38:10 INFO - Return code: 0 18:38:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:10 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 0x7f756feeef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, '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': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', '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 18:38:10 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 18:38:10 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 18:38:10 INFO - Using env: {'DISPLAY': ':0', 18:38:10 INFO - 'HOME': '/home/cltbld', 18:38:10 INFO - 'LANG': 'en_US.UTF-8', 18:38:10 INFO - 'LANGUAGE': 'en_US:en', 18:38:10 INFO - 'LOGNAME': 'cltbld', 18:38:10 INFO - 'MAIL': '/var/mail/cltbld', 18:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:10 INFO - 'MOZ_NO_REMOTE': '1', 18:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:10 INFO - 'NO_EM_RESTART': '1', 18:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:10 INFO - 'PWD': '/builds/slave/test', 18:38:10 INFO - 'SHELL': '/bin/bash', 18:38:10 INFO - 'SHLVL': '1', 18:38:10 INFO - 'TERM': 'linux', 18:38:10 INFO - 'TMOUT': '86400', 18:38:10 INFO - 'USER': 'cltbld', 18:38:10 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:10 INFO - '_': '/tools/buildbot/bin/python'} 18:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:10 INFO - Downloading/unpacking flake8 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:38:11 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 18:38:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:38:12 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 18:38:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 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 18:38:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:38:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:38:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:38:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:38:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:38:12 INFO - no previously-included directories found matching 'docs/_build' 18:38:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:38:12 INFO - Running setup.py install for pep8 18:38:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:38:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:38:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:38:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:38:12 INFO - no previously-included directories found matching 'docs/_build' 18:38:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:38:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:38:12 INFO - Cleaning up... 18:38:12 INFO - Return code: 0 18:38:12 INFO - Running post-action listener: _resource_record_post_action 18:38:12 INFO - Running post-action listener: _start_resource_monitoring 18:38:12 INFO - Starting resource monitoring. 18:38:12 INFO - ##### 18:38:12 INFO - ##### Running install step. 18:38:12 INFO - ##### 18:38:12 INFO - Running pre-action listener: _resource_record_pre_action 18:38:12 INFO - Running main action method: install 18:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:38:13 INFO - Reading from file tmpfile_stdout 18:38:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:38:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:38:13 INFO - Reading from file tmpfile_stdout 18:38:13 INFO - Output received: 18:38:13 INFO - Usage: mozinstall [options] installer 18:38:13 INFO - Options: 18:38:13 INFO - -h, --help show this help message and exit 18:38:13 INFO - -d DEST, --destination=DEST 18:38:13 INFO - Directory to install application into. [default: 18:38:13 INFO - "/builds/slave/test"] 18:38:13 INFO - --app=APP Application being installed. [default: firefox] 18:38:13 INFO - mkdir: /builds/slave/test/build/application 18:38:13 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'] 18:38:13 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 18:38:27 INFO - Reading from file tmpfile_stdout 18:38:27 INFO - Output received: 18:38:27 INFO - /builds/slave/test/build/application/firefox/firefox 18:38:27 INFO - Running post-action listener: _resource_record_post_action 18:38:27 INFO - ##### 18:38:27 INFO - ##### Running run-tests step. 18:38:27 INFO - ##### 18:38:27 INFO - Running pre-action listener: _resource_record_pre_action 18:38:27 INFO - Running main action method: run_tests 18:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:38:27 INFO - Python 2.7.3 18:38:27 INFO - Return code: 0 18:38:27 INFO - grabbing minidump binary from tooltool 18:38:27 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')]} 18:38:27 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 0x19480b0>, '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 0x1948fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1949440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:38:27 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 18:38:27 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 18:38:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:38:27 INFO - Return code: 0 18:38:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:38:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:38:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:38:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:38:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:38:28 INFO - Return code: 0 18:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] in /builds/slave/test/build 18:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost 18:38:28 INFO - Using env: {'DISPLAY': ':0', 18:38:28 INFO - 'HOME': '/home/cltbld', 18:38:28 INFO - 'LANG': 'en_US.UTF-8', 18:38:28 INFO - 'LANGUAGE': 'en_US:en', 18:38:28 INFO - 'LOGNAME': 'cltbld', 18:38:28 INFO - 'MAIL': '/var/mail/cltbld', 18:38:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:38:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:28 INFO - 'MOZ_NO_REMOTE': '1', 18:38:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:28 INFO - 'NO_EM_RESTART': '1', 18:38:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:28 INFO - 'PWD': '/builds/slave/test', 18:38:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:38:28 INFO - 'SHELL': '/bin/bash', 18:38:28 INFO - 'SHLVL': '1', 18:38:28 INFO - 'TERM': 'linux', 18:38:28 INFO - 'TMOUT': '86400', 18:38:28 INFO - 'USER': 'cltbld', 18:38:28 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869', 18:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:28 INFO - '_': '/tools/buildbot/bin/python'} 18:38:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] with output_timeout 3600 18:38:28 INFO - mozversion INFO | application_buildid: 20151214180411 18:38:28 INFO - mozversion INFO | application_changeset: 063c2822231032a310ad6508daf7a52486ec2e5c 18:38:28 INFO - mozversion INFO | application_display_name: Nightly 18:38:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:38:28 INFO - mozversion INFO | application_name: Firefox 18:38:28 INFO - mozversion INFO | application_remotingname: firefox 18:38:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:38:28 INFO - mozversion INFO | application_vendor: Mozilla 18:38:28 INFO - mozversion INFO | application_version: 45.0a1 18:38:28 INFO - mozversion INFO | platform_buildid: 20151214180411 18:38:28 INFO - mozversion INFO | platform_changeset: 063c2822231032a310ad6508daf7a52486ec2e5c 18:38:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:38:28 INFO - mozversion INFO | platform_version: 45.0a1 18:38:28 INFO - 2015-12-14 18:38:28,916 DEBUG : using testdate: 1450147108 18:38:28 INFO - 2015-12-14 18:38:28,916 DEBUG : actual date: 1450147108 18:38:28 INFO - 2015-12-14 18:38:28,928 INFO : starting webserver on 'localhost:57957' 18:38:28 INFO - 2015-12-14 18:38:28,929 INFO : Starting test suite talos-linux64-ix-029.e 18:38:28 INFO - 2015-12-14 18:38:28,929 INFO : Starting test dromaeo_css 18:38:28 INFO - 2015-12-14 18:38:28,929 DEBUG : operating with platform_type : linux_ 18:38:28 INFO - 2015-12-14 18:38:28,930 INFO : Initialising browser for dromaeo_css test... 18:38:28 INFO - 2015-12-14 18:38:28,943 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqXbBxU/profile http://localhost:57957/getInfo.html 18:38:34 INFO - 2015-12-14 18:38:34,017 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:38:34 INFO - 2015-12-14 18:38:34,018 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:38:34 INFO - 2015-12-14 18:38:34,018 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:38:34 INFO - 2015-12-14 18:38:34,018 DEBUG : BROWSER_OUTPUT: __metrics 18:38:34 INFO - 2015-12-14 18:38:34,154 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] 18:38:35 INFO - 2015-12-14 18:38:35,104 INFO : Browser initialized. 18:38:35 INFO - 2015-12-14 18:38:35,105 INFO : Running cycle 1/1 for dromaeo_css test... 18:38:35 INFO - 2015-12-14 18:38:35,105 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqXbBxU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:38:47 INFO - 2015-12-14 18:38:47,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 143314944 18:38:47 INFO - 2015-12-14 18:38:47,140 DEBUG : BROWSER_OUTPUT: 18:39:36 INFO - 2015-12-14 18:39:36,763 DEBUG : BROWSER_OUTPUT: 1450147176761 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] 18:41:48 INFO - 2015-12-14 18:41:48,354 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57957/tests/dromaeo/cssquery-ext.html) 18:41:49 INFO - 2015-12-14 18:41:49,639 DEBUG : BROWSER_OUTPUT: RSS: Main: 146161664 18:41:49 INFO - 2015-12-14 18:41:49,640 DEBUG : BROWSER_OUTPUT: 18:44:51 INFO - 2015-12-14 18:44:51,716 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/dromaeo/cssquery-ext.html (next: http://localhost:57957/tests/dromaeo/cssquery-jquery.html) 18:44:52 INFO - 2015-12-14 18:44:52,309 DEBUG : BROWSER_OUTPUT: RSS: Main: 146100224 18:44:52 INFO - 2015-12-14 18:44:52,309 DEBUG : BROWSER_OUTPUT: 18:47:54 INFO - 2015-12-14 18:47:54,112 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57957/tests/dromaeo/cssquery-mootools.html) 18:47:54 INFO - 2015-12-14 18:47:54,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 146382848 18:47:54 INFO - 2015-12-14 18:47:54,714 DEBUG : BROWSER_OUTPUT: 18:50:56 INFO - 2015-12-14 18:50:56,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57957/tests/dromaeo/cssquery-prototype.html) 18:50:57 INFO - 2015-12-14 18:50:57,038 DEBUG : BROWSER_OUTPUT: RSS: Main: 143024128 18:50:57 INFO - 2015-12-14 18:50:57,039 DEBUG : BROWSER_OUTPUT: 18:53:59 INFO - 2015-12-14 18:53:59,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57957/tests/dromaeo/cssquery-yui.html) 18:54:00 INFO - 2015-12-14 18:54:00,290 DEBUG : BROWSER_OUTPUT: RSS: Main: 144879616 18:54:00 INFO - 2015-12-14 18:54:00,291 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/dromaeo/cssquery-yui.html (next: http://localhost:57957/tests/dromaeo/cssquery-dojo.html) 18:57:02 INFO - 2015-12-14 18:57:02,893 DEBUG : BROWSER_OUTPUT: RSS: Main: 147578880 18:57:02 INFO - 2015-12-14 18:57:02,894 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,894 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:57:02 INFO - 2015-12-14 18:57:02,894 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:57:02 INFO - 2015-12-14 18:57:02,894 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:57:02 INFO - 2015-12-14 18:57:02,894 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:57:02 INFO - 2015-12-14 18:57:02,896 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2634.473105378924;2728.931776705583;2729.9590506142413;2749.4775992561413;2758.8482633455164;4618.676692631517;4787.138315103284;4833.613310935126;4834.154023045966;4870.561649451553;4696.50686677899;4696.50686677899;4736.97631511842;4848.709077455355;4857.708537487753;4196.769177695225;4281.12236991416;4302.483701955754;4323.178596066738;4329.631981281595;4315.244832154376;4379.868603941871;4422.889427764299;4432.20220360335;4472.396226509412;4171.6036976487185;4267.743935363888;4272.9359059614135;4279.079997800488;4390.97804510977;4301.548337424588;4462.219111655448;4466.776661166961;4468.955310446887;4472.172648060119;4372.453443319585;4376.343548467724;4379.934300985487;4383.9342409863875;4438.556144385568;4552.453705555355;4596.402467679214;4720.787564559603;4722.244440889442;4742.928856067162;5136.1525348317855;5136.921246538232;5150.845474635767;5152.896942061176;5201.973990130063;5105.157648987913;5125.179971204627;5130.615203859726;5155.9742201288755;5165.9225111623355;4609.7695115244105;4684.74233917131;4704.482506924235;4761.547652972997;4789.257665061921;5257.342832145981;5280.445553216934;5283.815066472655;5285.735713214324;5318.7074710891275;5041.59667226621;5054.721990290569;5079.6952182869145;5091.363579552556;5138.794448222029;4916.459189489154;4989.700617962933;5017.297578338962;5102.183650615883;5167;5066.873328166826;5073.137566613685;5091.974540127349;5094.77073531692;5139.974300128475;5074.5686616637995;5081.110805609078;5090.338256026767;5090.567301779391;5095.490450954949;4127.9174416511505;4140.668746500333;4214.4099826024385;4248.320268756984;4250.574942505725;3760.5863355030924;3761.0785357587565;3774.358359078963;3778.5465744110884;3803.9429408558894;4267.97866010668;4469.9776501117285;4479.283314669638;4484.75333856641;4491.73049617024;5011.6241281904;5057.949420505822;5134.306868572769;5136.897262054738;5168.3797944246935;4372.125574885035;4389.253826849444;4395.626371758436;4432.623227025739;4438.689291749546;4299.720518166309;4445.666575006822;4509.6392288616835;4522.914500519852;4572.565606267466;4742.170120228946;4798.136335459651;4808.855734327975;4809.398825146856;4836.250381190851;5127.128388173945;5135.563477104488;5149.0474262261605;5164.251183578435;5172.508611681884;4636.721796692209;4770.236762118045;4775.307580400892;4829.710217386968;4896.608271338356;4977.203647416396;5045.268436076822;5053.090443720019;5064.493550645054;5108.897822043613;5098.847034588968;5193.68837869729;5199.662021968711;5202.063628546898;5249.947500524946;4745.288206768874;4845.903081938412;4849;5001.899962000813;5057.393112826484;4871.805127794849;4876.658633895593;4880.95119048805;4971.254311853107;4995.225740010304;4810.63920205979;4842.903141937212;4851.878703032452;4856.417229932431;4857.222844344888;4967.751612419437;5006.799728010838;5008.198688209869;5013.197888337849;5015.398152221756;4692.319613655933;4834.323194752667;4846.224604063333;4850.417949846041;4856.247281671346 18:57:02 INFO - 2015-12-14 18:57:02,897 DEBUG : BROWSER_OUTPUT: |1;ext.html;547.4051920553064;574.9338826035007;582.4612233683841;588.7586089703221;596.2457491273539;35161.47257791132;36278.45582316264;36315;36356.81821590888;36393;39413.60586394135;39937.40093898594;39984.200315993665;40064;40067.398989015186;7889.329407000412;7969.641366138538;7975.7208497702595;8030.959845200795;8055.395845311596;7812.687492500293;7853.764387068369;7859.449838511307;7874.133845277015;7937.523748575075;7902.841943161134;7970.920290797105;7978.401619878503;7978.720744773934;7979.7606071817645;7927;7967.482113662593;7974.920250797534;8018.198180181994;8056.194380561956;7801.804954876116;8000.519968801832;8008.199180082003;8016.038075430927;8035.357171426272;37042.259154816755;37183.25633487315;37236.44145337822;37462;37467.25065498702;2891.161563146685;3121.8751249949973;3521.0669172669263;3686.686631636314;3692.4461330800327;27866.442671146666;28395.432091358263;28551;28817;28825;3295.40682677118;3296.1100502864333;3338.5326054352167;3392.2197894484407;3438.5186073949667;213273;213454;213568;214836;215116;53519.46480535145;53721.73139134279;54060.72969635126;54103.72948135234;54123;49719.50280497221;50545.49454505481;64905.67547162234;66309.66845165839;66405.33594664089;14082.43670253199;14202.644933876529;14242.85757142436;14307.642308942151;14357.138571685733;10377.325473844176;10377.533011014522;10390.896091038992;10433.947830260951;10457.581696732046;18473.1687074082;19098.809011909703;19125.808741912682;19240.615187696167;19251.13369898358;7526.5860377679755;7844.333231675258;7873.606319683993;7910.604469776488;8018.358531317481;813.0081300812999;867.0679020053468;920.392540923001;928.1831987850783;968.8837339519303;25734.35664108375;26135.215943521725;26224.204744491337;26471.205863824118;26740.331491712863;925.2644147902433;1009.9242556808268;1045.4772613693153;1062.824633935392;1106.441247170174;13713.314334283445;14055.22696251716;14079.862499000703;14101.85898141026;14128.364223609962;266775;270042;270305;271404;272947;45375.54624453779;45497.7725111379;45713;45727.77136114365;45902;1175.6355529785794;1248.1387842388722;1248.6566194296458;1257.9622611321674;1263.3809433377762;24837.50324993454;25333.61999569971;25768.484630307663;26116.60825087663;26352.736472635028;40007;40094.19811603693;40111;40346.59653403428;40435.7978210107;255767;257176;258548;258630;259176;28219;29294.26764330909;29459.55810662799;29498.705012949595;30007.849960750056;33480.497792532646;33692.49461258034;34337.484937726455;34682.47976280307;34846.47730284098;29562.260943476584;29811;30007.399852003273;32121.678783212803;32487.67512324941;32527.88568743119;32563.185920351232;32763.180920477178;32849.83575082109;32935.34129317352;33329.333413331115;33434.164145896546;33591;33782.662173378936;34117.829410852784;27516;29987.4002519944;33025.66974330226;33026.504602430505;33509.83245083856;23336.533269334857;23374;23528.29415117549;23564.293071207892;23584.764152358257 18:57:02 INFO - 2015-12-14 18:57:02,898 DEBUG : BROWSER_OUTPUT: |2;jquery.html;332.9926970912988;338.5446574357489;338.8695352289369;340.0682130961167;344.7397215102597;12524.436400361983;12777.616671499847;12885.74228515429;14851.257437128155;14965.700685986274;14218.14691118531;14357.210353430557;14489.347979340928;14683.77974330386;15260.694786104272;4860.6111511079025;4896.1186986342445;4941.234108713137;4951.579115775164;4981.526754958273;4822.445418776851;4964.726940018298;4997.450280469164;5002.149634562115;5043;4950.218758408996;4973.950260497385;4975.6765810222405;4987.426445958707;5015.648904576681;5758.884822303551;5852.9707351463185;5878;5891.587588868781;5900.350961394243;5048.066107770074;5147.459516750762;5188;5194.740262986836;5198.974005129988;11397.601083962021;11584.42077896102;11656.825147622792;11666.533338666442;12592.559260425933;1640.2454870759466;1668.307652324284;1745.938892138769;1773.8846974946587;1798.856091512676;8525.744227673118;8709.608067636971;8801.339899507562;8889.822203555957;8900.198982091642;1646.6459711162158;1668.3993762245582;1677.060845926285;1713.682968650791;1732.1339330334833;53914;55142;56248.15627765587;56517.43482565204;57159.42840571583;9925.156361709336;9988.25088118394;10027.746531683539;10037.949810250902;10038.247131465168;12313.876861231452;12325.260484370967;12337.444814983348;12349.320787356603;12367.567135150226;7023.613701246481;7155.427565794724;7193.712251509985;7226.458015648847;7266.672999715025;6761.628110453972;6775.1531058617675;6798.04827324175;6804.217514985741;6807.421369183675;4135.421041054255;4161.542230354658;4187.539370669285;4214.431051808028;4249.2563801334645;1718.3813827022261;1766.3287950578697;1773.1134432783608;1781.6703909776734;1792.5070605583307;363.66724447130576;365.27492926540793;405.4587126186574;795.7294519863159;865.2774932931073;9338.299627527962;9439.480828554359;9595.088466595662;9760.804783904283;9780.217582593517;389.7875657766524;390.94331321958157;399.76813448199977;402.1253773043677;410.6796698575116;5342.75957581902;5364.409914909357;5364.597655175878;5390.380106287827;5433.0763770159165;487229;497987;500726;501807;509147;72071.27928720646;74058;74077.62961185159;74960.25039749533;75694;436.884225680195;437.67611967144086;437.9036611945367;439.79769306119545;440.425245055206;8975.730728078168;9071.319651026331;9150.039245879354;9166.908330916605;9261.53692315368;29565;31365.372692545567;31797;32894.34211315808;33447.83276083604;43044.493442729356;43124.35313470363;43814.34278485762;43921.121577568905;45712.54287457083;8301.46040507365;8603.483790972561;8613.181747733955;8660.870086948575;8687.218150366498;10167;10263.332883362558;14135.929320353333;15691.686166276382;16142.273597688367;13680.65798355049;13838.86161138416;14297.928510357382;14376.496822611158;14440.205788681724;13248.138870973356;13662.93168534151;13857.930710346787;13914.165150091027;14041.438342466186;13671;14024;14087;14393.568192954228;14573.417063317349;8983.640654374014;8988.775280617776;9022.954885225532;9112.95443522778;9148.771280718036;9714.02859714023;13346.666333341744;14243.21662308583;14272.21502817355;14528.85471145275 18:57:02 INFO - 2015-12-14 18:57:02,898 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1502.361496364045;1619.0690353046998;1677.370985880295;1685.5196269063315;1699.39671416647;5777.566682498808;6052.848678783022;6734.124563806705;6891.7243310267595;6913.06673599064;6591.637459939701;6664.600123992552;6720.495962802785;6761.256261811209;6762.492813039017;1981.9207231710714;2000.3098930868828;2003.6693945498976;2011.2256781139295;2015.6674148765437;2021.82814460771;2025.8480613953939;2044.4990977210605;2075.9584808303825;2084;2024.5141166120172;2051.4256008317693;2075.2425364741835;2078.8440866934966;2083.270855200683;2034.4506983114477;2035.6844689073218;2036.9490762730902;2055.876647401146;2058.8249998750125;1969.606078784249;2001.2995451591971;2034.511717187879;2038.5413282011577;2056.7017782421467;6738.393544581011;6821.965890170567;6835.760748373833;6843.657817109174;6848.178218613748;4056.067104565937;4082.2447847148374;4091.3862920561855;4095.1195492968855;4107.589241075899;2595.1306312385373;2609.973900260992;2670.118860775939;2672.0647773279393;2685.9194224173307;861.2248975921658;875.8160786234898;884.5179377239419;891.8260939116875;891.9866202006975;9265.305102117301;9283.76790580241;9302.395344302598;9320;9326.953365233197;3758.1919887224376;3801.9809900950318;3828.578856325802;3839.9616003840165;3857.633524815138;8009.078955920144;8028.638711258008;8031;8036;8043.235892590194;4148.23257697327;4172.040430700956;4187.246295666749;4188.246115699203;4197.391378250137;3146.2606287522412;3221.2430078931434;3746.3631182699005;3775.244951009754;3782.224643947984;3274.3942370661503;3281.5898012748407;3309.4043072247227;3318.070940136766;3326.5010248462922;1050.847627094067;1067.6263307842194;1071.3625392891217;1076.34343050739;1076.7469644633504;873.4802792338548;875.7941883657336;875.8511053120857;891.0376793063476;1113.3264375052977;6411.775587854496;6420.06908998202;6428.5178611604315;6433.935660643428;6434.2600600930555;422.40440978221176;432.0796703022542;433.5794279548833;435.049417023804;437.3963929776951;4971.502849715;4996.3504744383;5001.674891132065;5012.072766538129;5018.749062546858;8122.472039317426;8150.551719655476;8219.301359384519;8255.215754503313;8273.131321211307;8114.310283625958;8129.75610731679;8169.714060007871;8241.50550966944;8269.83460330802;6504.447121994684;6527.412532871969;6554.475641948633;6582.078509008743;6635.137432133888;6120.17377654011;6143.047827586731;6145.0782382642965;6167.784127555514;6194.38056194377;7276.7089316426745;7304.415646748141;7334.96332518334;7481.4014878808475;7483.663235154322;7432.21961694036;7572.20491848348;7604.657790399335;7616.0860696716745;7663.731769388045;5061.873453163553;5494.2582751328055;5984.521238301013;6008.279006519246;6034.758609655564;6359.90460143089;6500.317466666121;6528.738850445929;6532.379423954717;6542.8364290893105;5981.7607295707685;5995.100734889801;6149.846253843511;6221.191245138284;6273.37266273369;6133.233345831771;6165.568410211242;6196;6220.346863579259;6393.776217832354;6278.748850045947;6319.462845657987;6367.490600751836;6378.77674281398;6380.361963803768;5796.333421656394;5796.884062318814;5828.59199856006;5842.561807864512;5845.795397161079;5962.880742385215;5981.3719559445635;5989.311229208697;6020.8795824084145;6066.908996365147 18:57:02 INFO - 2015-12-14 18:57:02,899 DEBUG : BROWSER_OUTPUT: |4;prototype.html;296.72552888578065;300.712819257609;305.9296361836776;307.1201009108901;307.16757586939394;5612.775488980441;5613.663180209185;5669.603127781052;5697.971510142444;5706.315242170935;5587.888242235153;5592.189132575774;5593.8601534961645;5601.103823388259;5607.186957891104;4212.515560710527;4298;4313.417688612044;4315.201687687788;4319.157764235975;4284.828606855722;4285.164392943378;4299.161663475624;4337.63130133939;4345.674074444413;4272.295071313245;4273.188094262096;4279.40088387626;4279.87160385189;4289.077848262623;4276.914461710749;4278.61492465678;4281.892952676192;4285.100128972919;4296.591823776736;3775.3959366501226;3783.05423644089;3785.488958990556;3786.4509646101437;3788.3370410178386;5238.030964271584;5250.606204534675;5308.575313974911;5320.334958130234;5361.865953351158;4915.1398505261795;4932.926006109911;4941.751503066096;4943.184374578191;4956.058348913695;5878;5901.616394934358;5904.704764761745;5905.350411454737;5914.733836977346;4023.517177938637;4082.346824508094;4093.5906409358827;4102.835886564534;4105.117399759066;6071.51427885768;6142.385761423822;6149.907751383733;6151.354107818659;6158.199434073542;4228.386883901817;4231.090315582164;4233.8518151865155;4238.427812245369;4243.236217480883;4289.742615443083;4293.613574778285;4299.892502687458;4301.419308393327;4303.182395344875;4807.365495731468;4887.413510378778;4896;4901.681390709592;4905.632077594195;5877.294724633071;5888.882222355529;5889.5877288590245;5891.3814049525035;5898.970505147447;1499.2503748125937;1525.3669727063366;1532.080751549084;1536.8770498360104;1540.3761476601994;1035.9015893490107;1047.0890325416935;1051.679237832453;1064.669952314785;1066.4880857188593;5109.744512774347;5112.6421150519855;5115.437301896788;5120;5122.154844450699;6116.877662446726;6125.050617154348;6126.417990290915;6142.477889379453;6148.323684394713;5070.6957582545165;5090.440051594322;5094.083065048253;5096.974515127401;5103.540681338697;3233.563468931711;3286.07989762862;3320.900372988814;3322.833858307075;3327.800331980089;5667.518260947865;5808.88382232353;5816.098504731774;5822.301323841166;5822.563307751935;6279.560430769802;6329.367063293634;6334.208223972004;6361.427471527492;6370.108184854124;5724.16999535065;5751.769929202868;5757.625754326039;5763.942360576509;5775;6125.571210015256;6168.506519478521;6169.876602468015;6181.196444462191;6188.040853667689;5797.91303130445;5963.165156878127;5971.954907891189;5994.910076348771;6002.369751176064;5730.22641943349;5770.6249093808765;5777.24895763548;5783.739731712168;5786.42135786418;3971.9871432785612;3992.840286388628;3998.7200895937003;4006.4390985261502;4008.7594744315434;6039.342689216731;6155.876882462236;6163.568550201443;6164.445199932027;6169.629822210682;5889.764409423575;5907.49786268172;5922.703864806656;5923.7926672566255;5931.673757943355;5860.443257890493;5870.530357571299;5874.265716785402;5899.941000589939;5904.202932604216;5637.3235211774845;5712.285964254468;5775.393583673654;5784.82645520635;5788.594798364074;5923.466887980103;5952.642841429355;5958.1360702697;5961.195238642727;5971;5772.509336706427;5796.971015144897;5808.332041815245;5815;5815.680137592473 18:57:02 INFO - 2015-12-14 18:57:02,900 DEBUG : BROWSER_OUTPUT: |5;yui.html;754.683033126087;766.7967988483051;768.2624680306905;771.2904128202053;771.7298945369122;11535.365554894488;11756.294622322644;11803.468843902023;11807.232529885563;11831.29012259265;10905.945470272658;11008.39453830039;11014.118870490362;11034.393108379036;11044.447777611127;801.1867954026653;822.9671761938788;825.1006403020706;827.6854795177824;827.9586020698941;804.0231119190182;806.1857523900844;806.6087947345533;809.1261437647327;809.8177909970268;856.3320609924269;861.4228466927143;863.5121156546531;863.6329559937002;863.6415887406719;852.8933883264623;855.8930133733284;857.4426622695236;857.7555396711907;859.7463748194235;627.7018416252289;631.0439683878927;632.7595513704808;636.3890664961642;636.9235691716977;12092.879071209263;12170.634880953585;12306;12307.138500304982;12309.753804923852;10934.890651093547;11031.613893513688;11130.109591232764;11150.386728729916;12027.699307517294;9324.440533568008;9470.100340467645;9492;9532.714018579454;9568.234541236754;3301.5443669885217;3313.2379552703014;3336.182635254378;3363.091965169415;3387.237871478922;37716.62283377159;39273.41089883654;39422.605773942465;39447;41965.79017104925;8871.689490867788;8922.509261990654;9040.231580315616;9108.8633670495;9179.81640367189;16083.195840207944;16118.516444506684;16153.59616009609;16177.110258935636;16248.918755406148;6547.279799222082;6579.703913323912;6596.703148358335;6622.1722284714415;6646;3768.5666148393284;3778.848846046127;3806.2197249563774;3833.884983450501;3862.1696335288048;2603.5964425514076;2637.591173368131;2647.1264482720658;2649.4303724699284;2653.0714250012343;481.71819485601316;488.38707422184933;489.29297165596086;490.82821012646;498.3770287141074;551.5807985930721;565.3498476751769;566.4788394676907;608.5527137553894;611.0498175337304;7320.487565870444;7458.589777562286;7493.070725869469;7519.736809211651;7545;590.7365374513068;597.7519329478305;604.8548348396441;605.5065121925704;610.1061944251693;3302.516487676586;3389.389909816257;3396.117009577528;3425.811580363054;3464.4110501214855;48604;49146;49929.75035124801;50344;50576.74711626418;44319.556804431544;44699.55300447019;45121;45486;45592.772036139606;39428.211435771125;41315;41480;41930.37104443375;42127.15745685069;7797.142314345304;7830;7921.287084162453;8047.161685114198;8253.628586713492;32279.193020173745;32342.221177834504;32352.83823580867;32426.675733242366;32611.836940815145;36891.63108368882;36981.81509092437;39024.80487597544;39986;40288.1942361157;9723.222142228746;9763.80472390534;9795;10142.797144056929;10474.266801323834;11551.88448115508;11599.942000289944;11740.178187526792;11756;12448.439820208294;10094.8485772712;10104;10125.443100629242;10965.945170274097;11411.714707132389;10080.848787268344;10585.55969309962;10837.67486975392;10980.560777568808;11384.772304553697;10644.84032739525;10721.195910306602;10821.567137314734;10837.729056773644;10968.122550196125;9182.311326650395;9618;9650.469224192737;9666.854997175189;9693.321467497208;8458.111898250596;8726.91273087261;8820.514871682119;8973.685920992733;9282.767930801783 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,346 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: __startTimestamp1450148222892__endTimestamp 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4701.85 Median:4834.15 stddev:500.59 (10.4%) stddev-sans-first:475.13 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: Values: 2634.5 2728.9 2730.0 2749.5 2758.8 4618.7 4787.1 4833.6 4834.2 4870.6 4696.5 4696.5 4737.0 4848.7 4857.7 4196.8 4281.1 4302.5 4323.2 4329.6 4315.2 4379.9 4422.9 4432.2 4472.4 4171.6 4267.7 4272.9 4279.1 4391.0 4301.5 4462.2 4466.8 4469.0 4472.2 4372.5 4376.3 4379.9 4383.9 4438.6 4552.5 4596.4 4720.8 4722.2 4742.9 5136.2 5136.9 5150.8 5152.9 5202.0 5105.2 5125.2 5130.6 5156.0 5165.9 4609.8 4684.7 4704.5 4761.5 4789.3 5257.3 5280.4 5283.8 5285.7 5318.7 5041.6 5054.7 5079.7 5091.4 5138.8 4916.5 4989.7 5017.3 5102.2 5167.0 5066.9 5073.1 5092.0 5094.8 5140.0 5074.6 5081.1 5090.3 5090.6 5095.5 4127.9 4140.7 4214.4 4248.3 4250.6 3760.6 3761.1 3774.4 3778.5 3803.9 4268.0 4470.0 4479.3 4484.8 4491.7 5011.6 5057.9 5134.3 5136.9 5168.4 4372.1 4389.3 4395.6 4432.6 4438.7 4299.7 4445.7 4509.6 4522.9 4572.6 4742.2 4798.1 4808.9 4809.4 4836.3 5127.1 5135.6 5149.0 5164.3 5172.5 4636.7 4770.2 4775.3 4829.7 4896.6 4977.2 5045.3 5053.1 5064.5 5108.9 5098.8 5193.7 5199.7 5202.1 5249.9 4745.3 4845.9 4849.0 5001.9 5057.4 4871.8 4876.7 4881.0 4971.3 4995.2 4810.6 4842.9 4851.9 4856.4 4857.2 4967.8 5006.8 5008.2 5013.2 5015.4 4692.3 4834.3 4846.2 4850.4 4856.2 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,901 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40738.79 Median:33495.17 stddev:64791.86 (193.4%) stddev-sans-first:64903.34 18:57:02 INFO - 2015-12-14 18:57:02,902 DEBUG : BROWSER_OUTPUT: Values: 547.4 574.9 582.5 588.8 596.2 35161.5 36278.5 36315.0 36356.8 36393.0 39413.6 39937.4 39984.2 40064.0 40067.4 7889.3 7969.6 7975.7 8031.0 8055.4 7812.7 7853.8 7859.4 7874.1 7937.5 7902.8 7970.9 7978.4 7978.7 7979.8 7927.0 7967.5 7974.9 8018.2 8056.2 7801.8 8000.5 8008.2 8016.0 8035.4 37042.3 37183.3 37236.4 37462.0 37467.3 2891.2 3121.9 3521.1 3686.7 3692.4 27866.4 28395.4 28551.0 28817.0 28825.0 3295.4 3296.1 3338.5 3392.2 3438.5 213273.0 213454.0 213568.0 214836.0 215116.0 53519.5 53721.7 54060.7 54103.7 54123.0 49719.5 50545.5 64905.7 66309.7 66405.3 14082.4 14202.6 14242.9 14307.6 14357.1 10377.3 10377.5 10390.9 10433.9 10457.6 18473.2 19098.8 19125.8 19240.6 19251.1 7526.6 7844.3 7873.6 7910.6 8018.4 813.0 867.1 920.4 928.2 968.9 25734.4 26135.2 26224.2 26471.2 26740.3 925.3 1009.9 1045.5 1062.8 1106.4 13713.3 14055.2 14079.9 14101.9 14128.4 266775.0 270042.0 270305.0 271404.0 272947.0 45375.5 45497.8 45713.0 45727.8 45902.0 1175.6 1248.1 1248.7 1258.0 1263.4 24837.5 25333.6 25768.5 26116.6 26352.7 40007.0 40094.2 40111.0 40346.6 40435.8 255767.0 257176.0 258548.0 258630.0 259176.0 28219.0 29294.3 29459.6 29498.7 30007.8 33480.5 33692.5 34337.5 34682.5 34846.5 29562.3 29811.0 30007.4 32121.7 32487.7 32527.9 32563.2 32763.2 32849.8 32935.3 33329.3 33434.2 33591.0 33782.7 34117.8 27516.0 29987.4 33025.7 33026.5 33509.8 23336.5 23374.0 23528.3 23564.3 23584.8 18:57:02 INFO - 2015-12-14 18:57:02,902 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,902 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26037.79 Median:22127.07 stddev:81710.36 (369.3%) stddev-sans-first:81916.78 18:57:02 INFO - 2015-12-14 18:57:02,903 DEBUG : BROWSER_OUTPUT: Values: 333.0 338.5 338.9 340.1 344.7 12524.4 12777.6 12885.7 14851.3 14965.7 14218.1 14357.2 14489.3 14683.8 15260.7 4860.6 4896.1 4941.2 4951.6 4981.5 4822.4 4964.7 4997.5 5002.1 5043.0 4950.2 4974.0 4975.7 4987.4 5015.6 5758.9 5853.0 5878.0 5891.6 5900.4 5048.1 5147.5 5188.0 5194.7 5199.0 11397.6 11584.4 11656.8 11666.5 12592.6 1640.2 1668.3 1745.9 1773.9 1798.9 8525.7 8709.6 8801.3 8889.8 8900.2 1646.6 1668.4 1677.1 1713.7 1732.1 53914.0 55142.0 56248.2 56517.4 57159.4 9925.2 9988.3 10027.7 10037.9 10038.2 12313.9 12325.3 12337.4 12349.3 12367.6 7023.6 7155.4 7193.7 7226.5 7266.7 6761.6 6775.2 6798.0 6804.2 6807.4 4135.4 4161.5 4187.5 4214.4 4249.3 1718.4 1766.3 1773.1 1781.7 1792.5 363.7 365.3 405.5 795.7 865.3 9338.3 9439.5 9595.1 9760.8 9780.2 389.8 390.9 399.8 402.1 410.7 5342.8 5364.4 5364.6 5390.4 5433.1 487229.0 497987.0 500726.0 501807.0 509147.0 72071.3 74058.0 74077.6 74960.3 75694.0 436.9 437.7 437.9 439.8 440.4 8975.7 9071.3 9150.0 9166.9 9261.5 29565.0 31365.4 31797.0 32894.3 33447.8 43044.5 43124.4 43814.3 43921.1 45712.5 8301.5 8603.5 8613.2 8660.9 8687.2 10167.0 10263.3 14135.9 15691.7 16142.3 13680.7 13838.9 14297.9 14376.5 14440.2 13248.1 13662.9 13857.9 13914.2 14041.4 13671.0 14024.0 14087.0 14393.6 14573.4 8983.6 8988.8 9023.0 9113.0 9148.8 9714.0 13346.7 14243.2 14272.2 14528.9 18:57:02 INFO - 2015-12-14 18:57:02,903 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,903 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4783.92 Median:6014.58 stddev:2484.47 (41.3%) stddev-sans-first:2479.20 18:57:02 INFO - 2015-12-14 18:57:02,903 DEBUG : BROWSER_OUTPUT: Values: 1502.4 1619.1 1677.4 1685.5 1699.4 5777.6 6052.8 6734.1 6891.7 6913.1 6591.6 6664.6 6720.5 6761.3 6762.5 1981.9 2000.3 2003.7 2011.2 2015.7 2021.8 2025.8 2044.5 2076.0 2084.0 2024.5 2051.4 2075.2 2078.8 2083.3 2034.5 2035.7 2036.9 2055.9 2058.8 1969.6 2001.3 2034.5 2038.5 2056.7 6738.4 6822.0 6835.8 6843.7 6848.2 4056.1 4082.2 4091.4 4095.1 4107.6 2595.1 2610.0 2670.1 2672.1 2685.9 861.2 875.8 884.5 891.8 892.0 9265.3 9283.8 9302.4 9320.0 9327.0 3758.2 3802.0 3828.6 3840.0 3857.6 8009.1 8028.6 8031.0 8036.0 8043.2 4148.2 4172.0 4187.2 4188.2 4197.4 3146.3 3221.2 3746.4 3775.2 3782.2 3274.4 3281.6 3309.4 3318.1 3326.5 1050.8 1067.6 1071.4 1076.3 1076.7 873.5 875.8 875.9 891.0 1113.3 6411.8 6420.1 6428.5 6433.9 6434.3 422.4 432.1 433.6 435.0 437.4 4971.5 4996.4 5001.7 5012.1 5018.7 8122.5 8150.6 8219.3 8255.2 8273.1 8114.3 8129.8 8169.7 8241.5 8269.8 6504.4 6527.4 6554.5 6582.1 6635.1 6120.2 6143.0 6145.1 6167.8 6194.4 7276.7 7304.4 7335.0 7481.4 7483.7 7432.2 7572.2 7604.7 7616.1 7663.7 5061.9 5494.3 5984.5 6008.3 6034.8 6359.9 6500.3 6528.7 6532.4 6542.8 5981.8 5995.1 6149.8 6221.2 6273.4 6133.2 6165.6 6196.0 6220.3 6393.8 6278.7 6319.5 6367.5 6378.8 6380.4 5796.3 5796.9 5828.6 5842.6 5845.8 5962.9 5981.4 5989.3 6020.9 6066.9 18:57:02 INFO - 2015-12-14 18:57:02,903 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,904 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4876.75 Median:5590.04 stddev:1441.77 (25.8%) stddev-sans-first:1404.23 18:57:02 INFO - 2015-12-14 18:57:02,905 DEBUG : BROWSER_OUTPUT: Values: 296.7 300.7 305.9 307.1 307.2 5612.8 5613.7 5669.6 5698.0 5706.3 5587.9 5592.2 5593.9 5601.1 5607.2 4212.5 4298.0 4313.4 4315.2 4319.2 4284.8 4285.2 4299.2 4337.6 4345.7 4272.3 4273.2 4279.4 4279.9 4289.1 4276.9 4278.6 4281.9 4285.1 4296.6 3775.4 3783.1 3785.5 3786.5 3788.3 5238.0 5250.6 5308.6 5320.3 5361.9 4915.1 4932.9 4941.8 4943.2 4956.1 5878.0 5901.6 5904.7 5905.4 5914.7 4023.5 4082.3 4093.6 4102.8 4105.1 6071.5 6142.4 6149.9 6151.4 6158.2 4228.4 4231.1 4233.9 4238.4 4243.2 4289.7 4293.6 4299.9 4301.4 4303.2 4807.4 4887.4 4896.0 4901.7 4905.6 5877.3 5888.9 5889.6 5891.4 5899.0 1499.3 1525.4 1532.1 1536.9 1540.4 1035.9 1047.1 1051.7 1064.7 1066.5 5109.7 5112.6 5115.4 5120.0 5122.2 6116.9 6125.1 6126.4 6142.5 6148.3 5070.7 5090.4 5094.1 5097.0 5103.5 3233.6 3286.1 3320.9 3322.8 3327.8 5667.5 5808.9 5816.1 5822.3 5822.6 6279.6 6329.4 6334.2 6361.4 6370.1 5724.2 5751.8 5757.6 5763.9 5775.0 6125.6 6168.5 6169.9 6181.2 6188.0 5797.9 5963.2 5972.0 5994.9 6002.4 5730.2 5770.6 5777.2 5783.7 5786.4 3972.0 3992.8 3998.7 4006.4 4008.8 6039.3 6155.9 6163.6 6164.4 6169.6 5889.8 5907.5 5922.7 5923.8 5931.7 5860.4 5870.5 5874.3 5899.9 5904.2 5637.3 5712.3 5775.4 5784.8 5788.6 5923.5 5952.6 5958.1 5961.2 5971.0 5772.5 5797.0 5808.3 5815.0 5815.7 18:57:02 INFO - 2015-12-14 18:57:02,905 DEBUG : BROWSER_OUTPUT: 18:57:02 INFO - 2015-12-14 18:57:02,905 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12263.48 Median:45539.39 stddev:13818.39 (30.3%) stddev-sans-first:13830.12 18:57:02 INFO - 2015-12-14 18:57:02,905 DEBUG : BROWSER_OUTPUT: Values: 754.7 766.8 768.3 771.3 771.7 11535.4 11756.3 11803.5 11807.2 11831.3 10905.9 11008.4 11014.1 11034.4 11044.4 801.2 823.0 825.1 827.7 828.0 804.0 806.2 806.6 809.1 809.8 856.3 861.4 863.5 863.6 863.6 852.9 855.9 857.4 857.8 859.7 627.7 631.0 632.8 636.4 636.9 12092.9 12170.6 12306.0 12307.1 12309.8 10934.9 11031.6 11130.1 11150.4 12027.7 9324.4 9470.1 9492.0 9532.7 9568.2 3301.5 3313.2 3336.2 3363.1 3387.2 37716.6 39273.4 39422.6 39447.0 41965.8 8871.7 8922.5 9040.2 9108.9 9179.8 16083.2 16118.5 16153.6 16177.1 16248.9 6547.3 6579.7 6596.7 6622.2 6646.0 3768.6 3778.8 3806.2 3833.9 3862.2 2603.6 2637.6 2647.1 2649.4 2653.1 481.7 488.4 489.3 490.8 498.4 551.6 565.3 566.5 608.6 611.0 7320.5 7458.6 7493.1 7519.7 7545.0 590.7 597.8 604.9 605.5 610.1 3302.5 3389.4 3396.1 3425.8 3464.4 48604.0 49146.0 49929.8 50344.0 50576.7 44319.6 44699.6 45121.0 45486.0 45592.8 39428.2 41315.0 41480.0 41930.4 42127.2 7797.1 7830.0 7921.3 8047.2 8253.6 32279.2 32342.2 32352.8 32426.7 32611.8 36891.6 36981.8 39024.8 39986.0 40288.2 9723.2 9763.8 9795.0 10142.8 10474.3 11551.9 11599.9 11740.2 11756.0 12448.4 10094.8 10104.0 10125.4 10965.9 11411.7 10080.8 10585.6 10837.7 10980.6 11384.8 10644.8 10721.2 10821.6 10837.7 10968.1 9182.3 9618.0 9650.5 9666.9 9693.3 8458.1 8726.9 8820.5 8973.7 9282.8 18:57:02 INFO - 2015-12-14 18:57:02,905 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:57:02 INFO - 2015-12-14 18:57:02,905 DEBUG : BROWSER_OUTPUT: 18:57:03 INFO - 2015-12-14 18:57:03,656 INFO : Browser exited with error code: 0 18:57:03 INFO - 2015-12-14 18:57:03,680 INFO : Completed test dromaeo_css (00:18:34) 18:57:03 INFO - 2015-12-14 18:57:03,680 INFO : Starting test kraken 18:57:03 INFO - 2015-12-14 18:57:03,680 DEBUG : operating with platform_type : linux_ 18:57:03 INFO - 2015-12-14 18:57:03,680 INFO : Initialising browser for kraken test... 18:57:03 INFO - 2015-12-14 18:57:03,686 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi7EKNK/profile http://localhost:57957/getInfo.html 18:57:08 INFO - 2015-12-14 18:57:08,586 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:57:08 INFO - 2015-12-14 18:57:08,587 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:57:08 INFO - 2015-12-14 18:57:08,587 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:57:08 INFO - 2015-12-14 18:57:08,587 DEBUG : BROWSER_OUTPUT: __metrics 18:57:08 INFO - 2015-12-14 18:57:08,703 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] 18:57:09 INFO - 2015-12-14 18:57:09,600 INFO : Browser initialized. 18:57:09 INFO - 2015-12-14 18:57:09,600 INFO : Running cycle 1/1 for kraken test... 18:57:09 INFO - 2015-12-14 18:57:09,601 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi7EKNK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:57:21 INFO - 2015-12-14 18:57:21,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 143282176 18:57:21 INFO - 2015-12-14 18:57:21,636 DEBUG : BROWSER_OUTPUT: 18:57:24 INFO - 2015-12-14 18:57:24,224 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57957/tests/kraken/driver.html?testName=audio-beat-detection) 18:57:24 INFO - 2015-12-14 18:57:24,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 143314944 18:57:24 INFO - 2015-12-14 18:57:24,857 DEBUG : BROWSER_OUTPUT: 18:57:27 INFO - 2015-12-14 18:57:27,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57957/tests/kraken/driver.html?testName=audio-dft) 18:57:28 INFO - 2015-12-14 18:57:28,228 DEBUG : BROWSER_OUTPUT: RSS: Main: 143826944 18:57:28 INFO - 2015-12-14 18:57:28,228 DEBUG : BROWSER_OUTPUT: 18:57:31 INFO - 2015-12-14 18:57:31,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57957/tests/kraken/driver.html?testName=audio-fft) 18:57:31 INFO - 2015-12-14 18:57:31,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 138371072 18:57:31 INFO - 2015-12-14 18:57:31,700 DEBUG : BROWSER_OUTPUT: 18:57:33 INFO - 2015-12-14 18:57:33,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57957/tests/kraken/driver.html?testName=audio-oscillator) 18:57:34 INFO - 2015-12-14 18:57:34,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 139243520 18:57:34 INFO - 2015-12-14 18:57:34,386 DEBUG : BROWSER_OUTPUT: 18:57:36 INFO - 2015-12-14 18:57:36,648 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57957/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:57:37 INFO - 2015-12-14 18:57:37,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 140800000 18:57:37 INFO - 2015-12-14 18:57:37,250 DEBUG : BROWSER_OUTPUT: 18:57:40 INFO - 2015-12-14 18:57:40,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57957/tests/kraken/driver.html?testName=imaging-darkroom) 18:57:40 INFO - 2015-12-14 18:57:40,856 DEBUG : BROWSER_OUTPUT: RSS: Main: 141979648 18:57:40 INFO - 2015-12-14 18:57:40,856 DEBUG : BROWSER_OUTPUT: 18:57:44 INFO - 2015-12-14 18:57:44,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57957/tests/kraken/driver.html?testName=imaging-desaturate) 18:57:44 INFO - 2015-12-14 18:57:44,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 139067392 18:57:44 INFO - 2015-12-14 18:57:44,931 DEBUG : BROWSER_OUTPUT: 18:57:47 INFO - 2015-12-14 18:57:47,781 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57957/tests/kraken/driver.html?testName=json-parse-financial) 18:57:48 INFO - 2015-12-14 18:57:48,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 139747328 18:57:48 INFO - 2015-12-14 18:57:48,375 DEBUG : BROWSER_OUTPUT: 18:57:50 INFO - 2015-12-14 18:57:50,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57957/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:57:50 INFO - 2015-12-14 18:57:50,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 140025856 18:57:50 INFO - 2015-12-14 18:57:50,739 DEBUG : BROWSER_OUTPUT: 18:57:52 INFO - 2015-12-14 18:57:52,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:57:53 INFO - 2015-12-14 18:57:53,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 140271616 18:57:53 INFO - 2015-12-14 18:57:53,263 DEBUG : BROWSER_OUTPUT: 18:57:55 INFO - 2015-12-14 18:57:55,406 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:57:56 INFO - 2015-12-14 18:57:56,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 140054528 18:57:56 INFO - 2015-12-14 18:57:56,008 DEBUG : BROWSER_OUTPUT: 18:57:58 INFO - 2015-12-14 18:57:58,588 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:57:59 INFO - 2015-12-14 18:57:59,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 140931072 18:57:59 INFO - 2015-12-14 18:57:59,194 DEBUG : BROWSER_OUTPUT: 18:58:01 INFO - 2015-12-14 18:58:01,823 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:58:02 INFO - 2015-12-14 18:58:02,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 141791232 18:58:02 INFO - 2015-12-14 18:58:02,416 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57957/tests/kraken/driver.html?testName=ai-astar) 18:58:04 INFO - 2015-12-14 18:58:04,482 DEBUG : BROWSER_OUTPUT: RSS: Main: 140910592 18:58:04 INFO - 2015-12-14 18:58:04,482 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;111;114;113;115;124;112;113;113;113 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;137;157;172;140;155;153;133;179;135 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;164;163;162;162;166;167;163;166;164 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;74;76;75;144;73;73;73;73;155 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;96;134;94;95;96;137;95;95;130 18:58:04 INFO - 2015-12-14 18:58:04,486 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;110;109;110;110;110;110 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;148;147;148;147;148;149;147;148;148 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;97;97;96;97;96;96;96;96 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;73;73;73;76;71;74;81;71;72 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;69;69;69;69;69;68;68;68;69 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;92;94;85;86;85;88;94;84;85 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;118;119;126;119;131;140;127;124;122 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;153;154;153;152;142;157;155;152;137 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;72;76;78;73;71;70;73;71;74;72 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,813 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: __startTimestamp1450148284484__endTimestamp 18:58:04 INFO - 2015-12-14 18:58:04,487 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.20 Median:113.50 stddev:3.61 (3.2%) stddev-sans-first:3.83 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: Values: 114.0 111.0 114.0 113.0 115.0 124.0 112.0 113.0 113.0 113.0 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:152.30 Median:156.00 stddev:15.92 (10.2%) stddev-sans-first:16.50 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: Values: 162.0 137.0 157.0 172.0 140.0 155.0 153.0 133.0 179.0 135.0 18:58:04 INFO - 2015-12-14 18:58:04,495 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:1.76 (1.1%) stddev-sans-first:1.83 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: Values: 163.0 164.0 163.0 162.0 162.0 166.0 167.0 163.0 166.0 164.0 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.90 Median:73.00 stddev:32.06 (43.9%) stddev-sans-first:33.49 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: Values: 73.0 74.0 76.0 75.0 144.0 73.0 73.0 73.0 73.0 155.0 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:95.00 stddev:18.84 (19.8%) stddev-sans-first:19.34 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: Values: 93.0 96.0 134.0 94.0 95.0 96.0 137.0 95.0 95.0 130.0 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 110.0 109.0 110.0 110.0 110.0 110.0 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: Values: 147.0 148.0 147.0 148.0 147.0 148.0 149.0 147.0 148.0 148.0 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,496 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 97.0 97.0 96.0 97.0 96.0 96.0 96.0 96.0 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.70 Median:73.00 stddev:2.95 (4.0%) stddev-sans-first:3.11 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 73.0 73.0 76.0 71.0 74.0 81.0 71.0 72.0 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:0.71 (1.0%) stddev-sans-first:0.50 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: Values: 67.0 69.0 69.0 69.0 69.0 69.0 68.0 68.0 68.0 69.0 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.00 stddev:4.08 (4.7%) stddev-sans-first:4.11 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: Values: 84.0 92.0 94.0 85.0 86.0 85.0 88.0 94.0 84.0 85.0 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:125.00 stddev:6.67 (5.3%) stddev-sans-first:7.04 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: Values: 123.0 118.0 119.0 126.0 119.0 131.0 140.0 127.0 124.0 122.0 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.60 Median:153.00 stddev:6.90 (4.5%) stddev-sans-first:6.58 18:58:04 INFO - 2015-12-14 18:58:04,497 DEBUG : BROWSER_OUTPUT: Values: 141.0 153.0 154.0 153.0 152.0 142.0 157.0 155.0 152.0 137.0 18:58:04 INFO - 2015-12-14 18:58:04,498 DEBUG : BROWSER_OUTPUT: 18:58:04 INFO - 2015-12-14 18:58:04,498 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:73.00 stddev:2.45 (3.4%) stddev-sans-first:2.57 18:58:04 INFO - 2015-12-14 18:58:04,498 DEBUG : BROWSER_OUTPUT: Values: 72.0 76.0 78.0 73.0 71.0 70.0 73.0 71.0 74.0 72.0 18:58:04 INFO - 2015-12-14 18:58:04,498 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:58:04 INFO - 2015-12-14 18:58:04,498 DEBUG : BROWSER_OUTPUT: 18:58:05 INFO - 2015-12-14 18:58:05,396 INFO : Browser exited with error code: 0 18:58:05 INFO - 2015-12-14 18:58:05,445 INFO : Completed test kraken (00:01:01) 18:58:05 INFO - 2015-12-14 18:58:05,445 INFO : Starting test v8_7 18:58:05 INFO - 2015-12-14 18:58:05,445 DEBUG : operating with platform_type : linux_ 18:58:05 INFO - 2015-12-14 18:58:05,445 INFO : Initialising browser for v8_7 test... 18:58:05 INFO - 2015-12-14 18:58:05,451 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeX6fsO/profile http://localhost:57957/getInfo.html 18:58:10 INFO - 2015-12-14 18:58:10,517 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:58:10 INFO - 2015-12-14 18:58:10,517 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:58:10 INFO - 2015-12-14 18:58:10,517 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:58:10 INFO - 2015-12-14 18:58:10,517 DEBUG : BROWSER_OUTPUT: __metrics 18:58:10 INFO - 2015-12-14 18:58:10,654 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] 18:58:11 INFO - 2015-12-14 18:58:11,592 INFO : Browser initialized. 18:58:11 INFO - 2015-12-14 18:58:11,592 INFO : Running cycle 1/1 for v8_7 test... 18:58:11 INFO - 2015-12-14 18:58:11,593 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeX6fsO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:58:23 INFO - 2015-12-14 18:58:23,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 142835712 18:58:23 INFO - 2015-12-14 18:58:23,606 DEBUG : BROWSER_OUTPUT: 18:58:44 INFO - 2015-12-14 18:58:44,951 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57957/tests/v8_7/run.html (next: http://localhost:57957/tests/v8_7/run.html) 18:58:45 INFO - 2015-12-14 18:58:45,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 138027008 18:58:45 INFO - 2015-12-14 18:58:45,201 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,201 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:58:45 INFO - 2015-12-14 18:58:45,201 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |0;Richards;166.72224074691565 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.3143294432753 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5143.589743589743 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1071.8113612004288 18:58:45 INFO - 2015-12-14 18:58:45,202 DEBUG : BROWSER_OUTPUT: |5;Earley;698.5345429169574 18:58:45 INFO - 2015-12-14 18:58:45,203 DEBUG : BROWSER_OUTPUT: |6;Boyer;12228.915662650603 18:58:45 INFO - 2015-12-14 18:58:45,203 DEBUG : BROWSER_OUTPUT: |7;RegExp;26368.42105263158 18:58:45 INFO - 2015-12-14 18:58:45,203 DEBUG : BROWSER_OUTPUT: |8;Splay;593.4718100890208 18:58:45 INFO - 2015-12-14 18:58:45,203 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 18:58:45 INFO - 2015-12-14 18:58:45,203 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:58:45 INFO - 2015-12-14 18:58:45,203 DEBUG : BROWSER_OUTPUT: __startTimestamp1450148325200__endTimestamp 18:58:45 INFO - 2015-12-14 18:58:45,204 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,204 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:58:45 INFO - 2015-12-14 18:58:45,205 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:58:45 INFO - 2015-12-14 18:58:45,205 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,205 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.72 Median:166.72 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,206 DEBUG : BROWSER_OUTPUT: Values: 166.7 18:58:45 INFO - 2015-12-14 18:58:45,206 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,206 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.31 Median:191.31 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,206 DEBUG : BROWSER_OUTPUT: Values: 191.3 18:58:45 INFO - 2015-12-14 18:58:45,206 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,206 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,207 DEBUG : BROWSER_OUTPUT: Values: 278.4 18:58:45 INFO - 2015-12-14 18:58:45,207 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,207 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5143.59 Median:5143.59 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,207 DEBUG : BROWSER_OUTPUT: Values: 5143.6 18:58:45 INFO - 2015-12-14 18:58:45,207 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,207 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1071.81 Median:1071.81 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: Values: 1071.8 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:698.53 Median:698.53 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: Values: 698.5 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12228.92 Median:12228.92 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: Values: 12228.9 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26368.42 Median:26368.42 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: Values: 26368.4 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.47 Median:593.47 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: Values: 593.5 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 18:58:45 INFO - 2015-12-14 18:58:45,208 DEBUG : BROWSER_OUTPUT: Values: 4850.2 18:58:45 INFO - 2015-12-14 18:58:45,209 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:58:45 INFO - 2015-12-14 18:58:45,209 DEBUG : BROWSER_OUTPUT: 18:58:46 INFO - 2015-12-14 18:58:46,172 INFO : Browser exited with error code: 0 18:58:46 INFO - 2015-12-14 18:58:46,186 INFO : Completed test v8_7 (00:00:40) 18:58:46 INFO - 2015-12-14 18:58:46,284 INFO : Completed test suite (00:20:17) 18:58:46 INFO - 2015-12-14 18:58:46,285 DEBUG : Working with test: dromaeo_css 18:58:46 INFO - 2015-12-14 18:58:46,285 DEBUG : Generating results file: dromaeo_css 18:58:46 INFO - 2015-12-14 18:58:46,286 DEBUG : Working with test: kraken 18:58:46 INFO - 2015-12-14 18:58:46,286 DEBUG : Generating results file: kraken 18:58:46 INFO - 2015-12-14 18:58:46,286 INFO : javascript benchmark 18:58:46 INFO - 2015-12-14 18:58:46,286 DEBUG : Working with test: v8_7 18:58:46 INFO - 2015-12-14 18:58:46,286 DEBUG : Generating results file: v8_7 18:58:46 INFO - 2015-12-14 18:58:46,287 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:58:46 INFO - 2015-12-14 18:58:46,343 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 18:58:46 INFO - 2015-12-14 18:58:46,344 DEBUG : process_Request line: dromaeo_css 6210.99 graph.html#tests=[[72,132,43]] 18:58:46 INFO - 2015-12-14 18:58:46,344 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:58:46 INFO - 2015-12-14 18:58:46,374 DEBUG : process_Request line: kraken 1557.00 graph.html#tests=[[232,132,43]] 18:58:46 INFO - 2015-12-14 18:58:46,374 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:58:46 INFO - 2015-12-14 18:58:46,406 DEBUG : process_Request line: v8_7 21347.17 graph.html#tests=[[230,132,43]] 18:58:46 INFO - 2015-12-14 18:58:46,407 INFO : javascript benchmark 18:58:46 INFO - 2015-12-14 18:58:46,410 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2634.473105378924, 2728.931776705583, 2729.9590506142413, 2749.4775992561413, 2758.8482633455164, 4618.676692631517, 4787.138315103284, 4833.613310935126, 4834.154023045966, 4870.561649451553, 4696.50686677899, 4696.50686677899, 4736.97631511842, 4848.709077455355, 4857.708537487753, 4196.769177695225, 4281.12236991416, 4302.483701955754, 4323.178596066738, 4329.631981281595, 4315.244832154376, 4379.868603941871, 4422.889427764299, 4432.20220360335, 4472.396226509412, 4171.6036976487185, 4267.743935363888, 4272.9359059614135, 4279.079997800488, 4390.97804510977, 4301.548337424588, 4462.219111655448, 4466.776661166961, 4468.955310446887, 4472.172648060119, 4372.453443319585, 4376.343548467724, 4379.934300985487, 4383.9342409863875, 4438.556144385568, 4552.453705555355, 4596.402467679214, 4720.787564559603, 4722.244440889442, 4742.928856067162, 5136.1525348317855, 5136.921246538232, 5150.845474635767, 5152.896942061176, 5201.973990130063, 5105.157648987913, 5125.179971204627, 5130.615203859726, 5155.9742201288755, 5165.9225111623355, 4609.7695115244105, 4684.74233917131, 4704.482506924235, 4761.547652972997, 4789.257665061921, 5257.342832145981, 5280.445553216934, 5283.815066472655, 5285.735713214324, 5318.7074710891275, 5041.59667226621, 5054.721990290569, 5079.6952182869145, 5091.363579552556, 5138.794448222029, 4916.459189489154, 4989.700617962933, 5017.297578338962, 5102.183650615883, 5167.0, 5066.873328166826, 5073.137566613685, 5091.974540127349, 5094.77073531692, 5139.974300128475, 5074.5686616637995, 5081.110805609078, 5090.338256026767, 5090.567301779391, 5095.490450954949, 4127.9174416511505, 4140.668746500333, 4214.4099826024385, 4248.320268756984, 4250.574942505725, 3760.5863355030924, 3761.0785357587565, 3774.358359078963, 3778.5465744110884, 3803.9429408558894, 4267.97866010668, 4469.9776501117285, 4479.283314669638, 4484.75333856641, 4491.73049617024, 5011.6241281904, 5057.949420505822, 5134.306868572769, 5136.897262054738, 5168.3797944246935, 4372.125574885035, 4389.253826849444, 4395.626371758436, 4432.623227025739, 4438.689291749546, 4299.720518166309, 4445.666575006822, 4509.6392288616835, 4522.914500519852, 4572.565606267466, 4742.170120228946, 4798.136335459651, 4808.855734327975, 4809.398825146856, 4836.250381190851, 5127.128388173945, 5135.563477104488, 5149.0474262261605, 5164.251183578435, 5172.508611681884, 4636.721796692209, 4770.236762118045, 4775.307580400892, 4829.710217386968, 4896.608271338356, 4977.203647416396, 5045.268436076822, 5053.090443720019, 5064.493550645054, 5108.897822043613, 5098.847034588968, 5193.68837869729, 5199.662021968711, 5202.063628546898, 5249.947500524946, 4745.288206768874, 4845.903081938412, 4849.0, 5001.899962000813, 5057.393112826484, 4871.805127794849, 4876.658633895593, 4880.95119048805, 4971.254311853107, 4995.225740010304, 4810.63920205979, 4842.903141937212, 4851.878703032452, 4856.417229932431, 4857.222844344888, 4967.751612419437, 5006.799728010838, 5008.198688209869, 5013.197888337849, 5015.398152221756, 4692.319613655933, 4834.323194752667, 4846.224604063333, 4850.417949846041, 4856.247281671346], "unit": "ms", "name": "dojo.html", "value": 4670.33665892927}, {"lowerIsBetter": false, "replicates": [547.4051920553064, 574.9338826035007, 582.4612233683841, 588.7586089703221, 596.2457491273539, 35161.47257791132, 36278.45582316264, 36315.0, 36356.81821590888, 36393.0, 39413.60586394135, 39937.40093898594, 39984.200315993665, 40064.0, 40067.398989015186, 7889.329407000412, 7969.641366138538, 7975.7208497702595, 8030.959845200795, 8055.395845311596, 7812.687492500293, 7853.764387068369, 7859.449838511307, 7874.133845277015, 7937.523748575075, 7902.841943161134, 7970.920290797105, 7978.401619878503, 7978.720744773934, 7979.7606071817645, 7927.0, 7967.482113662593, 7974.920250797534, 8018.198180181994, 8056.194380561956, 7801.804954876116, 8000.519968801832, 8008.199180082003, 8016.038075430927, 8035.357171426272, 37042.259154816755, 37183.25633487315, 37236.44145337822, 37462.0, 37467.25065498702, 2891.161563146685, 3121.8751249949973, 3521.0669172669263, 3686.686631636314, 3692.4461330800327, 27866.442671146666, 28395.432091358263, 28551.0, 28817.0, 28825.0, 3295.40682677118, 3296.1100502864333, 3338.5326054352167, 3392.2197894484407, 3438.5186073949667, 213273.0, 213454.0, 213568.0, 214836.0, 215116.0, 53519.46480535145, 53721.73139134279, 54060.72969635126, 54103.72948135234, 54123.0, 49719.50280497221, 50545.49454505481, 64905.67547162234, 66309.66845165839, 66405.33594664089, 14082.43670253199, 14202.644933876529, 14242.85757142436, 14307.642308942151, 14357.138571685733, 10377.325473844176, 10377.533011014522, 10390.896091038992, 10433.947830260951, 10457.581696732046, 18473.1687074082, 19098.809011909703, 19125.808741912682, 19240.615187696167, 19251.13369898358, 7526.5860377679755, 7844.333231675258, 7873.606319683993, 7910.604469776488, 8018.358531317481, 813.0081300812999, 867.0679020053468, 920.392540923001, 928.1831987850783, 968.8837339519303, 25734.35664108375, 26135.215943521725, 26224.204744491337, 26471.205863824118, 26740.331491712863, 925.2644147902433, 1009.9242556808268, 1045.4772613693153, 1062.824633935392, 1106.441247170174, 13713.314334283445, 14055.22696251716, 14079.862499000703, 14101.85898141026, 14128.364223609962, 266775.0, 270042.0, 270305.0, 271404.0, 272947.0, 45375.54624453779, 45497.7725111379, 45713.0, 45727.77136114365, 45902.0, 1175.6355529785794, 1248.1387842388722, 1248.6566194296458, 1257.9622611321674, 1263.3809433377762, 24837.50324993454, 25333.61999569971, 25768.484630307663, 26116.60825087663, 26352.736472635028, 40007.0, 40094.19811603693, 40111.0, 40346.59653403428, 40435.7978210107, 255767.0, 257176.0, 258548.0, 258630.0, 259176.0, 28219.0, 29294.26764330909, 29459.55810662799, 29498.705012949595, 30007.849960750056, 33480.497792532646, 33692.49461258034, 34337.484937726455, 34682.47976280307, 34846.47730284098, 29562.260943476584, 29811.0, 30007.399852003273, 32121.678783212803, 32487.67512324941, 32527.88568743119, 32563.185920351232, 32763.180920477178, 32849.83575082109, 32935.34129317352, 33329.333413331115, 33434.164145896546, 33591.0, 33782.662173378936, 34117.829410852784, 27516.0, 29987.4002519944, 33025.66974330226, 33026.504602430505, 33509.83245083856, 23336.533269334857, 23374.0, 23528.29415117549, 23564.293071207892, 23584.764152358257], "unit": "ms", "name": "ext.html", "value": 16927.396837741948}, {"lowerIsBetter": false, "replicates": [332.9926970912988, 338.5446574357489, 338.8695352289369, 340.0682130961167, 344.7397215102597, 12524.436400361983, 12777.616671499847, 12885.74228515429, 14851.257437128155, 14965.700685986274, 14218.14691118531, 14357.210353430557, 14489.347979340928, 14683.77974330386, 15260.694786104272, 4860.6111511079025, 4896.1186986342445, 4941.234108713137, 4951.579115775164, 4981.526754958273, 4822.445418776851, 4964.726940018298, 4997.450280469164, 5002.149634562115, 5043.0, 4950.218758408996, 4973.950260497385, 4975.6765810222405, 4987.426445958707, 5015.648904576681, 5758.884822303551, 5852.9707351463185, 5878.0, 5891.587588868781, 5900.350961394243, 5048.066107770074, 5147.459516750762, 5188.0, 5194.740262986836, 5198.974005129988, 11397.601083962021, 11584.42077896102, 11656.825147622792, 11666.533338666442, 12592.559260425933, 1640.2454870759466, 1668.307652324284, 1745.938892138769, 1773.8846974946587, 1798.856091512676, 8525.744227673118, 8709.608067636971, 8801.339899507562, 8889.822203555957, 8900.198982091642, 1646.6459711162158, 1668.3993762245582, 1677.060845926285, 1713.682968650791, 1732.1339330334833, 53914.0, 55142.0, 56248.15627765587, 56517.43482565204, 57159.42840571583, 9925.156361709336, 9988.25088118394, 10027.746531683539, 10037.949810250902, 10038.247131465168, 12313.876861231452, 12325.260484370967, 12337.444814983348, 12349.320787356603, 12367.567135150226, 7023.613701246481, 7155.427565794724, 7193.712251509985, 7226.458015648847, 7266.672999715025, 6761.628110453972, 6775.1531058617675, 6798.04827324175, 6804.217514985741, 6807.421369183675, 4135.421041054255, 4161.542230354658, 4187.539370669285, 4214.431051808028, 4249.2563801334645, 1718.3813827022261, 1766.3287950578697, 1773.1134432783608, 1781.6703909776734, 1792.5070605583307, 363.66724447130576, 365.27492926540793, 405.4587126186574, 795.7294519863159, 865.2774932931073, 9338.299627527962, 9439.480828554359, 9595.088466595662, 9760.804783904283, 9780.217582593517, 389.7875657766524, 390.94331321958157, 399.76813448199977, 402.1253773043677, 410.6796698575116, 5342.75957581902, 5364.409914909357, 5364.597655175878, 5390.380106287827, 5433.0763770159165, 487229.0, 497987.0, 500726.0, 501807.0, 509147.0, 72071.27928720646, 74058.0, 74077.62961185159, 74960.25039749533, 75694.0, 436.884225680195, 437.67611967144086, 437.9036611945367, 439.79769306119545, 440.425245055206, 8975.730728078168, 9071.319651026331, 9150.039245879354, 9166.908330916605, 9261.53692315368, 29565.0, 31365.372692545567, 31797.0, 32894.34211315808, 33447.83276083604, 43044.493442729356, 43124.35313470363, 43814.34278485762, 43921.121577568905, 45712.54287457083, 8301.46040507365, 8603.483790972561, 8613.181747733955, 8660.870086948575, 8687.218150366498, 10167.0, 10263.332883362558, 14135.929320353333, 15691.686166276382, 16142.273597688367, 13680.65798355049, 13838.86161138416, 14297.928510357382, 14376.496822611158, 14440.205788681724, 13248.138870973356, 13662.93168534151, 13857.930710346787, 13914.165150091027, 14041.438342466186, 13671.0, 14024.0, 14087.0, 14393.568192954228, 14573.417063317349, 8983.640654374014, 8988.775280617776, 9022.954885225532, 9112.95443522778, 9148.771280718036, 9714.02859714023, 13346.666333341744, 14243.21662308583, 14272.21502817355, 14528.85471145275], "unit": "ms", "name": "jquery.html", "value": 7366.164522899211}, {"lowerIsBetter": false, "replicates": [1502.361496364045, 1619.0690353046998, 1677.370985880295, 1685.5196269063315, 1699.39671416647, 5777.566682498808, 6052.848678783022, 6734.124563806705, 6891.7243310267595, 6913.06673599064, 6591.637459939701, 6664.600123992552, 6720.495962802785, 6761.256261811209, 6762.492813039017, 1981.9207231710714, 2000.3098930868828, 2003.6693945498976, 2011.2256781139295, 2015.6674148765437, 2021.82814460771, 2025.8480613953939, 2044.4990977210605, 2075.9584808303825, 2084.0, 2024.5141166120172, 2051.4256008317693, 2075.2425364741835, 2078.8440866934966, 2083.270855200683, 2034.4506983114477, 2035.6844689073218, 2036.9490762730902, 2055.876647401146, 2058.8249998750125, 1969.606078784249, 2001.2995451591971, 2034.511717187879, 2038.5413282011577, 2056.7017782421467, 6738.393544581011, 6821.965890170567, 6835.760748373833, 6843.657817109174, 6848.178218613748, 4056.067104565937, 4082.2447847148374, 4091.3862920561855, 4095.1195492968855, 4107.589241075899, 2595.1306312385373, 2609.973900260992, 2670.118860775939, 2672.0647773279393, 2685.9194224173307, 861.2248975921658, 875.8160786234898, 884.5179377239419, 891.8260939116875, 891.9866202006975, 9265.305102117301, 9283.76790580241, 9302.395344302598, 9320.0, 9326.953365233197, 3758.1919887224376, 3801.9809900950318, 3828.578856325802, 3839.9616003840165, 3857.633524815138, 8009.078955920144, 8028.638711258008, 8031.0, 8036.0, 8043.235892590194, 4148.23257697327, 4172.040430700956, 4187.246295666749, 4188.246115699203, 4197.391378250137, 3146.2606287522412, 3221.2430078931434, 3746.3631182699005, 3775.244951009754, 3782.224643947984, 3274.3942370661503, 3281.5898012748407, 3309.4043072247227, 3318.070940136766, 3326.5010248462922, 1050.847627094067, 1067.6263307842194, 1071.3625392891217, 1076.34343050739, 1076.7469644633504, 873.4802792338548, 875.7941883657336, 875.8511053120857, 891.0376793063476, 1113.3264375052977, 6411.775587854496, 6420.06908998202, 6428.5178611604315, 6433.935660643428, 6434.2600600930555, 422.40440978221176, 432.0796703022542, 433.5794279548833, 435.049417023804, 437.3963929776951, 4971.502849715, 4996.3504744383, 5001.674891132065, 5012.072766538129, 5018.749062546858, 8122.472039317426, 8150.551719655476, 8219.301359384519, 8255.215754503313, 8273.131321211307, 8114.310283625958, 8129.75610731679, 8169.714060007871, 8241.50550966944, 8269.83460330802, 6504.447121994684, 6527.412532871969, 6554.475641948633, 6582.078509008743, 6635.137432133888, 6120.17377654011, 6143.047827586731, 6145.0782382642965, 6167.784127555514, 6194.38056194377, 7276.7089316426745, 7304.415646748141, 7334.96332518334, 7481.4014878808475, 7483.663235154322, 7432.21961694036, 7572.20491848348, 7604.657790399335, 7616.0860696716745, 7663.731769388045, 5061.873453163553, 5494.2582751328055, 5984.521238301013, 6008.279006519246, 6034.758609655564, 6359.90460143089, 6500.317466666121, 6528.738850445929, 6532.379423954717, 6542.8364290893105, 5981.7607295707685, 5995.100734889801, 6149.846253843511, 6221.191245138284, 6273.37266273369, 6133.233345831771, 6165.568410211242, 6196.0, 6220.346863579259, 6393.776217832354, 6278.748850045947, 6319.462845657987, 6367.490600751836, 6378.77674281398, 6380.361963803768, 5796.333421656394, 5796.884062318814, 5828.59199856006, 5842.561807864512, 5845.795397161079, 5962.880742385215, 5981.3719559445635, 5989.311229208697, 6020.8795824084145, 6066.908996365147], "unit": "ms", "name": "mootools.html", "value": 3872.41881319381}, {"lowerIsBetter": false, "replicates": [296.72552888578065, 300.712819257609, 305.9296361836776, 307.1201009108901, 307.16757586939394, 5612.775488980441, 5613.663180209185, 5669.603127781052, 5697.971510142444, 5706.315242170935, 5587.888242235153, 5592.189132575774, 5593.8601534961645, 5601.103823388259, 5607.186957891104, 4212.515560710527, 4298.0, 4313.417688612044, 4315.201687687788, 4319.157764235975, 4284.828606855722, 4285.164392943378, 4299.161663475624, 4337.63130133939, 4345.674074444413, 4272.295071313245, 4273.188094262096, 4279.40088387626, 4279.87160385189, 4289.077848262623, 4276.914461710749, 4278.61492465678, 4281.892952676192, 4285.100128972919, 4296.591823776736, 3775.3959366501226, 3783.05423644089, 3785.488958990556, 3786.4509646101437, 3788.3370410178386, 5238.030964271584, 5250.606204534675, 5308.575313974911, 5320.334958130234, 5361.865953351158, 4915.1398505261795, 4932.926006109911, 4941.751503066096, 4943.184374578191, 4956.058348913695, 5878.0, 5901.616394934358, 5904.704764761745, 5905.350411454737, 5914.733836977346, 4023.517177938637, 4082.346824508094, 4093.5906409358827, 4102.835886564534, 4105.117399759066, 6071.51427885768, 6142.385761423822, 6149.907751383733, 6151.354107818659, 6158.199434073542, 4228.386883901817, 4231.090315582164, 4233.8518151865155, 4238.427812245369, 4243.236217480883, 4289.742615443083, 4293.613574778285, 4299.892502687458, 4301.419308393327, 4303.182395344875, 4807.365495731468, 4887.413510378778, 4896.0, 4901.681390709592, 4905.632077594195, 5877.294724633071, 5888.882222355529, 5889.5877288590245, 5891.3814049525035, 5898.970505147447, 1499.2503748125937, 1525.3669727063366, 1532.080751549084, 1536.8770498360104, 1540.3761476601994, 1035.9015893490107, 1047.0890325416935, 1051.679237832453, 1064.669952314785, 1066.4880857188593, 5109.744512774347, 5112.6421150519855, 5115.437301896788, 5120.0, 5122.154844450699, 6116.877662446726, 6125.050617154348, 6126.417990290915, 6142.477889379453, 6148.323684394713, 5070.6957582545165, 5090.440051594322, 5094.083065048253, 5096.974515127401, 5103.540681338697, 3233.563468931711, 3286.07989762862, 3320.900372988814, 3322.833858307075, 3327.800331980089, 5667.518260947865, 5808.88382232353, 5816.098504731774, 5822.301323841166, 5822.563307751935, 6279.560430769802, 6329.367063293634, 6334.208223972004, 6361.427471527492, 6370.108184854124, 5724.16999535065, 5751.769929202868, 5757.625754326039, 5763.942360576509, 5775.0, 6125.571210015256, 6168.506519478521, 6169.876602468015, 6181.196444462191, 6188.040853667689, 5797.91303130445, 5963.165156878127, 5971.954907891189, 5994.910076348771, 6002.369751176064, 5730.22641943349, 5770.6249093808765, 5777.24895763548, 5783.739731712168, 5786.42135786418, 3971.9871432785612, 3992.840286388628, 3998.7200895937003, 4006.4390985261502, 4008.7594744315434, 6039.342689216731, 6155.876882462236, 6163.568550201443, 6164.445199932027, 6169.629822210682, 5889.764409423575, 5907.49786268172, 5922.703864806656, 5923.7926672566255, 5931.673757943355, 5860.443257890493, 5870.530357571299, 5874.265716785402, 5899.941000589939, 5904.202932604216, 5637.3235211774845, 5712.285964254468, 5775.393583673654, 5784.82645520635, 5788.594798364074, 5923.466887980103, 5952.642841429355, 5958.1360702697, 5961.195238642727, 5971.0, 5772.509336706427, 5796.971015144897, 5808.332041815245, 5815.0, 5815.680137592473], "unit": "ms", "name": "prototype.html", "value": 4411.18375463206}, {"lowerIsBetter": false, "replicates": [754.683033126087, 766.7967988483051, 768.2624680306905, 771.2904128202053, 771.7298945369122, 11535.365554894488, 11756.294622322644, 11803.468843902023, 11807.232529885563, 11831.29012259265, 10905.945470272658, 11008.39453830039, 11014.118870490362, 11034.393108379036, 11044.447777611127, 801.1867954026653, 822.9671761938788, 825.1006403020706, 827.6854795177824, 827.9586020698941, 804.0231119190182, 806.1857523900844, 806.6087947345533, 809.1261437647327, 809.8177909970268, 856.3320609924269, 861.4228466927143, 863.5121156546531, 863.6329559937002, 863.6415887406719, 852.8933883264623, 855.8930133733284, 857.4426622695236, 857.7555396711907, 859.7463748194235, 627.7018416252289, 631.0439683878927, 632.7595513704808, 636.3890664961642, 636.9235691716977, 12092.879071209263, 12170.634880953585, 12306.0, 12307.138500304982, 12309.753804923852, 10934.890651093547, 11031.613893513688, 11130.109591232764, 11150.386728729916, 12027.699307517294, 9324.440533568008, 9470.100340467645, 9492.0, 9532.714018579454, 9568.234541236754, 3301.5443669885217, 3313.2379552703014, 3336.182635254378, 3363.091965169415, 3387.237871478922, 37716.62283377159, 39273.41089883654, 39422.605773942465, 39447.0, 41965.79017104925, 8871.689490867788, 8922.509261990654, 9040.231580315616, 9108.8633670495, 9179.81640367189, 16083.195840207944, 16118.516444506684, 16153.59616009609, 16177.110258935636, 16248.918755406148, 6547.279799222082, 6579.703913323912, 6596.703148358335, 6622.1722284714415, 6646.0, 3768.5666148393284, 3778.848846046127, 3806.2197249563774, 3833.884983450501, 3862.1696335288048, 2603.5964425514076, 2637.591173368131, 2647.1264482720658, 2649.4303724699284, 2653.0714250012343, 481.71819485601316, 488.38707422184933, 489.29297165596086, 490.82821012646, 498.3770287141074, 551.5807985930721, 565.3498476751769, 566.4788394676907, 608.5527137553894, 611.0498175337304, 7320.487565870444, 7458.589777562286, 7493.070725869469, 7519.736809211651, 7545.0, 590.7365374513068, 597.7519329478305, 604.8548348396441, 605.5065121925704, 610.1061944251693, 3302.516487676586, 3389.389909816257, 3396.117009577528, 3425.811580363054, 3464.4110501214855, 48604.0, 49146.0, 49929.75035124801, 50344.0, 50576.74711626418, 44319.556804431544, 44699.55300447019, 45121.0, 45486.0, 45592.772036139606, 39428.211435771125, 41315.0, 41480.0, 41930.37104443375, 42127.15745685069, 7797.142314345304, 7830.0, 7921.287084162453, 8047.161685114198, 8253.628586713492, 32279.193020173745, 32342.221177834504, 32352.83823580867, 32426.675733242366, 32611.836940815145, 36891.63108368882, 36981.81509092437, 39024.80487597544, 39986.0, 40288.1942361157, 9723.222142228746, 9763.80472390534, 9795.0, 10142.797144056929, 10474.266801323834, 11551.88448115508, 11599.942000289944, 11740.178187526792, 11756.0, 12448.439820208294, 10094.8485772712, 10104.0, 10125.443100629242, 10965.945170274097, 11411.714707132389, 10080.848787268344, 10585.55969309962, 10837.67486975392, 10980.560777568808, 11384.772304553697, 10644.84032739525, 10721.195910306602, 10821.567137314734, 10837.729056773644, 10968.122550196125, 9182.311326650395, 9618.0, 9650.469224192737, 9666.854997175189, 9693.321467497208, 8458.111898250596, 8726.91273087261, 8820.514871682119, 8973.685920992733, 9282.767930801783], "unit": "ms", "name": "yui.html", "value": 5770.360406997842}], "name": "dromaeo_css", "value": 6210.987912781277}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 111.0, 114.0, 113.0, 115.0, 124.0, 112.0, 113.0, 113.0, 113.0], "unit": "ms", "name": "ai-astar", "value": 114.2}, {"lowerIsBetter": true, "replicates": [162.0, 137.0, 157.0, 172.0, 140.0, 155.0, 153.0, 133.0, 179.0, 135.0], "unit": "ms", "name": "audio-beat-detection", "value": 152.3}, {"lowerIsBetter": true, "replicates": [163.0, 164.0, 163.0, 162.0, 162.0, 166.0, 167.0, 163.0, 166.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 164.0}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 76.0, 75.0, 144.0, 73.0, 73.0, 73.0, 73.0, 155.0], "unit": "ms", "name": "audio-fft", "value": 88.9}, {"lowerIsBetter": true, "replicates": [93.0, 96.0, 134.0, 94.0, 95.0, 96.0, 137.0, 95.0, 95.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 149.0, 147.0, 148.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 97.0, 97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 73.0, 73.0, 76.0, 71.0, 74.0, 81.0, 71.0, 72.0], "unit": "ms", "name": "json-parse-financial", "value": 73.7}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 69.0, 69.0, 69.0, 69.0, 68.0, 68.0, 68.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.5}, {"lowerIsBetter": true, "replicates": [84.0, 92.0, 94.0, 85.0, 86.0, 85.0, 88.0, 94.0, 84.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.7}, {"lowerIsBetter": true, "replicates": [123.0, 118.0, 119.0, 126.0, 119.0, 131.0, 140.0, 127.0, 124.0, 122.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.9}, {"lowerIsBetter": true, "replicates": [141.0, 153.0, 154.0, 153.0, 152.0, 142.0, 157.0, 155.0, 152.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.6}, {"lowerIsBetter": true, "replicates": [72.0, 76.0, 78.0, 73.0, 71.0, 70.0, 73.0, 71.0, 74.0, 72.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 73.0}], "name": "kraken", "value": 1557.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.72224074691565], "unit": "score", "name": "Richards", "value": 211.74139599999998}, {"lowerIsBetter": false, "replicates": [191.3143294432753], "unit": "score", "name": "DeltaBlue", "value": 345.5987859999999}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5143.589743589743], "unit": "score", "name": "Decrypt", "value": 51.75004486540379}, {"lowerIsBetter": false, "replicates": [1071.8113612004288], "unit": "score", "name": "RayTrace", "value": 690.4097369999998}, {"lowerIsBetter": false, "replicates": [698.5345429169574], "unit": "score", "name": "Earley", "value": 954.0873916083917}, {"lowerIsBetter": false, "replicates": [12228.915662650603], "unit": "score", "name": "Boyer", "value": 54.498944827586165}, {"lowerIsBetter": false, "replicates": [26368.42105263158], "unit": "score", "name": "RegExp", "value": 34.54833333333334}, {"lowerIsBetter": false, "replicates": [593.4718100890208], "unit": "score", "name": "Splay", "value": 137.31233500000002}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 21347.172151980747}]} 18:58:46 INFO - RETURN: dromaeo_css: 6210.99 18:58:46 INFO - RETURN: kraken: 1557.00 18:58:46 INFO - RETURN: v8_7: 21347.17 18:58:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21347.17"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1557.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6210.99"}}} 18:58:46 INFO - Return code: 0 18:58:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:58:46 INFO - # TBPL SUCCESS # 18:58:46 INFO - Running post-action listener: _resource_record_post_action 18:58:46 INFO - Running post-run listener: _resource_record_post_run 18:58:47 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 0; Write bytes: 515948544; Read time: 0; Write time: 635124 18:58:47 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 239255552; Read time: 0; Write time: 403156 18:58:47 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 0; Write bytes: 272683008; Read time: 0; Write time: 231124 18:58:47 INFO - Running post-run listener: _upload_blobber_files 18:58:47 INFO - Blob upload gear active. 18:58:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:58:47 INFO - Copying logs to upload dir... 18:58:47 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.951904 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-14 18:58:47.382121) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-14 18:58:47.385411) ========= 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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009937 build_url: 'https://queue.taskcluster.net/v1/task/dW3JwpeUR4WrseI68-ZkIQ/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-14 18:58:47.437080) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:58:47.437404) ========= 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=1d09c2d9237d126f9c84bbb400000198-1450146813.531353-1196347869 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004652 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 18:58:47.487108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-14 18:58:47.487429) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-14 18:58:47.487740) ========= ========= Total master_lag: 0.30 =========