builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-007 starttime: 1450228638.38 results: success (0) buildid: 20151215163208 builduid: 086d71ac045c4569932b78764b26e44b revision: a6616b301f1c3c270ac60794ba7e0e7f722fe48b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.379725) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.380195) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.380492) ========= 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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006226 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.403313) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.403654) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.417209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.417618) ========= 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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.467832) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.468143) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.468542) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.468846) ========= 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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-15 17:17:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.7M=0s 2015-12-15 17:17:18 (43.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109550 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.591927) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.592263) ========= 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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018731 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:18.642131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 17:17:18.642498) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a6616b301f1c3c270ac60794ba7e0e7f722fe48b --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 a6616b301f1c3c270ac60794ba7e0e7f722fe48b --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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-15 17:17:18,923 truncating revision to first 12 chars 2015-12-15 17:17:18,923 Setting DEBUG logging. 2015-12-15 17:17:18,923 attempt 1/10 2015-12-15 17:17:18,923 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a6616b301f1c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-15 17:17:19,737 unpacking tar archive at: fx-team-a6616b301f1c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.086515 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 17:17:19.742398) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:19.742735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:19.753676) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:19.754055) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 17:17:19.754540) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-15 17:17:19.754828) ========= /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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:17:20 INFO - MultiFileLogger online at 20151215 17:17:20 in /builds/slave/test 17:17:20 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 17:17:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:17:20 INFO - {'append_to_log': False, 17:17:20 INFO - 'base_work_dir': '/builds/slave/test', 17:17:20 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:17:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:17:20 INFO - 'branch': 'Fx-Team-Non-PGO', 17:17:20 INFO - 'buildbot_json_path': 'buildprops.json', 17:17:20 INFO - 'config_files': ('talos/linux_config.py',), 17:17:20 INFO - 'default_actions': ('clobber', 17:17:20 INFO - 'read-buildbot-config', 17:17:20 INFO - 'download-and-extract', 17:17:20 INFO - 'populate-webroot', 17:17:20 INFO - 'create-virtualenv', 17:17:20 INFO - 'install', 17:17:20 INFO - 'run-tests'), 17:17:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:17:20 INFO - 'download_minidump_stackwalk': True, 17:17:20 INFO - 'download_symbols': 'ondemand', 17:17:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:17:20 INFO - 'tooltool.py': '/tools/tooltool.py', 17:17:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:17:20 INFO - '/tools/misc-python/virtualenv.py')}, 17:17:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:17:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:17:20 INFO - 'installer_path': 'installer.exe', 17:17:20 INFO - 'log_level': 'info', 17:17:20 INFO - 'log_name': 'talos', 17:17:20 INFO - 'log_to_console': True, 17:17:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:17:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:17:20 INFO - 'opt_config_files': (), 17:17:20 INFO - 'pip_index': False, 17:17:20 INFO - 'sps_profile': False, 17:17:20 INFO - 'sps_profile_interval': 0, 17:17:20 INFO - 'suite': 'dromaeojs-e10s', 17:17:20 INFO - 'system_bits': '64', 17:17:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:17:20 INFO - 'title': 'talos-linux64-ix-007', 17:17:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:17:20 INFO - 'use_talos_json': True, 17:17:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:17:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:17:20 INFO - 'work_dir': 'build'} 17:17:20 INFO - ##### 17:17:20 INFO - ##### Running clobber step. 17:17:20 INFO - ##### 17:17:20 INFO - Running pre-action listener: _resource_record_pre_action 17:17:20 INFO - Running main action method: clobber 17:17:20 INFO - rmtree: /builds/slave/test/build 17:17:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:17:20 INFO - Running post-action listener: _resource_record_post_action 17:17:20 INFO - ##### 17:17:20 INFO - ##### Running read-buildbot-config step. 17:17:20 INFO - ##### 17:17:20 INFO - Running pre-action listener: _resource_record_pre_action 17:17:20 INFO - Running main action method: read_buildbot_config 17:17:20 INFO - Using buildbot properties: 17:17:20 INFO - { 17:17:20 INFO - "properties": { 17:17:20 INFO - "buildnumber": 577, 17:17:20 INFO - "product": "firefox", 17:17:20 INFO - "script_repo_revision": "production", 17:17:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 17:17:20 INFO - "repository": "", 17:17:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 17:17:20 INFO - "buildid": "20151215163208", 17:17:20 INFO - "slavename": "talos-linux64-ix-007", 17:17:20 INFO - "pgo_build": "False", 17:17:20 INFO - "basedir": "/builds/slave/test", 17:17:20 INFO - "project": "", 17:17:20 INFO - "platform": "ubuntu64_hw", 17:17:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:17:20 INFO - "slavebuilddir": "test", 17:17:20 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:17:20 INFO - "branch": "fx-team", 17:17:20 INFO - "repo_path": "integration/fx-team", 17:17:20 INFO - "stage_platform": "linux64", 17:17:20 INFO - "builduid": "086d71ac045c4569932b78764b26e44b", 17:17:20 INFO - "revision": "a6616b301f1c3c270ac60794ba7e0e7f722fe48b" 17:17:20 INFO - }, 17:17:20 INFO - "sourcestamp": { 17:17:20 INFO - "repository": "", 17:17:20 INFO - "hasPatch": false, 17:17:20 INFO - "project": "", 17:17:20 INFO - "branch": "fx-team-linux64-talos", 17:17:20 INFO - "changes": [ 17:17:20 INFO - { 17:17:20 INFO - "category": null, 17:17:20 INFO - "files": [ 17:17:20 INFO - { 17:17:20 INFO - "url": null, 17:17:20 INFO - "name": "https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 17:17:20 INFO - } 17:17:20 INFO - ], 17:17:20 INFO - "repository": "", 17:17:20 INFO - "rev": "a6616b301f1c3c270ac60794ba7e0e7f722fe48b", 17:17:20 INFO - "who": "dmosedale@mozilla.com", 17:17:20 INFO - "when": 1450228448, 17:17:20 INFO - "number": 7364246, 17:17:20 INFO - "comments": "Bug 1219158 - update Hello FTU panel height, r=mikedeboer", 17:17:20 INFO - "project": "", 17:17:20 INFO - "at": "Tue 15 Dec 2015 17:14:08", 17:17:20 INFO - "branch": "fx-team-linux64-talos", 17:17:20 INFO - "revlink": "", 17:17:20 INFO - "properties": [ 17:17:20 INFO - [ 17:17:20 INFO - "buildid", 17:17:20 INFO - "20151215163208", 17:17:20 INFO - "Change" 17:17:20 INFO - ], 17:17:20 INFO - [ 17:17:20 INFO - "builduid", 17:17:20 INFO - "086d71ac045c4569932b78764b26e44b", 17:17:20 INFO - "Change" 17:17:20 INFO - ], 17:17:20 INFO - [ 17:17:20 INFO - "pgo_build", 17:17:20 INFO - "False", 17:17:20 INFO - "Change" 17:17:20 INFO - ] 17:17:20 INFO - ], 17:17:20 INFO - "revision": "a6616b301f1c3c270ac60794ba7e0e7f722fe48b" 17:17:20 INFO - } 17:17:20 INFO - ], 17:17:20 INFO - "revision": "a6616b301f1c3c270ac60794ba7e0e7f722fe48b" 17:17:20 INFO - } 17:17:20 INFO - } 17:17:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 17:17:20 INFO - Running post-action listener: _resource_record_post_action 17:17:20 INFO - ##### 17:17:20 INFO - ##### Running download-and-extract step. 17:17:20 INFO - ##### 17:17:20 INFO - Running pre-action listener: _resource_record_pre_action 17:17:20 INFO - Running main action method: download_and_extract 17:17:20 INFO - mkdir: /builds/slave/test/build/tests 17:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:20 INFO - https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:17:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/test_packages.json 17:17:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/test_packages.json 17:17:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 17:17:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 17:17:21 INFO - Downloaded 1302 bytes. 17:17:21 INFO - Reading from file /builds/slave/test/build/test_packages.json 17:17:21 INFO - Using the following test package requirements: 17:17:21 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:17:21 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:17:21 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'jsshell-linux-x86_64.zip'], 17:17:21 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:17:21 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:17:21 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:17:21 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:17:21 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:17:21 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:17:21 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:21 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:17:21 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:17:21 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')]} 17:17:21 INFO - https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:17:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:17:22 INFO - Downloaded 21868751 bytes. 17:17:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 17:17:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:22 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:17:23 INFO - Return code: 0 17:17:23 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:23 INFO - https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:17:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:17:24 INFO - Downloaded 21868751 bytes. 17:17:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 17:17:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:17:25 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:17:26 INFO - Return code: 0 17:17:26 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')]} 17:17:26 INFO - https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:17:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:17:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:17:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:17:27 INFO - Downloaded 11132052 bytes. 17:17:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 17:17:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:17:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 17:17:27 INFO - Return code: 0 17:17: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')]} 17:17:27 INFO - https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:17:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:17:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:17:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:17:29 INFO - Downloaded 57744074 bytes. 17:17:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:17:29 INFO - mkdir: /builds/slave/test/properties 17:17:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:29 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:29 INFO - Contents: 17:17:29 INFO - build_url:https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:17:29 INFO - Running post-action listener: _resource_record_post_action 17:17:29 INFO - Running post-action listener: set_extra_try_arguments 17:17:29 INFO - ##### 17:17:29 INFO - ##### Running populate-webroot step. 17:17:29 INFO - ##### 17:17:29 INFO - Running pre-action listener: _resource_record_pre_action 17:17:29 INFO - Running main action method: populate_webroot 17:17:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:29 INFO - '--tppagecycles', 17:17:29 INFO - '7'], 17:17:29 INFO - 'tests': ['tsvgm']}}, 17:17:29 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:17:29 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:29 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:17:29 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:17:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'tests': ['damp', 'tps']}, 17:17:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['damp', 'tps']}, 17:17:29 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:17:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:29 INFO - 'other': {'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore']}, 17:17:29 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore']}, 17:17:29 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore']}, 17:17:29 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore']}, 17:17:29 INFO - 'other_l64': {'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore']}, 17:17:29 INFO - 'other_nol64': {'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore']}, 17:17:29 INFO - 'svgr': {'tests': ['tsvgx', 17:17:29 INFO - 'tsvgr_opacity', 17:17:29 INFO - 'tart', 17:17:29 INFO - 'tscrollx', 17:17:29 INFO - 'cart']}, 17:17:29 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['tsvgx', 17:17:29 INFO - 'tsvgr_opacity', 17:17:29 INFO - 'tart', 17:17:29 INFO - 'tscrollx', 17:17:29 INFO - 'cart']}, 17:17:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'tests': ['tp5o']}, 17:17:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'talos_options': ['--e10s'], 17:17:29 INFO - 'tests': ['tp5o']}, 17:17:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'talos_options': ['--xperf_path', 17:17:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:29 INFO - 'tests': ['tp5n']}, 17:17:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:29 INFO - 'talos_options': ['--e10s', 17:17:29 INFO - '--xperf_path', 17:17:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:29 INFO - 'tests': ['tp5n']}}, 17:17:29 INFO - 'talos.zip': {'path': '', 17:17:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:29 INFO - Running post-action listener: _resource_record_post_action 17:17:29 INFO - ##### 17:17:29 INFO - ##### Running create-virtualenv step. 17:17:29 INFO - ##### 17:17:29 INFO - Running pre-action listener: _resource_record_pre_action 17:17:29 INFO - Running main action method: create_virtualenv 17:17:29 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:29 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 17:17:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:29 INFO - Using real prefix '/usr' 17:17:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:30 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:32 INFO - Installing pip.................done. 17:17:32 INFO - Return code: 0 17:17:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:32 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')]} 17:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:32 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')]} 17:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:32 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:32 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 17:17:32 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 17:17:32 INFO - Using env: {'DISPLAY': ':0', 17:17:32 INFO - 'HOME': '/home/cltbld', 17:17:32 INFO - 'LANG': 'en_US.UTF-8', 17:17:32 INFO - 'LANGUAGE': 'en_US:en', 17:17:32 INFO - 'LOGNAME': 'cltbld', 17:17:32 INFO - 'MAIL': '/var/mail/cltbld', 17:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:32 INFO - 'MOZ_NO_REMOTE': '1', 17:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:32 INFO - 'NO_EM_RESTART': '1', 17:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:32 INFO - 'PWD': '/builds/slave/test', 17:17:32 INFO - 'SHELL': '/bin/bash', 17:17:32 INFO - 'SHLVL': '1', 17:17:32 INFO - 'TERM': 'linux', 17:17:32 INFO - 'TMOUT': '86400', 17:17:32 INFO - 'USER': 'cltbld', 17:17:32 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:32 INFO - '_': '/tools/buildbot/bin/python'} 17:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:32 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 17:17:32 INFO - Cleaning up... 17:17:32 INFO - Return code: 0 17:17:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:17:32 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')]} 17:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:32 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')]} 17:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:32 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:32 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 17:17:32 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 17:17:32 INFO - Using env: {'DISPLAY': ':0', 17:17:32 INFO - 'HOME': '/home/cltbld', 17:17:32 INFO - 'LANG': 'en_US.UTF-8', 17:17:32 INFO - 'LANGUAGE': 'en_US:en', 17:17:32 INFO - 'LOGNAME': 'cltbld', 17:17:32 INFO - 'MAIL': '/var/mail/cltbld', 17:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:32 INFO - 'MOZ_NO_REMOTE': '1', 17:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:32 INFO - 'NO_EM_RESTART': '1', 17:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:32 INFO - 'PWD': '/builds/slave/test', 17:17:32 INFO - 'SHELL': '/bin/bash', 17:17:32 INFO - 'SHLVL': '1', 17:17:32 INFO - 'TERM': 'linux', 17:17:32 INFO - 'TMOUT': '86400', 17:17:32 INFO - 'USER': 'cltbld', 17:17:32 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:32 INFO - '_': '/tools/buildbot/bin/python'} 17:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:32 INFO - Downloading/unpacking psutil>=0.7.1 17:17:32 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 17:17:32 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 17:17:32 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 17:17:32 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 17:17:32 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 17:17:32 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 17:17:34 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 17:17:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:34 INFO - Installing collected packages: psutil 17:17:34 INFO - Running setup.py install for psutil 17:17:34 INFO - building 'psutil._psutil_linux' extension 17:17:34 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 17:17:34 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 17:17:34 INFO - building 'psutil._psutil_posix' extension 17:17:34 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 17:17:34 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 17:17:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:34 INFO - Successfully installed psutil 17:17:34 INFO - Cleaning up... 17:17:34 INFO - Return code: 0 17:17:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:34 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:34 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 17:17:34 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 17:17:34 INFO - Using env: {'DISPLAY': ':0', 17:17:34 INFO - 'HOME': '/home/cltbld', 17:17:34 INFO - 'LANG': 'en_US.UTF-8', 17:17:34 INFO - 'LANGUAGE': 'en_US:en', 17:17:34 INFO - 'LOGNAME': 'cltbld', 17:17:34 INFO - 'MAIL': '/var/mail/cltbld', 17:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:34 INFO - 'MOZ_NO_REMOTE': '1', 17:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:34 INFO - 'NO_EM_RESTART': '1', 17:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:34 INFO - 'PWD': '/builds/slave/test', 17:17:34 INFO - 'SHELL': '/bin/bash', 17:17:34 INFO - 'SHLVL': '1', 17:17:34 INFO - 'TERM': 'linux', 17:17:34 INFO - 'TMOUT': '86400', 17:17:34 INFO - 'USER': 'cltbld', 17:17:34 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:34 INFO - '_': '/tools/buildbot/bin/python'} 17:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:17:35 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 17:17:35 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 17:17:35 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 17:17:35 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 17:17:35 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 17:17:35 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 17:17:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:17:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:17:36 INFO - Installing collected packages: mozsystemmonitor 17:17:36 INFO - Running setup.py install for mozsystemmonitor 17:17:36 INFO - Successfully installed mozsystemmonitor 17:17:36 INFO - Cleaning up... 17:17:36 INFO - Return code: 0 17:17:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:36 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:36 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 17:17:36 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 17:17:36 INFO - Using env: {'DISPLAY': ':0', 17:17:36 INFO - 'HOME': '/home/cltbld', 17:17:36 INFO - 'LANG': 'en_US.UTF-8', 17:17:36 INFO - 'LANGUAGE': 'en_US:en', 17:17:36 INFO - 'LOGNAME': 'cltbld', 17:17:36 INFO - 'MAIL': '/var/mail/cltbld', 17:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:36 INFO - 'MOZ_NO_REMOTE': '1', 17:17:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:36 INFO - 'NO_EM_RESTART': '1', 17:17:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:36 INFO - 'PWD': '/builds/slave/test', 17:17:36 INFO - 'SHELL': '/bin/bash', 17:17:36 INFO - 'SHLVL': '1', 17:17:36 INFO - 'TERM': 'linux', 17:17:36 INFO - 'TMOUT': '86400', 17:17:36 INFO - 'USER': 'cltbld', 17:17:36 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:36 INFO - '_': '/tools/buildbot/bin/python'} 17:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:37 INFO - Downloading/unpacking blobuploader==1.2.4 17:17:37 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 17:17:37 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 17:17:37 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 17:17:37 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 17:17:37 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 17:17:37 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 17:17:38 INFO - Downloading blobuploader-1.2.4.tar.gz 17:17:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:17:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:17:38 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 17:17:38 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 17:17:38 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 17:17:38 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 17:17:38 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 17:17:38 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 17:17:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:17:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:17:39 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 17:17:39 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 17:17:39 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 17:17:39 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 17:17:39 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 17:17:39 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 17:17:39 INFO - Downloading docopt-0.6.1.tar.gz 17:17:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:17:39 INFO - Installing collected packages: blobuploader, requests, docopt 17:17:39 INFO - Running setup.py install for blobuploader 17:17:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:17:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:17:39 INFO - Running setup.py install for requests 17:17:39 INFO - Running setup.py install for docopt 17:17:40 INFO - Successfully installed blobuploader requests docopt 17:17:40 INFO - Cleaning up... 17:17:40 INFO - Return code: 0 17:17:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:40 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:40 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 17:17:40 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 17:17:40 INFO - Using env: {'DISPLAY': ':0', 17:17:40 INFO - 'HOME': '/home/cltbld', 17:17:40 INFO - 'LANG': 'en_US.UTF-8', 17:17:40 INFO - 'LANGUAGE': 'en_US:en', 17:17:40 INFO - 'LOGNAME': 'cltbld', 17:17:40 INFO - 'MAIL': '/var/mail/cltbld', 17:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:40 INFO - 'MOZ_NO_REMOTE': '1', 17:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:40 INFO - 'NO_EM_RESTART': '1', 17:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:40 INFO - 'PWD': '/builds/slave/test', 17:17:40 INFO - 'SHELL': '/bin/bash', 17:17:40 INFO - 'SHLVL': '1', 17:17:40 INFO - 'TERM': 'linux', 17:17:40 INFO - 'TMOUT': '86400', 17:17:40 INFO - 'USER': 'cltbld', 17:17:40 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:40 INFO - '_': '/tools/buildbot/bin/python'} 17:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:40 INFO - Running setup.py (path:/tmp/pip-Ex6JXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:40 INFO - Running setup.py (path:/tmp/pip-n5W1EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:40 INFO - Running setup.py (path:/tmp/pip-WtY0pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:40 INFO - Running setup.py (path:/tmp/pip-d7g4ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:40 INFO - Running setup.py (path:/tmp/pip-Pxpze5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:40 INFO - Running setup.py (path:/tmp/pip-nRswAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:40 INFO - Running setup.py (path:/tmp/pip-urlnlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:40 INFO - Running setup.py (path:/tmp/pip-0vKOZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:40 INFO - Running setup.py (path:/tmp/pip-iRfHSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:40 INFO - Running setup.py (path:/tmp/pip-GXpkCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:41 INFO - Running setup.py (path:/tmp/pip-PaDSCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:41 INFO - Running setup.py (path:/tmp/pip-BGL2oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:41 INFO - Running setup.py (path:/tmp/pip-VvjfSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:41 INFO - Running setup.py (path:/tmp/pip-AsOJZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:41 INFO - Running setup.py (path:/tmp/pip-eQ8IFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:41 INFO - Running setup.py (path:/tmp/pip-h8RdkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:41 INFO - Running setup.py (path:/tmp/pip-gNBIm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:17:41 INFO - Running setup.py install for manifestparser 17:17:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:17:41 INFO - Running setup.py install for mozcrash 17:17:41 INFO - Running setup.py install for mozdebug 17:17:41 INFO - Running setup.py install for mozdevice 17:17:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:17:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:17:41 INFO - Running setup.py install for mozfile 17:17:42 INFO - Running setup.py install for mozhttpd 17:17:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for mozinfo 17:17:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for mozInstall 17:17:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for mozleak 17:17:42 INFO - Running setup.py install for mozlog 17:17:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for moznetwork 17:17:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for mozprocess 17:17:42 INFO - Running setup.py install for mozprofile 17:17:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for mozrunner 17:17:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:17:42 INFO - Running setup.py install for mozscreenshot 17:17:43 INFO - Running setup.py install for moztest 17:17:43 INFO - Running setup.py install for mozversion 17:17:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:17:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:17:43 INFO - Cleaning up... 17:17:43 INFO - Return code: 0 17:17:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:43 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:43 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 17:17:43 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 17:17:43 INFO - Using env: {'DISPLAY': ':0', 17:17:43 INFO - 'HOME': '/home/cltbld', 17:17:43 INFO - 'LANG': 'en_US.UTF-8', 17:17:43 INFO - 'LANGUAGE': 'en_US:en', 17:17:43 INFO - 'LOGNAME': 'cltbld', 17:17:43 INFO - 'MAIL': '/var/mail/cltbld', 17:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:43 INFO - 'MOZ_NO_REMOTE': '1', 17:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:43 INFO - 'NO_EM_RESTART': '1', 17:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:43 INFO - 'PWD': '/builds/slave/test', 17:17:43 INFO - 'SHELL': '/bin/bash', 17:17:43 INFO - 'SHLVL': '1', 17:17:43 INFO - 'TERM': 'linux', 17:17:43 INFO - 'TMOUT': '86400', 17:17:43 INFO - 'USER': 'cltbld', 17:17:43 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:43 INFO - '_': '/tools/buildbot/bin/python'} 17:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:43 INFO - Running setup.py (path:/tmp/pip-tqQA1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:43 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)) 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:43 INFO - Running setup.py (path:/tmp/pip-6vWmOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:43 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)) 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:43 INFO - Running setup.py (path:/tmp/pip-lylSEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:43 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)) 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:43 INFO - Running setup.py (path:/tmp/pip-yKezvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:43 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)) 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:43 INFO - Running setup.py (path:/tmp/pip-7yyzU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:43 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)) 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:43 INFO - Running setup.py (path:/tmp/pip-y8pA7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:43 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)) 17:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:43 INFO - Running setup.py (path:/tmp/pip-v9VGf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:44 INFO - Running setup.py (path:/tmp/pip-xgREdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:44 INFO - Running setup.py (path:/tmp/pip-e9jW56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:44 INFO - Running setup.py (path:/tmp/pip-DAZsr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:44 INFO - Running setup.py (path:/tmp/pip-kEHWSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:44 INFO - Running setup.py (path:/tmp/pip-a5Pz4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:44 INFO - Running setup.py (path:/tmp/pip-mxjvWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:44 INFO - Running setup.py (path:/tmp/pip-L0sfO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:44 INFO - Running setup.py (path:/tmp/pip-dXtGTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:44 INFO - Running setup.py (path:/tmp/pip-mhtCyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:44 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)) 17:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:44 INFO - Running setup.py (path:/tmp/pip-9Yzq9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:44 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)) 17:17:44 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)) 17:17:44 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)) 17:17:44 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)) 17:17:44 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)) 17:17:44 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)) 17:17:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:44 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 17:17:44 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 17:17:44 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 17:17:44 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 17:17:44 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 17:17:44 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 17:17:46 INFO - Downloading blessings-1.5.1.tar.gz 17:17:46 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 17:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:46 INFO - Installing collected packages: blessings 17:17:46 INFO - Running setup.py install for blessings 17:17:46 INFO - Successfully installed blessings 17:17:46 INFO - Cleaning up... 17:17:46 INFO - Return code: 0 17:17:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:46 INFO - Reading from file tmpfile_stdout 17:17:46 INFO - Current package versions: 17:17:46 INFO - argparse == 1.2.1 17:17:46 INFO - blessings == 1.5.1 17:17:46 INFO - blobuploader == 1.2.4 17:17:46 INFO - docopt == 0.6.1 17:17:46 INFO - manifestparser == 1.1 17:17:46 INFO - mozInstall == 1.12 17:17:46 INFO - mozcrash == 0.16 17:17:46 INFO - mozdebug == 0.1 17:17:46 INFO - mozdevice == 0.47 17:17:46 INFO - mozfile == 1.2 17:17:46 INFO - mozhttpd == 0.7 17:17:46 INFO - mozinfo == 0.9 17:17:46 INFO - mozleak == 0.1 17:17:46 INFO - mozlog == 3.1 17:17:46 INFO - moznetwork == 0.27 17:17:46 INFO - mozprocess == 0.22 17:17:46 INFO - mozprofile == 0.27 17:17:46 INFO - mozrunner == 6.11 17:17:46 INFO - mozscreenshot == 0.1 17:17:46 INFO - mozsystemmonitor == 0.0 17:17:46 INFO - moztest == 0.7 17:17:46 INFO - mozversion == 1.4 17:17:46 INFO - psutil == 3.1.1 17:17:46 INFO - requests == 1.2.3 17:17:46 INFO - wsgiref == 0.1.2 17:17:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:46 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 17:17:46 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 17:17:46 INFO - Using env: {'DISPLAY': ':0', 17:17:46 INFO - 'HOME': '/home/cltbld', 17:17:46 INFO - 'LANG': 'en_US.UTF-8', 17:17:46 INFO - 'LANGUAGE': 'en_US:en', 17:17:46 INFO - 'LOGNAME': 'cltbld', 17:17:46 INFO - 'MAIL': '/var/mail/cltbld', 17:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:46 INFO - 'MOZ_NO_REMOTE': '1', 17:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:46 INFO - 'NO_EM_RESTART': '1', 17:17:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:46 INFO - 'PWD': '/builds/slave/test', 17:17:46 INFO - 'SHELL': '/bin/bash', 17:17:46 INFO - 'SHLVL': '1', 17:17:46 INFO - 'TERM': 'linux', 17:17:46 INFO - 'TMOUT': '86400', 17:17:46 INFO - 'USER': 'cltbld', 17:17:46 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:46 INFO - '_': '/tools/buildbot/bin/python'} 17:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 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)) 17:17:47 INFO - Cleaning up... 17:17:47 INFO - Return code: 0 17:17:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:47 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:47 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 17:17:47 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 17:17:47 INFO - Using env: {'DISPLAY': ':0', 17:17:47 INFO - 'HOME': '/home/cltbld', 17:17:47 INFO - 'LANG': 'en_US.UTF-8', 17:17:47 INFO - 'LANGUAGE': 'en_US:en', 17:17:47 INFO - 'LOGNAME': 'cltbld', 17:17:47 INFO - 'MAIL': '/var/mail/cltbld', 17:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:47 INFO - 'MOZ_NO_REMOTE': '1', 17:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:47 INFO - 'NO_EM_RESTART': '1', 17:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:47 INFO - 'PWD': '/builds/slave/test', 17:17:47 INFO - 'SHELL': '/bin/bash', 17:17:47 INFO - 'SHLVL': '1', 17:17:47 INFO - 'TERM': 'linux', 17:17:47 INFO - 'TMOUT': '86400', 17:17:47 INFO - 'USER': 'cltbld', 17:17:47 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:47 INFO - '_': '/tools/buildbot/bin/python'} 17:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:47 INFO - Downloading/unpacking jsonschema 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 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 17:17:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:49 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 17:17:49 INFO - Downloading/unpacking functools32 (from jsonschema) 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:49 INFO - warning: no files found matching '*.txt' 17:17:49 INFO - no previously-included directories found matching 'build' 17:17:49 INFO - no previously-included directories found matching 'dist' 17:17:49 INFO - no previously-included directories found matching '.git*' 17:17:49 INFO - Installing collected packages: jsonschema, functools32 17:17:49 INFO - Running setup.py install for functools32 17:17:49 INFO - warning: no files found matching '*.txt' 17:17:49 INFO - no previously-included directories found matching 'build' 17:17:49 INFO - no previously-included directories found matching 'dist' 17:17:49 INFO - no previously-included directories found matching '.git*' 17:17:49 INFO - Successfully installed jsonschema functools32 17:17:49 INFO - Cleaning up... 17:17:49 INFO - Return code: 0 17:17:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:49 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 0x7ffe9b191f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, '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': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', '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 17:17:49 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 17:17:49 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 17:17:49 INFO - Using env: {'DISPLAY': ':0', 17:17:49 INFO - 'HOME': '/home/cltbld', 17:17:49 INFO - 'LANG': 'en_US.UTF-8', 17:17:49 INFO - 'LANGUAGE': 'en_US:en', 17:17:49 INFO - 'LOGNAME': 'cltbld', 17:17:49 INFO - 'MAIL': '/var/mail/cltbld', 17:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:49 INFO - 'MOZ_NO_REMOTE': '1', 17:17:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:49 INFO - 'NO_EM_RESTART': '1', 17:17:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:49 INFO - 'PWD': '/builds/slave/test', 17:17:49 INFO - 'SHELL': '/bin/bash', 17:17:49 INFO - 'SHLVL': '1', 17:17:49 INFO - 'TERM': 'linux', 17:17:49 INFO - 'TMOUT': '86400', 17:17:49 INFO - 'USER': 'cltbld', 17:17:49 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:49 INFO - '_': '/tools/buildbot/bin/python'} 17:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:49 INFO - Downloading/unpacking flake8 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 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 17:17:49 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 17:17:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:17:51 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 17:17:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:17:51 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 17:17:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:51 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 17:17:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:17:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:17:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:17:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:17:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:17:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:17:52 INFO - no previously-included directories found matching 'docs/_build' 17:17:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:17:52 INFO - Running setup.py install for pep8 17:17:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:17:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:17:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:17:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:17:52 INFO - no previously-included directories found matching 'docs/_build' 17:17:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:17:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:17:52 INFO - Cleaning up... 17:17:52 INFO - Return code: 0 17:17:52 INFO - Running post-action listener: _resource_record_post_action 17:17:52 INFO - Running post-action listener: _start_resource_monitoring 17:17:52 INFO - Starting resource monitoring. 17:17:52 INFO - ##### 17:17:52 INFO - ##### Running install step. 17:17:52 INFO - ##### 17:17:52 INFO - Running pre-action listener: _resource_record_pre_action 17:17:52 INFO - Running main action method: install 17:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:52 INFO - Reading from file tmpfile_stdout 17:17:52 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:52 INFO - Reading from file tmpfile_stdout 17:17:52 INFO - Output received: 17:17:52 INFO - Usage: mozinstall [options] installer 17:17:52 INFO - Options: 17:17:52 INFO - -h, --help show this help message and exit 17:17:52 INFO - -d DEST, --destination=DEST 17:17:52 INFO - Directory to install application into. [default: 17:17:52 INFO - "/builds/slave/test"] 17:17:52 INFO - --app=APP Application being installed. [default: firefox] 17:17:52 INFO - mkdir: /builds/slave/test/build/application 17:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:18:07 INFO - Reading from file tmpfile_stdout 17:18:07 INFO - Output received: 17:18:07 INFO - /builds/slave/test/build/application/firefox/firefox 17:18:07 INFO - Running post-action listener: _resource_record_post_action 17:18:07 INFO - ##### 17:18:07 INFO - ##### Running run-tests step. 17:18:07 INFO - ##### 17:18:07 INFO - Running pre-action listener: _resource_record_pre_action 17:18:07 INFO - Running main action method: run_tests 17:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:18:07 INFO - Python 2.7.3 17:18:07 INFO - Return code: 0 17:18:07 INFO - grabbing minidump binary from tooltool 17:18: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')]} 17:18:07 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 0x1e330b0>, '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 0x1e33fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e34440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:18:07 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 17:18:07 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 17:18:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:18:07 INFO - Return code: 0 17:18:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:18:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:18:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:18:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:18:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:18:08 INFO - Return code: 0 17:18:08 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/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost'] in /builds/slave/test/build 17:18:08 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/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost 17:18:08 INFO - Using env: {'DISPLAY': ':0', 17:18:08 INFO - 'HOME': '/home/cltbld', 17:18:08 INFO - 'LANG': 'en_US.UTF-8', 17:18:08 INFO - 'LANGUAGE': 'en_US:en', 17:18:08 INFO - 'LOGNAME': 'cltbld', 17:18:08 INFO - 'MAIL': '/var/mail/cltbld', 17:18:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:18:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:08 INFO - 'MOZ_NO_REMOTE': '1', 17:18:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:08 INFO - 'NO_EM_RESTART': '1', 17:18:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:08 INFO - 'PWD': '/builds/slave/test', 17:18:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:18:08 INFO - 'SHELL': '/bin/bash', 17:18:08 INFO - 'SHLVL': '1', 17:18:08 INFO - 'TERM': 'linux', 17:18:08 INFO - 'TMOUT': '86400', 17:18:08 INFO - 'USER': 'cltbld', 17:18:08 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235', 17:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:08 INFO - '_': '/tools/buildbot/bin/python'} 17:18:08 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/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost'] with output_timeout 3600 17:18:08 INFO - mozversion INFO | application_buildid: 20151215163208 17:18:08 INFO - mozversion INFO | application_changeset: a6616b301f1c3c270ac60794ba7e0e7f722fe48b 17:18:08 INFO - mozversion INFO | application_display_name: Nightly 17:18:08 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:18:08 INFO - mozversion INFO | application_name: Firefox 17:18:08 INFO - mozversion INFO | application_remotingname: firefox 17:18:08 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 17:18:08 INFO - mozversion INFO | application_vendor: Mozilla 17:18:08 INFO - mozversion INFO | application_version: 46.0a1 17:18:08 INFO - mozversion INFO | platform_buildid: 20151215163208 17:18:08 INFO - mozversion INFO | platform_changeset: a6616b301f1c3c270ac60794ba7e0e7f722fe48b 17:18:08 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 17:18:08 INFO - mozversion INFO | platform_version: 46.0a1 17:18:08 INFO - 2015-12-15 17:18:08,344 DEBUG : using testdate: 1450228688 17:18:08 INFO - 2015-12-15 17:18:08,344 DEBUG : actual date: 1450228688 17:18:08 INFO - 2015-12-15 17:18:08,354 INFO : starting webserver on 'localhost:60727' 17:18:08 INFO - 2015-12-15 17:18:08,355 INFO : Starting test suite talos-linux64-ix-007.e 17:18:08 INFO - 2015-12-15 17:18:08,355 INFO : Starting test dromaeo_css 17:18:08 INFO - 2015-12-15 17:18:08,356 DEBUG : operating with platform_type : linux_ 17:18:08 INFO - 2015-12-15 17:18:08,356 INFO : Initialising browser for dromaeo_css test... 17:18:08 INFO - 2015-12-15 17:18:08,370 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB9WLlt/profile http://localhost:60727/getInfo.html 17:18:13 INFO - 2015-12-15 17:18:13,308 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:18:13 INFO - 2015-12-15 17:18:13,308 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:18:13 INFO - 2015-12-15 17:18:13,308 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:18:13 INFO - 2015-12-15 17:18:13,308 DEBUG : BROWSER_OUTPUT: __metrics 17:18:13 INFO - 2015-12-15 17:18:13,424 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] 17:18:14 INFO - 2015-12-15 17:18:14,325 INFO : Browser initialized. 17:18:14 INFO - 2015-12-15 17:18:14,325 INFO : Running cycle 1/1 for dromaeo_css test... 17:18:14 INFO - 2015-12-15 17:18:14,326 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB9WLlt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:18:26 INFO - 2015-12-15 17:18:26,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 143556608 17:18:26 INFO - 2015-12-15 17:18:26,418 DEBUG : BROWSER_OUTPUT: 17:19:16 INFO - 2015-12-15 17:19:16,046 DEBUG : BROWSER_OUTPUT: 1450228756046 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] 17:21:28 INFO - 2015-12-15 17:21:28,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60727/tests/dromaeo/cssquery-ext.html) 17:21:29 INFO - 2015-12-15 17:21:29,175 DEBUG : BROWSER_OUTPUT: RSS: Main: 146288640 17:21:29 INFO - 2015-12-15 17:21:29,175 DEBUG : BROWSER_OUTPUT: 17:24:31 INFO - 2015-12-15 17:24:31,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/dromaeo/cssquery-ext.html (next: http://localhost:60727/tests/dromaeo/cssquery-jquery.html) 17:24:31 INFO - 2015-12-15 17:24:31,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 144388096 17:24:31 INFO - 2015-12-15 17:24:31,830 DEBUG : BROWSER_OUTPUT: 17:27:33 INFO - 2015-12-15 17:27:33,664 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60727/tests/dromaeo/cssquery-mootools.html) 17:27:34 INFO - 2015-12-15 17:27:34,273 DEBUG : BROWSER_OUTPUT: RSS: Main: 146370560 17:27:34 INFO - 2015-12-15 17:27:34,274 DEBUG : BROWSER_OUTPUT: 17:30:36 INFO - 2015-12-15 17:30:36,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60727/tests/dromaeo/cssquery-prototype.html) 17:30:36 INFO - 2015-12-15 17:30:36,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 143818752 17:30:36 INFO - 2015-12-15 17:30:36,689 DEBUG : BROWSER_OUTPUT: 17:33:39 INFO - 2015-12-15 17:33:39,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60727/tests/dromaeo/cssquery-yui.html) 17:33:39 INFO - 2015-12-15 17:33:39,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 145260544 17:33:39 INFO - 2015-12-15 17:33:39,913 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,456 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/dromaeo/cssquery-yui.html (next: http://localhost:60727/tests/dromaeo/cssquery-dojo.html) 17:36:42 INFO - 2015-12-15 17:36:42,706 DEBUG : BROWSER_OUTPUT: RSS: Main: 147570688 17:36:42 INFO - 2015-12-15 17:36:42,706 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,707 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:36:42 INFO - 2015-12-15 17:36:42,708 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:36:42 INFO - 2015-12-15 17:36:42,708 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:36:42 INFO - 2015-12-15 17:36:42,708 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:36:42 INFO - 2015-12-15 17:36:42,709 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2653.9734602653975;2736.4116714906318;2763.336799168199;2765.6266404035464;2767.474179905818;4598.5401459854;4773.546513081256;4790.904181916364;4796.016816552603;4832.323474713543;4697.67116301859;4711.528847115295;4725.102230576188;4790.425148982127;4822.324874517571;4384.232759267132;4446.7554284514335;4462.12988467249;4507.932381014271;4524.68327217095;4346.195953748551;4389.122175564884;4478.26108692067;4492.258777301758;4566.269396896497;4226.260404429213;4335.7181783184;4343.435353404037;4424.557544245566;4493.595576398107;4394.472663280395;4474.284114541658;4481.77591120446;4492.8876778080485;4507.3689683444345;4434.423524941769;4463.754493502856;4479.350494178326;4479.686421950432;4507.256302710049;4454.5990860822685;4574.153781550391;4629.837955671569;4752.263399173134;4756.738379389132;5108.770105345231;5159.948400515985;5171.146760784466;5201.5838732901275;5240.947590524084;5190.662606930538;5205.687658740488;5209.7134657593815;5211.661242019257;5222.4255331913455;4650.255959046536;4709.270063140219;4749.168895443318;4800.135975524369;4853.684510506806;5276.868078297996;5333.946660533423;5341;5355.758990845421;5414.052540805344;4828.106800241966;5098.2352647103235;5118.846434606968;5125.513076257749;5130.230465430215;5054.772535235923;5066.316047333637;5105.361829771278;5145.253938178943;5189.42916279209;5118;5118.411382690963;5119.462456442095;5125.769340379692;5169.53474187325;4759.333693282943;4840.080384726891;5095.668781529189;5096.592272618182;5108.131617624938;4168.24971505132;4181.644560212416;4220.620144187038;4287.442632457761;4329.112531930947;3746.9063273417837;3768.2086761779783;3771.7925514096987;3808.028952617065;3808.9047773805323;4259.105587826529;4464.508904020555;4467.86596402102;4478.552144785495;4485.798139083749;4994.101061808909;5005.824796132118;5069.771860266297;5094.6688465249645;5121.436641969455;4353.6081752641785;4384.780760962003;4403.097365040159;4412.580804823537;4432.512423633398;4342.782860856944;4442.378067070613;4503.347014682852;4541.6593755468475;4577.084583083397;4818.10864989978;4837.346958160603;4868.707877527218;4875.000624871964;4922.163232250455;5186.507281808223;5193.766280517461;5214.2960700304975;5222.112240918977;5267.341582302212;4578.931316030195;4667.813287468599;4795.784189711401;4918;4920.532549407801;5115.87210319745;5134.332536770196;5134.486551344836;5143.92284115746;5145.125328694206;5176.4305926347315;5199.870003249949;5205.947940520698;5217.765200565907;5261.210818377274;4774.689645173053;4884.462709102067;4950.801967921243;5027.497250274943;5064.949350506447;4982.078315511642;4992.650514463952;4995.950040499694;5013.774380152975;5065.214891691794;4896.04527117223;4913.877153071059;4941.728204948618;4945.233488809096;4980.850574482772;4883.902321953612;4950.108980383566;5008.398992120969;5020.246962955586;5023.196288593807;4901.264810278487;4966.751662416792;4996.075725990703;4997.575206107522;5039.622028347815 17:36:42 INFO - 2015-12-15 17:36:42,710 DEBUG : BROWSER_OUTPUT: |1;ext.html;550.8595308196409;551.6741114038102;570.7060863655215;587.3480436715246;594.4412252482666;32199.517007244882;32590;32681.67318326816;32749.508757368658;32755.017349479524;38126.23747525048;38590.80704596473;38599.42100868489;38655.45378184869;38687;7820.3743700503965;7957.880631790527;7970.521768693868;7974.122846486882;8006.039275286974;7740.922590774105;7795.259450352236;7803.453758236926;7812.882806757903;7815.335696465779;7808.4924479908905;7886.6845326186885;7901.960490197541;7919.168487308836;7946.205379462065;7875.212478752136;7925.1678573749505;7936.880946785803;7953.960230198841;7962.442629015914;7765.495242809256;7880.527168369858;7912.564808935506;7919.683212671486;7928.643211055459;34940.65059349399;35012.64987350119;35162.47256291158;35177.12057198565;35236.64763352359;2859.37093839355;2972.9405411891858;3005.3989202159437;3211.678832116793;3251.122197006795;26596.20211393661;26614.798364459366;26871.596926046124;26954.460910781676;27077.86461067682;3327.218103745618;3428.6914177724125;3463.168839478507;3495.5281037059926;3517;194673;195747;196886;196913.01543492335;197348;49153.50846491489;49881.002379952195;50254;50515;51788;45601;45746.085078298915;45800.312995305096;45976;46078.30882536765;13925;14038.85961140396;14076.366563504667;14079.577612671636;14146.787798183035;10988.230823842254;11038.227324087236;11078.889211107948;11086.168537359568;11113.888861111285;17780.91109544444;17810.376636817648;17959.461216163538;17987.370442034466;18023.819761802213;7741;7801.336886364609;7881.921180788233;8035.232248905231;8056.355491560661;913.7715571107224;916.9266458683289;939.9624015039321;968.1867231525412;1048.444324508012;24844.50310993806;25187.62218566723;25643.871780640977;25972.35069123287;26116.477670446486;954.0173621170205;977.6724797192878;983.233078199006;988.1205726903062;1005.215931573374;12651.493940242288;12717.109802313935;12871.742565148832;12881.54914578004;12990.610281691565;253903;255685;257835;258150;258185;43121.78439107785;43280.78359608182;43416.13167736628;43490;43503;1221.3832014832453;1238.0652607281625;1246.5138595947587;1248.712796056894;1251.8998480121568;24539.263822085366;24968.876400561654;25007.12475063364;25265.368365791;25270.241892742513;38076;38658;38714;38724;38878.222435551696;238597;243251;244907;244942;245192;27136.864315679086;28440.288992775346;28668.856655716587;28671.56992645154;28805.42389152247;32459;32822.34355312928;33343.49984750279;33358.33283334368;34298.82850585731;30690.386192275582;31077.378452431276;31114.68885311118;31429.371412571163;31628.683713162573;31341.8432907834;31346.843265783526;31416.528752069193;31609.683903160672;31771.84114079415;31063;31197.376052479278;31278.37443251168;31360.215994600316;32907.341853162325;27650.86174569114;27927.02255421132;28253.71746282511;28755.5686664696;28897.85551072231;22565.21021764296;23073.65389519192;23159.305220843402;23175.76824231736;23209.419764506023 17:36:42 INFO - 2015-12-15 17:36:42,711 DEBUG : BROWSER_OUTPUT: |2;jquery.html;333.09066249139846;333.1288680101535;337.1974700213493;340.90284268983345;341.2015882834168;15409.306581203844;15449.69100617987;15788.842111578895;16116.83883161168;16620.501384958472;10034.896161422237;10239.744006399844;14364.784528232085;15959.281832317545;15962.441314553993;4591.6097131743845;4607.331936869152;4714.387129673138;4727.881802954919;4732.05358928214;4662.533746625344;4680.157571637104;4698.97650511747;4703.035877645075;4706.952930470686;4638.907221855561;4665.440147182343;4813.253945638432;4976.62675299354;4991.201407774757;4933.038057578774;4935.950640493585;4947.50524947506;4965;4974.701517908937;5029.547340739351;5053.292539044537;5058.54473097423;5069.898602027975;5088.847334579968;11136.276142050741;11277.887221127765;13514.729705405935;18861.151248193863;18996.145173467226;1785.7946336171376;1846.1230915315252;1882.3694062489083;1892.640398324314;1945.8540609454349;9099.408538444979;9156.175944165057;9551.713448596483;10349.327293725884;12769.233845969271;1728.930842766288;1730.9307627694877;1733.3759846455266;1749.6238308763677;1806.6838303297002;53641;54103;54471.72764136193;54825;55231.44768552303;9534.427934323963;9681.08029737188;9804.754881128029;9805.80388392228;9884.011598840058;12364.19632723888;12366.19619724715;12391.690207744878;12393.504259829686;12422.689432764253;7050.753223637148;7289.307515785992;7329.157146928065;7379.557226566423;7427.442941779388;7127.572345659277;7134.143902731706;7154.105736782903;7173.390261827803;7188.460865435113;4119.423280740699;4134.276501612206;4160.958390416057;4162.313218318944;4195.958040419618;1671.4651311580178;1736.5919009032866;1741.9912900435418;1745.2582652372691;1749.4052022312221;761.9847603047908;830.227464363576;894.7181637784078;940.036462625814;1002.6590959073804;15184.392624294904;15398.923005385126;15464;15922.681546369007;16226.756598651029;818.4925346285341;853.249140756143;864.7708357285325;874.8294082653948;883.2492381475694;8952.377168060684;9130.81738365229;9181;9198.126178013212;9310.627574896927;311225;316182;317485;318623;318716;64573.677131614044;67764.6611766938;69183.65408173027;69784;69869;1056.915446764257;1058.8252938265255;1059.06802014227;1088.2382332367374;1093.3330668292335;14286.785698214328;14448.349824257723;14493.78259326132;14570.562883113524;14835.332410041357;31792.364152716353;32346.514802277514;32972.83513582417;33526.66473335236;34265.31469370649;40037.599624003386;40662.1867562653;40940;41982.370264445446;42829;8066.556339401389;8419.36854735885;8531.914680853111;8540;8542;9216.815663686823;9432.95283523578;9442.197413219954;9629.903700962901;9709.009653021256;8013.398995075509;8258.25675689191;9073.183413492818;9125.269978401844;9462.337636365388;8289.253967142746;8960.641574336954;9150.359474836698;9366.066252173967;9819.018098190123;9476.763080923032;9542.761430964281;9784.9021509784;9789.363691360039;9796.65711700087;14320.283985800459;14322;14432.494862679756;14450.638734031734;14598.029266048947;14716.852831471548;14718.779218311947;14850.777238341218;15003.624909377353;15177.241137942838 17:36:42 INFO - 2015-12-15 17:36:42,712 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1469.559132260323;1642.219945525875;1713.9485815425535;1726.8014178369492;1750.68487672219;6009.57932944694;6103.359147289537;6970.268121847209;7546.471746977713;7550.282723141299;6580.243272023718;6690;6735.562188457747;6737.056812046318;6759.560628559152;2064.5767617638385;2106.304919376602;2108.2621082621035;2112.239593746258;2124.1928067334393;2071.564971356017;2077.6883467479847;2085.134669112324;2112.3240563019763;2117.1531387444993;2036.2669439001947;2042.4383294594031;2094.3716884934533;2095.5389814240843;2098.0139334512755;2000.7098970649247;2011.175418078588;2015.3248661698349;2018.515556266493;2024.5141166120172;2021.7169596256535;2040.2247146084394;2065.514604068043;2068.524239424941;2072.575122099966;7282.635868206621;7308.744193953239;7327;7343.375813055896;7374.373178279852;4230.640395566381;4283.250431174532;4285.5928686774705;4299.828006879721;4305.74165550068;2756.4073724149202;2770.2104900103373;2813.620161278242;2833.5607980763016;2842.701516340775;813.1218284252992;826.0417915218318;826.7602395305354;831.3473922970439;854.8247609240091;9939.50302484873;9947.452890091043;9971.20230381575;9990.400575965392;9992.150667193297;3842.3852183650483;3858.5948475409677;3920.725549211585;3969.960300397017;3996.8201430935096;8524.104968978292;8553.657853685783;8555.828883422297;8580.2706769924;8611.224989750952;4368.082702632482;4372.453443319585;4391.297392417262;4397.450318710161;4399.12017596482;3244.9351012980082;3346.9330613388083;3763.7741735495956;3931.2334094851803;3963.801809909493;3400.608929973035;3404.897853064412;3411.880584179542;3459.41189997701;3470.9132271692906;1057.6721216422934;1058.3491152940885;1060.4538662588773;1066.344198318029;1078.2075174746556;1198.6164427383328;1201.2192075151222;1216.1122380661989;1217.1236709569096;1223.5411720604773;6877.828054298682;6918.584884906922;6925.580256047499;6941.479389045841;6962.930370696228;401.0635166885297;415.5947950748057;417.4239549421839;420.690792267683;421.0064248373835;5490.478404551562;5510.669359838423;5528.474794894479;5551.028570000234;5561.193626924154;8157.75526734199;8365.163483651708;8418.158184181655;8433.620487077973;8728.214460698568;8395.412321137585;8462.449940753831;8467;8512.70205542803;8529.74410767678;6647.767328143492;6772.2211945625895;6865.347791959756;6873.793786186422;6908.9309106909295;6939.167299924042;7015.473839462163;7018.368346848604;7024.26245244242;7026.086608740831;7785.610719464117;7932.167122452291;7937.2459616336355;7998.160193179632;8013.599320034092;7560.886586701094;7749.3413059890545;7752.302292793675;7789.221077892165;8125.349972002107;5533;5616.719164041863;6245.406686364788;6446.709898054505;6528.955367141235;6699.464042876461;6706.396424321834;6737.797866064026;6746.966265168643;6771.085903403175;6416;6471.546991710535;6482.2869484357625;6605.801825945229;6661.866762664817;6232.626042437469;6253.5622506424115;6371.840703982438;6438.742450301936;6493.350664933658;6495.350464953467;6505.0567667688865;6526.216853977553;6553.5084868635995;6554.049662798963;6028.6985650716415;6031.728572214349;6041.818745437644;6061.9393806063135;6122.877542449037;6220.782272620437;6288.717007734755;6334.429901308904;6344.365563443619;6379.617222966637 17:36:42 INFO - 2015-12-15 17:36:42,713 DEBUG : BROWSER_OUTPUT: |4;prototype.html;298.6669863102641;300.03837700170953;307.65849906603665;310.75916164972153;310.84211312859617;5510.669359838407;5575.358833734122;5576.247206627104;5669.0646043402785;5782.392848750884;5569.081101618238;5607.57943154264;5634.126710359901;5634.521065709409;5645.774169033234;4250.426192464009;4252.14957008598;4252.425922500462;4253.298205796055;4253.425787518676;4249.957500424987;4255.425517555122;4256.1913236485125;4260.1266740318315;4267.48790145183;4126.5667104954;4189.329707246841;4196.916061678765;4214.578542145776;4235.6187943085115;4225.957740422587;4232.492100947906;4244.320908654631;4252.787360631972;4258.744475331475;3629.4374371972385;3631.346357655621;3645.7994810285422;3650.2881938022097;3674.503941967827;5377.8117765878005;5401.78392864285;5411.567074634059;5420.91868621971;5427.375851777057;4717.858464246044;4821.783019764119;4836.443808961979;4848.7090774553635;4865.9026819463415;5633.436656334334;5705.4294570543025;5801.274840644919;5828.475437210629;5829.795957141522;3932.1152740633415;3937.7440466369594;3941.5467221269632;3943.2310699413624;3949.210157968389;6077.544184186204;6145.907811382833;6166.167567378391;6166.907496387557;6171.9691401542705;4012.3179059559943;4075.1849630074103;4108.219438306772;4120.855770048034;4150.481189851269;4125.97937010313;4139.275626765364;4180.352045432963;4186.832526698898;4191.706580539394;4812.181929072066;4829.396325459317;4834.7824347904425;4835.371401717754;4843.4430040545085;5856.853578660568;5862.912056319158;5865.648061116347;5878.882422351529;5887.234659494324;1437.4322142753554;1450.760624496946;1482.3477669825243;1483.0212060040321;1484.8812095032588;942.948137852411;963.1187463470845;964.9807003859884;965.6378857928277;967.0909345215615;5016.423111342242;5016.899662006739;5067.290579318898;5068.847934561969;5078.415982162099;5978.372270911492;5996.070609055603;6014.609050411709;6028.065649824284;6032.366601506778;5040.647154699209;5062.544371006627;5063.000114954572;5073.518015788494;5076.289319495274;3219.2434777827193;3233.094733474631;3266.3630592034324;3279.0982479817762;3317.137544238515;5764.337101233328;5770.278715160605;5796;5802.448767367094;5815.3021637402935;6288.842778930563;6328.6835658216905;6336.17629708135;6341.936580634135;6346.904796428057;5675.858103547444;5760.279965004374;5771.249737534094;5799.101139323411;5801.332846722681;6145.231846019247;6158;6158.538109641884;6167.629942203482;6169.2288463942;5993.910091348721;5996.730147143302;5997;6003.219581454383;6004.39956004396;5768.625039372428;5772.884542309046;5778.16216648592;5787.913181302368;5787.987102257004;4003.8798836034966;4075.3886916962692;4108.383742438658;4109.753414795002;4116.135611521614;6002.069679199731;6132.754689812357;6134.110553969738;6140.938590614037;6146.200993870768;5852.092925596539;5891.675957822189;5901.793437229677;5903.91144132847;5910.852228694317;5848.64908105515;5853.619514731528;5854.151148083589;5858.121281807763;5858.2677165354335;5610.887782244251;5662.8301150965535;5735.082386818256;5759.856003599776;5774.133879918046;5901.203337549376;5915.881682366415;5930.466258036798;5941.435563621622;5962.642241465526;5753.741081651252;5766.443060373592;5798.855028624318;5802.883942321046;5812.738426770721 17:36:42 INFO - 2015-12-15 17:36:42,714 DEBUG : BROWSER_OUTPUT: |5;yui.html;669;761.9542827430354;773.1070613441474;780.1295253219522;799.1489064146683;11266.887331126687;11448.255863368875;11462.083033357332;11483.966443020137;11496.137789665767;11618.941905290461;11703.414829258545;11801.291922484657;13385.866141338583;13463.394147263372;806.3186607316808;818.6684392820918;818.7543736878943;819.6803246733779;820.3396266005863;611.5382885921123;631.3465563142149;778.4006315137341;814.5560669435172;814.6415577146066;845.6828689241535;847.0301504777027;852.7143406958645;854.0946596607616;854.628236717027;850.5789634131145;850.5959669157163;851.8679604661256;852.4373913217278;852.6163226548078;623.688155922039;627.1877918096077;627.5701144715885;628.0202883501706;628.7265039707696;12337.444814983348;12345.19756215852;12377.876221237853;12512;12517.43671534774;11257.662270131908;11527.48126334317;11550.653480395602;11704.824427633592;11822.467988940432;9415.717528474088;9515.04849515043;9530.142287194185;9610.567524461416;9716;3325.3848038112783;3360.176756694601;3370.898873033813;3379.172102834821;3392.2537041850755;40507.189856203004;41251;41272.587274127174;41319.586804131875;41340.966475838046;9681.128698417177;9965.80068398628;9998.400095994264;10032.598696052077;10186.592536298613;16867.072311022766;16950.237239324015;17030.318787248372;17071.658566828595;17076.146192690318;6397.296297407282;6416.326285740023;6420.646864422408;6438.549301548846;6610.636414997125;3539.911502212465;3548.1661809474754;3550.928981420357;3566.946495802565;3596.2987217492337;2612.046602989933;2633.591793272046;2640.115561286952;2641.1944356971308;2645.2989957661234;456.2494696224692;456.6301295950291;459.5496413514774;461.87067621066154;464.846600621794;511.53194826733125;539.1535289595377;540.9309391577233;594.9107633854957;599.3137857153534;7823.452358334862;8143.6742530298125;8168.632411541495;8319.792005199797;8740.78148046304;466.5497794628132;526.2947650148819;570.4295704295704;633.7369991453587;653.496807458264;3746.033332500459;3830.540335159799;3862.8841134766003;3925.3548811946343;3925.9018524536546;49255.261171082464;49678.503214968114;49821.501784982414;49894;49915.75042124766;45173;45445;45568.3164752529;45653.31520027202;45835.77082114568;41296.38055429171;41346.79326603408;41399.1720165601;41514.792426037675;41668.791656041525;7851.607419628882;7982.920170798217;8010.319122874388;8106.189381061847;8139.918600813916;31052.378952420375;31723;32527;33416.49875251922;35103.71825831313;38036.42945355766;38591.228175435775;38778.22443551169;39136.804315979374;39208;10072.294939354468;10143.442110683984;10202.38785672862;10216.642417515352;10442.843357349799;11217;11363.31820090764;11379.715507112058;11385.772284554097;11976.16166868311;10665.9466702666;10951.616693415692;11119.27724697892;11287.887121128684;11433.59982400612;10442.791144176921;10532.262741608014;10854.565817367216;10936.453177340942;11144.275622084539;10787;10789.352638841694;10945.945270273598;11216.607418740305;11292.322460652387;9108.407953483002;9155.404898681565;9276.860847087164;9278.95360523193;9666.323357364918;8652.826943461221;8983.505907175168;9307.348485605942;9415.67681730344;9570.234381249622 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,336 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: __startTimestamp1450229802706__endTimestamp 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: Number of tests: 6 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4736.45 Median:4840.08 stddev:502.50 (10.4%) stddev-sans-first:476.75 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: Values: 2654.0 2736.4 2763.3 2765.6 2767.5 4598.5 4773.5 4790.9 4796.0 4832.3 4697.7 4711.5 4725.1 4790.4 4822.3 4384.2 4446.8 4462.1 4507.9 4524.7 4346.2 4389.1 4478.3 4492.3 4566.3 4226.3 4335.7 4343.4 4424.6 4493.6 4394.5 4474.3 4481.8 4492.9 4507.4 4434.4 4463.8 4479.4 4479.7 4507.3 4454.6 4574.2 4629.8 4752.3 4756.7 5108.8 5159.9 5171.1 5201.6 5240.9 5190.7 5205.7 5209.7 5211.7 5222.4 4650.3 4709.3 4749.2 4800.1 4853.7 5276.9 5333.9 5341.0 5355.8 5414.1 4828.1 5098.2 5118.8 5125.5 5130.2 5054.8 5066.3 5105.4 5145.3 5189.4 5118.0 5118.4 5119.5 5125.8 5169.5 4759.3 4840.1 5095.7 5096.6 5108.1 4168.2 4181.6 4220.6 4287.4 4329.1 3746.9 3768.2 3771.8 3808.0 3808.9 4259.1 4464.5 4467.9 4478.6 4485.8 4994.1 5005.8 5069.8 5094.7 5121.4 4353.6 4384.8 4403.1 4412.6 4432.5 4342.8 4442.4 4503.3 4541.7 4577.1 4818.1 4837.3 4868.7 4875.0 4922.2 5186.5 5193.8 5214.3 5222.1 5267.3 4578.9 4667.8 4795.8 4918.0 4920.5 5115.9 5134.3 5134.5 5143.9 5145.1 5176.4 5199.9 5205.9 5217.8 5261.2 4774.7 4884.5 4950.8 5027.5 5064.9 4982.1 4992.7 4996.0 5013.8 5065.2 4896.0 4913.9 4941.7 4945.2 4980.9 4883.9 4950.1 5008.4 5020.2 5023.2 4901.3 4966.8 4996.1 4997.6 5039.6 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,715 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:38300.79 Median:32329.26 stddev:60741.04 (187.9%) stddev-sans-first:60845.31 17:36:42 INFO - 2015-12-15 17:36:42,716 DEBUG : BROWSER_OUTPUT: Values: 550.9 551.7 570.7 587.3 594.4 32199.5 32590.0 32681.7 32749.5 32755.0 38126.2 38590.8 38599.4 38655.5 38687.0 7820.4 7957.9 7970.5 7974.1 8006.0 7740.9 7795.3 7803.5 7812.9 7815.3 7808.5 7886.7 7902.0 7919.2 7946.2 7875.2 7925.2 7936.9 7954.0 7962.4 7765.5 7880.5 7912.6 7919.7 7928.6 34940.7 35012.6 35162.5 35177.1 35236.6 2859.4 2972.9 3005.4 3211.7 3251.1 26596.2 26614.8 26871.6 26954.5 27077.9 3327.2 3428.7 3463.2 3495.5 3517.0 194673.0 195747.0 196886.0 196913.0 197348.0 49153.5 49881.0 50254.0 50515.0 51788.0 45601.0 45746.1 45800.3 45976.0 46078.3 13925.0 14038.9 14076.4 14079.6 14146.8 10988.2 11038.2 11078.9 11086.2 11113.9 17780.9 17810.4 17959.5 17987.4 18023.8 7741.0 7801.3 7881.9 8035.2 8056.4 913.8 916.9 940.0 968.2 1048.4 24844.5 25187.6 25643.9 25972.4 26116.5 954.0 977.7 983.2 988.1 1005.2 12651.5 12717.1 12871.7 12881.5 12990.6 253903.0 255685.0 257835.0 258150.0 258185.0 43121.8 43280.8 43416.1 43490.0 43503.0 1221.4 1238.1 1246.5 1248.7 1251.9 24539.3 24968.9 25007.1 25265.4 25270.2 38076.0 38658.0 38714.0 38724.0 38878.2 238597.0 243251.0 244907.0 244942.0 245192.0 27136.9 28440.3 28668.9 28671.6 28805.4 32459.0 32822.3 33343.5 33358.3 34298.8 30690.4 31077.4 31114.7 31429.4 31628.7 31341.8 31346.8 31416.5 31609.7 31771.8 31063.0 31197.4 31278.4 31360.2 32907.3 27650.9 27927.0 28253.7 28755.6 28897.9 22565.2 23073.7 23159.3 23175.8 23209.4 17:36:42 INFO - 2015-12-15 17:36:42,716 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,716 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:20974.89 Median:4650.72 stddev:52155.93 (1121.5%) stddev-sans-first:52279.21 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: Values: 333.1 333.1 337.2 340.9 341.2 15409.3 15449.7 15788.8 16116.8 16620.5 10034.9 10239.7 14364.8 15959.3 15962.4 4591.6 4607.3 4714.4 4727.9 4732.1 4662.5 4680.2 4699.0 4703.0 4707.0 4638.9 4665.4 4813.3 4976.6 4991.2 4933.0 4936.0 4947.5 4965.0 4974.7 5029.5 5053.3 5058.5 5069.9 5088.8 11136.3 11277.9 13514.7 18861.2 18996.1 1785.8 1846.1 1882.4 1892.6 1945.9 9099.4 9156.2 9551.7 10349.3 12769.2 1728.9 1730.9 1733.4 1749.6 1806.7 53641.0 54103.0 54471.7 54825.0 55231.4 9534.4 9681.1 9804.8 9805.8 9884.0 12364.2 12366.2 12391.7 12393.5 12422.7 7050.8 7289.3 7329.2 7379.6 7427.4 7127.6 7134.1 7154.1 7173.4 7188.5 4119.4 4134.3 4161.0 4162.3 4196.0 1671.5 1736.6 1742.0 1745.3 1749.4 762.0 830.2 894.7 940.0 1002.7 15184.4 15398.9 15464.0 15922.7 16226.8 818.5 853.2 864.8 874.8 883.2 8952.4 9130.8 9181.0 9198.1 9310.6 311225.0 316182.0 317485.0 318623.0 318716.0 64573.7 67764.7 69183.7 69784.0 69869.0 1056.9 1058.8 1059.1 1088.2 1093.3 14286.8 14448.3 14493.8 14570.6 14835.3 31792.4 32346.5 32972.8 33526.7 34265.3 40037.6 40662.2 40940.0 41982.4 42829.0 8066.6 8419.4 8531.9 8540.0 8542.0 9216.8 9433.0 9442.2 9629.9 9709.0 8013.4 8258.3 9073.2 9125.3 9462.3 8289.3 8960.6 9150.4 9366.1 9819.0 9476.8 9542.8 9784.9 9789.4 9796.7 14320.3 14322.0 14432.5 14450.6 14598.0 14716.9 14718.8 14850.8 15003.6 15177.2 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5024.10 Median:6239.02 stddev:2634.51 (42.2%) stddev-sans-first:2628.35 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: Values: 1469.6 1642.2 1713.9 1726.8 1750.7 6009.6 6103.4 6970.3 7546.5 7550.3 6580.2 6690.0 6735.6 6737.1 6759.6 2064.6 2106.3 2108.3 2112.2 2124.2 2071.6 2077.7 2085.1 2112.3 2117.2 2036.3 2042.4 2094.4 2095.5 2098.0 2000.7 2011.2 2015.3 2018.5 2024.5 2021.7 2040.2 2065.5 2068.5 2072.6 7282.6 7308.7 7327.0 7343.4 7374.4 4230.6 4283.3 4285.6 4299.8 4305.7 2756.4 2770.2 2813.6 2833.6 2842.7 813.1 826.0 826.8 831.3 854.8 9939.5 9947.5 9971.2 9990.4 9992.2 3842.4 3858.6 3920.7 3970.0 3996.8 8524.1 8553.7 8555.8 8580.3 8611.2 4368.1 4372.5 4391.3 4397.5 4399.1 3244.9 3346.9 3763.8 3931.2 3963.8 3400.6 3404.9 3411.9 3459.4 3470.9 1057.7 1058.3 1060.5 1066.3 1078.2 1198.6 1201.2 1216.1 1217.1 1223.5 6877.8 6918.6 6925.6 6941.5 6962.9 401.1 415.6 417.4 420.7 421.0 5490.5 5510.7 5528.5 5551.0 5561.2 8157.8 8365.2 8418.2 8433.6 8728.2 8395.4 8462.4 8467.0 8512.7 8529.7 6647.8 6772.2 6865.3 6873.8 6908.9 6939.2 7015.5 7018.4 7024.3 7026.1 7785.6 7932.2 7937.2 7998.2 8013.6 7560.9 7749.3 7752.3 7789.2 8125.3 5533.0 5616.7 6245.4 6446.7 6529.0 6699.5 6706.4 6737.8 6747.0 6771.1 6416.0 6471.5 6482.3 6605.8 6661.9 6232.6 6253.6 6371.8 6438.7 6493.4 6495.4 6505.1 6526.2 6553.5 6554.0 6028.7 6031.7 6041.8 6061.9 6122.9 6220.8 6288.7 6334.4 6344.4 6379.6 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4838.01 Median:5622.16 stddev:1456.37 (25.9%) stddev-sans-first:1420.04 17:36:42 INFO - 2015-12-15 17:36:42,717 DEBUG : BROWSER_OUTPUT: Values: 298.7 300.0 307.7 310.8 310.8 5510.7 5575.4 5576.2 5669.1 5782.4 5569.1 5607.6 5634.1 5634.5 5645.8 4250.4 4252.1 4252.4 4253.3 4253.4 4250.0 4255.4 4256.2 4260.1 4267.5 4126.6 4189.3 4196.9 4214.6 4235.6 4226.0 4232.5 4244.3 4252.8 4258.7 3629.4 3631.3 3645.8 3650.3 3674.5 5377.8 5401.8 5411.6 5420.9 5427.4 4717.9 4821.8 4836.4 4848.7 4865.9 5633.4 5705.4 5801.3 5828.5 5829.8 3932.1 3937.7 3941.5 3943.2 3949.2 6077.5 6145.9 6166.2 6166.9 6172.0 4012.3 4075.2 4108.2 4120.9 4150.5 4126.0 4139.3 4180.4 4186.8 4191.7 4812.2 4829.4 4834.8 4835.4 4843.4 5856.9 5862.9 5865.6 5878.9 5887.2 1437.4 1450.8 1482.3 1483.0 1484.9 942.9 963.1 965.0 965.6 967.1 5016.4 5016.9 5067.3 5068.8 5078.4 5978.4 5996.1 6014.6 6028.1 6032.4 5040.6 5062.5 5063.0 5073.5 5076.3 3219.2 3233.1 3266.4 3279.1 3317.1 5764.3 5770.3 5796.0 5802.4 5815.3 6288.8 6328.7 6336.2 6341.9 6346.9 5675.9 5760.3 5771.2 5799.1 5801.3 6145.2 6158.0 6158.5 6167.6 6169.2 5993.9 5996.7 5997.0 6003.2 6004.4 5768.6 5772.9 5778.2 5787.9 5788.0 4003.9 4075.4 4108.4 4109.8 4116.1 6002.1 6132.8 6134.1 6140.9 6146.2 5852.1 5891.7 5901.8 5903.9 5910.9 5848.6 5853.6 5854.2 5858.1 5858.3 5610.9 5662.8 5735.1 5759.9 5774.1 5901.2 5915.9 5930.5 5941.4 5962.6 5753.7 5766.4 5798.9 5802.9 5812.7 17:36:42 INFO - 2015-12-15 17:36:42,718 DEBUG : BROWSER_OUTPUT: 17:36:42 INFO - 2015-12-15 17:36:42,718 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12474.67 Median:41591.79 stddev:13958.12 (33.6%) stddev-sans-first:13969.12 17:36:42 INFO - 2015-12-15 17:36:42,719 DEBUG : BROWSER_OUTPUT: Values: 669.0 762.0 773.1 780.1 799.1 11266.9 11448.3 11462.1 11484.0 11496.1 11618.9 11703.4 11801.3 13385.9 13463.4 806.3 818.7 818.8 819.7 820.3 611.5 631.3 778.4 814.6 814.6 845.7 847.0 852.7 854.1 854.6 850.6 850.6 851.9 852.4 852.6 623.7 627.2 627.6 628.0 628.7 12337.4 12345.2 12377.9 12512.0 12517.4 11257.7 11527.5 11550.7 11704.8 11822.5 9415.7 9515.0 9530.1 9610.6 9716.0 3325.4 3360.2 3370.9 3379.2 3392.3 40507.2 41251.0 41272.6 41319.6 41341.0 9681.1 9965.8 9998.4 10032.6 10186.6 16867.1 16950.2 17030.3 17071.7 17076.1 6397.3 6416.3 6420.6 6438.5 6610.6 3539.9 3548.2 3550.9 3566.9 3596.3 2612.0 2633.6 2640.1 2641.2 2645.3 456.2 456.6 459.5 461.9 464.8 511.5 539.2 540.9 594.9 599.3 7823.5 8143.7 8168.6 8319.8 8740.8 466.5 526.3 570.4 633.7 653.5 3746.0 3830.5 3862.9 3925.4 3925.9 49255.3 49678.5 49821.5 49894.0 49915.8 45173.0 45445.0 45568.3 45653.3 45835.8 41296.4 41346.8 41399.2 41514.8 41668.8 7851.6 7982.9 8010.3 8106.2 8139.9 31052.4 31723.0 32527.0 33416.5 35103.7 38036.4 38591.2 38778.2 39136.8 39208.0 10072.3 10143.4 10202.4 10216.6 10442.8 11217.0 11363.3 11379.7 11385.8 11976.2 10665.9 10951.6 11119.3 11287.9 11433.6 10442.8 10532.3 10854.6 10936.5 11144.3 10787.0 10789.4 10945.9 11216.6 11292.3 9108.4 9155.4 9276.9 9279.0 9666.3 8652.8 8983.5 9307.3 9415.7 9570.2 17:36:42 INFO - 2015-12-15 17:36:42,719 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:36:42 INFO - 2015-12-15 17:36:42,719 DEBUG : BROWSER_OUTPUT: 17:36:43 INFO - 2015-12-15 17:36:43,437 INFO : Browser exited with error code: 0 17:36:43 INFO - 2015-12-15 17:36:43,458 INFO : Completed test dromaeo_css (00:18:35) 17:36:43 INFO - 2015-12-15 17:36:43,458 INFO : Starting test kraken 17:36:43 INFO - 2015-12-15 17:36:43,458 DEBUG : operating with platform_type : linux_ 17:36:43 INFO - 2015-12-15 17:36:43,459 INFO : Initialising browser for kraken test... 17:36:43 INFO - 2015-12-15 17:36:43,465 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRNVsiv/profile http://localhost:60727/getInfo.html 17:36:48 INFO - 2015-12-15 17:36:48,209 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:36:48 INFO - 2015-12-15 17:36:48,209 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:36:48 INFO - 2015-12-15 17:36:48,209 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:36:48 INFO - 2015-12-15 17:36:48,210 DEBUG : BROWSER_OUTPUT: __metrics 17:36:48 INFO - 2015-12-15 17:36:48,334 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] 17:36:49 INFO - 2015-12-15 17:36:49,297 INFO : Browser initialized. 17:36:49 INFO - 2015-12-15 17:36:49,298 INFO : Running cycle 1/1 for kraken test... 17:36:49 INFO - 2015-12-15 17:36:49,298 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRNVsiv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:37:01 INFO - 2015-12-15 17:37:01,383 DEBUG : BROWSER_OUTPUT: RSS: Main: 142843904 17:37:01 INFO - 2015-12-15 17:37:01,383 DEBUG : BROWSER_OUTPUT: 17:37:03 INFO - 2015-12-15 17:37:03,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60727/tests/kraken/driver.html?testName=audio-beat-detection) 17:37:04 INFO - 2015-12-15 17:37:04,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 144805888 17:37:04 INFO - 2015-12-15 17:37:04,585 DEBUG : BROWSER_OUTPUT: 17:37:07 INFO - 2015-12-15 17:37:07,348 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60727/tests/kraken/driver.html?testName=audio-dft) 17:37:07 INFO - 2015-12-15 17:37:07,950 DEBUG : BROWSER_OUTPUT: RSS: Main: 144068608 17:37:07 INFO - 2015-12-15 17:37:07,950 DEBUG : BROWSER_OUTPUT: 17:37:10 INFO - 2015-12-15 17:37:10,849 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60727/tests/kraken/driver.html?testName=audio-fft) 17:37:11 INFO - 2015-12-15 17:37:11,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 137797632 17:37:11 INFO - 2015-12-15 17:37:11,430 DEBUG : BROWSER_OUTPUT: 17:37:13 INFO - 2015-12-15 17:37:13,551 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60727/tests/kraken/driver.html?testName=audio-oscillator) 17:37:14 INFO - 2015-12-15 17:37:14,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 138924032 17:37:14 INFO - 2015-12-15 17:37:14,137 DEBUG : BROWSER_OUTPUT: 17:37:16 INFO - 2015-12-15 17:37:16,392 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60727/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:37:16 INFO - 2015-12-15 17:37:16,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 140218368 17:37:16 INFO - 2015-12-15 17:37:16,986 DEBUG : BROWSER_OUTPUT: 17:37:20 INFO - 2015-12-15 17:37:20,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60727/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:20 INFO - 2015-12-15 17:37:20,693 DEBUG : BROWSER_OUTPUT: RSS: Main: 140820480 17:37:20 INFO - 2015-12-15 17:37:20,693 DEBUG : BROWSER_OUTPUT: 17:37:24 INFO - 2015-12-15 17:37:24,127 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60727/tests/kraken/driver.html?testName=imaging-desaturate) 17:37:24 INFO - 2015-12-15 17:37:24,716 DEBUG : BROWSER_OUTPUT: RSS: Main: 141774848 17:37:24 INFO - 2015-12-15 17:37:24,717 DEBUG : BROWSER_OUTPUT: 17:37:27 INFO - 2015-12-15 17:37:27,656 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60727/tests/kraken/driver.html?testName=json-parse-financial) 17:37:28 INFO - 2015-12-15 17:37:28,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 141656064 17:37:28 INFO - 2015-12-15 17:37:28,247 DEBUG : BROWSER_OUTPUT: 17:37:30 INFO - 2015-12-15 17:37:30,025 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60727/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:37:30 INFO - 2015-12-15 17:37:30,618 DEBUG : BROWSER_OUTPUT: RSS: Main: 141467648 17:37:30 INFO - 2015-12-15 17:37:30,618 DEBUG : BROWSER_OUTPUT: 17:37:32 INFO - 2015-12-15 17:37:32,521 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:37:33 INFO - 2015-12-15 17:37:33,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 140636160 17:37:33 INFO - 2015-12-15 17:37:33,134 DEBUG : BROWSER_OUTPUT: 17:37:35 INFO - 2015-12-15 17:37:35,308 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:37:35 INFO - 2015-12-15 17:37:35,910 DEBUG : BROWSER_OUTPUT: RSS: Main: 140419072 17:37:35 INFO - 2015-12-15 17:37:35,910 DEBUG : BROWSER_OUTPUT: 17:37:38 INFO - 2015-12-15 17:37:38,450 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:37:39 INFO - 2015-12-15 17:37:39,044 DEBUG : BROWSER_OUTPUT: RSS: Main: 140959744 17:37:39 INFO - 2015-12-15 17:37:39,044 DEBUG : BROWSER_OUTPUT: 17:37:41 INFO - 2015-12-15 17:37:41,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:37:42 INFO - 2015-12-15 17:37:42,247 DEBUG : BROWSER_OUTPUT: RSS: Main: 140529664 17:37:42 INFO - 2015-12-15 17:37:42,248 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,017 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60727/tests/kraken/driver.html?testName=ai-astar) 17:37:44 INFO - 2015-12-15 17:37:44,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 141565952 17:37:44 INFO - 2015-12-15 17:37:44,267 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,269 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:37:44 INFO - 2015-12-15 17:37:44,269 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:37:44 INFO - 2015-12-15 17:37:44,270 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:37:44 INFO - 2015-12-15 17:37:44,270 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:37:44 INFO - 2015-12-15 17:37:44,270 DEBUG : BROWSER_OUTPUT: |0;ai-astar;118;110;111;112;114;125;113;112;115;113 17:37:44 INFO - 2015-12-15 17:37:44,270 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;134;158;167;131;168;154;135;165;131 17:37:44 INFO - 2015-12-15 17:37:44,271 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;163;164;163;164;166;165;163;166;162 17:37:44 INFO - 2015-12-15 17:37:44,271 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;74;81;133;73;73;72;78;87 17:37:44 INFO - 2015-12-15 17:37:44,271 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;96;96;128;94;94;94;129;95;94;133 17:37:44 INFO - 2015-12-15 17:37:44,271 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;111;110;110;109;110;111;110;111;111 17:37:44 INFO - 2015-12-15 17:37:44,273 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;148;148;147;147;147;148;147;149;148 17:37:44 INFO - 2015-12-15 17:37:44,273 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;98;97;96;96;96;95;101;97;96;96 17:37:44 INFO - 2015-12-15 17:37:44,273 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;72;73;74;72;72;75;79;70;70 17:37:44 INFO - 2015-12-15 17:37:44,274 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;69;68;68;68;68;68;68;69 17:37:44 INFO - 2015-12-15 17:37:44,274 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;84;85;93;84;85;83;87;87;84 17:37:44 INFO - 2015-12-15 17:37:44,274 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;131;121;128;123;131;121;124;131;128;128 17:37:44 INFO - 2015-12-15 17:37:44,274 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;148;152;146;149;136;149;151;149;137 17:37:44 INFO - 2015-12-15 17:37:44,274 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;72;70;73;70;71;71;69;70;75;68 17:37:44 INFO - 2015-12-15 17:37:44,275 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:37:44 INFO - 2015-12-15 17:37:44,275 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:37:44 INFO - 2015-12-15 17:37:44,275 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,783 17:37:44 INFO - 2015-12-15 17:37:44,275 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:37:44 INFO - 2015-12-15 17:37:44,276 DEBUG : BROWSER_OUTPUT: __startTimestamp1450229864268__endTimestamp 17:37:44 INFO - 2015-12-15 17:37:44,276 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,279 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:37:44 INFO - 2015-12-15 17:37:44,280 DEBUG : BROWSER_OUTPUT: Number of tests: 14 17:37:44 INFO - 2015-12-15 17:37:44,280 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,280 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.30 Median:113.50 stddev:4.37 (3.9%) stddev-sans-first:4.43 17:37:44 INFO - 2015-12-15 17:37:44,280 DEBUG : BROWSER_OUTPUT: Values: 118.0 110.0 111.0 112.0 114.0 125.0 113.0 112.0 115.0 113.0 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.50 Median:160.00 stddev:15.86 (9.9%) stddev-sans-first:16.26 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: Values: 162.0 134.0 158.0 167.0 131.0 168.0 154.0 135.0 165.0 131.0 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:1.48 (0.9%) stddev-sans-first:1.41 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: Values: 162.0 163.0 164.0 163.0 164.0 166.0 165.0 163.0 166.0 162.0 17:37:44 INFO - 2015-12-15 17:37:44,281 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.70 Median:73.50 stddev:18.65 (25.4%) stddev-sans-first:19.51 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 74.0 81.0 133.0 73.0 73.0 72.0 78.0 87.0 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:94.00 stddev:17.11 (18.2%) stddev-sans-first:17.81 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 128.0 94.0 94.0 94.0 129.0 95.0 94.0 133.0 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,282 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.40 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 17:37:44 INFO - 2015-12-15 17:37:44,283 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 110.0 110.0 109.0 110.0 111.0 110.0 111.0 111.0 17:37:44 INFO - 2015-12-15 17:37:44,283 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,283 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 17:37:44 INFO - 2015-12-15 17:37:44,283 DEBUG : BROWSER_OUTPUT: Values: 147.0 148.0 148.0 147.0 147.0 147.0 148.0 147.0 149.0 148.0 17:37:44 INFO - 2015-12-15 17:37:44,283 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,283 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.80 Median:96.00 stddev:1.69 (1.8%) stddev-sans-first:1.73 17:37:44 INFO - 2015-12-15 17:37:44,284 DEBUG : BROWSER_OUTPUT: Values: 98.0 97.0 96.0 96.0 96.0 95.0 101.0 97.0 96.0 96.0 17:37:44 INFO - 2015-12-15 17:37:44,284 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,284 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.10 Median:73.50 stddev:2.64 (3.6%) stddev-sans-first:2.78 17:37:44 INFO - 2015-12-15 17:37:44,284 DEBUG : BROWSER_OUTPUT: Values: 74.0 72.0 73.0 74.0 72.0 72.0 75.0 79.0 70.0 70.0 17:37:44 INFO - 2015-12-15 17:37:44,284 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,284 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 17:37:44 INFO - 2015-12-15 17:37:44,285 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 69.0 68.0 68.0 68.0 68.0 68.0 68.0 69.0 17:37:44 INFO - 2015-12-15 17:37:44,285 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,286 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:2.87 (3.4%) stddev-sans-first:3.03 17:37:44 INFO - 2015-12-15 17:37:44,286 DEBUG : BROWSER_OUTPUT: Values: 85.0 84.0 85.0 93.0 84.0 85.0 83.0 87.0 87.0 84.0 17:37:44 INFO - 2015-12-15 17:37:44,286 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,286 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:128.00 stddev:4.03 (3.2%) stddev-sans-first:3.95 17:37:44 INFO - 2015-12-15 17:37:44,286 DEBUG : BROWSER_OUTPUT: Values: 131.0 121.0 128.0 123.0 131.0 121.0 124.0 131.0 128.0 128.0 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.00 Median:149.00 stddev:5.60 (3.8%) stddev-sans-first:5.83 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: Values: 143.0 148.0 152.0 146.0 149.0 136.0 149.0 151.0 149.0 137.0 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:71.00 stddev:2.02 (2.9%) stddev-sans-first:2.11 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: Values: 72.0 70.0 73.0 70.0 71.0 71.0 69.0 70.0 75.0 68.0 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:37:44 INFO - 2015-12-15 17:37:44,287 DEBUG : BROWSER_OUTPUT: 17:37:45 INFO - 2015-12-15 17:37:45,209 INFO : Browser exited with error code: 0 17:37:45 INFO - 2015-12-15 17:37:45,258 INFO : Completed test kraken (00:01:01) 17:37:45 INFO - 2015-12-15 17:37:45,258 INFO : Starting test v8_7 17:37:45 INFO - 2015-12-15 17:37:45,258 DEBUG : operating with platform_type : linux_ 17:37:45 INFO - 2015-12-15 17:37:45,258 INFO : Initialising browser for v8_7 test... 17:37:45 INFO - 2015-12-15 17:37:45,265 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Bwowd/profile http://localhost:60727/getInfo.html 17:37:50 INFO - 2015-12-15 17:37:50,091 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:37:50 INFO - 2015-12-15 17:37:50,092 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:37:50 INFO - 2015-12-15 17:37:50,092 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:37:50 INFO - 2015-12-15 17:37:50,092 DEBUG : BROWSER_OUTPUT: __metrics 17:37:50 INFO - 2015-12-15 17:37:50,228 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] 17:37:51 INFO - 2015-12-15 17:37:51,173 INFO : Browser initialized. 17:37:51 INFO - 2015-12-15 17:37:51,173 INFO : Running cycle 1/1 for v8_7 test... 17:37:51 INFO - 2015-12-15 17:37:51,174 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Bwowd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:38:03 INFO - 2015-12-15 17:38:03,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 142667776 17:38:03 INFO - 2015-12-15 17:38:03,155 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,585 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60727/tests/v8_7/run.html (next: http://localhost:60727/tests/v8_7/run.html) 17:38:24 INFO - 2015-12-15 17:38:24,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 137814016 17:38:24 INFO - 2015-12-15 17:38:24,835 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,836 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:38:24 INFO - 2015-12-15 17:38:24,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:38:24 INFO - 2015-12-15 17:38:24,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:38:24 INFO - 2015-12-15 17:38:24,836 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:38:24 INFO - 2015-12-15 17:38:24,836 DEBUG : BROWSER_OUTPUT: |0;Richards;166.333998669328 17:38:24 INFO - 2015-12-15 17:38:24,836 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.56318426424198 17:38:24 INFO - 2015-12-15 17:38:24,837 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4857462269424 17:38:24 INFO - 2015-12-15 17:38:24,837 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5148.717948717948 17:38:24 INFO - 2015-12-15 17:38:24,837 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1077.5862068965516 17:38:24 INFO - 2015-12-15 17:38:24,837 DEBUG : BROWSER_OUTPUT: |5;Earley;727.802037845706 17:38:24 INFO - 2015-12-15 17:38:24,838 DEBUG : BROWSER_OUTPUT: |6;Boyer;12204.819277108434 17:38:24 INFO - 2015-12-15 17:38:24,838 DEBUG : BROWSER_OUTPUT: |7;RegExp;26230.76923076923 17:38:24 INFO - 2015-12-15 17:38:24,838 DEBUG : BROWSER_OUTPUT: |8;Splay;590.318772136954 17:38:24 INFO - 2015-12-15 17:38:24,838 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 17:38:24 INFO - 2015-12-15 17:38:24,838 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:38:24 INFO - 2015-12-15 17:38:24,838 DEBUG : BROWSER_OUTPUT: __startTimestamp1450229904834__endTimestamp 17:38:24 INFO - 2015-12-15 17:38:24,839 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,839 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:38:24 INFO - 2015-12-15 17:38:24,839 DEBUG : BROWSER_OUTPUT: Number of tests: 10 17:38:24 INFO - 2015-12-15 17:38:24,839 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,839 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.33 Median:166.33 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,840 DEBUG : BROWSER_OUTPUT: Values: 166.3 17:38:24 INFO - 2015-12-15 17:38:24,840 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,840 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.56 Median:185.56 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,840 DEBUG : BROWSER_OUTPUT: Values: 185.6 17:38:24 INFO - 2015-12-15 17:38:24,840 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,840 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: Values: 279.5 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5148.72 Median:5148.72 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: Values: 5148.7 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1077.59 Median:1077.59 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: Values: 1077.6 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:727.80 Median:727.80 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: Values: 727.8 17:38:24 INFO - 2015-12-15 17:38:24,841 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12204.82 Median:12204.82 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: Values: 12204.8 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26230.77 Median:26230.77 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: Values: 26230.8 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:590.32 Median:590.32 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: Values: 590.3 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: Values: 4835.7 17:38:24 INFO - 2015-12-15 17:38:24,842 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:38:24 INFO - 2015-12-15 17:38:24,843 DEBUG : BROWSER_OUTPUT: 17:38:25 INFO - 2015-12-15 17:38:25,765 INFO : Browser exited with error code: 0 17:38:25 INFO - 2015-12-15 17:38:25,779 INFO : Completed test v8_7 (00:00:40) 17:38:25 INFO - 2015-12-15 17:38:25,840 INFO : Completed test suite (00:20:17) 17:38:25 INFO - 2015-12-15 17:38:25,841 DEBUG : Working with test: dromaeo_css 17:38:25 INFO - 2015-12-15 17:38:25,841 DEBUG : Generating results file: dromaeo_css 17:38:25 INFO - 2015-12-15 17:38:25,842 DEBUG : Working with test: kraken 17:38:25 INFO - 2015-12-15 17:38:25,842 DEBUG : Generating results file: kraken 17:38:25 INFO - 2015-12-15 17:38:25,842 INFO : javascript benchmark 17:38:25 INFO - 2015-12-15 17:38:25,842 DEBUG : Working with test: v8_7 17:38:25 INFO - 2015-12-15 17:38:25,842 DEBUG : Generating results file: v8_7 17:38:25 INFO - 2015-12-15 17:38:25,843 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:38:26 INFO - 2015-12-15 17:38:26,002 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 17:38:26 INFO - 2015-12-15 17:38:26,002 DEBUG : process_Request line: dromaeo_css 6284.06 graph.html#tests=[[72,132,43]] 17:38:26 INFO - 2015-12-15 17:38:26,002 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:38:26 INFO - 2015-12-15 17:38:26,034 DEBUG : process_Request line: kraken 1540.90 graph.html#tests=[[232,132,43]] 17:38:26 INFO - 2015-12-15 17:38:26,034 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:38:26 INFO - 2015-12-15 17:38:26,063 DEBUG : process_Request line: v8_7 21340.32 graph.html#tests=[[230,132,43]] 17:38:26 INFO - 2015-12-15 17:38:26,064 INFO : javascript benchmark 17:38:26 INFO - 2015-12-15 17:38:26,068 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2653.9734602653975, 2736.4116714906318, 2763.336799168199, 2765.6266404035464, 2767.474179905818, 4598.5401459854, 4773.546513081256, 4790.904181916364, 4796.016816552603, 4832.323474713543, 4697.67116301859, 4711.528847115295, 4725.102230576188, 4790.425148982127, 4822.324874517571, 4384.232759267132, 4446.7554284514335, 4462.12988467249, 4507.932381014271, 4524.68327217095, 4346.195953748551, 4389.122175564884, 4478.26108692067, 4492.258777301758, 4566.269396896497, 4226.260404429213, 4335.7181783184, 4343.435353404037, 4424.557544245566, 4493.595576398107, 4394.472663280395, 4474.284114541658, 4481.77591120446, 4492.8876778080485, 4507.3689683444345, 4434.423524941769, 4463.754493502856, 4479.350494178326, 4479.686421950432, 4507.256302710049, 4454.5990860822685, 4574.153781550391, 4629.837955671569, 4752.263399173134, 4756.738379389132, 5108.770105345231, 5159.948400515985, 5171.146760784466, 5201.5838732901275, 5240.947590524084, 5190.662606930538, 5205.687658740488, 5209.7134657593815, 5211.661242019257, 5222.4255331913455, 4650.255959046536, 4709.270063140219, 4749.168895443318, 4800.135975524369, 4853.684510506806, 5276.868078297996, 5333.946660533423, 5341.0, 5355.758990845421, 5414.052540805344, 4828.106800241966, 5098.2352647103235, 5118.846434606968, 5125.513076257749, 5130.230465430215, 5054.772535235923, 5066.316047333637, 5105.361829771278, 5145.253938178943, 5189.42916279209, 5118.0, 5118.411382690963, 5119.462456442095, 5125.769340379692, 5169.53474187325, 4759.333693282943, 4840.080384726891, 5095.668781529189, 5096.592272618182, 5108.131617624938, 4168.24971505132, 4181.644560212416, 4220.620144187038, 4287.442632457761, 4329.112531930947, 3746.9063273417837, 3768.2086761779783, 3771.7925514096987, 3808.028952617065, 3808.9047773805323, 4259.105587826529, 4464.508904020555, 4467.86596402102, 4478.552144785495, 4485.798139083749, 4994.101061808909, 5005.824796132118, 5069.771860266297, 5094.6688465249645, 5121.436641969455, 4353.6081752641785, 4384.780760962003, 4403.097365040159, 4412.580804823537, 4432.512423633398, 4342.782860856944, 4442.378067070613, 4503.347014682852, 4541.6593755468475, 4577.084583083397, 4818.10864989978, 4837.346958160603, 4868.707877527218, 4875.000624871964, 4922.163232250455, 5186.507281808223, 5193.766280517461, 5214.2960700304975, 5222.112240918977, 5267.341582302212, 4578.931316030195, 4667.813287468599, 4795.784189711401, 4918.0, 4920.532549407801, 5115.87210319745, 5134.332536770196, 5134.486551344836, 5143.92284115746, 5145.125328694206, 5176.4305926347315, 5199.870003249949, 5205.947940520698, 5217.765200565907, 5261.210818377274, 4774.689645173053, 4884.462709102067, 4950.801967921243, 5027.497250274943, 5064.949350506447, 4982.078315511642, 4992.650514463952, 4995.950040499694, 5013.774380152975, 5065.214891691794, 4896.04527117223, 4913.877153071059, 4941.728204948618, 4945.233488809096, 4980.850574482772, 4883.902321953612, 4950.108980383566, 5008.398992120969, 5020.246962955586, 5023.196288593807, 4901.264810278487, 4966.751662416792, 4996.075725990703, 4997.575206107522, 5039.622028347815], "unit": "score", "name": "dojo.html", "value": 4704.993206227492}, {"lowerIsBetter": false, "replicates": [550.8595308196409, 551.6741114038102, 570.7060863655215, 587.3480436715246, 594.4412252482666, 32199.517007244882, 32590.0, 32681.67318326816, 32749.508757368658, 32755.017349479524, 38126.23747525048, 38590.80704596473, 38599.42100868489, 38655.45378184869, 38687.0, 7820.3743700503965, 7957.880631790527, 7970.521768693868, 7974.122846486882, 8006.039275286974, 7740.922590774105, 7795.259450352236, 7803.453758236926, 7812.882806757903, 7815.335696465779, 7808.4924479908905, 7886.6845326186885, 7901.960490197541, 7919.168487308836, 7946.205379462065, 7875.212478752136, 7925.1678573749505, 7936.880946785803, 7953.960230198841, 7962.442629015914, 7765.495242809256, 7880.527168369858, 7912.564808935506, 7919.683212671486, 7928.643211055459, 34940.65059349399, 35012.64987350119, 35162.47256291158, 35177.12057198565, 35236.64763352359, 2859.37093839355, 2972.9405411891858, 3005.3989202159437, 3211.678832116793, 3251.122197006795, 26596.20211393661, 26614.798364459366, 26871.596926046124, 26954.460910781676, 27077.86461067682, 3327.218103745618, 3428.6914177724125, 3463.168839478507, 3495.5281037059926, 3517.0, 194673.0, 195747.0, 196886.0, 196913.01543492335, 197348.0, 49153.50846491489, 49881.002379952195, 50254.0, 50515.0, 51788.0, 45601.0, 45746.085078298915, 45800.312995305096, 45976.0, 46078.30882536765, 13925.0, 14038.85961140396, 14076.366563504667, 14079.577612671636, 14146.787798183035, 10988.230823842254, 11038.227324087236, 11078.889211107948, 11086.168537359568, 11113.888861111285, 17780.91109544444, 17810.376636817648, 17959.461216163538, 17987.370442034466, 18023.819761802213, 7741.0, 7801.336886364609, 7881.921180788233, 8035.232248905231, 8056.355491560661, 913.7715571107224, 916.9266458683289, 939.9624015039321, 968.1867231525412, 1048.444324508012, 24844.50310993806, 25187.62218566723, 25643.871780640977, 25972.35069123287, 26116.477670446486, 954.0173621170205, 977.6724797192878, 983.233078199006, 988.1205726903062, 1005.215931573374, 12651.493940242288, 12717.109802313935, 12871.742565148832, 12881.54914578004, 12990.610281691565, 253903.0, 255685.0, 257835.0, 258150.0, 258185.0, 43121.78439107785, 43280.78359608182, 43416.13167736628, 43490.0, 43503.0, 1221.3832014832453, 1238.0652607281625, 1246.5138595947587, 1248.712796056894, 1251.8998480121568, 24539.263822085366, 24968.876400561654, 25007.12475063364, 25265.368365791, 25270.241892742513, 38076.0, 38658.0, 38714.0, 38724.0, 38878.222435551696, 238597.0, 243251.0, 244907.0, 244942.0, 245192.0, 27136.864315679086, 28440.288992775346, 28668.856655716587, 28671.56992645154, 28805.42389152247, 32459.0, 32822.34355312928, 33343.49984750279, 33358.33283334368, 34298.82850585731, 30690.386192275582, 31077.378452431276, 31114.68885311118, 31429.371412571163, 31628.683713162573, 31341.8432907834, 31346.843265783526, 31416.528752069193, 31609.683903160672, 31771.84114079415, 31063.0, 31197.376052479278, 31278.37443251168, 31360.215994600316, 32907.341853162325, 27650.86174569114, 27927.02255421132, 28253.71746282511, 28755.5686664696, 28897.85551072231, 22565.21021764296, 23073.65389519192, 23159.305220843402, 23175.76824231736, 23209.419764506023], "unit": "score", "name": "ext.html", "value": 16278.459152689207}, {"lowerIsBetter": false, "replicates": [333.09066249139846, 333.1288680101535, 337.1974700213493, 340.90284268983345, 341.2015882834168, 15409.306581203844, 15449.69100617987, 15788.842111578895, 16116.83883161168, 16620.501384958472, 10034.896161422237, 10239.744006399844, 14364.784528232085, 15959.281832317545, 15962.441314553993, 4591.6097131743845, 4607.331936869152, 4714.387129673138, 4727.881802954919, 4732.05358928214, 4662.533746625344, 4680.157571637104, 4698.97650511747, 4703.035877645075, 4706.952930470686, 4638.907221855561, 4665.440147182343, 4813.253945638432, 4976.62675299354, 4991.201407774757, 4933.038057578774, 4935.950640493585, 4947.50524947506, 4965.0, 4974.701517908937, 5029.547340739351, 5053.292539044537, 5058.54473097423, 5069.898602027975, 5088.847334579968, 11136.276142050741, 11277.887221127765, 13514.729705405935, 18861.151248193863, 18996.145173467226, 1785.7946336171376, 1846.1230915315252, 1882.3694062489083, 1892.640398324314, 1945.8540609454349, 9099.408538444979, 9156.175944165057, 9551.713448596483, 10349.327293725884, 12769.233845969271, 1728.930842766288, 1730.9307627694877, 1733.3759846455266, 1749.6238308763677, 1806.6838303297002, 53641.0, 54103.0, 54471.72764136193, 54825.0, 55231.44768552303, 9534.427934323963, 9681.08029737188, 9804.754881128029, 9805.80388392228, 9884.011598840058, 12364.19632723888, 12366.19619724715, 12391.690207744878, 12393.504259829686, 12422.689432764253, 7050.753223637148, 7289.307515785992, 7329.157146928065, 7379.557226566423, 7427.442941779388, 7127.572345659277, 7134.143902731706, 7154.105736782903, 7173.390261827803, 7188.460865435113, 4119.423280740699, 4134.276501612206, 4160.958390416057, 4162.313218318944, 4195.958040419618, 1671.4651311580178, 1736.5919009032866, 1741.9912900435418, 1745.2582652372691, 1749.4052022312221, 761.9847603047908, 830.227464363576, 894.7181637784078, 940.036462625814, 1002.6590959073804, 15184.392624294904, 15398.923005385126, 15464.0, 15922.681546369007, 16226.756598651029, 818.4925346285341, 853.249140756143, 864.7708357285325, 874.8294082653948, 883.2492381475694, 8952.377168060684, 9130.81738365229, 9181.0, 9198.126178013212, 9310.627574896927, 311225.0, 316182.0, 317485.0, 318623.0, 318716.0, 64573.677131614044, 67764.6611766938, 69183.65408173027, 69784.0, 69869.0, 1056.915446764257, 1058.8252938265255, 1059.06802014227, 1088.2382332367374, 1093.3330668292335, 14286.785698214328, 14448.349824257723, 14493.78259326132, 14570.562883113524, 14835.332410041357, 31792.364152716353, 32346.514802277514, 32972.83513582417, 33526.66473335236, 34265.31469370649, 40037.599624003386, 40662.1867562653, 40940.0, 41982.370264445446, 42829.0, 8066.556339401389, 8419.36854735885, 8531.914680853111, 8540.0, 8542.0, 9216.815663686823, 9432.95283523578, 9442.197413219954, 9629.903700962901, 9709.009653021256, 8013.398995075509, 8258.25675689191, 9073.183413492818, 9125.269978401844, 9462.337636365388, 8289.253967142746, 8960.641574336954, 9150.359474836698, 9366.066252173967, 9819.018098190123, 9476.763080923032, 9542.761430964281, 9784.9021509784, 9789.363691360039, 9796.65711700087, 14320.283985800459, 14322.0, 14432.494862679756, 14450.638734031734, 14598.029266048947, 14716.852831471548, 14718.779218311947, 14850.777238341218, 15003.624909377353, 15177.241137942838], "unit": "score", "name": "jquery.html", "value": 7834.0067521405135}, {"lowerIsBetter": false, "replicates": [1469.559132260323, 1642.219945525875, 1713.9485815425535, 1726.8014178369492, 1750.68487672219, 6009.57932944694, 6103.359147289537, 6970.268121847209, 7546.471746977713, 7550.282723141299, 6580.243272023718, 6690.0, 6735.562188457747, 6737.056812046318, 6759.560628559152, 2064.5767617638385, 2106.304919376602, 2108.2621082621035, 2112.239593746258, 2124.1928067334393, 2071.564971356017, 2077.6883467479847, 2085.134669112324, 2112.3240563019763, 2117.1531387444993, 2036.2669439001947, 2042.4383294594031, 2094.3716884934533, 2095.5389814240843, 2098.0139334512755, 2000.7098970649247, 2011.175418078588, 2015.3248661698349, 2018.515556266493, 2024.5141166120172, 2021.7169596256535, 2040.2247146084394, 2065.514604068043, 2068.524239424941, 2072.575122099966, 7282.635868206621, 7308.744193953239, 7327.0, 7343.375813055896, 7374.373178279852, 4230.640395566381, 4283.250431174532, 4285.5928686774705, 4299.828006879721, 4305.74165550068, 2756.4073724149202, 2770.2104900103373, 2813.620161278242, 2833.5607980763016, 2842.701516340775, 813.1218284252992, 826.0417915218318, 826.7602395305354, 831.3473922970439, 854.8247609240091, 9939.50302484873, 9947.452890091043, 9971.20230381575, 9990.400575965392, 9992.150667193297, 3842.3852183650483, 3858.5948475409677, 3920.725549211585, 3969.960300397017, 3996.8201430935096, 8524.104968978292, 8553.657853685783, 8555.828883422297, 8580.2706769924, 8611.224989750952, 4368.082702632482, 4372.453443319585, 4391.297392417262, 4397.450318710161, 4399.12017596482, 3244.9351012980082, 3346.9330613388083, 3763.7741735495956, 3931.2334094851803, 3963.801809909493, 3400.608929973035, 3404.897853064412, 3411.880584179542, 3459.41189997701, 3470.9132271692906, 1057.6721216422934, 1058.3491152940885, 1060.4538662588773, 1066.344198318029, 1078.2075174746556, 1198.6164427383328, 1201.2192075151222, 1216.1122380661989, 1217.1236709569096, 1223.5411720604773, 6877.828054298682, 6918.584884906922, 6925.580256047499, 6941.479389045841, 6962.930370696228, 401.0635166885297, 415.5947950748057, 417.4239549421839, 420.690792267683, 421.0064248373835, 5490.478404551562, 5510.669359838423, 5528.474794894479, 5551.028570000234, 5561.193626924154, 8157.75526734199, 8365.163483651708, 8418.158184181655, 8433.620487077973, 8728.214460698568, 8395.412321137585, 8462.449940753831, 8467.0, 8512.70205542803, 8529.74410767678, 6647.767328143492, 6772.2211945625895, 6865.347791959756, 6873.793786186422, 6908.9309106909295, 6939.167299924042, 7015.473839462163, 7018.368346848604, 7024.26245244242, 7026.086608740831, 7785.610719464117, 7932.167122452291, 7937.2459616336355, 7998.160193179632, 8013.599320034092, 7560.886586701094, 7749.3413059890545, 7752.302292793675, 7789.221077892165, 8125.349972002107, 5533.0, 5616.719164041863, 6245.406686364788, 6446.709898054505, 6528.955367141235, 6699.464042876461, 6706.396424321834, 6737.797866064026, 6746.966265168643, 6771.085903403175, 6416.0, 6471.546991710535, 6482.2869484357625, 6605.801825945229, 6661.866762664817, 6232.626042437469, 6253.5622506424115, 6371.840703982438, 6438.742450301936, 6493.350664933658, 6495.350464953467, 6505.0567667688865, 6526.216853977553, 6553.5084868635995, 6554.049662798963, 6028.6985650716415, 6031.728572214349, 6041.818745437644, 6061.9393806063135, 6122.877542449037, 6220.782272620437, 6288.717007734755, 6334.429901308904, 6344.365563443619, 6379.617222966637], "unit": "score", "name": "mootools.html", "value": 4045.0265746920686}, {"lowerIsBetter": false, "replicates": [298.6669863102641, 300.03837700170953, 307.65849906603665, 310.75916164972153, 310.84211312859617, 5510.669359838407, 5575.358833734122, 5576.247206627104, 5669.0646043402785, 5782.392848750884, 5569.081101618238, 5607.57943154264, 5634.126710359901, 5634.521065709409, 5645.774169033234, 4250.426192464009, 4252.14957008598, 4252.425922500462, 4253.298205796055, 4253.425787518676, 4249.957500424987, 4255.425517555122, 4256.1913236485125, 4260.1266740318315, 4267.48790145183, 4126.5667104954, 4189.329707246841, 4196.916061678765, 4214.578542145776, 4235.6187943085115, 4225.957740422587, 4232.492100947906, 4244.320908654631, 4252.787360631972, 4258.744475331475, 3629.4374371972385, 3631.346357655621, 3645.7994810285422, 3650.2881938022097, 3674.503941967827, 5377.8117765878005, 5401.78392864285, 5411.567074634059, 5420.91868621971, 5427.375851777057, 4717.858464246044, 4821.783019764119, 4836.443808961979, 4848.7090774553635, 4865.9026819463415, 5633.436656334334, 5705.4294570543025, 5801.274840644919, 5828.475437210629, 5829.795957141522, 3932.1152740633415, 3937.7440466369594, 3941.5467221269632, 3943.2310699413624, 3949.210157968389, 6077.544184186204, 6145.907811382833, 6166.167567378391, 6166.907496387557, 6171.9691401542705, 4012.3179059559943, 4075.1849630074103, 4108.219438306772, 4120.855770048034, 4150.481189851269, 4125.97937010313, 4139.275626765364, 4180.352045432963, 4186.832526698898, 4191.706580539394, 4812.181929072066, 4829.396325459317, 4834.7824347904425, 4835.371401717754, 4843.4430040545085, 5856.853578660568, 5862.912056319158, 5865.648061116347, 5878.882422351529, 5887.234659494324, 1437.4322142753554, 1450.760624496946, 1482.3477669825243, 1483.0212060040321, 1484.8812095032588, 942.948137852411, 963.1187463470845, 964.9807003859884, 965.6378857928277, 967.0909345215615, 5016.423111342242, 5016.899662006739, 5067.290579318898, 5068.847934561969, 5078.415982162099, 5978.372270911492, 5996.070609055603, 6014.609050411709, 6028.065649824284, 6032.366601506778, 5040.647154699209, 5062.544371006627, 5063.000114954572, 5073.518015788494, 5076.289319495274, 3219.2434777827193, 3233.094733474631, 3266.3630592034324, 3279.0982479817762, 3317.137544238515, 5764.337101233328, 5770.278715160605, 5796.0, 5802.448767367094, 5815.3021637402935, 6288.842778930563, 6328.6835658216905, 6336.17629708135, 6341.936580634135, 6346.904796428057, 5675.858103547444, 5760.279965004374, 5771.249737534094, 5799.101139323411, 5801.332846722681, 6145.231846019247, 6158.0, 6158.538109641884, 6167.629942203482, 6169.2288463942, 5993.910091348721, 5996.730147143302, 5997.0, 6003.219581454383, 6004.39956004396, 5768.625039372428, 5772.884542309046, 5778.16216648592, 5787.913181302368, 5787.987102257004, 4003.8798836034966, 4075.3886916962692, 4108.383742438658, 4109.753414795002, 4116.135611521614, 6002.069679199731, 6132.754689812357, 6134.110553969738, 6140.938590614037, 6146.200993870768, 5852.092925596539, 5891.675957822189, 5901.793437229677, 5903.91144132847, 5910.852228694317, 5848.64908105515, 5853.619514731528, 5854.151148083589, 5858.121281807763, 5858.2677165354335, 5610.887782244251, 5662.8301150965535, 5735.082386818256, 5759.856003599776, 5774.133879918046, 5901.203337549376, 5915.881682366415, 5930.466258036798, 5941.435563621622, 5962.642241465526, 5753.741081651252, 5766.443060373592, 5798.855028624318, 5802.883942321046, 5812.738426770721], "unit": "score", "name": "prototype.html", "value": 4362.667036717136}, {"lowerIsBetter": false, "replicates": [669.0, 761.9542827430354, 773.1070613441474, 780.1295253219522, 799.1489064146683, 11266.887331126687, 11448.255863368875, 11462.083033357332, 11483.966443020137, 11496.137789665767, 11618.941905290461, 11703.414829258545, 11801.291922484657, 13385.866141338583, 13463.394147263372, 806.3186607316808, 818.6684392820918, 818.7543736878943, 819.6803246733779, 820.3396266005863, 611.5382885921123, 631.3465563142149, 778.4006315137341, 814.5560669435172, 814.6415577146066, 845.6828689241535, 847.0301504777027, 852.7143406958645, 854.0946596607616, 854.628236717027, 850.5789634131145, 850.5959669157163, 851.8679604661256, 852.4373913217278, 852.6163226548078, 623.688155922039, 627.1877918096077, 627.5701144715885, 628.0202883501706, 628.7265039707696, 12337.444814983348, 12345.19756215852, 12377.876221237853, 12512.0, 12517.43671534774, 11257.662270131908, 11527.48126334317, 11550.653480395602, 11704.824427633592, 11822.467988940432, 9415.717528474088, 9515.04849515043, 9530.142287194185, 9610.567524461416, 9716.0, 3325.3848038112783, 3360.176756694601, 3370.898873033813, 3379.172102834821, 3392.2537041850755, 40507.189856203004, 41251.0, 41272.587274127174, 41319.586804131875, 41340.966475838046, 9681.128698417177, 9965.80068398628, 9998.400095994264, 10032.598696052077, 10186.592536298613, 16867.072311022766, 16950.237239324015, 17030.318787248372, 17071.658566828595, 17076.146192690318, 6397.296297407282, 6416.326285740023, 6420.646864422408, 6438.549301548846, 6610.636414997125, 3539.911502212465, 3548.1661809474754, 3550.928981420357, 3566.946495802565, 3596.2987217492337, 2612.046602989933, 2633.591793272046, 2640.115561286952, 2641.1944356971308, 2645.2989957661234, 456.2494696224692, 456.6301295950291, 459.5496413514774, 461.87067621066154, 464.846600621794, 511.53194826733125, 539.1535289595377, 540.9309391577233, 594.9107633854957, 599.3137857153534, 7823.452358334862, 8143.6742530298125, 8168.632411541495, 8319.792005199797, 8740.78148046304, 466.5497794628132, 526.2947650148819, 570.4295704295704, 633.7369991453587, 653.496807458264, 3746.033332500459, 3830.540335159799, 3862.8841134766003, 3925.3548811946343, 3925.9018524536546, 49255.261171082464, 49678.503214968114, 49821.501784982414, 49894.0, 49915.75042124766, 45173.0, 45445.0, 45568.3164752529, 45653.31520027202, 45835.77082114568, 41296.38055429171, 41346.79326603408, 41399.1720165601, 41514.792426037675, 41668.791656041525, 7851.607419628882, 7982.920170798217, 8010.319122874388, 8106.189381061847, 8139.918600813916, 31052.378952420375, 31723.0, 32527.0, 33416.49875251922, 35103.71825831313, 38036.42945355766, 38591.228175435775, 38778.22443551169, 39136.804315979374, 39208.0, 10072.294939354468, 10143.442110683984, 10202.38785672862, 10216.642417515352, 10442.843357349799, 11217.0, 11363.31820090764, 11379.715507112058, 11385.772284554097, 11976.16166868311, 10665.9466702666, 10951.616693415692, 11119.27724697892, 11287.887121128684, 11433.59982400612, 10442.791144176921, 10532.262741608014, 10854.565817367216, 10936.453177340942, 11144.275622084539, 10787.0, 10789.352638841694, 10945.945270273598, 11216.607418740305, 11292.322460652387, 9108.407953483002, 9155.404898681565, 9276.860847087164, 9278.95360523193, 9666.323357364918, 8652.826943461221, 8983.505907175168, 9307.348485605942, 9415.67681730344, 9570.234381249622], "unit": "score", "name": "yui.html", "value": 5815.253963631908}], "name": "dromaeo_css", "value": 6284.05462767778}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [118.0, 110.0, 111.0, 112.0, 114.0, 125.0, 113.0, 112.0, 115.0, 113.0], "unit": "score", "name": "ai-astar", "value": 114.3}, {"lowerIsBetter": true, "replicates": [162.0, 134.0, 158.0, 167.0, 131.0, 168.0, 154.0, 135.0, 165.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 150.5}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 164.0, 163.0, 164.0, 166.0, 165.0, 163.0, 166.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.8}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 74.0, 81.0, 133.0, 73.0, 73.0, 72.0, 78.0, 87.0], "unit": "score", "name": "audio-fft", "value": 81.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 128.0, 94.0, 94.0, 94.0, 129.0, 95.0, 94.0, 133.0], "unit": "score", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [111.0, 111.0, 110.0, 110.0, 109.0, 110.0, 111.0, 110.0, 111.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.4}, {"lowerIsBetter": true, "replicates": [147.0, 148.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 149.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.6}, {"lowerIsBetter": true, "replicates": [98.0, 97.0, 96.0, 96.0, 96.0, 95.0, 101.0, 97.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.8}, {"lowerIsBetter": true, "replicates": [74.0, 72.0, 73.0, 74.0, 72.0, 72.0, 75.0, 79.0, 70.0, 70.0], "unit": "score", "name": "json-parse-financial", "value": 73.1}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.2}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 85.0, 93.0, 84.0, 85.0, 83.0, 87.0, 87.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.7}, {"lowerIsBetter": true, "replicates": [131.0, 121.0, 128.0, 123.0, 131.0, 121.0, 124.0, 131.0, 128.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.6}, {"lowerIsBetter": true, "replicates": [143.0, 148.0, 152.0, 146.0, 149.0, 136.0, 149.0, 151.0, 149.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.0}, {"lowerIsBetter": true, "replicates": [72.0, 70.0, 73.0, 70.0, 71.0, 71.0, 69.0, 70.0, 75.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.9}], "name": "kraken", "value": 1540.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.333998669328], "unit": "score", "name": "Richards", "value": 212.23562399999997}, {"lowerIsBetter": false, "replicates": [185.56318426424198], "unit": "score", "name": "DeltaBlue", "value": 356.309902}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5148.717948717948], "unit": "score", "name": "Decrypt", "value": 51.69850099601596}, {"lowerIsBetter": false, "replicates": [1077.5862068965516], "unit": "score", "name": "RayTrace", "value": 686.7097920000001}, {"lowerIsBetter": false, "replicates": [727.802037845706], "unit": "score", "name": "Earley", "value": 915.720162}, {"lowerIsBetter": false, "replicates": [12204.819277108434], "unit": "score", "name": "Boyer", "value": 54.606543928923976}, {"lowerIsBetter": false, "replicates": [26230.76923076923], "unit": "score", "name": "RegExp", "value": 34.729633431085055}, {"lowerIsBetter": false, "replicates": [590.318772136954], "unit": "score", "name": "Splay", "value": 138.04575399999996}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21340.324146312363}]} 17:38:26 INFO - RETURN: dromaeo_css: 6284.06 17:38:26 INFO - RETURN: kraken: 1540.90 17:38:26 INFO - RETURN: v8_7: 21340.32 17:38:26 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21340.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1540.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6284.06"}}} 17:38:26 INFO - Return code: 0 17:38:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:38:26 INFO - # TBPL SUCCESS # 17:38:26 INFO - Running post-action listener: _resource_record_post_action 17:38:26 INFO - Running post-run listener: _resource_record_post_run 17:38:27 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 28672; Write bytes: 505843712; Read time: 148; Write time: 496732 17:38:27 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 231378944; Read time: 72; Write time: 298328 17:38:27 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 24576; Write bytes: 274104320; Read time: 76; Write time: 198368 17:38:27 INFO - Running post-run listener: _upload_blobber_files 17:38:27 INFO - Blob upload gear active. 17:38:27 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 17:38:27 INFO - Copying logs to upload dir... 17:38:27 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.316969 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-15 17:38:27.088948) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 17:38:27.092932) ========= 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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009947 build_url: 'https://queue.taskcluster.net/v1/task/OfypD-URR0eoY1Te8noNrA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 17:38:27.145970) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:38:27.146411) ========= 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=6ef107882637bc5fc02d78b7000001c2-1450228637.926333-1972572235 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 17:38:27.196559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 17:38:27.196906) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 17:38:27.197251) ========= ========= Total master_lag: 0.23 =========