builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-030 starttime: 1448628753.6 results: success (0) buildid: 20151127035613 builduid: 2ef2170d6a8b435682faf05e4839195c revision: 2a711953a62c00ee3ddc753592ccffac7876c966 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.604198) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.604588) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.604913) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006488 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.647979) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.648241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.671555) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.671865) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004657 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.721917) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.722174) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.722519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.722850) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-27 04:52:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 137M=0s 2015-11-27 04:52:33 (137 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.197161 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.937768) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.938073) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017596 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.992976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:33.993296) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2a711953a62c00ee3ddc753592ccffac7876c966 --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 2a711953a62c00ee3ddc753592ccffac7876c966 --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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-27 04:52:34,003 truncating revision to first 12 chars 2015-11-27 04:52:34,003 Setting DEBUG logging. 2015-11-27 04:52:34,003 attempt 1/10 2015-11-27 04:52:34,003 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2a711953a62c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-27 04:52:34,247 unpacking tar archive at: fx-team-2a711953a62c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.525414 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:34.533319) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:34.533584) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:34.557992) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:34.558220) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-27 04:52:34.558586) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-27 04:52:34.558859) ========= /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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:52:34 INFO - MultiFileLogger online at 20151127 04:52:34 in /builds/slave/test 04:52:34 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 04:52:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:34 INFO - {'append_to_log': False, 04:52:34 INFO - 'base_work_dir': '/builds/slave/test', 04:52:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:52:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:34 INFO - 'branch': 'Fx-Team-Non-PGO', 04:52:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:34 INFO - 'config_files': ('talos/linux_config.py',), 04:52:34 INFO - 'default_actions': ('clobber', 04:52:34 INFO - 'read-buildbot-config', 04:52:34 INFO - 'download-and-extract', 04:52:34 INFO - 'populate-webroot', 04:52:34 INFO - 'create-virtualenv', 04:52:34 INFO - 'install', 04:52:34 INFO - 'run-tests'), 04:52:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:34 INFO - 'download_minidump_stackwalk': True, 04:52:34 INFO - 'download_symbols': 'ondemand', 04:52:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:34 INFO - 'installer_path': 'installer.exe', 04:52:34 INFO - 'log_level': 'info', 04:52:34 INFO - 'log_name': 'talos', 04:52:34 INFO - 'log_to_console': True, 04:52:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:52:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:52:34 INFO - 'opt_config_files': (), 04:52:34 INFO - 'pip_index': False, 04:52:34 INFO - 'sps_profile': False, 04:52:34 INFO - 'sps_profile_interval': 0, 04:52:34 INFO - 'suite': 'dromaeojs-e10s', 04:52:34 INFO - 'system_bits': '64', 04:52:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:34 INFO - 'title': 'talos-linux64-ix-030', 04:52:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:34 INFO - 'use_talos_json': True, 04:52:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:34 INFO - 'work_dir': 'build'} 04:52:34 INFO - ##### 04:52:34 INFO - ##### Running clobber step. 04:52:34 INFO - ##### 04:52:34 INFO - Running pre-action listener: _resource_record_pre_action 04:52:34 INFO - Running main action method: clobber 04:52:34 INFO - rmtree: /builds/slave/test/build 04:52:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:35 INFO - Running post-action listener: _resource_record_post_action 04:52:35 INFO - ##### 04:52:35 INFO - ##### Running read-buildbot-config step. 04:52:35 INFO - ##### 04:52:35 INFO - Running pre-action listener: _resource_record_pre_action 04:52:35 INFO - Running main action method: read_buildbot_config 04:52:35 INFO - Using buildbot properties: 04:52:35 INFO - { 04:52:35 INFO - "properties": { 04:52:35 INFO - "buildnumber": 469, 04:52:35 INFO - "product": "firefox", 04:52:35 INFO - "script_repo_revision": "production", 04:52:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 04:52:35 INFO - "repository": "", 04:52:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 04:52:35 INFO - "buildid": "20151127035613", 04:52:35 INFO - "slavename": "talos-linux64-ix-030", 04:52:35 INFO - "pgo_build": "False", 04:52:35 INFO - "basedir": "/builds/slave/test", 04:52:35 INFO - "project": "", 04:52:35 INFO - "platform": "ubuntu64_hw", 04:52:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:52:35 INFO - "slavebuilddir": "test", 04:52:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:52:35 INFO - "branch": "fx-team", 04:52:35 INFO - "repo_path": "integration/fx-team", 04:52:35 INFO - "stage_platform": "linux64", 04:52:35 INFO - "builduid": "2ef2170d6a8b435682faf05e4839195c", 04:52:35 INFO - "revision": "2a711953a62c00ee3ddc753592ccffac7876c966" 04:52:35 INFO - }, 04:52:35 INFO - "sourcestamp": { 04:52:35 INFO - "repository": "", 04:52:35 INFO - "hasPatch": false, 04:52:35 INFO - "project": "", 04:52:35 INFO - "branch": "fx-team-linux64-talos", 04:52:35 INFO - "changes": [ 04:52:35 INFO - { 04:52:35 INFO - "category": null, 04:52:35 INFO - "files": [ 04:52:35 INFO - { 04:52:35 INFO - "url": null, 04:52:35 INFO - "name": "https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 04:52:35 INFO - } 04:52:35 INFO - ], 04:52:35 INFO - "repository": "", 04:52:35 INFO - "rev": "2a711953a62c00ee3ddc753592ccffac7876c966", 04:52:35 INFO - "who": "s.kaspari@gmail.com", 04:52:35 INFO - "when": 1448628701, 04:52:35 INFO - "number": 6714686, 04:52:35 INFO - "comments": "Bug 1189336 - (Part 4) Migrate old restrictions if needed. r=ally", 04:52:35 INFO - "project": "", 04:52:35 INFO - "at": "Fri 27 Nov 2015 04:51:41", 04:52:35 INFO - "branch": "fx-team-linux64-talos", 04:52:35 INFO - "revlink": "", 04:52:35 INFO - "properties": [ 04:52:35 INFO - [ 04:52:35 INFO - "buildid", 04:52:35 INFO - "20151127035613", 04:52:35 INFO - "Change" 04:52:35 INFO - ], 04:52:35 INFO - [ 04:52:35 INFO - "builduid", 04:52:35 INFO - "2ef2170d6a8b435682faf05e4839195c", 04:52:35 INFO - "Change" 04:52:35 INFO - ], 04:52:35 INFO - [ 04:52:35 INFO - "pgo_build", 04:52:35 INFO - "False", 04:52:35 INFO - "Change" 04:52:35 INFO - ] 04:52:35 INFO - ], 04:52:35 INFO - "revision": "2a711953a62c00ee3ddc753592ccffac7876c966" 04:52:35 INFO - } 04:52:35 INFO - ], 04:52:35 INFO - "revision": "2a711953a62c00ee3ddc753592ccffac7876c966" 04:52:35 INFO - } 04:52:35 INFO - } 04:52:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 04:52:35 INFO - Running post-action listener: _resource_record_post_action 04:52:35 INFO - ##### 04:52:35 INFO - ##### Running download-and-extract step. 04:52:35 INFO - ##### 04:52:35 INFO - Running pre-action listener: _resource_record_pre_action 04:52:35 INFO - Running main action method: download_and_extract 04:52:35 INFO - mkdir: /builds/slave/test/build/tests 04:52:35 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')]} 04:52:35 INFO - https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:52:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/test_packages.json 04:52:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/test_packages.json 04:52:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:52:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:52:40 INFO - Downloaded 1302 bytes. 04:52:40 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:52:40 INFO - Using the following test package requirements: 04:52:40 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:52:40 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:52:40 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'jsshell-linux-x86_64.zip'], 04:52:40 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:52:40 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:52:40 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:52:40 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:52:40 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:52:40 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:52:40 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:40 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:52:40 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:52: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')]} 04:52:40 INFO - https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:52:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:52:44 INFO - Downloaded 21517181 bytes. 04:52:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:52:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:52:45 INFO - Return code: 0 04:52:45 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:45 INFO - https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:52:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:52:48 INFO - Downloaded 21517181 bytes. 04:52:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:52:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:52:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:52:49 INFO - Return code: 0 04:52: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')]} 04:52:49 INFO - https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:52:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:52:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:52:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:52:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:52:50 INFO - Downloaded 11131728 bytes. 04:52:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:52:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:52:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:52:50 INFO - Return code: 0 04:52:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:50 INFO - https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:52:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:52:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:52:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:52:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:52:52 INFO - Downloaded 56872241 bytes. 04:52:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:52:52 INFO - mkdir: /builds/slave/test/properties 04:52:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:52:52 INFO - Writing to file /builds/slave/test/properties/build_url 04:52:52 INFO - Contents: 04:52:52 INFO - build_url:https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:52:52 INFO - Running post-action listener: _resource_record_post_action 04:52:52 INFO - Running post-action listener: set_extra_try_arguments 04:52:52 INFO - ##### 04:52:52 INFO - ##### Running populate-webroot step. 04:52:52 INFO - ##### 04:52:52 INFO - Running pre-action listener: _resource_record_pre_action 04:52:52 INFO - Running main action method: populate_webroot 04:52:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:52:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:52:52 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 04:52:52 INFO - '../fennec_ids.txt'], 04:52:52 INFO - 'tests': ['tcheck2']}, 04:52:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:52:52 INFO - '--tppagecycles', 04:52:52 INFO - '7'], 04:52:52 INFO - 'tests': ['tsvgm']}}, 04:52:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:52:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:52:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:52:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:52:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'tests': ['damp', 'tps']}, 04:52:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['damp', 'tps']}, 04:52:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:52:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:52:52 INFO - 'other': {'tests': ['a11yr', 04:52:52 INFO - 'ts_paint', 04:52:52 INFO - 'tpaint', 04:52:52 INFO - 'sessionrestore', 04:52:52 INFO - 'sessionrestore_no_auto_restore']}, 04:52:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['a11yr', 04:52:52 INFO - 'ts_paint', 04:52:52 INFO - 'tpaint', 04:52:52 INFO - 'sessionrestore', 04:52:52 INFO - 'sessionrestore_no_auto_restore']}, 04:52:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['a11yr', 04:52:52 INFO - 'ts_paint', 04:52:52 INFO - 'tpaint', 04:52:52 INFO - 'sessionrestore', 04:52:52 INFO - 'sessionrestore_no_auto_restore']}, 04:52:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['a11yr', 04:52:52 INFO - 'ts_paint', 04:52:52 INFO - 'tpaint', 04:52:52 INFO - 'sessionrestore', 04:52:52 INFO - 'sessionrestore_no_auto_restore']}, 04:52:52 INFO - 'other_l64': {'tests': ['a11yr', 04:52:52 INFO - 'ts_paint', 04:52:52 INFO - 'tpaint', 04:52:52 INFO - 'sessionrestore', 04:52:52 INFO - 'sessionrestore_no_auto_restore']}, 04:52:52 INFO - 'other_nol64': {'tests': ['a11yr', 04:52:52 INFO - 'ts_paint', 04:52:52 INFO - 'tpaint', 04:52:52 INFO - 'sessionrestore', 04:52:52 INFO - 'sessionrestore_no_auto_restore']}, 04:52:52 INFO - 'svgr': {'tests': ['tsvgx', 04:52:52 INFO - 'tsvgr_opacity', 04:52:52 INFO - 'tart', 04:52:52 INFO - 'tscrollx', 04:52:52 INFO - 'cart']}, 04:52:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['tsvgx', 04:52:52 INFO - 'tsvgr_opacity', 04:52:52 INFO - 'tart', 04:52:52 INFO - 'tscrollx', 04:52:52 INFO - 'cart']}, 04:52:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'tests': ['tp5o']}, 04:52:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'talos_options': ['--e10s'], 04:52:52 INFO - 'tests': ['tp5o']}, 04:52:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'talos_options': ['--xperf_path', 04:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:52 INFO - 'tests': ['tp5n']}, 04:52:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:52 INFO - 'talos_options': ['--e10s', 04:52:52 INFO - '--xperf_path', 04:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:52 INFO - 'tests': ['tp5n']}}, 04:52:52 INFO - 'talos.zip': {'path': '', 04:52:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:52:52 INFO - Running post-action listener: _resource_record_post_action 04:52:52 INFO - ##### 04:52:52 INFO - ##### Running create-virtualenv step. 04:52:52 INFO - ##### 04:52:52 INFO - Running pre-action listener: _resource_record_pre_action 04:52:52 INFO - Running main action method: create_virtualenv 04:52:52 INFO - Creating virtualenv /builds/slave/test/build/venv 04:52:52 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 04:52:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:52:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:52 INFO - Using real prefix '/usr' 04:52:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:52 INFO - Installing distribute.............................................................................................................................................................................................done. 04:52:54 INFO - Installing pip.................done. 04:52:54 INFO - Return code: 0 04:52:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:54 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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:52:54 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 04:52:54 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 04:52:54 INFO - Using env: {'DISPLAY': ':0', 04:52:54 INFO - 'HOME': '/home/cltbld', 04:52:54 INFO - 'LANG': 'en_US.UTF-8', 04:52:54 INFO - 'LANGUAGE': 'en_US:en', 04:52:54 INFO - 'LOGNAME': 'cltbld', 04:52:54 INFO - 'MAIL': '/var/mail/cltbld', 04:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:54 INFO - 'MOZ_NO_REMOTE': '1', 04:52:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:54 INFO - 'NO_EM_RESTART': '1', 04:52:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:54 INFO - 'PWD': '/builds/slave/test', 04:52:54 INFO - 'SHELL': '/bin/bash', 04:52:54 INFO - 'SHLVL': '1', 04:52:54 INFO - 'TERM': 'linux', 04:52:54 INFO - 'TMOUT': '86400', 04:52:54 INFO - 'USER': 'cltbld', 04:52:54 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:54 INFO - '_': '/tools/buildbot/bin/python'} 04:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:54 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 04:52:54 INFO - Cleaning up... 04:52:54 INFO - Return code: 0 04:52:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:52:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:52:55 INFO - Using env: {'DISPLAY': ':0', 04:52:55 INFO - 'HOME': '/home/cltbld', 04:52:55 INFO - 'LANG': 'en_US.UTF-8', 04:52:55 INFO - 'LANGUAGE': 'en_US:en', 04:52:55 INFO - 'LOGNAME': 'cltbld', 04:52:55 INFO - 'MAIL': '/var/mail/cltbld', 04:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:55 INFO - 'MOZ_NO_REMOTE': '1', 04:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:55 INFO - 'NO_EM_RESTART': '1', 04:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:55 INFO - 'PWD': '/builds/slave/test', 04:52:55 INFO - 'SHELL': '/bin/bash', 04:52:55 INFO - 'SHLVL': '1', 04:52:55 INFO - 'TERM': 'linux', 04:52:55 INFO - 'TMOUT': '86400', 04:52:55 INFO - 'USER': 'cltbld', 04:52:55 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:55 INFO - '_': '/tools/buildbot/bin/python'} 04:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:55 INFO - Downloading/unpacking psutil>=0.7.1 04:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:56 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:52:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:52:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:57 INFO - Installing collected packages: psutil 04:52:57 INFO - Running setup.py install for psutil 04:52:57 INFO - building 'psutil._psutil_linux' extension 04:52:57 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 04:52:57 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 04:52:57 INFO - building 'psutil._psutil_posix' extension 04:52:57 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 04:52:57 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 04:52:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:57 INFO - Successfully installed psutil 04:52:57 INFO - Cleaning up... 04:52:57 INFO - Return code: 0 04:52:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:52:57 INFO - Using env: {'DISPLAY': ':0', 04:52:57 INFO - 'HOME': '/home/cltbld', 04:52:57 INFO - 'LANG': 'en_US.UTF-8', 04:52:57 INFO - 'LANGUAGE': 'en_US:en', 04:52:57 INFO - 'LOGNAME': 'cltbld', 04:52:57 INFO - 'MAIL': '/var/mail/cltbld', 04:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:57 INFO - 'MOZ_NO_REMOTE': '1', 04:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:57 INFO - 'NO_EM_RESTART': '1', 04:52:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:57 INFO - 'PWD': '/builds/slave/test', 04:52:57 INFO - 'SHELL': '/bin/bash', 04:52:57 INFO - 'SHLVL': '1', 04:52:57 INFO - 'TERM': 'linux', 04:52:57 INFO - 'TMOUT': '86400', 04:52:57 INFO - 'USER': 'cltbld', 04:52:57 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:57 INFO - '_': '/tools/buildbot/bin/python'} 04:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:52:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:52:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:52:59 INFO - Installing collected packages: mozsystemmonitor 04:52:59 INFO - Running setup.py install for mozsystemmonitor 04:52:59 INFO - Successfully installed mozsystemmonitor 04:52:59 INFO - Cleaning up... 04:52:59 INFO - Return code: 0 04:52:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:59 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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:52:59 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 04:52:59 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 04:52:59 INFO - Using env: {'DISPLAY': ':0', 04:52:59 INFO - 'HOME': '/home/cltbld', 04:52:59 INFO - 'LANG': 'en_US.UTF-8', 04:52:59 INFO - 'LANGUAGE': 'en_US:en', 04:52:59 INFO - 'LOGNAME': 'cltbld', 04:52:59 INFO - 'MAIL': '/var/mail/cltbld', 04:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:59 INFO - 'MOZ_NO_REMOTE': '1', 04:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:59 INFO - 'NO_EM_RESTART': '1', 04:52:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:59 INFO - 'PWD': '/builds/slave/test', 04:52:59 INFO - 'SHELL': '/bin/bash', 04:52:59 INFO - 'SHLVL': '1', 04:52:59 INFO - 'TERM': 'linux', 04:52:59 INFO - 'TMOUT': '86400', 04:52:59 INFO - 'USER': 'cltbld', 04:52:59 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:59 INFO - '_': '/tools/buildbot/bin/python'} 04:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:59 INFO - Downloading/unpacking blobuploader==1.2.4 04:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:01 INFO - Downloading blobuploader-1.2.4.tar.gz 04:53:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:53:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:53:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 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 04:53:01 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 04:53:02 INFO - Downloading docopt-0.6.1.tar.gz 04:53:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:53:02 INFO - Installing collected packages: blobuploader, requests, docopt 04:53:02 INFO - Running setup.py install for blobuploader 04:53:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:53:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:53:02 INFO - Running setup.py install for requests 04:53:02 INFO - Running setup.py install for docopt 04:53:02 INFO - Successfully installed blobuploader requests docopt 04:53:02 INFO - Cleaning up... 04:53:02 INFO - Return code: 0 04:53:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:02 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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:53:02 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 04:53:02 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 04:53:02 INFO - Using env: {'DISPLAY': ':0', 04:53:02 INFO - 'HOME': '/home/cltbld', 04:53:02 INFO - 'LANG': 'en_US.UTF-8', 04:53:02 INFO - 'LANGUAGE': 'en_US:en', 04:53:02 INFO - 'LOGNAME': 'cltbld', 04:53:02 INFO - 'MAIL': '/var/mail/cltbld', 04:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:02 INFO - 'MOZ_NO_REMOTE': '1', 04:53:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:02 INFO - 'NO_EM_RESTART': '1', 04:53:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:02 INFO - 'PWD': '/builds/slave/test', 04:53:02 INFO - 'SHELL': '/bin/bash', 04:53:02 INFO - 'SHLVL': '1', 04:53:02 INFO - 'TERM': 'linux', 04:53:02 INFO - 'TMOUT': '86400', 04:53:02 INFO - 'USER': 'cltbld', 04:53:02 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:02 INFO - '_': '/tools/buildbot/bin/python'} 04:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:03 INFO - Running setup.py (path:/tmp/pip-16ivOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:03 INFO - Running setup.py (path:/tmp/pip-3mbTV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:03 INFO - Running setup.py (path:/tmp/pip-cjJp_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:03 INFO - Running setup.py (path:/tmp/pip-C6wgAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:03 INFO - Running setup.py (path:/tmp/pip-WAJ09b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:03 INFO - Running setup.py (path:/tmp/pip-uuZ0w8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:03 INFO - Running setup.py (path:/tmp/pip-XA1JfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:03 INFO - Running setup.py (path:/tmp/pip-UbbTVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:03 INFO - Running setup.py (path:/tmp/pip-7oj_YE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:03 INFO - Running setup.py (path:/tmp/pip-VVxH6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:03 INFO - Running setup.py (path:/tmp/pip-9jzyNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:03 INFO - Running setup.py (path:/tmp/pip-fTEB9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:03 INFO - Running setup.py (path:/tmp/pip-M8avFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:03 INFO - Running setup.py (path:/tmp/pip-WAHfWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:04 INFO - Running setup.py (path:/tmp/pip-0kQvzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:04 INFO - Running setup.py (path:/tmp/pip-vOsXEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:04 INFO - Running setup.py (path:/tmp/pip-GRw_RD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:53:04 INFO - Running setup.py install for manifestparser 04:53:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:53:04 INFO - Running setup.py install for mozcrash 04:53:04 INFO - Running setup.py install for mozdebug 04:53:04 INFO - Running setup.py install for mozdevice 04:53:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:53:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:53:04 INFO - Running setup.py install for mozfile 04:53:04 INFO - Running setup.py install for mozhttpd 04:53:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:53:04 INFO - Running setup.py install for mozinfo 04:53:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:53:04 INFO - Running setup.py install for mozInstall 04:53:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Running setup.py install for mozleak 04:53:05 INFO - Running setup.py install for mozlog 04:53:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Running setup.py install for moznetwork 04:53:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Running setup.py install for mozprocess 04:53:05 INFO - Running setup.py install for mozprofile 04:53:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Running setup.py install for mozrunner 04:53:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Running setup.py install for mozscreenshot 04:53:05 INFO - Running setup.py install for moztest 04:53:05 INFO - Running setup.py install for mozversion 04:53:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:53:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:53:06 INFO - Cleaning up... 04:53:06 INFO - Return code: 0 04:53:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:06 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')]} 04:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:06 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')]} 04:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:06 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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:53:06 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 04:53:06 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 04:53:06 INFO - Using env: {'DISPLAY': ':0', 04:53:06 INFO - 'HOME': '/home/cltbld', 04:53:06 INFO - 'LANG': 'en_US.UTF-8', 04:53:06 INFO - 'LANGUAGE': 'en_US:en', 04:53:06 INFO - 'LOGNAME': 'cltbld', 04:53:06 INFO - 'MAIL': '/var/mail/cltbld', 04:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:06 INFO - 'MOZ_NO_REMOTE': '1', 04:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:06 INFO - 'NO_EM_RESTART': '1', 04:53:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:06 INFO - 'PWD': '/builds/slave/test', 04:53:06 INFO - 'SHELL': '/bin/bash', 04:53:06 INFO - 'SHLVL': '1', 04:53:06 INFO - 'TERM': 'linux', 04:53:06 INFO - 'TMOUT': '86400', 04:53:06 INFO - 'USER': 'cltbld', 04:53:06 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:06 INFO - '_': '/tools/buildbot/bin/python'} 04:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:06 INFO - Running setup.py (path:/tmp/pip-uQE4E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:06 INFO - Running setup.py (path:/tmp/pip-_0E_Gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:06 INFO - Running setup.py (path:/tmp/pip-y4dJQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:06 INFO - Running setup.py (path:/tmp/pip-cLOK1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:06 INFO - Running setup.py (path:/tmp/pip-iXLniY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:06 INFO - Running setup.py (path:/tmp/pip-BxM2sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:06 INFO - Running setup.py (path:/tmp/pip-7X8dO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:06 INFO - Running setup.py (path:/tmp/pip-AOJQmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:06 INFO - Running setup.py (path:/tmp/pip-rbMhQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:06 INFO - Running setup.py (path:/tmp/pip-0zjp9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:06 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)) 04:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:06 INFO - Running setup.py (path:/tmp/pip-HwCYZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:07 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)) 04:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:07 INFO - Running setup.py (path:/tmp/pip-pG4rrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:07 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)) 04:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:07 INFO - Running setup.py (path:/tmp/pip-ykEwa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:07 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)) 04:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:07 INFO - Running setup.py (path:/tmp/pip-mRQgkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:07 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)) 04:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:07 INFO - Running setup.py (path:/tmp/pip-Z5vDKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:07 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)) 04:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:07 INFO - Running setup.py (path:/tmp/pip-4bA4pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:07 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)) 04:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:07 INFO - Running setup.py (path:/tmp/pip-oG2CQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:07 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)) 04:53:07 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)) 04:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:07 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)) 04:53:07 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)) 04:53:07 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)) 04:53:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:09 INFO - Downloading blessings-1.5.1.tar.gz 04:53:09 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 04:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:53:09 INFO - Installing collected packages: blessings 04:53:09 INFO - Running setup.py install for blessings 04:53:09 INFO - Successfully installed blessings 04:53:09 INFO - Cleaning up... 04:53:09 INFO - Return code: 0 04:53:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:09 INFO - Reading from file tmpfile_stdout 04:53:09 INFO - Current package versions: 04:53:09 INFO - argparse == 1.2.1 04:53:09 INFO - blessings == 1.5.1 04:53:09 INFO - blobuploader == 1.2.4 04:53:09 INFO - docopt == 0.6.1 04:53:09 INFO - manifestparser == 1.1 04:53:09 INFO - mozInstall == 1.12 04:53:09 INFO - mozcrash == 0.16 04:53:09 INFO - mozdebug == 0.1 04:53:09 INFO - mozdevice == 0.47 04:53:09 INFO - mozfile == 1.2 04:53:09 INFO - mozhttpd == 0.7 04:53:09 INFO - mozinfo == 0.9 04:53:09 INFO - mozleak == 0.1 04:53:09 INFO - mozlog == 3.1 04:53:09 INFO - moznetwork == 0.27 04:53:09 INFO - mozprocess == 0.22 04:53:09 INFO - mozprofile == 0.27 04:53:09 INFO - mozrunner == 6.11 04:53:09 INFO - mozscreenshot == 0.1 04:53:09 INFO - mozsystemmonitor == 0.0 04:53:09 INFO - moztest == 0.7 04:53:09 INFO - mozversion == 1.4 04:53:09 INFO - psutil == 3.1.1 04:53:09 INFO - requests == 1.2.3 04:53:09 INFO - wsgiref == 0.1.2 04:53:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:53:09 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 04:53:09 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 04:53:09 INFO - Using env: {'DISPLAY': ':0', 04:53:09 INFO - 'HOME': '/home/cltbld', 04:53:09 INFO - 'LANG': 'en_US.UTF-8', 04:53:09 INFO - 'LANGUAGE': 'en_US:en', 04:53:09 INFO - 'LOGNAME': 'cltbld', 04:53:09 INFO - 'MAIL': '/var/mail/cltbld', 04:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:09 INFO - 'MOZ_NO_REMOTE': '1', 04:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:09 INFO - 'NO_EM_RESTART': '1', 04:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:09 INFO - 'PWD': '/builds/slave/test', 04:53:09 INFO - 'SHELL': '/bin/bash', 04:53:09 INFO - 'SHLVL': '1', 04:53:09 INFO - 'TERM': 'linux', 04:53:09 INFO - 'TMOUT': '86400', 04:53:09 INFO - 'USER': 'cltbld', 04:53:09 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:09 INFO - '_': '/tools/buildbot/bin/python'} 04:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 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)) 04:53:09 INFO - Cleaning up... 04:53:09 INFO - Return code: 0 04:53:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:53:09 INFO - Using env: {'DISPLAY': ':0', 04:53:09 INFO - 'HOME': '/home/cltbld', 04:53:09 INFO - 'LANG': 'en_US.UTF-8', 04:53:09 INFO - 'LANGUAGE': 'en_US:en', 04:53:09 INFO - 'LOGNAME': 'cltbld', 04:53:09 INFO - 'MAIL': '/var/mail/cltbld', 04:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:09 INFO - 'MOZ_NO_REMOTE': '1', 04:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:09 INFO - 'NO_EM_RESTART': '1', 04:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:09 INFO - 'PWD': '/builds/slave/test', 04:53:09 INFO - 'SHELL': '/bin/bash', 04:53:09 INFO - 'SHLVL': '1', 04:53:09 INFO - 'TERM': 'linux', 04:53:09 INFO - 'TMOUT': '86400', 04:53:09 INFO - 'USER': 'cltbld', 04:53:09 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:09 INFO - '_': '/tools/buildbot/bin/python'} 04:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:10 INFO - Downloading/unpacking jsonschema 04:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:11 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 04:53:11 INFO - Downloading/unpacking functools32 (from jsonschema) 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:11 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:11 INFO - warning: no files found matching '*.txt' 04:53:11 INFO - no previously-included directories found matching 'build' 04:53:11 INFO - no previously-included directories found matching 'dist' 04:53:11 INFO - no previously-included directories found matching '.git*' 04:53:11 INFO - Installing collected packages: jsonschema, functools32 04:53:12 INFO - Running setup.py install for functools32 04:53:12 INFO - warning: no files found matching '*.txt' 04:53:12 INFO - no previously-included directories found matching 'build' 04:53:12 INFO - no previously-included directories found matching 'dist' 04:53:12 INFO - no previously-included directories found matching '.git*' 04:53:12 INFO - Successfully installed jsonschema functools32 04:53:12 INFO - Cleaning up... 04:53:12 INFO - Return code: 0 04:53:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:53:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:12 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 0x7fee12abdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10348a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, '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': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', '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 04:53:12 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 04:53:12 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 04:53:12 INFO - Using env: {'DISPLAY': ':0', 04:53:12 INFO - 'HOME': '/home/cltbld', 04:53:12 INFO - 'LANG': 'en_US.UTF-8', 04:53:12 INFO - 'LANGUAGE': 'en_US:en', 04:53:12 INFO - 'LOGNAME': 'cltbld', 04:53:12 INFO - 'MAIL': '/var/mail/cltbld', 04:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:12 INFO - 'MOZ_NO_REMOTE': '1', 04:53:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:12 INFO - 'NO_EM_RESTART': '1', 04:53:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:12 INFO - 'PWD': '/builds/slave/test', 04:53:12 INFO - 'SHELL': '/bin/bash', 04:53:12 INFO - 'SHLVL': '1', 04:53:12 INFO - 'TERM': 'linux', 04:53:12 INFO - 'TMOUT': '86400', 04:53:12 INFO - 'USER': 'cltbld', 04:53:12 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:12 INFO - '_': '/tools/buildbot/bin/python'} 04:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:12 INFO - Downloading/unpacking flake8 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:53:14 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 04:53:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:53:14 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 04:53:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 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 04:53:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:14 INFO - no previously-included directories found matching 'docs/_build' 04:53:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:53:14 INFO - Running setup.py install for pep8 04:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:14 INFO - no previously-included directories found matching 'docs/_build' 04:53:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:53:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:53:14 INFO - Cleaning up... 04:53:14 INFO - Return code: 0 04:53:14 INFO - Running post-action listener: _resource_record_post_action 04:53:14 INFO - Running post-action listener: _start_resource_monitoring 04:53:14 INFO - Starting resource monitoring. 04:53:14 INFO - ##### 04:53:14 INFO - ##### Running install step. 04:53:14 INFO - ##### 04:53:14 INFO - Running pre-action listener: _resource_record_pre_action 04:53:14 INFO - Running main action method: install 04:53:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:15 INFO - Reading from file tmpfile_stdout 04:53:15 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:15 INFO - Reading from file tmpfile_stdout 04:53:15 INFO - Output received: 04:53:15 INFO - Usage: mozinstall [options] installer 04:53:15 INFO - Options: 04:53:15 INFO - -h, --help show this help message and exit 04:53:15 INFO - -d DEST, --destination=DEST 04:53:15 INFO - Directory to install application into. [default: 04:53:15 INFO - "/builds/slave/test"] 04:53:15 INFO - --app=APP Application being installed. [default: firefox] 04:53:15 INFO - mkdir: /builds/slave/test/build/application 04:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:53:29 INFO - Reading from file tmpfile_stdout 04:53:29 INFO - Output received: 04:53:29 INFO - /builds/slave/test/build/application/firefox/firefox 04:53:29 INFO - Running post-action listener: _resource_record_post_action 04:53:29 INFO - ##### 04:53:29 INFO - ##### Running run-tests step. 04:53:29 INFO - ##### 04:53:29 INFO - Running pre-action listener: _resource_record_pre_action 04:53:29 INFO - Running main action method: run_tests 04:53:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:53:29 INFO - Python 2.7.3 04:53:29 INFO - Return code: 0 04:53:29 INFO - grabbing minidump binary from tooltool 04:53:29 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')]} 04:53:29 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 0x11910b0>, '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 0x1191fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1192440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:53:29 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 04:53:29 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 04:53:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:53:29 INFO - Return code: 0 04:53:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:53:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:53:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:53:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:53:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:53:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:53:30 INFO - Return code: 0 04:53:30 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/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] in /builds/slave/test/build 04:53:30 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/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost 04:53:30 INFO - Using env: {'DISPLAY': ':0', 04:53:30 INFO - 'HOME': '/home/cltbld', 04:53:30 INFO - 'LANG': 'en_US.UTF-8', 04:53:30 INFO - 'LANGUAGE': 'en_US:en', 04:53:30 INFO - 'LOGNAME': 'cltbld', 04:53:30 INFO - 'MAIL': '/var/mail/cltbld', 04:53:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:53:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:30 INFO - 'MOZ_NO_REMOTE': '1', 04:53:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:53:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:30 INFO - 'NO_EM_RESTART': '1', 04:53:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:30 INFO - 'PWD': '/builds/slave/test', 04:53:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:53:30 INFO - 'SHELL': '/bin/bash', 04:53:30 INFO - 'SHLVL': '1', 04:53:30 INFO - 'TERM': 'linux', 04:53:30 INFO - 'TMOUT': '86400', 04:53:30 INFO - 'USER': 'cltbld', 04:53:30 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638', 04:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:30 INFO - '_': '/tools/buildbot/bin/python'} 04:53:30 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/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] with output_timeout 3600 04:53:30 INFO - mozversion INFO | application_buildid: 20151127035613 04:53:30 INFO - mozversion INFO | application_changeset: 2a711953a62c00ee3ddc753592ccffac7876c966 04:53:30 INFO - mozversion INFO | application_display_name: Nightly 04:53:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:53:30 INFO - mozversion INFO | application_name: Firefox 04:53:30 INFO - mozversion INFO | application_remotingname: firefox 04:53:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 04:53:30 INFO - mozversion INFO | application_vendor: Mozilla 04:53:30 INFO - mozversion INFO | application_version: 45.0a1 04:53:30 INFO - mozversion INFO | platform_buildid: 20151127035613 04:53:30 INFO - mozversion INFO | platform_changeset: 2a711953a62c00ee3ddc753592ccffac7876c966 04:53:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 04:53:30 INFO - mozversion INFO | platform_version: 45.0a1 04:53:30 INFO - 2015-11-27 04:53:30,778 DEBUG : using testdate: 1448628810 04:53:30 INFO - 2015-11-27 04:53:30,778 DEBUG : actual date: 1448628810 04:53:30 INFO - 2015-11-27 04:53:30,787 INFO : starting webserver on 'localhost:44670' 04:53:30 INFO - 2015-11-27 04:53:30,788 INFO : Starting test suite talos-linux64-ix-030.e 04:53:30 INFO - 2015-11-27 04:53:30,788 INFO : Starting test dromaeo_css 04:53:30 INFO - 2015-11-27 04:53:30,788 DEBUG : operating with platform_type : linux_ 04:53:30 INFO - 2015-11-27 04:53:30,789 INFO : Initialising browser for dromaeo_css test... 04:53:30 INFO - 2015-11-27 04:53:30,803 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH1Rhg9/profile http://localhost:44670/getInfo.html 04:53:35 INFO - 2015-11-27 04:53:35,675 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:53:35 INFO - 2015-11-27 04:53:35,675 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:53:35 INFO - 2015-11-27 04:53:35,675 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:53:35 INFO - 2015-11-27 04:53:35,675 DEBUG : BROWSER_OUTPUT: __metrics 04:53:36 INFO - 2015-11-27 04:53:36,769 INFO : Browser initialized. 04:53:36 INFO - 2015-11-27 04:53:36,770 INFO : Running cycle 1/1 for dromaeo_css test... 04:53:36 INFO - 2015-11-27 04:53:36,770 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH1Rhg9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:53:48 INFO - 2015-11-27 04:53:48,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 138625024 04:53:48 INFO - 2015-11-27 04:53:48,662 DEBUG : BROWSER_OUTPUT: 04:54:38 INFO - 2015-11-27 04:54:38,319 DEBUG : BROWSER_OUTPUT: 1448628878314 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] 04:56:49 INFO - 2015-11-27 04:56:49,692 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44670/tests/dromaeo/cssquery-ext.html) 04:56:50 INFO - 2015-11-27 04:56:50,821 DEBUG : BROWSER_OUTPUT: RSS: Main: 144814080 04:56:50 INFO - 2015-11-27 04:56:50,822 DEBUG : BROWSER_OUTPUT: 04:59:52 INFO - 2015-11-27 04:59:52,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/dromaeo/cssquery-ext.html (next: http://localhost:44670/tests/dromaeo/cssquery-jquery.html) 04:59:53 INFO - 2015-11-27 04:59:53,329 DEBUG : BROWSER_OUTPUT: RSS: Main: 143577088 04:59:53 INFO - 2015-11-27 04:59:53,330 DEBUG : BROWSER_OUTPUT: 05:02:55 INFO - 2015-11-27 05:02:55,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44670/tests/dromaeo/cssquery-mootools.html) 05:02:55 INFO - 2015-11-27 05:02:55,683 DEBUG : BROWSER_OUTPUT: RSS: Main: 143298560 05:02:55 INFO - 2015-11-27 05:02:55,683 DEBUG : BROWSER_OUTPUT: 05:05:57 INFO - 2015-11-27 05:05:57,414 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44670/tests/dromaeo/cssquery-prototype.html) 05:05:58 INFO - 2015-11-27 05:05:58,000 DEBUG : BROWSER_OUTPUT: RSS: Main: 141946880 05:05:58 INFO - 2015-11-27 05:05:58,000 DEBUG : BROWSER_OUTPUT: 05:09:00 INFO - 2015-11-27 05:09:00,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44670/tests/dromaeo/cssquery-yui.html) 05:09:01 INFO - 2015-11-27 05:09:01,158 DEBUG : BROWSER_OUTPUT: RSS: Main: 142712832 05:09:01 INFO - 2015-11-27 05:09:01,158 DEBUG : BROWSER_OUTPUT: 05:12:03 INFO - 2015-11-27 05:12:03,739 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/dromaeo/cssquery-yui.html (next: http://localhost:44670/tests/dromaeo/cssquery-dojo.html) 05:12:03 INFO - 2015-11-27 05:12:03,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 144994304 05:12:03 INFO - 2015-11-27 05:12:03,998 DEBUG : BROWSER_OUTPUT: 05:12:03 INFO - 2015-11-27 05:12:03,998 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:12:03 INFO - 2015-11-27 05:12:03,998 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:12:04 INFO - 2015-11-27 05:12:03,998 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:12:04 INFO - 2015-11-27 05:12:03,998 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:12:04 INFO - 2015-11-27 05:12:03,999 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2655.1768951625;2663.507251158537;2708.6614173228345;2714.362124900648;2729.358600728829;4492.707973981689;4653.767311634421;4657.138429390565;4662.533746625336;4664.7667616619165;4558.384618076559;4678.461976872661;4697.506761790014;4700.7649617519155;4706.458757242918;4196.4334814799995;4284.143171365724;4340.066885619591;4349.325854492558;4381.364702118207;4035.5835830717606;4355.237833379162;4363.410939523155;4383.780810959455;4474.798634061459;4214.051838336381;4368.432103826514;4408.25059739845;4453.042595841877;4457.59881610655;4213.768242746648;4314.80583373749;4335.761533115678;4340.782960851976;4342.913141737147;4322.740635561876;4348.21732088224;4372.672049596261;4383.912321753579;4397.692161548693;4454.198244316021;4631.4442266927845;4639.211334073216;4641.930371044437;4690.624750019991;4851.054044461332;4911.361523002022;4974.602031837444;4989.376327959005;5035.370578677665;5085.262636917663;5091.694498330112;5093.719845408538;5094.414142373637;5107.872303192413;4502.842400515966;4599.471060827981;4710.5289471052965;4718.834840780556;4723.315119307681;5040.445550989388;5093.643444958891;5103.438621751604;5110.642255042111;5128.333316668809;4972.8508144755715;4975.950240497621;4976.975115124401;4989.600831933437;5023.49765023502;4824.8793780155775;4895.779689913962;4937.802487900443;5038.3198268234055;5065.721385323769;5008.198688209869;5010.148274793294;5012.04771093491;5017.874553136127;5021.347224860818;4997.7251251180805;4998.175301075355;4998.450170481244;5000.374953130859;5030.597552195815;4088.713790034669;4137.40007698882;4191.1827193697545;4254.340577210537;4306.074194048295;3687.6681098701247;3718.2749363873786;3730.291244663505;3768.2840260350445;3769.566499852552;4358.738475691469;4371.912561748811;4373.6282415994365;4375.037491751809;4395.077033822933;4787.784549695272;4902.362692849978;4915.311856340115;4981.95378970413;5020.799168033238;4366.6506679465565;4374;4395.164918665443;4400;4430.158269928703;4373.606375426227;4537.0925814837165;4543.34121552373;4546.227141385906;4577.244754615451;4670.673052886265;4749.477557468745;4760.59534939534;4772.618190544678;4804.135255654016;5035.118854200574;5054.924176137288;5070.366204224472;5077.365329333833;5101.285819985131;4570.88572785683;4680.438347398334;4761.166795810788;4807.399075115611;4856.490068542752;4882.2432522959;4925.6305777066145;4958.033183529177;4958.479359667183;5006.148954677641;5055.317532133115;5080.542751152414;5085.796568137233;5088.084144853962;5122.923156152735;4715.386999690156;4778.95221047799;4816.084943860656;4908.730019848943;4951.282064100757;4867.269909513601;4906.63200259989;4919.606431485544;4933.186024305949;4948.233023881304;4810.5670489656095;4824.107540105092;4827.300041494034;4848.75756212195;4859.343988561641;4713.2458806590785;4809.495003024632;4813.109574728686;4918.803247870045;4939.506049395175;4793.400824896888;4798.7360695160705;4813.95186048135;4822.734749588806;4829.5653391194965 05:12:04 INFO - 2015-11-27 05:12:03,999 DEBUG : BROWSER_OUTPUT: |1;ext.html;549.0967358694946;553.2862607236665;556.2351766321308;557.8800557880057;559.9944000559994;37124.62875371245;38258.61741382585;38300.61699383005;38327;38344.80827595862;40300.39549406754;40788.59211407885;40819.79590102053;40842.978925526804;40873.386899196536;7936.7222147225;8080.838383232332;8114.066882308522;8123;8125.715599953974;7900.525968441883;7946.165652606451;7962.960185199066;7983.481073730218;7996.840063198732;7992.280694737473;8069.596520173968;8073.233042860918;8081.7171399001045;8123.918760812376;7991.800204994864;7999.240072193191;8054.597270136528;8066.314363279128;8078.030636323679;7905.644246008944;8023.598820059032;8031.799205019863;8038.799030024237;8057.435979481439;37725;38037.61962380369;38143.23713525742;38200.80899595512;38298.23403531914;2980.4784162771643;3211.735726312944;3248.870045198189;3295.670432956709;3376.3922493951077;28238;28353.71646283552;28402;28904.60562410842;29158.27104322388;3451.9482207766905;3463.5497385339995;3486.564179477565;3490.8778192763384;3494.72042236623;207231;207669;208456;208729;208828;47618.761906190244;55002.44997549973;55155.72422137864;55376.44623553794;55425.445745542835;43458.130837383076;65917.67041164859;67244.3275567238;67321.3267867315;67581.66209168923;12335.69160770988;12615.495380184873;12634.620961371174;12720.554780582635;12752.489900404065;9585;9821.508924553744;9838.065383788671;9840.57311689801;9863.112319891101;19357.612847743247;19919.800801991794;19923.701144482846;19928.90035549813;19981.800181998286;7407;7636.69453221876;7642.541447513167;7723.575203363756;7728.4590078693955;785.6778720724476;875.6628697951335;875.8160786234835;947.5357075033185;985.9014098590084;25926.35184120412;26493.205203843914;26670.733292666824;26706;26755.866220668773;928.2713070239964;930.0978051290238;937.7702462896634;963.6001059560344;964.5755867418313;13468.461261549624;13701.931490342484;13883.930580347236;13886.375113119933;14273.429062837371;254458;259429;260664.23689749444;261487;261560;45939.54060459419;46472.3029154563;46581.6025519235;46586.5341346589;46767.76616116966;1115.358668765463;1131.2420678145663;1136.1365362324693;1160.3560024186488;1164.5632887667125;25064;26171.214863554123;26428;26531.86734066317;26572.867135664197;40098;40388;40411.59588404196;40685.59314406937;40769.796151020244;236468;236507;237245;237626;238915;24677.876610616833;25242.621360679237;28608.57087143653;28806.85596572004;28890.566641500816;34145.31709365849;34523;34731.65268347284;35380.82309588538;35421.46867796934;31012.379752404373;31214.531782023743;31330.843345784037;31889.521657175625;31949.520757189126;33021.17447063843;33218;33244.1688957778;33668.66331336655;33680.49479257862;32957.67042329546;33040.1739956503;33266.33467330688;33281.334373312886;33291.16772080717;28466.28834279077;29725;30581.694183057883;30616;30662;23669.408264793517;23734;23756;23963.76036239685;24157.51684966326 05:12:04 INFO - 2015-11-27 05:12:03,999 DEBUG : BROWSER_OUTPUT: |2;jquery.html;315.3692614770459;317.1500378984322;318.4506725897828;323.61974679751285;323.6876414260239;14882;14921.253937303132;15240.161791101486;15741.291641876114;16139.919300403511;8557.828843423144;8713.346499012569;10063;13922.303884805744;13994.510192143278;3747.2130852521004;4431.379606855026;4432.756198409086;4437.378766972626;4449.377087207794;4607.285870690049;4734.4081989751285;4738.4313882334;4740.028294199681;4741.739204343759;4721.598664113537;4728.645351598627;6206.162168107316;6561.901571476432;6575.671216439183;6196.10156527301;6266.498680105604;6312.5896816707;6320.71556779946;6348.714307856158;6344.904826427607;6348.143000694893;6349.428551430347;6350.714217860162;6359.300476947532;18983.71524427135;20750.377488675364;21381;22065;22150.667739983914;1883.1525813383896;1933.2460340467228;1999.0604415924493;2001.0495014867968;2061.134323584098;13849.653758656013;13893.722125557431;13922;14095.22476263805;14204.857951420458;1860.5813691919345;1877.108373522566;1878.7933327333983;1900.163927871732;1915.5977244778612;60848;63032.36967630311;64318.356816431704;64360.67819660919;65753.73970082382;15160.620984475256;15359.232038398035;15403.691926161639;15466.5360039199;15527.689446211012;23152.18967336135;23166.53666926652;23378.883105584362;23468.765312347;23604.88197559001;7774.533527988338;7801.4929029612895;7810.804729881798;7816.296533312029;7922.36621070313;6264.21697287839;6276.623402595767;6282.026285925689;6286.339934306832;6297.937020629826;7454.739084132029;7512.849743005109;7527.774166775005;7594.582297973554;7619.9238007619215;2883.178294186189;2974.3456439583256;2983.4480621085168;2986.2086547065046;3090.1193159949307;321.88733943119723;323.11788816531003;329.90762586475824;330.7073240182408;338.25921232500747;9341.299402544837;9499.90500095004;9657.855132173023;9747.3664211826;14814.703705925822;803.9155888631726;830.9958450207711;846.4794151596724;850.128618166372;919.2048877720723;8700.434471759316;8846.557672116369;8933.28533717314;9042.909570904207;9063.32025098107;485439;492198;498203;500526;500848;70778;72540.99999999894;73235.26764732391;73889.26110738932;74078.25921740821;1044.7440377107657;1070.882202957674;1072.9248952573246;1096.6107032003683;1099.5217080569978;13323.733525329246;13560.86439135596;13934.930325348309;14080.507182248572;14254.857451425352;30492.695073048984;32527.34945301128;32586.02241932746;33523;34063;44586.55413445824;46176.30735539037;46413;46488.53511464977;47400.525994740994;7616.657250423855;8324.83350333002;8390.580470976305;8434.283085937765;8436.156384361515;9540;9711.854322185032;14431;16304.347826086823;16493.83506164923;13190.538331158363;13278.269695166859;13774;13866.514671986835;14193.503227386991;13201.735965280832;13268.736108683397;13535.86464135346;13842.169469831842;14117.717645647235;14037.1577705338;14258;14264.072835265675;14496.492622758153;14532.128072315694;7793.454458187873;7891.881621775563;7941.444098913021;7944.960275198587;7982.760517184493;8285.418841759963;8288.627011784363;14194.290285485891;14226.715465690835;14260.286985650884 05:12:04 INFO - 2015-11-27 05:12:04,002 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1470.7940888275637;1579.6524764551798;1614.7981502312211;1628.9837101628987;1651.0506458786197;5961.135635332874;6089.969550152254;6644.402003819655;6887.3457021582935;6893.4140598049225;6406.135171751812;6520.217573891128;6553.508486863492;6558.639274839894;6573.3098024707315;1934.351992082654;1963.224526312113;1969.0253324604269;1974.3583335416047;1975;1949.4249196487071;1967.3114409956472;1981.217419119447;1993.4418362858423;2005.4986253436641;1916.8945707986056;1921.372152554357;1934.8355389791884;1939.85451091168;1946.8150525700037;1865.3844231403605;1880.097553174484;1885.830275275225;1893.943181704551;1899.3257393625202;1912.540990162365;1931.94204173875;1933.5166208447888;2010.7486564179478;2015;6673.132492775908;6674.432673222782;6708.060871477997;6719.798406047827;6724.529282950195;3785.716071294664;3866.709996750255;3868;3888.902777430587;3894.3963685628773;2575.7166711661703;2606.7784238339764;2620.8296460729994;2629.198094581152;2646.78825693944;828.6892415344246;888.373696543935;891.416122439803;935.780091678455;941.4445477168435;8725.694600688945;8761.561921903944;8763.474191548528;8786.824263514694;8800.999999999936;3478.826058697055;3527.3827080260853;3554.662307080823;3557.4130268505405;3568.232829941575;7754.9224507755325;7788.532688038736;7790.026246719161;7791.922080779233;7799.259070388304;3865.7100717446306;3914.64768170866;3921.254961557295;3925.5878132795647;3930.194310166409;3318.452455344841;3406.7785593936314;3496.597891242489;3513.1392808761507;3525.153963048901;3065.294982154117;3069.248034231583;3070.247789291593;3082.044566184445;3092.273315770792;917.1378903830378;918.4443411735937;919.0763282900775;920.2132176988712;921.3826736086838;1105.165599972016;1120.2214460949724;1125.201107213871;1127.5264388956657;1164.470166074434;6269.7492100316385;6271.310155882849;6286.591371560834;6297.118403423525;6304.968475157595;1107.2581370481796;1112.2214449885112;1126.2285334545863;1136.3011747775058;1143.3768596115137;4963.429205641325;5070.670406423571;5212.166053431432;5241.030409374278;5261.4738526147075;7890.526568405914;7904.130545639975;8075.636596353178;8089.555074470961;8122.147174546706;7284.70861165558;7448.4786064976015;7485.550866947891;7486.176520582731;7913.169117242606;6337.746490140343;6395.872082558323;6406.071119687713;6430.356964303626;6436.388543088399;6214.285357183981;6272.8118156455375;6327.145835312174;6329.873402531832;6338.271098823694;7038.753643622448;7205.603691796792;7227.421806255382;7231.385332246818;7279.235680253497;7230.313120253568;7296.96351518239;7356.889646655198;7359.632018398952;7418;5289.20661900718;5397.730113494231;6097.878042439038;6130.601510901777;6132.141500189888;6144;6313.337099604475;6337.366263373626;6353.809385718436;6361.109444677842;5715.799947001835;5894.557908156819;6004.699765011645;6069.666168360783;6254.874902502015;5936.703164841827;6013.5189184865985;6139.815805525842;6157.568970172045;6177.6911154441195;6123.142760013513;6224.595401298901;6228.968855155696;6238.719257633508;6246.562740608114;5723.3418156912485;5778.653280803165;5782.508486778671;5783.277090363705;5813.680247586423;5749.108888122348;5803.129530570448;5842.737076831638;5862.912056319244;5926.733297001731 05:12:04 INFO - 2015-11-27 05:12:04,004 DEBUG : BROWSER_OUTPUT: |4;prototype.html;282.35763637724176;287.8388102662508;290.7833663920378;292.06830211624913;293.2799976058776;5289.391719952207;5564.387917329095;5602.971985140069;5652.09566469365;5661;5553.694546799935;5555.083411237151;5559.749811258493;5565.443455654443;5592.692401917893;4303.870883873481;4305.5909688579695;4308;4315.784210789464;4327.329263964075;4302.48370195577;4307.504636966758;4311.655067594601;4326.43756311679;4334.739915605058;4062.4718786557705;4186.49762028557;4267.14657068586;4285.442892423996;4300.591443812833;4193.622573968358;4217.8312867485265;4220.725652832556;4237.703360764748;4241.554636763157;3633;3665.6334366563397;3666.633336666339;3672.063623775947;3677.2277821657485;5292.126799078147;5314.362276526803;5314.813981510629;5314.9468505314835;5325;4767.570918617341;4860.635452341088;4864.708117512925;4865.41615006198;4888.511148885119;5759;5798.217240672498;5807.912881306784;5827.854303642401;5842.386549412293;3975.840966361342;3979.7612143271494;3990.5610382857863;3990.940135897935;3999.3800960851113;5940;6077.665728384937;6158.076288556707;6161.260648722137;6166.136740856239;4068.16602596467;4082.9387559186043;4096.9795151024055;4109.609587089221;4124.52567954689;4129.772862492592;4133.338665813455;4158.064435502049;4162.146759914271;4175.97912010438;4792.544708252711;4792.592629626451;4792.7363994980615;4802.567768900746;4820.25286080658;5708.0581704019205;5820.738066787005;5832.416758324133;5834.94165058344;5838.036723940502;1451.7822326651087;1471.757160068598;1472.8968972171845;1473.9336729846968;1475.1739026145392;970.0832713086066;971.9173870220969;978.5351957819978;994.2493417469764;997.670768646345;5066.417362003343;5074.492550744896;5092.796288148433;5093.541581257705;5097.9745101275;6055.5163984824;6074.240719910012;6090.512758979271;6102.816915492543;6110.083487476825;5089.770960306795;5090.389153301628;5096.108181068298;5100.69395836251;5108.744562771846;3193.297474555594;3267.134209434502;3296.044147197334;3298.620658624241;3300.3234336961345;5780.104083867007;5788.710564471759;5797.536197104306;5807.738651760681;5823.912641310384;6281.905771413432;6330.68346582669;6338.207724034496;6347.5556711029785;6351.936480635227;5675.2054712340305;5774.105013722879;5792.420757924174;5809;5819.214406055128;6114.908276375769;6151.784687535915;6154.353792851865;6155.384461553809;6157.784477543264;5994.250718660168;5996.040633498619;6014.8195554133445;6017.428344307284;6022.337542870193;5525.833345821669;5758.654480731169;5784.1034639630925;5787.305523337226;5817.883642327214;3771.321162190832;4028.637422631977;4064.3293856513346;4112.239235741397;4127.360259159835;6043.063325184604;6063.423974722395;6070;6072.119542666201;6073.180120683651;5803.187553742395;5809.2738407699035;5821.417858214314;5827.271591051119;5833.0667093266575;5736.6558006519745;5838.3869693683255;5875.383084776037;5889.234399528034;5896.997510423153;5757.769689212552;5774.971125144515;5781.306243250837;5794.565407594531;5799.246098007232;5900.055991041413;5902.4097590240635;5929.644221346733;5963.522918166623;5979.073243647242;5625.128105143709;5744.655320680772;5775.884482310246;5782.074868021265;5796.101604251347 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: |5;yui.html;736.1644533454529;751.7556794041931;752.8833030880215;779.5634444710959;779.9493032952857;10398.584056637725;10606.310589811657;10755.62355317565;10759.300645458039;10768.78462430751;10149.797004059914;10216.335938164033;10256.897431025705;10265.589376424934;10273.434961077137;590.1266126133314;602.5390576209204;761.702935855017;773.0877564473918;775.9107702614187;769.5690413368503;770.0336078221824;776.3750181104238;778.1479280188213;779.0534500581792;807.5800583696474;817.652497688483;819.9098099209082;820.4216027700456;821.48246604639;807.3057170833079;809.2878267124893;810.8662070758318;811.5942028985507;815.8164413007086;594.5570549940322;600.6546235914367;602.0667964654812;602.6113157013746;603.110412142087;10592.205584581186;10690.62582809598;10692.893071069346;10716;10752.462376881125;10980.725481862937;11844.170908036442;11889.227200231957;11895.643130706007;12205.084618653547;9183.540822958892;9414.764630884283;9427.67003154893;9434;9483.952580237055;3184.7133757961665;3261.429249881285;3269.591301087364;3294.1764558860286;3325.866965321384;39967.200655986715;40319.193616127806;40400;40418.39372409416;40453.1909361814;8750;9058;9112.95443522778;9129.77175570616;9410.435373877452;17100.65798684044;17131.143442827808;17216.913915430592;17217.569560760832;17234.482965511055;6336.936630633727;6367.681615919186;6561;6561.0814485972005;6566.737330506822;3204.9359012819614;3328.3509715605223;3341.8663253470077;3354.6142193647556;3388.3900897838625;2496.2386472126177;2543;2546.5034318307753;2548.2737419835257;2549.4263790646955;446.3394176618558;451.9435070616173;456.56398139776434;458.79354290569376;462.4496848749977;510.69358384968723;530.0141736370348;542.6608369768894;568.8464114689011;571.1803561888703;7538.396928245727;7703.1526532081425;7805.609719514002;7815.374770018385;7961.164077771867;467.50678034673746;473.8057396467432;499.07670809003054;503.51913922204204;542.1948406616171;3650.2152037312107;3659.304732100892;3724.7578907370935;3871.380579107329;3946;46394.76802616033;47275.29087063697;47276.29085563719;47409.762951185716;47518.76240618844;41568.37647435291;42288;42559.14881702411;42593;42774.78612606917;42967.57032429761;43532;43792.12415751667;43879.78060109679;43974.12051758947;7760.650770715219;7760.961195193988;7840.098388685375;7987.16134805837;8052.637631306722;29751.404971900873;30013.5497967535;30486.390272194876;30724.231894202825;32222.677773221967;39229;40278.798606006785;40279.3958090623;40342.79828600838;40397.798011010935;9930.25523085786;9940.353876997971;9957.253206009433;10175.7456063599;10211.081002709792;11181.888181118085;11318.207725459139;11430.1998860079;11587.942060289644;11638;9415.905840941503;9496.43021418717;9659.034096590285;9910.15763660097;9910.504474776377;9889.357191782512;9911.207103431563;10167.389956602627;10245;10428.217883658604;9569.425834449956;9713.465759383302;9782.461964592016;9830.361026533252;10351.534180962026;8985.910140898508;9086.863697044682;9202.769930751785;9384.202342800938;9528.142467177988;8099.959500202462;8332.050129396619;8361.122082181284;8443.363619635878;8551.572421378782 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,328 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: __startTimestamp1448629923991__endTimestamp 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,005 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:12:04 INFO - 2015-11-27 05:12:04,006 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:12:04 INFO - 2015-11-27 05:12:04,006 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,006 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4636.27 Median:4761.17 stddev:472.94 (9.9%) stddev-sans-first:448.12 05:12:04 INFO - 2015-11-27 05:12:04,007 DEBUG : BROWSER_OUTPUT: Values: 2655.2 2663.5 2708.7 2714.4 2729.4 4492.7 4653.8 4657.1 4662.5 4664.8 4558.4 4678.5 4697.5 4700.8 4706.5 4196.4 4284.1 4340.1 4349.3 4381.4 4035.6 4355.2 4363.4 4383.8 4474.8 4214.1 4368.4 4408.3 4453.0 4457.6 4213.8 4314.8 4335.8 4340.8 4342.9 4322.7 4348.2 4372.7 4383.9 4397.7 4454.2 4631.4 4639.2 4641.9 4690.6 4851.1 4911.4 4974.6 4989.4 5035.4 5085.3 5091.7 5093.7 5094.4 5107.9 4502.8 4599.5 4710.5 4718.8 4723.3 5040.4 5093.6 5103.4 5110.6 5128.3 4972.9 4976.0 4977.0 4989.6 5023.5 4824.9 4895.8 4937.8 5038.3 5065.7 5008.2 5010.1 5012.0 5017.9 5021.3 4997.7 4998.2 4998.5 5000.4 5030.6 4088.7 4137.4 4191.2 4254.3 4306.1 3687.7 3718.3 3730.3 3768.3 3769.6 4358.7 4371.9 4373.6 4375.0 4395.1 4787.8 4902.4 4915.3 4982.0 5020.8 4366.7 4374.0 4395.2 4400.0 4430.2 4373.6 4537.1 4543.3 4546.2 4577.2 4670.7 4749.5 4760.6 4772.6 4804.1 5035.1 5054.9 5070.4 5077.4 5101.3 4570.9 4680.4 4761.2 4807.4 4856.5 4882.2 4925.6 4958.0 4958.5 5006.1 5055.3 5080.5 5085.8 5088.1 5122.9 4715.4 4779.0 4816.1 4908.7 4951.3 4867.3 4906.6 4919.6 4933.2 4948.2 4810.6 4824.1 4827.3 4848.8 4859.3 4713.2 4809.5 4813.1 4918.8 4939.5 4793.4 4798.7 4814.0 4822.7 4829.6 05:12:04 INFO - 2015-11-27 05:12:04,007 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,007 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39839.30 Median:3488.72 stddev:61454.69 (1761.5%) stddev-sans-first:61556.28 05:12:04 INFO - 2015-11-27 05:12:04,007 DEBUG : BROWSER_OUTPUT: Values: 549.1 553.3 556.2 557.9 560.0 37124.6 38258.6 38300.6 38327.0 38344.8 40300.4 40788.6 40819.8 40843.0 40873.4 7936.7 8080.8 8114.1 8123.0 8125.7 7900.5 7946.2 7963.0 7983.5 7996.8 7992.3 8069.6 8073.2 8081.7 8123.9 7991.8 7999.2 8054.6 8066.3 8078.0 7905.6 8023.6 8031.8 8038.8 8057.4 37725.0 38037.6 38143.2 38200.8 38298.2 2980.5 3211.7 3248.9 3295.7 3376.4 28238.0 28353.7 28402.0 28904.6 29158.3 3451.9 3463.5 3486.6 3490.9 3494.7 207231.0 207669.0 208456.0 208729.0 208828.0 47618.8 55002.4 55155.7 55376.4 55425.4 43458.1 65917.7 67244.3 67321.3 67581.7 12335.7 12615.5 12634.6 12720.6 12752.5 9585.0 9821.5 9838.1 9840.6 9863.1 19357.6 19919.8 19923.7 19928.9 19981.8 7407.0 7636.7 7642.5 7723.6 7728.5 785.7 875.7 875.8 947.5 985.9 25926.4 26493.2 26670.7 26706.0 26755.9 928.3 930.1 937.8 963.6 964.6 13468.5 13701.9 13883.9 13886.4 14273.4 254458.0 259429.0 260664.2 261487.0 261560.0 45939.5 46472.3 46581.6 46586.5 46767.8 1115.4 1131.2 1136.1 1160.4 1164.6 25064.0 26171.2 26428.0 26531.9 26572.9 40098.0 40388.0 40411.6 40685.6 40769.8 236468.0 236507.0 237245.0 237626.0 238915.0 24677.9 25242.6 28608.6 28806.9 28890.6 34145.3 34523.0 34731.7 35380.8 35421.5 31012.4 31214.5 31330.8 31889.5 31949.5 33021.2 33218.0 33244.2 33668.7 33680.5 32957.7 33040.2 33266.3 33281.3 33291.2 28466.3 29725.0 30581.7 30616.0 30662.0 23669.4 23734.0 23756.0 23963.8 24157.5 05:12:04 INFO - 2015-11-27 05:12:04,007 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,007 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27383.43 Median:4089.30 stddev:80955.88 (1979.7%) stddev-sans-first:81157.47 05:12:04 INFO - 2015-11-27 05:12:04,008 DEBUG : BROWSER_OUTPUT: Values: 315.4 317.2 318.5 323.6 323.7 14882.0 14921.3 15240.2 15741.3 16139.9 8557.8 8713.3 10063.0 13922.3 13994.5 3747.2 4431.4 4432.8 4437.4 4449.4 4607.3 4734.4 4738.4 4740.0 4741.7 4721.6 4728.6 6206.2 6561.9 6575.7 6196.1 6266.5 6312.6 6320.7 6348.7 6344.9 6348.1 6349.4 6350.7 6359.3 18983.7 20750.4 21381.0 22065.0 22150.7 1883.2 1933.2 1999.1 2001.0 2061.1 13849.7 13893.7 13922.0 14095.2 14204.9 1860.6 1877.1 1878.8 1900.2 1915.6 60848.0 63032.4 64318.4 64360.7 65753.7 15160.6 15359.2 15403.7 15466.5 15527.7 23152.2 23166.5 23378.9 23468.8 23604.9 7774.5 7801.5 7810.8 7816.3 7922.4 6264.2 6276.6 6282.0 6286.3 6297.9 7454.7 7512.8 7527.8 7594.6 7619.9 2883.2 2974.3 2983.4 2986.2 3090.1 321.9 323.1 329.9 330.7 338.3 9341.3 9499.9 9657.9 9747.4 14814.7 803.9 831.0 846.5 850.1 919.2 8700.4 8846.6 8933.3 9042.9 9063.3 485439.0 492198.0 498203.0 500526.0 500848.0 70778.0 72541.0 73235.3 73889.3 74078.3 1044.7 1070.9 1072.9 1096.6 1099.5 13323.7 13560.9 13934.9 14080.5 14254.9 30492.7 32527.3 32586.0 33523.0 34063.0 44586.6 46176.3 46413.0 46488.5 47400.5 7616.7 8324.8 8390.6 8434.3 8436.2 9540.0 9711.9 14431.0 16304.3 16493.8 13190.5 13278.3 13774.0 13866.5 14193.5 13201.7 13268.7 13535.9 13842.2 14117.7 14037.2 14258.0 14264.1 14496.5 14532.1 7793.5 7891.9 7941.4 7945.0 7982.8 8285.4 8288.6 14194.3 14226.7 14260.3 05:12:04 INFO - 2015-11-27 05:12:04,008 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,008 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4675.42 Median:5982.92 stddev:2379.04 (39.8%) stddev-sans-first:2373.52 05:12:04 INFO - 2015-11-27 05:12:04,009 DEBUG : BROWSER_OUTPUT: Values: 1470.8 1579.7 1614.8 1629.0 1651.1 5961.1 6090.0 6644.4 6887.3 6893.4 6406.1 6520.2 6553.5 6558.6 6573.3 1934.4 1963.2 1969.0 1974.4 1975.0 1949.4 1967.3 1981.2 1993.4 2005.5 1916.9 1921.4 1934.8 1939.9 1946.8 1865.4 1880.1 1885.8 1893.9 1899.3 1912.5 1931.9 1933.5 2010.7 2015.0 6673.1 6674.4 6708.1 6719.8 6724.5 3785.7 3866.7 3868.0 3888.9 3894.4 2575.7 2606.8 2620.8 2629.2 2646.8 828.7 888.4 891.4 935.8 941.4 8725.7 8761.6 8763.5 8786.8 8801.0 3478.8 3527.4 3554.7 3557.4 3568.2 7754.9 7788.5 7790.0 7791.9 7799.3 3865.7 3914.6 3921.3 3925.6 3930.2 3318.5 3406.8 3496.6 3513.1 3525.2 3065.3 3069.2 3070.2 3082.0 3092.3 917.1 918.4 919.1 920.2 921.4 1105.2 1120.2 1125.2 1127.5 1164.5 6269.7 6271.3 6286.6 6297.1 6305.0 1107.3 1112.2 1126.2 1136.3 1143.4 4963.4 5070.7 5212.2 5241.0 5261.5 7890.5 7904.1 8075.6 8089.6 8122.1 7284.7 7448.5 7485.6 7486.2 7913.2 6337.7 6395.9 6406.1 6430.4 6436.4 6214.3 6272.8 6327.1 6329.9 6338.3 7038.8 7205.6 7227.4 7231.4 7279.2 7230.3 7297.0 7356.9 7359.6 7418.0 5289.2 5397.7 6097.9 6130.6 6132.1 6144.0 6313.3 6337.4 6353.8 6361.1 5715.8 5894.6 6004.7 6069.7 6254.9 5936.7 6013.5 6139.8 6157.6 6177.7 6123.1 6224.6 6229.0 6238.7 6246.6 5723.3 5778.7 5782.5 5783.3 5813.7 5749.1 5803.1 5842.7 5862.9 5926.7 05:12:04 INFO - 2015-11-27 05:12:04,009 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,009 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4834.81 Median:5564.92 stddev:1450.20 (26.1%) stddev-sans-first:1413.44 05:12:04 INFO - 2015-11-27 05:12:04,009 DEBUG : BROWSER_OUTPUT: Values: 282.4 287.8 290.8 292.1 293.3 5289.4 5564.4 5603.0 5652.1 5661.0 5553.7 5555.1 5559.7 5565.4 5592.7 4303.9 4305.6 4308.0 4315.8 4327.3 4302.5 4307.5 4311.7 4326.4 4334.7 4062.5 4186.5 4267.1 4285.4 4300.6 4193.6 4217.8 4220.7 4237.7 4241.6 3633.0 3665.6 3666.6 3672.1 3677.2 5292.1 5314.4 5314.8 5314.9 5325.0 4767.6 4860.6 4864.7 4865.4 4888.5 5759.0 5798.2 5807.9 5827.9 5842.4 3975.8 3979.8 3990.6 3990.9 3999.4 5940.0 6077.7 6158.1 6161.3 6166.1 4068.2 4082.9 4097.0 4109.6 4124.5 4129.8 4133.3 4158.1 4162.1 4176.0 4792.5 4792.6 4792.7 4802.6 4820.3 5708.1 5820.7 5832.4 5834.9 5838.0 1451.8 1471.8 1472.9 1473.9 1475.2 970.1 971.9 978.5 994.2 997.7 5066.4 5074.5 5092.8 5093.5 5098.0 6055.5 6074.2 6090.5 6102.8 6110.1 5089.8 5090.4 5096.1 5100.7 5108.7 3193.3 3267.1 3296.0 3298.6 3300.3 5780.1 5788.7 5797.5 5807.7 5823.9 6281.9 6330.7 6338.2 6347.6 6351.9 5675.2 5774.1 5792.4 5809.0 5819.2 6114.9 6151.8 6154.4 6155.4 6157.8 5994.3 5996.0 6014.8 6017.4 6022.3 5525.8 5758.7 5784.1 5787.3 5817.9 3771.3 4028.6 4064.3 4112.2 4127.4 6043.1 6063.4 6070.0 6072.1 6073.2 5803.2 5809.3 5821.4 5827.3 5833.1 5736.7 5838.4 5875.4 5889.2 5897.0 5757.8 5775.0 5781.3 5794.6 5799.2 5900.1 5902.4 5929.6 5963.5 5979.1 5625.1 5744.7 5775.9 5782.1 5796.1 05:12:04 INFO - 2015-11-27 05:12:04,009 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,009 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12025.81 Median:23941.78 stddev:13699.43 (57.2%) stddev-sans-first:13711.63 05:12:04 INFO - 2015-11-27 05:12:04,011 DEBUG : BROWSER_OUTPUT: Values: 736.2 751.8 752.9 779.6 779.9 10398.6 10606.3 10755.6 10759.3 10768.8 10149.8 10216.3 10256.9 10265.6 10273.4 590.1 602.5 761.7 773.1 775.9 769.6 770.0 776.4 778.1 779.1 807.6 817.7 819.9 820.4 821.5 807.3 809.3 810.9 811.6 815.8 594.6 600.7 602.1 602.6 603.1 10592.2 10690.6 10692.9 10716.0 10752.5 10980.7 11844.2 11889.2 11895.6 12205.1 9183.5 9414.8 9427.7 9434.0 9484.0 3184.7 3261.4 3269.6 3294.2 3325.9 39967.2 40319.2 40400.0 40418.4 40453.2 8750.0 9058.0 9113.0 9129.8 9410.4 17100.7 17131.1 17216.9 17217.6 17234.5 6336.9 6367.7 6561.0 6561.1 6566.7 3204.9 3328.4 3341.9 3354.6 3388.4 2496.2 2543.0 2546.5 2548.3 2549.4 446.3 451.9 456.6 458.8 462.4 510.7 530.0 542.7 568.8 571.2 7538.4 7703.2 7805.6 7815.4 7961.2 467.5 473.8 499.1 503.5 542.2 3650.2 3659.3 3724.8 3871.4 3946.0 46394.8 47275.3 47276.3 47409.8 47518.8 41568.4 42288.0 42559.1 42593.0 42774.8 42967.6 43532.0 43792.1 43879.8 43974.1 7760.7 7761.0 7840.1 7987.2 8052.6 29751.4 30013.5 30486.4 30724.2 32222.7 39229.0 40278.8 40279.4 40342.8 40397.8 9930.3 9940.4 9957.3 10175.7 10211.1 11181.9 11318.2 11430.2 11587.9 11638.0 9415.9 9496.4 9659.0 9910.2 9910.5 9889.4 9911.2 10167.4 10245.0 10428.2 9569.4 9713.5 9782.5 9830.4 10351.5 8985.9 9086.9 9202.8 9384.2 9528.1 8100.0 8332.1 8361.1 8443.4 8551.6 05:12:04 INFO - 2015-11-27 05:12:04,011 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:12:04 INFO - 2015-11-27 05:12:04,011 DEBUG : BROWSER_OUTPUT: 05:12:04 INFO - 2015-11-27 05:12:04,681 INFO : Browser exited with error code: 0 05:12:04 INFO - 2015-11-27 05:12:04,697 INFO : Completed test dromaeo_css (00:18:33) 05:12:04 INFO - 2015-11-27 05:12:04,698 INFO : Starting test kraken 05:12:04 INFO - 2015-11-27 05:12:04,698 DEBUG : operating with platform_type : linux_ 05:12:04 INFO - 2015-11-27 05:12:04,698 INFO : Initialising browser for kraken test... 05:12:04 INFO - 2015-11-27 05:12:04,704 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVQbCst/profile http://localhost:44670/getInfo.html 05:12:09 INFO - 2015-11-27 05:12:09,315 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:12:09 INFO - 2015-11-27 05:12:09,315 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:12:09 INFO - 2015-11-27 05:12:09,315 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:12:09 INFO - 2015-11-27 05:12:09,315 DEBUG : BROWSER_OUTPUT: __metrics 05:12:10 INFO - 2015-11-27 05:12:10,377 INFO : Browser initialized. 05:12:10 INFO - 2015-11-27 05:12:10,378 INFO : Running cycle 1/1 for kraken test... 05:12:10 INFO - 2015-11-27 05:12:10,378 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVQbCst/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:12:22 INFO - 2015-11-27 05:12:22,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 138698752 05:12:22 INFO - 2015-11-27 05:12:22,257 DEBUG : BROWSER_OUTPUT: 05:12:24 INFO - 2015-11-27 05:12:24,837 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44670/tests/kraken/driver.html?testName=audio-beat-detection) 05:12:25 INFO - 2015-11-27 05:12:25,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 141316096 05:12:25 INFO - 2015-11-27 05:12:25,446 DEBUG : BROWSER_OUTPUT: 05:12:28 INFO - 2015-11-27 05:12:28,196 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44670/tests/kraken/driver.html?testName=audio-dft) 05:12:28 INFO - 2015-11-27 05:12:28,802 DEBUG : BROWSER_OUTPUT: RSS: Main: 140951552 05:12:28 INFO - 2015-11-27 05:12:28,803 DEBUG : BROWSER_OUTPUT: 05:12:31 INFO - 2015-11-27 05:12:31,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44670/tests/kraken/driver.html?testName=audio-fft) 05:12:32 INFO - 2015-11-27 05:12:32,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 136605696 05:12:32 INFO - 2015-11-27 05:12:32,304 DEBUG : BROWSER_OUTPUT: 05:12:34 INFO - 2015-11-27 05:12:34,402 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44670/tests/kraken/driver.html?testName=audio-oscillator) 05:12:35 INFO - 2015-11-27 05:12:35,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 135872512 05:12:35 INFO - 2015-11-27 05:12:35,028 DEBUG : BROWSER_OUTPUT: 05:12:37 INFO - 2015-11-27 05:12:37,301 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44670/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:12:37 INFO - 2015-11-27 05:12:37,882 DEBUG : BROWSER_OUTPUT: RSS: Main: 137334784 05:12:37 INFO - 2015-11-27 05:12:37,882 DEBUG : BROWSER_OUTPUT: 05:12:40 INFO - 2015-11-27 05:12:40,925 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44670/tests/kraken/driver.html?testName=imaging-darkroom) 05:12:41 INFO - 2015-11-27 05:12:41,526 DEBUG : BROWSER_OUTPUT: RSS: Main: 137601024 05:12:41 INFO - 2015-11-27 05:12:41,526 DEBUG : BROWSER_OUTPUT: 05:12:45 INFO - 2015-11-27 05:12:45,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44670/tests/kraken/driver.html?testName=imaging-desaturate) 05:12:45 INFO - 2015-11-27 05:12:45,637 DEBUG : BROWSER_OUTPUT: RSS: Main: 135237632 05:12:45 INFO - 2015-11-27 05:12:45,638 DEBUG : BROWSER_OUTPUT: 05:12:48 INFO - 2015-11-27 05:12:48,562 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44670/tests/kraken/driver.html?testName=json-parse-financial) 05:12:49 INFO - 2015-11-27 05:12:49,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 135888896 05:12:49 INFO - 2015-11-27 05:12:49,164 DEBUG : BROWSER_OUTPUT: 05:12:50 INFO - 2015-11-27 05:12:50,958 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44670/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:12:51 INFO - 2015-11-27 05:12:51,540 DEBUG : BROWSER_OUTPUT: RSS: Main: 138104832 05:12:51 INFO - 2015-11-27 05:12:51,540 DEBUG : BROWSER_OUTPUT: 05:12:53 INFO - 2015-11-27 05:12:53,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:12:54 INFO - 2015-11-27 05:12:54,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 137699328 05:12:54 INFO - 2015-11-27 05:12:54,041 DEBUG : BROWSER_OUTPUT: 05:12:56 INFO - 2015-11-27 05:12:56,226 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:12:56 INFO - 2015-11-27 05:12:56,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 136818688 05:12:56 INFO - 2015-11-27 05:12:56,820 DEBUG : BROWSER_OUTPUT: 05:12:59 INFO - 2015-11-27 05:12:59,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:00 INFO - 2015-11-27 05:13:00,039 DEBUG : BROWSER_OUTPUT: RSS: Main: 137023488 05:13:00 INFO - 2015-11-27 05:13:00,039 DEBUG : BROWSER_OUTPUT: 05:13:02 INFO - 2015-11-27 05:13:02,675 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:03 INFO - 2015-11-27 05:13:03,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 138555392 05:13:03 INFO - 2015-11-27 05:13:03,256 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,052 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44670/tests/kraken/driver.html?testName=ai-astar) 05:13:05 INFO - 2015-11-27 05:13:05,299 DEBUG : BROWSER_OUTPUT: RSS: Main: 138158080 05:13:05 INFO - 2015-11-27 05:13:05,299 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,303 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:13:05 INFO - 2015-11-27 05:13:05,303 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:13:05 INFO - 2015-11-27 05:13:05,303 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:13:05 INFO - 2015-11-27 05:13:05,303 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:13:05 INFO - 2015-11-27 05:13:05,303 DEBUG : BROWSER_OUTPUT: |0;ai-astar;116;107;115;115;113;125;115;113;113;117 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;134;163;183;130;161;157;136;167;135 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;163;163;165;161;163;166;164;162;161 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |3;audio-fft;75;74;72;73;72;72;143;72;71;73 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;96;96;135;94;94;95;135;95;95;133 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;111;110;110;110;109;110 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;148;148;148;148;147;147;148 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;95;96;96;96;95;95 05:13:05 INFO - 2015-11-27 05:13:05,304 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;74;83;74;74;79;73;81;73;73 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;69;70;69;70;69;69;69 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;92;86;83;83;90;86;84;92;84;85 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;134;129;122;126;130;140;124;130;123;126 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;154;156;149;136;155;149;147;145;144;147 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;70;70;71;70;72;69;71;70;69 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:13:05 INFO - 2015-11-27 05:13:05,305 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:13:05 INFO - 2015-11-27 05:13:05,306 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,779 05:13:05 INFO - 2015-11-27 05:13:05,306 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:13:05 INFO - 2015-11-27 05:13:05,306 DEBUG : BROWSER_OUTPUT: __startTimestamp1448629985301__endTimestamp 05:13:05 INFO - 2015-11-27 05:13:05,306 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:4.48 (3.9%) stddev-sans-first:4.74 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: Values: 116.0 107.0 115.0 115.0 113.0 125.0 115.0 113.0 113.0 117.0 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:152.30 Median:159.00 stddev:17.61 (11.1%) stddev-sans-first:18.59 05:13:05 INFO - 2015-11-27 05:13:05,314 DEBUG : BROWSER_OUTPUT: Values: 157.0 134.0 163.0 183.0 130.0 161.0 157.0 136.0 167.0 135.0 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:1.63 (1.0%) stddev-sans-first:1.69 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: Values: 162.0 163.0 163.0 165.0 161.0 163.0 166.0 164.0 162.0 161.0 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.70 Median:72.50 stddev:22.27 (30.7%) stddev-sans-first:23.56 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 72.0 73.0 72.0 72.0 143.0 72.0 71.0 73.0 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:95.00 stddev:19.02 (20.0%) stddev-sans-first:19.77 05:13:05 INFO - 2015-11-27 05:13:05,315 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 135.0 94.0 94.0 95.0 135.0 95.0 95.0 133.0 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 111.0 110.0 110.0 110.0 109.0 110.0 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 148.0 148.0 148.0 148.0 147.0 147.0 148.0 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,316 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:75.90 Median:74.50 stddev:3.70 (5.0%) stddev-sans-first:3.91 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 83.0 74.0 74.0 79.0 73.0 81.0 73.0 73.0 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 69.0 70.0 69.0 70.0 69.0 69.0 69.0 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.00 stddev:3.54 (4.1%) stddev-sans-first:3.14 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: Values: 92.0 86.0 83.0 83.0 90.0 86.0 84.0 92.0 84.0 85.0 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:129.50 stddev:5.50 (4.2%) stddev-sans-first:5.45 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: Values: 134.0 129.0 122.0 126.0 130.0 140.0 124.0 130.0 123.0 126.0 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.20 Median:149.00 stddev:5.98 (4.0%) stddev-sans-first:5.96 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: Values: 154.0 156.0 149.0 136.0 155.0 149.0 147.0 145.0 144.0 147.0 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: 05:13:05 INFO - 2015-11-27 05:13:05,317 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 05:13:05 INFO - 2015-11-27 05:13:05,318 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 71.0 70.0 72.0 69.0 71.0 70.0 69.0 05:13:05 INFO - 2015-11-27 05:13:05,318 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:13:05 INFO - 2015-11-27 05:13:05,318 DEBUG : BROWSER_OUTPUT: 05:13:06 INFO - 2015-11-27 05:13:06,181 INFO : Browser exited with error code: 0 05:13:06 INFO - 2015-11-27 05:13:06,229 INFO : Completed test kraken (00:01:01) 05:13:06 INFO - 2015-11-27 05:13:06,230 INFO : Starting test v8_7 05:13:06 INFO - 2015-11-27 05:13:06,230 DEBUG : operating with platform_type : linux_ 05:13:06 INFO - 2015-11-27 05:13:06,230 INFO : Initialising browser for v8_7 test... 05:13:06 INFO - 2015-11-27 05:13:06,236 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7iFtWl/profile http://localhost:44670/getInfo.html 05:13:10 INFO - 2015-11-27 05:13:10,937 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:13:10 INFO - 2015-11-27 05:13:10,937 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:13:10 INFO - 2015-11-27 05:13:10,938 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:13:10 INFO - 2015-11-27 05:13:10,938 DEBUG : BROWSER_OUTPUT: __metrics 05:13:12 INFO - 2015-11-27 05:13:12,065 INFO : Browser initialized. 05:13:12 INFO - 2015-11-27 05:13:12,066 INFO : Running cycle 1/1 for v8_7 test... 05:13:12 INFO - 2015-11-27 05:13:12,066 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7iFtWl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:13:24 INFO - 2015-11-27 05:13:24,022 DEBUG : BROWSER_OUTPUT: RSS: Main: 138665984 05:13:24 INFO - 2015-11-27 05:13:24,022 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44670/tests/v8_7/run.html (next: http://localhost:44670/tests/v8_7/run.html) 05:13:45 INFO - 2015-11-27 05:13:45,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 137461760 05:13:45 INFO - 2015-11-27 05:13:45,737 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,737 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:13:45 INFO - 2015-11-27 05:13:45,737 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:13:45 INFO - 2015-11-27 05:13:45,737 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:13:45 INFO - 2015-11-27 05:13:45,738 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:13:45 INFO - 2015-11-27 05:13:45,738 DEBUG : BROWSER_OUTPUT: |0;Richards;167.44809109176154 05:13:45 INFO - 2015-11-27 05:13:45,738 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;184.84288354898337 05:13:45 INFO - 2015-11-27 05:13:45,738 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.135788585887 05:13:45 INFO - 2015-11-27 05:13:45,739 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 05:13:45 INFO - 2015-11-27 05:13:45,739 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1086.9565217391305 05:13:45 INFO - 2015-11-27 05:13:45,739 DEBUG : BROWSER_OUTPUT: |5;Earley;717.8750897343862 05:13:45 INFO - 2015-11-27 05:13:45,739 DEBUG : BROWSER_OUTPUT: |6;Boyer;12292.682926829268 05:13:45 INFO - 2015-11-27 05:13:45,740 DEBUG : BROWSER_OUTPUT: |7;RegExp;25300 05:13:45 INFO - 2015-11-27 05:13:45,740 DEBUG : BROWSER_OUTPUT: |8;Splay;683.9945280437756 05:13:45 INFO - 2015-11-27 05:13:45,740 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 05:13:45 INFO - 2015-11-27 05:13:45,740 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:13:45 INFO - 2015-11-27 05:13:45,741 DEBUG : BROWSER_OUTPUT: __startTimestamp1448630025734__endTimestamp 05:13:45 INFO - 2015-11-27 05:13:45,741 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,741 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:13:45 INFO - 2015-11-27 05:13:45,741 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:13:45 INFO - 2015-11-27 05:13:45,741 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,741 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.45 Median:167.45 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: Values: 167.4 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:184.84 Median:184.84 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: Values: 184.8 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.14 Median:281.14 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: Values: 281.1 05:13:45 INFO - 2015-11-27 05:13:45,742 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: Values: 5202.1 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1086.96 Median:1086.96 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: Values: 1087.0 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:717.88 Median:717.88 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: Values: 717.9 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12292.68 Median:12292.68 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,743 DEBUG : BROWSER_OUTPUT: Values: 12292.7 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25300.00 Median:25300.00 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: Values: 25300.0 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:683.99 Median:683.99 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: Values: 684.0 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:13:45 INFO - 2015-11-27 05:13:45,744 DEBUG : BROWSER_OUTPUT: Values: 4830.9 05:13:45 INFO - 2015-11-27 05:13:45,745 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:13:45 INFO - 2015-11-27 05:13:45,745 DEBUG : BROWSER_OUTPUT: 05:13:46 INFO - 2015-11-27 05:13:46,649 INFO : Browser exited with error code: 0 05:13:46 INFO - 2015-11-27 05:13:46,666 INFO : Completed test v8_7 (00:00:40) 05:13:46 INFO - 2015-11-27 05:13:46,756 INFO : Completed test suite (00:20:15) 05:13:46 INFO - 2015-11-27 05:13:46,756 DEBUG : Working with test: dromaeo_css 05:13:46 INFO - 2015-11-27 05:13:46,756 DEBUG : Generating results file: dromaeo_css 05:13:46 INFO - 2015-11-27 05:13:46,757 DEBUG : Working with test: kraken 05:13:46 INFO - 2015-11-27 05:13:46,757 DEBUG : Generating results file: kraken 05:13:46 INFO - 2015-11-27 05:13:46,757 INFO : javascript benchmark 05:13:46 INFO - 2015-11-27 05:13:46,758 DEBUG : Working with test: v8_7 05:13:46 INFO - 2015-11-27 05:13:46,758 DEBUG : Generating results file: v8_7 05:13:46 INFO - 2015-11-27 05:13:46,758 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:13:46 INFO - 2015-11-27 05:13:46,912 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 05:13:46 INFO - 2015-11-27 05:13:46,912 DEBUG : process_Request line: dromaeo_css 6294.07 graph.html#tests=[[72,132,43]] 05:13:46 INFO - 2015-11-27 05:13:46,913 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:13:46 INFO - 2015-11-27 05:13:46,945 DEBUG : process_Request line: kraken 1548.40 graph.html#tests=[[232,132,43]] 05:13:46 INFO - 2015-11-27 05:13:46,945 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:13:47 INFO - 2015-11-27 05:13:47,053 DEBUG : process_Request line: v8_7 21061.83 graph.html#tests=[[230,132,43]] 05:13:47 INFO - 2015-11-27 05:13:47,054 INFO : javascript benchmark 05:13:47 INFO - 2015-11-27 05:13:47,058 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2655.1768951625, 2663.507251158537, 2708.6614173228345, 2714.362124900648, 2729.358600728829, 4492.707973981689, 4653.767311634421, 4657.138429390565, 4662.533746625336, 4664.7667616619165, 4558.384618076559, 4678.461976872661, 4697.506761790014, 4700.7649617519155, 4706.458757242918, 4196.4334814799995, 4284.143171365724, 4340.066885619591, 4349.325854492558, 4381.364702118207, 4035.5835830717606, 4355.237833379162, 4363.410939523155, 4383.780810959455, 4474.798634061459, 4214.051838336381, 4368.432103826514, 4408.25059739845, 4453.042595841877, 4457.59881610655, 4213.768242746648, 4314.80583373749, 4335.761533115678, 4340.782960851976, 4342.913141737147, 4322.740635561876, 4348.21732088224, 4372.672049596261, 4383.912321753579, 4397.692161548693, 4454.198244316021, 4631.4442266927845, 4639.211334073216, 4641.930371044437, 4690.624750019991, 4851.054044461332, 4911.361523002022, 4974.602031837444, 4989.376327959005, 5035.370578677665, 5085.262636917663, 5091.694498330112, 5093.719845408538, 5094.414142373637, 5107.872303192413, 4502.842400515966, 4599.471060827981, 4710.5289471052965, 4718.834840780556, 4723.315119307681, 5040.445550989388, 5093.643444958891, 5103.438621751604, 5110.642255042111, 5128.333316668809, 4972.8508144755715, 4975.950240497621, 4976.975115124401, 4989.600831933437, 5023.49765023502, 4824.8793780155775, 4895.779689913962, 4937.802487900443, 5038.3198268234055, 5065.721385323769, 5008.198688209869, 5010.148274793294, 5012.04771093491, 5017.874553136127, 5021.347224860818, 4997.7251251180805, 4998.175301075355, 4998.450170481244, 5000.374953130859, 5030.597552195815, 4088.713790034669, 4137.40007698882, 4191.1827193697545, 4254.340577210537, 4306.074194048295, 3687.6681098701247, 3718.2749363873786, 3730.291244663505, 3768.2840260350445, 3769.566499852552, 4358.738475691469, 4371.912561748811, 4373.6282415994365, 4375.037491751809, 4395.077033822933, 4787.784549695272, 4902.362692849978, 4915.311856340115, 4981.95378970413, 5020.799168033238, 4366.6506679465565, 4374.0, 4395.164918665443, 4400.0, 4430.158269928703, 4373.606375426227, 4537.0925814837165, 4543.34121552373, 4546.227141385906, 4577.244754615451, 4670.673052886265, 4749.477557468745, 4760.59534939534, 4772.618190544678, 4804.135255654016, 5035.118854200574, 5054.924176137288, 5070.366204224472, 5077.365329333833, 5101.285819985131, 4570.88572785683, 4680.438347398334, 4761.166795810788, 4807.399075115611, 4856.490068542752, 4882.2432522959, 4925.6305777066145, 4958.033183529177, 4958.479359667183, 5006.148954677641, 5055.317532133115, 5080.542751152414, 5085.796568137233, 5088.084144853962, 5122.923156152735, 4715.386999690156, 4778.95221047799, 4816.084943860656, 4908.730019848943, 4951.282064100757, 4867.269909513601, 4906.63200259989, 4919.606431485544, 4933.186024305949, 4948.233023881304, 4810.5670489656095, 4824.107540105092, 4827.300041494034, 4848.75756212195, 4859.343988561641, 4713.2458806590785, 4809.495003024632, 4813.109574728686, 4918.803247870045, 4939.506049395175, 4793.400824896888, 4798.7360695160705, 4813.95186048135, 4822.734749588806, 4829.5653391194965], "unit": "ms", "name": "dojo.html", "value": 4607.479577121108}, {"lowerIsBetter": false, "replicates": [549.0967358694946, 553.2862607236665, 556.2351766321308, 557.8800557880057, 559.9944000559994, 37124.62875371245, 38258.61741382585, 38300.61699383005, 38327.0, 38344.80827595862, 40300.39549406754, 40788.59211407885, 40819.79590102053, 40842.978925526804, 40873.386899196536, 7936.7222147225, 8080.838383232332, 8114.066882308522, 8123.0, 8125.715599953974, 7900.525968441883, 7946.165652606451, 7962.960185199066, 7983.481073730218, 7996.840063198732, 7992.280694737473, 8069.596520173968, 8073.233042860918, 8081.7171399001045, 8123.918760812376, 7991.800204994864, 7999.240072193191, 8054.597270136528, 8066.314363279128, 8078.030636323679, 7905.644246008944, 8023.598820059032, 8031.799205019863, 8038.799030024237, 8057.435979481439, 37725.0, 38037.61962380369, 38143.23713525742, 38200.80899595512, 38298.23403531914, 2980.4784162771643, 3211.735726312944, 3248.870045198189, 3295.670432956709, 3376.3922493951077, 28238.0, 28353.71646283552, 28402.0, 28904.60562410842, 29158.27104322388, 3451.9482207766905, 3463.5497385339995, 3486.564179477565, 3490.8778192763384, 3494.72042236623, 207231.0, 207669.0, 208456.0, 208729.0, 208828.0, 47618.761906190244, 55002.44997549973, 55155.72422137864, 55376.44623553794, 55425.445745542835, 43458.130837383076, 65917.67041164859, 67244.3275567238, 67321.3267867315, 67581.66209168923, 12335.69160770988, 12615.495380184873, 12634.620961371174, 12720.554780582635, 12752.489900404065, 9585.0, 9821.508924553744, 9838.065383788671, 9840.57311689801, 9863.112319891101, 19357.612847743247, 19919.800801991794, 19923.701144482846, 19928.90035549813, 19981.800181998286, 7407.0, 7636.69453221876, 7642.541447513167, 7723.575203363756, 7728.4590078693955, 785.6778720724476, 875.6628697951335, 875.8160786234835, 947.5357075033185, 985.9014098590084, 25926.35184120412, 26493.205203843914, 26670.733292666824, 26706.0, 26755.866220668773, 928.2713070239964, 930.0978051290238, 937.7702462896634, 963.6001059560344, 964.5755867418313, 13468.461261549624, 13701.931490342484, 13883.930580347236, 13886.375113119933, 14273.429062837371, 254458.0, 259429.0, 260664.23689749444, 261487.0, 261560.0, 45939.54060459419, 46472.3029154563, 46581.6025519235, 46586.5341346589, 46767.76616116966, 1115.358668765463, 1131.2420678145663, 1136.1365362324693, 1160.3560024186488, 1164.5632887667125, 25064.0, 26171.214863554123, 26428.0, 26531.86734066317, 26572.867135664197, 40098.0, 40388.0, 40411.59588404196, 40685.59314406937, 40769.796151020244, 236468.0, 236507.0, 237245.0, 237626.0, 238915.0, 24677.876610616833, 25242.621360679237, 28608.57087143653, 28806.85596572004, 28890.566641500816, 34145.31709365849, 34523.0, 34731.65268347284, 35380.82309588538, 35421.46867796934, 31012.379752404373, 31214.531782023743, 31330.843345784037, 31889.521657175625, 31949.520757189126, 33021.17447063843, 33218.0, 33244.1688957778, 33668.66331336655, 33680.49479257862, 32957.67042329546, 33040.1739956503, 33266.33467330688, 33281.334373312886, 33291.16772080717, 28466.28834279077, 29725.0, 30581.694183057883, 30616.0, 30662.0, 23669.408264793517, 23734.0, 23756.0, 23963.76036239685, 24157.51684966326], "unit": "ms", "name": "ext.html", "value": 16751.793956341946}, {"lowerIsBetter": false, "replicates": [315.3692614770459, 317.1500378984322, 318.4506725897828, 323.61974679751285, 323.6876414260239, 14882.0, 14921.253937303132, 15240.161791101486, 15741.291641876114, 16139.919300403511, 8557.828843423144, 8713.346499012569, 10063.0, 13922.303884805744, 13994.510192143278, 3747.2130852521004, 4431.379606855026, 4432.756198409086, 4437.378766972626, 4449.377087207794, 4607.285870690049, 4734.4081989751285, 4738.4313882334, 4740.028294199681, 4741.739204343759, 4721.598664113537, 4728.645351598627, 6206.162168107316, 6561.901571476432, 6575.671216439183, 6196.10156527301, 6266.498680105604, 6312.5896816707, 6320.71556779946, 6348.714307856158, 6344.904826427607, 6348.143000694893, 6349.428551430347, 6350.714217860162, 6359.300476947532, 18983.71524427135, 20750.377488675364, 21381.0, 22065.0, 22150.667739983914, 1883.1525813383896, 1933.2460340467228, 1999.0604415924493, 2001.0495014867968, 2061.134323584098, 13849.653758656013, 13893.722125557431, 13922.0, 14095.22476263805, 14204.857951420458, 1860.5813691919345, 1877.108373522566, 1878.7933327333983, 1900.163927871732, 1915.5977244778612, 60848.0, 63032.36967630311, 64318.356816431704, 64360.67819660919, 65753.73970082382, 15160.620984475256, 15359.232038398035, 15403.691926161639, 15466.5360039199, 15527.689446211012, 23152.18967336135, 23166.53666926652, 23378.883105584362, 23468.765312347, 23604.88197559001, 7774.533527988338, 7801.4929029612895, 7810.804729881798, 7816.296533312029, 7922.36621070313, 6264.21697287839, 6276.623402595767, 6282.026285925689, 6286.339934306832, 6297.937020629826, 7454.739084132029, 7512.849743005109, 7527.774166775005, 7594.582297973554, 7619.9238007619215, 2883.178294186189, 2974.3456439583256, 2983.4480621085168, 2986.2086547065046, 3090.1193159949307, 321.88733943119723, 323.11788816531003, 329.90762586475824, 330.7073240182408, 338.25921232500747, 9341.299402544837, 9499.90500095004, 9657.855132173023, 9747.3664211826, 14814.703705925822, 803.9155888631726, 830.9958450207711, 846.4794151596724, 850.128618166372, 919.2048877720723, 8700.434471759316, 8846.557672116369, 8933.28533717314, 9042.909570904207, 9063.32025098107, 485439.0, 492198.0, 498203.0, 500526.0, 500848.0, 70778.0, 72540.99999999894, 73235.26764732391, 73889.26110738932, 74078.25921740821, 1044.7440377107657, 1070.882202957674, 1072.9248952573246, 1096.6107032003683, 1099.5217080569978, 13323.733525329246, 13560.86439135596, 13934.930325348309, 14080.507182248572, 14254.857451425352, 30492.695073048984, 32527.34945301128, 32586.02241932746, 33523.0, 34063.0, 44586.55413445824, 46176.30735539037, 46413.0, 46488.53511464977, 47400.525994740994, 7616.657250423855, 8324.83350333002, 8390.580470976305, 8434.283085937765, 8436.156384361515, 9540.0, 9711.854322185032, 14431.0, 16304.347826086823, 16493.83506164923, 13190.538331158363, 13278.269695166859, 13774.0, 13866.514671986835, 14193.503227386991, 13201.735965280832, 13268.736108683397, 13535.86464135346, 13842.169469831842, 14117.717645647235, 14037.1577705338, 14258.0, 14264.072835265675, 14496.492622758153, 14532.128072315694, 7793.454458187873, 7891.881621775563, 7941.444098913021, 7944.960275198587, 7982.760517184493, 8285.418841759963, 8288.627011784363, 14194.290285485891, 14226.715465690835, 14260.286985650884], "unit": "ms", "name": "jquery.html", "value": 8587.394130142115}, {"lowerIsBetter": false, "replicates": [1470.7940888275637, 1579.6524764551798, 1614.7981502312211, 1628.9837101628987, 1651.0506458786197, 5961.135635332874, 6089.969550152254, 6644.402003819655, 6887.3457021582935, 6893.4140598049225, 6406.135171751812, 6520.217573891128, 6553.508486863492, 6558.639274839894, 6573.3098024707315, 1934.351992082654, 1963.224526312113, 1969.0253324604269, 1974.3583335416047, 1975.0, 1949.4249196487071, 1967.3114409956472, 1981.217419119447, 1993.4418362858423, 2005.4986253436641, 1916.8945707986056, 1921.372152554357, 1934.8355389791884, 1939.85451091168, 1946.8150525700037, 1865.3844231403605, 1880.097553174484, 1885.830275275225, 1893.943181704551, 1899.3257393625202, 1912.540990162365, 1931.94204173875, 1933.5166208447888, 2010.7486564179478, 2015.0, 6673.132492775908, 6674.432673222782, 6708.060871477997, 6719.798406047827, 6724.529282950195, 3785.716071294664, 3866.709996750255, 3868.0, 3888.902777430587, 3894.3963685628773, 2575.7166711661703, 2606.7784238339764, 2620.8296460729994, 2629.198094581152, 2646.78825693944, 828.6892415344246, 888.373696543935, 891.416122439803, 935.780091678455, 941.4445477168435, 8725.694600688945, 8761.561921903944, 8763.474191548528, 8786.824263514694, 8800.999999999936, 3478.826058697055, 3527.3827080260853, 3554.662307080823, 3557.4130268505405, 3568.232829941575, 7754.9224507755325, 7788.532688038736, 7790.026246719161, 7791.922080779233, 7799.259070388304, 3865.7100717446306, 3914.64768170866, 3921.254961557295, 3925.5878132795647, 3930.194310166409, 3318.452455344841, 3406.7785593936314, 3496.597891242489, 3513.1392808761507, 3525.153963048901, 3065.294982154117, 3069.248034231583, 3070.247789291593, 3082.044566184445, 3092.273315770792, 917.1378903830378, 918.4443411735937, 919.0763282900775, 920.2132176988712, 921.3826736086838, 1105.165599972016, 1120.2214460949724, 1125.201107213871, 1127.5264388956657, 1164.470166074434, 6269.7492100316385, 6271.310155882849, 6286.591371560834, 6297.118403423525, 6304.968475157595, 1107.2581370481796, 1112.2214449885112, 1126.2285334545863, 1136.3011747775058, 1143.3768596115137, 4963.429205641325, 5070.670406423571, 5212.166053431432, 5241.030409374278, 5261.4738526147075, 7890.526568405914, 7904.130545639975, 8075.636596353178, 8089.555074470961, 8122.147174546706, 7284.70861165558, 7448.4786064976015, 7485.550866947891, 7486.176520582731, 7913.169117242606, 6337.746490140343, 6395.872082558323, 6406.071119687713, 6430.356964303626, 6436.388543088399, 6214.285357183981, 6272.8118156455375, 6327.145835312174, 6329.873402531832, 6338.271098823694, 7038.753643622448, 7205.603691796792, 7227.421806255382, 7231.385332246818, 7279.235680253497, 7230.313120253568, 7296.96351518239, 7356.889646655198, 7359.632018398952, 7418.0, 5289.20661900718, 5397.730113494231, 6097.878042439038, 6130.601510901777, 6132.141500189888, 6144.0, 6313.337099604475, 6337.366263373626, 6353.809385718436, 6361.109444677842, 5715.799947001835, 5894.557908156819, 6004.699765011645, 6069.666168360783, 6254.874902502015, 5936.703164841827, 6013.5189184865985, 6139.815805525842, 6157.568970172045, 6177.6911154441195, 6123.142760013513, 6224.595401298901, 6228.968855155696, 6238.719257633508, 6246.562740608114, 5723.3418156912485, 5778.653280803165, 5782.508486778671, 5783.277090363705, 5813.680247586423, 5749.108888122348, 5803.129530570448, 5842.737076831638, 5862.912056319244, 5926.733297001731], "unit": "ms", "name": "mootools.html", "value": 3877.6853982132825}, {"lowerIsBetter": false, "replicates": [282.35763637724176, 287.8388102662508, 290.7833663920378, 292.06830211624913, 293.2799976058776, 5289.391719952207, 5564.387917329095, 5602.971985140069, 5652.09566469365, 5661.0, 5553.694546799935, 5555.083411237151, 5559.749811258493, 5565.443455654443, 5592.692401917893, 4303.870883873481, 4305.5909688579695, 4308.0, 4315.784210789464, 4327.329263964075, 4302.48370195577, 4307.504636966758, 4311.655067594601, 4326.43756311679, 4334.739915605058, 4062.4718786557705, 4186.49762028557, 4267.14657068586, 4285.442892423996, 4300.591443812833, 4193.622573968358, 4217.8312867485265, 4220.725652832556, 4237.703360764748, 4241.554636763157, 3633.0, 3665.6334366563397, 3666.633336666339, 3672.063623775947, 3677.2277821657485, 5292.126799078147, 5314.362276526803, 5314.813981510629, 5314.9468505314835, 5325.0, 4767.570918617341, 4860.635452341088, 4864.708117512925, 4865.41615006198, 4888.511148885119, 5759.0, 5798.217240672498, 5807.912881306784, 5827.854303642401, 5842.386549412293, 3975.840966361342, 3979.7612143271494, 3990.5610382857863, 3990.940135897935, 3999.3800960851113, 5940.0, 6077.665728384937, 6158.076288556707, 6161.260648722137, 6166.136740856239, 4068.16602596467, 4082.9387559186043, 4096.9795151024055, 4109.609587089221, 4124.52567954689, 4129.772862492592, 4133.338665813455, 4158.064435502049, 4162.146759914271, 4175.97912010438, 4792.544708252711, 4792.592629626451, 4792.7363994980615, 4802.567768900746, 4820.25286080658, 5708.0581704019205, 5820.738066787005, 5832.416758324133, 5834.94165058344, 5838.036723940502, 1451.7822326651087, 1471.757160068598, 1472.8968972171845, 1473.9336729846968, 1475.1739026145392, 970.0832713086066, 971.9173870220969, 978.5351957819978, 994.2493417469764, 997.670768646345, 5066.417362003343, 5074.492550744896, 5092.796288148433, 5093.541581257705, 5097.9745101275, 6055.5163984824, 6074.240719910012, 6090.512758979271, 6102.816915492543, 6110.083487476825, 5089.770960306795, 5090.389153301628, 5096.108181068298, 5100.69395836251, 5108.744562771846, 3193.297474555594, 3267.134209434502, 3296.044147197334, 3298.620658624241, 3300.3234336961345, 5780.104083867007, 5788.710564471759, 5797.536197104306, 5807.738651760681, 5823.912641310384, 6281.905771413432, 6330.68346582669, 6338.207724034496, 6347.5556711029785, 6351.936480635227, 5675.2054712340305, 5774.105013722879, 5792.420757924174, 5809.0, 5819.214406055128, 6114.908276375769, 6151.784687535915, 6154.353792851865, 6155.384461553809, 6157.784477543264, 5994.250718660168, 5996.040633498619, 6014.8195554133445, 6017.428344307284, 6022.337542870193, 5525.833345821669, 5758.654480731169, 5784.1034639630925, 5787.305523337226, 5817.883642327214, 3771.321162190832, 4028.637422631977, 4064.3293856513346, 4112.239235741397, 4127.360259159835, 6043.063325184604, 6063.423974722395, 6070.0, 6072.119542666201, 6073.180120683651, 5803.187553742395, 5809.2738407699035, 5821.417858214314, 5827.271591051119, 5833.0667093266575, 5736.6558006519745, 5838.3869693683255, 5875.383084776037, 5889.234399528034, 5896.997510423153, 5757.769689212552, 5774.971125144515, 5781.306243250837, 5794.565407594531, 5799.246098007232, 5900.055991041413, 5902.4097590240635, 5929.644221346733, 5963.522918166623, 5979.073243647242, 5625.128105143709, 5744.655320680772, 5775.884482310246, 5782.074868021265, 5796.101604251347], "unit": "ms", "name": "prototype.html", "value": 4357.4066521525465}, {"lowerIsBetter": false, "replicates": [736.1644533454529, 751.7556794041931, 752.8833030880215, 779.5634444710959, 779.9493032952857, 10398.584056637725, 10606.310589811657, 10755.62355317565, 10759.300645458039, 10768.78462430751, 10149.797004059914, 10216.335938164033, 10256.897431025705, 10265.589376424934, 10273.434961077137, 590.1266126133314, 602.5390576209204, 761.702935855017, 773.0877564473918, 775.9107702614187, 769.5690413368503, 770.0336078221824, 776.3750181104238, 778.1479280188213, 779.0534500581792, 807.5800583696474, 817.652497688483, 819.9098099209082, 820.4216027700456, 821.48246604639, 807.3057170833079, 809.2878267124893, 810.8662070758318, 811.5942028985507, 815.8164413007086, 594.5570549940322, 600.6546235914367, 602.0667964654812, 602.6113157013746, 603.110412142087, 10592.205584581186, 10690.62582809598, 10692.893071069346, 10716.0, 10752.462376881125, 10980.725481862937, 11844.170908036442, 11889.227200231957, 11895.643130706007, 12205.084618653547, 9183.540822958892, 9414.764630884283, 9427.67003154893, 9434.0, 9483.952580237055, 3184.7133757961665, 3261.429249881285, 3269.591301087364, 3294.1764558860286, 3325.866965321384, 39967.200655986715, 40319.193616127806, 40400.0, 40418.39372409416, 40453.1909361814, 8750.0, 9058.0, 9112.95443522778, 9129.77175570616, 9410.435373877452, 17100.65798684044, 17131.143442827808, 17216.913915430592, 17217.569560760832, 17234.482965511055, 6336.936630633727, 6367.681615919186, 6561.0, 6561.0814485972005, 6566.737330506822, 3204.9359012819614, 3328.3509715605223, 3341.8663253470077, 3354.6142193647556, 3388.3900897838625, 2496.2386472126177, 2543.0, 2546.5034318307753, 2548.2737419835257, 2549.4263790646955, 446.3394176618558, 451.9435070616173, 456.56398139776434, 458.79354290569376, 462.4496848749977, 510.69358384968723, 530.0141736370348, 542.6608369768894, 568.8464114689011, 571.1803561888703, 7538.396928245727, 7703.1526532081425, 7805.609719514002, 7815.374770018385, 7961.164077771867, 467.50678034673746, 473.8057396467432, 499.07670809003054, 503.51913922204204, 542.1948406616171, 3650.2152037312107, 3659.304732100892, 3724.7578907370935, 3871.380579107329, 3946.0, 46394.76802616033, 47275.29087063697, 47276.29085563719, 47409.762951185716, 47518.76240618844, 41568.37647435291, 42288.0, 42559.14881702411, 42593.0, 42774.78612606917, 42967.57032429761, 43532.0, 43792.12415751667, 43879.78060109679, 43974.12051758947, 7760.650770715219, 7760.961195193988, 7840.098388685375, 7987.16134805837, 8052.637631306722, 29751.404971900873, 30013.5497967535, 30486.390272194876, 30724.231894202825, 32222.677773221967, 39229.0, 40278.798606006785, 40279.3958090623, 40342.79828600838, 40397.798011010935, 9930.25523085786, 9940.353876997971, 9957.253206009433, 10175.7456063599, 10211.081002709792, 11181.888181118085, 11318.207725459139, 11430.1998860079, 11587.942060289644, 11638.0, 9415.905840941503, 9496.43021418717, 9659.034096590285, 9910.15763660097, 9910.504474776377, 9889.357191782512, 9911.207103431563, 10167.389956602627, 10245.0, 10428.217883658604, 9569.425834449956, 9713.465759383302, 9782.461964592016, 9830.361026533252, 10351.534180962026, 8985.910140898508, 9086.863697044682, 9202.769930751785, 9384.202342800938, 9528.142467177988, 8099.959500202462, 8332.050129396619, 8361.122082181284, 8443.363619635878, 8551.572421378782], "unit": "ms", "name": "yui.html", "value": 5550.796133583826}], "name": "dromaeo_css", "value": 6294.068338816075}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 107.0, 115.0, 115.0, 113.0, 125.0, 115.0, 113.0, 113.0, 117.0], "unit": "ms", "name": "ai-astar", "value": 114.9}, {"lowerIsBetter": true, "replicates": [157.0, 134.0, 163.0, 183.0, 130.0, 161.0, 157.0, 136.0, 167.0, 135.0], "unit": "ms", "name": "audio-beat-detection", "value": 152.3}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 163.0, 165.0, 161.0, 163.0, 166.0, 164.0, 162.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 163.0}, {"lowerIsBetter": true, "replicates": [75.0, 74.0, 72.0, 73.0, 72.0, 72.0, 143.0, 72.0, 71.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 79.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 135.0, 94.0, 94.0, 95.0, 135.0, 95.0, 95.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 111.0, 110.0, 110.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 147.0, 147.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [75.0, 74.0, 83.0, 74.0, 74.0, 79.0, 73.0, 81.0, 73.0, 73.0], "unit": "ms", "name": "json-parse-financial", "value": 75.9}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 69.0, 70.0, 69.0, 70.0, 69.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [92.0, 86.0, 83.0, 83.0, 90.0, 86.0, 84.0, 92.0, 84.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.5}, {"lowerIsBetter": true, "replicates": [134.0, 129.0, 122.0, 126.0, 130.0, 140.0, 124.0, 130.0, 123.0, 126.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 128.4}, {"lowerIsBetter": true, "replicates": [154.0, 156.0, 149.0, 136.0, 155.0, 149.0, 147.0, 145.0, 144.0, 147.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.2}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 71.0, 70.0, 72.0, 69.0, 71.0, 70.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.2}], "name": "kraken", "value": 1548.4000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.44809109176154], "unit": "score", "name": "Richards", "value": 210.82354399999997}, {"lowerIsBetter": false, "replicates": [184.84288354898337], "unit": "score", "name": "DeltaBlue", "value": 357.6983799999999}, {"lowerIsBetter": false, "replicates": [281.135788585887], "unit": "score", "name": "Encrypt", "value": 946.8058169999996}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1086.9565217391305], "unit": "score", "name": "RayTrace", "value": 680.7898799999998}, {"lowerIsBetter": false, "replicates": [717.8750897343862], "unit": "score", "name": "Earley", "value": 928.3829590000004}, {"lowerIsBetter": false, "replicates": [12292.682926829268], "unit": "score", "name": "Boyer", "value": 54.21623611111113}, {"lowerIsBetter": false, "replicates": [25300.0], "unit": "score", "name": "RegExp", "value": 36.007312252964404}, {"lowerIsBetter": false, "replicates": [683.9945280437756], "unit": "score", "name": "Splay", "value": 119.13984200000004}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21061.827729891775}]} 05:13:47 INFO - RETURN: dromaeo_css: 6294.07 05:13:47 INFO - RETURN: kraken: 1548.40 05:13:47 INFO - RETURN: v8_7: 21061.83 05:13:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21061.83"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1548.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6294.07"}}} 05:13:47 INFO - Return code: 0 05:13:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:13:47 INFO - # TBPL SUCCESS # 05:13:47 INFO - Running post-action listener: _resource_record_post_action 05:13:47 INFO - Running post-run listener: _resource_record_post_run 05:13:48 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 502636544; Read time: 16; Write time: 426352 05:13:48 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 229617664; Read time: 0; Write time: 286320 05:13:48 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 4096; Write bytes: 273018880; Read time: 16; Write time: 140032 05:13:48 INFO - Running post-run listener: _upload_blobber_files 05:13:48 INFO - Blob upload gear active. 05:13:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:13:48 INFO - Copying logs to upload dir... 05:13:48 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1273.760647 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-27 05:13:48.349798) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-27 05:13:48.356703) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009994 build_url: 'https://queue.taskcluster.net/v1/task/zRYqzmbtRZuDETykfqu9iw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-27 05:13:48.408249) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 05:13:48.408570) ========= 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=23f5739b2d6aa5d9caf5c428000001ae-1448626958.934377-405044638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004611 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-27 05:13:48.459563) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-27 05:13:48.460285) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-27 05:13:48.462629) ========= ========= Total master_lag: 0.27 =========