builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-058 starttime: 1450302965.86 results: success (0) buildid: 20151216124705 builduid: 05c5456ac3804f26a0bf80572e3ba27d revision: 5621bf5d87e491c8856b90e5ff86919c96bf04c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.863016) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.863662) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.864058) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.004381 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.881835) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.882187) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.896042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.896447) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.946148) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.946485) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.946879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:05.947191) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 13:56:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2015-12-16 13:56:06 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115742 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.075924) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.076265) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020457 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.128224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.128777) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5621bf5d87e491c8856b90e5ff86919c96bf04c6 --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 5621bf5d87e491c8856b90e5ff86919c96bf04c6 --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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 13:56:06,611 truncating revision to first 12 chars 2015-12-16 13:56:06,611 Setting DEBUG logging. 2015-12-16 13:56:06,611 attempt 1/10 2015-12-16 13:56:06,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5621bf5d87e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 13:56:06,896 unpacking tar archive at: fx-team-5621bf5d87e4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558942 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.701509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.701904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.711103) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.711460) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 13:56:06.711979) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-16 13:56:06.712346) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:56:07 INFO - MultiFileLogger online at 20151216 13:56:07 in /builds/slave/test 13:56:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 13:56:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:56:07 INFO - {'append_to_log': False, 13:56:07 INFO - 'base_work_dir': '/builds/slave/test', 13:56:07 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 13:56:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:56:07 INFO - 'branch': 'Fx-Team-Non-PGO', 13:56:07 INFO - 'buildbot_json_path': 'buildprops.json', 13:56:07 INFO - 'config_files': ('talos/linux_config.py',), 13:56:07 INFO - 'default_actions': ('clobber', 13:56:07 INFO - 'read-buildbot-config', 13:56:07 INFO - 'download-and-extract', 13:56:07 INFO - 'populate-webroot', 13:56:07 INFO - 'create-virtualenv', 13:56:07 INFO - 'install', 13:56:07 INFO - 'run-tests'), 13:56:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:56:07 INFO - 'download_minidump_stackwalk': True, 13:56:07 INFO - 'download_symbols': 'ondemand', 13:56:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:56:07 INFO - 'tooltool.py': '/tools/tooltool.py', 13:56:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:56:07 INFO - '/tools/misc-python/virtualenv.py')}, 13:56:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:56:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:56:07 INFO - 'installer_path': 'installer.exe', 13:56:07 INFO - 'log_level': 'info', 13:56:07 INFO - 'log_name': 'talos', 13:56:07 INFO - 'log_to_console': True, 13:56:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:56:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:56:07 INFO - 'opt_config_files': (), 13:56:07 INFO - 'pip_index': False, 13:56:07 INFO - 'sps_profile': False, 13:56:07 INFO - 'sps_profile_interval': 0, 13:56:07 INFO - 'suite': 'dromaeojs', 13:56:07 INFO - 'system_bits': '64', 13:56:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:56:07 INFO - 'title': 'talos-linux64-ix-058', 13:56:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:56:07 INFO - 'use_talos_json': True, 13:56:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:56:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:56:07 INFO - 'work_dir': 'build'} 13:56:07 INFO - ##### 13:56:07 INFO - ##### Running clobber step. 13:56:07 INFO - ##### 13:56:07 INFO - Running pre-action listener: _resource_record_pre_action 13:56:07 INFO - Running main action method: clobber 13:56:07 INFO - rmtree: /builds/slave/test/build 13:56:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:56:07 INFO - Running post-action listener: _resource_record_post_action 13:56:07 INFO - ##### 13:56:07 INFO - ##### Running read-buildbot-config step. 13:56:07 INFO - ##### 13:56:07 INFO - Running pre-action listener: _resource_record_pre_action 13:56:07 INFO - Running main action method: read_buildbot_config 13:56:07 INFO - Using buildbot properties: 13:56:07 INFO - { 13:56:07 INFO - "project": "", 13:56:07 INFO - "product": "firefox", 13:56:07 INFO - "script_repo_revision": "production", 13:56:07 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 13:56:07 INFO - "repository": "", 13:56:07 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 13:56:07 INFO - "buildid": "20151216124705", 13:56:07 INFO - "pgo_build": "False", 13:56:07 INFO - "basedir": "/builds/slave/test", 13:56:07 INFO - "buildnumber": 2226, 13:56:07 INFO - "slavename": "talos-linux64-ix-058", 13:56:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:56:07 INFO - "platform": "ubuntu64_hw", 13:56:07 INFO - "scheduler": "tests-fx-team-linux64-talos", 13:56:07 INFO - "branch": "fx-team", 13:56:07 INFO - "repo_path": "integration/fx-team", 13:56:07 INFO - "revision": "5621bf5d87e491c8856b90e5ff86919c96bf04c6", 13:56:07 INFO - "stage_platform": "linux64", 13:56:07 INFO - "builduid": "05c5456ac3804f26a0bf80572e3ba27d", 13:56:07 INFO - "slavebuilddir": "test" 13:56:07 INFO - } 13:56:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 13:56:07 INFO - Running post-action listener: _resource_record_post_action 13:56:07 INFO - ##### 13:56:07 INFO - ##### Running download-and-extract step. 13:56:07 INFO - ##### 13:56:07 INFO - Running pre-action listener: _resource_record_pre_action 13:56:07 INFO - Running main action method: download_and_extract 13:56:07 INFO - mkdir: /builds/slave/test/build/tests 13:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:07 INFO - https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 13:56:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/test_packages.json 13:56:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/test_packages.json 13:56:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 13:56:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 13:56:08 INFO - Downloaded 1302 bytes. 13:56:08 INFO - Reading from file /builds/slave/test/build/test_packages.json 13:56:08 INFO - Using the following test package requirements: 13:56:08 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 13:56:08 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:56:08 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'jsshell-linux-x86_64.zip'], 13:56:08 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:56:08 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 13:56:08 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:56:08 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:56:08 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:56:08 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 13:56:08 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 13:56:08 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:56:08 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:08 INFO - https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:56:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:56:09 INFO - Downloaded 21870297 bytes. 13:56:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 13:56:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:09 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 13:56:10 INFO - Return code: 0 13:56:10 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:10 INFO - https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:56:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:56:11 INFO - Downloaded 21870297 bytes. 13:56:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 13:56:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 13:56:11 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 13:56:13 INFO - Return code: 0 13:56:13 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')]} 13:56:13 INFO - https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:56:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 13:56:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 13:56:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 13:56:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:56:13 INFO - Downloaded 11132052 bytes. 13:56:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 13:56:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 13:56:13 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 13:56:14 INFO - Return code: 0 13:56:14 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')]} 13:56:14 INFO - https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:56:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 13:56:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 13:56:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 13:56:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:56:15 INFO - Downloaded 57773015 bytes. 13:56:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 13:56:15 INFO - mkdir: /builds/slave/test/properties 13:56:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:56:15 INFO - Writing to file /builds/slave/test/properties/build_url 13:56:15 INFO - Contents: 13:56:15 INFO - build_url:https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 13:56:15 INFO - Running post-action listener: _resource_record_post_action 13:56:15 INFO - Running post-action listener: set_extra_try_arguments 13:56:15 INFO - ##### 13:56:15 INFO - ##### Running populate-webroot step. 13:56:15 INFO - ##### 13:56:15 INFO - Running pre-action listener: _resource_record_pre_action 13:56:15 INFO - Running main action method: populate_webroot 13:56:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:56:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:56:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:56:15 INFO - '--tppagecycles', 13:56:15 INFO - '7'], 13:56:15 INFO - 'tests': ['tsvgm']}}, 13:56:15 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:56:15 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:56:15 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:56:15 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:56:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:56:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:56:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'tests': ['damp', 'tps']}, 13:56:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['damp', 'tps']}, 13:56:15 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:56:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:56:15 INFO - 'other': {'tests': ['a11yr', 13:56:15 INFO - 'ts_paint', 13:56:15 INFO - 'tpaint', 13:56:15 INFO - 'sessionrestore', 13:56:15 INFO - 'sessionrestore_no_auto_restore']}, 13:56:15 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['a11yr', 13:56:15 INFO - 'ts_paint', 13:56:15 INFO - 'tpaint', 13:56:15 INFO - 'sessionrestore', 13:56:15 INFO - 'sessionrestore_no_auto_restore']}, 13:56:15 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['a11yr', 13:56:15 INFO - 'ts_paint', 13:56:15 INFO - 'tpaint', 13:56:15 INFO - 'sessionrestore', 13:56:15 INFO - 'sessionrestore_no_auto_restore']}, 13:56:15 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['a11yr', 13:56:15 INFO - 'ts_paint', 13:56:15 INFO - 'tpaint', 13:56:15 INFO - 'sessionrestore', 13:56:15 INFO - 'sessionrestore_no_auto_restore']}, 13:56:15 INFO - 'other_l64': {'tests': ['a11yr', 13:56:15 INFO - 'ts_paint', 13:56:15 INFO - 'tpaint', 13:56:15 INFO - 'sessionrestore', 13:56:15 INFO - 'sessionrestore_no_auto_restore']}, 13:56:15 INFO - 'other_nol64': {'tests': ['a11yr', 13:56:15 INFO - 'ts_paint', 13:56:15 INFO - 'tpaint', 13:56:15 INFO - 'sessionrestore', 13:56:15 INFO - 'sessionrestore_no_auto_restore']}, 13:56:15 INFO - 'svgr': {'tests': ['tsvgx', 13:56:15 INFO - 'tsvgr_opacity', 13:56:15 INFO - 'tart', 13:56:15 INFO - 'tscrollx', 13:56:15 INFO - 'cart']}, 13:56:15 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['tsvgx', 13:56:15 INFO - 'tsvgr_opacity', 13:56:15 INFO - 'tart', 13:56:15 INFO - 'tscrollx', 13:56:15 INFO - 'cart']}, 13:56:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'tests': ['tp5o']}, 13:56:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'talos_options': ['--e10s'], 13:56:15 INFO - 'tests': ['tp5o']}, 13:56:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'talos_options': ['--xperf_path', 13:56:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:56:15 INFO - 'tests': ['tp5n']}, 13:56:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:56:15 INFO - 'talos_options': ['--e10s', 13:56:15 INFO - '--xperf_path', 13:56:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:56:15 INFO - 'tests': ['tp5n']}}, 13:56:15 INFO - 'talos.zip': {'path': '', 13:56:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:56:15 INFO - Running post-action listener: _resource_record_post_action 13:56:15 INFO - ##### 13:56:15 INFO - ##### Running create-virtualenv step. 13:56:15 INFO - ##### 13:56:15 INFO - Running pre-action listener: _resource_record_pre_action 13:56:15 INFO - Running main action method: create_virtualenv 13:56:15 INFO - Creating virtualenv /builds/slave/test/build/venv 13:56:15 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 13:56:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:56:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:56:15 INFO - Using real prefix '/usr' 13:56:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:56:16 INFO - Installing distribute.............................................................................................................................................................................................done. 13:56:18 INFO - Installing pip.................done. 13:56:18 INFO - Return code: 0 13:56:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:56:18 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')]} 13:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:18 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')]} 13:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:18 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:18 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 13:56:18 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 13:56:18 INFO - Using env: {'DISPLAY': ':0', 13:56:18 INFO - 'HOME': '/home/cltbld', 13:56:18 INFO - 'LANG': 'en_US.UTF-8', 13:56:18 INFO - 'LANGUAGE': 'en_US:en', 13:56:18 INFO - 'LOGNAME': 'cltbld', 13:56:18 INFO - 'MAIL': '/var/mail/cltbld', 13:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:18 INFO - 'MOZ_NO_REMOTE': '1', 13:56:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:18 INFO - 'NO_EM_RESTART': '1', 13:56:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:18 INFO - 'PWD': '/builds/slave/test', 13:56:18 INFO - 'SHELL': '/bin/bash', 13:56:18 INFO - 'SHLVL': '1', 13:56:18 INFO - 'TERM': 'linux', 13:56:18 INFO - 'TMOUT': '86400', 13:56:18 INFO - 'USER': 'cltbld', 13:56:18 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:18 INFO - '_': '/tools/buildbot/bin/python'} 13:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:18 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 13:56:18 INFO - Cleaning up... 13:56:18 INFO - Return code: 0 13:56:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:56:18 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')]} 13:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:18 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')]} 13:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:18 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:18 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 13:56:18 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 13:56:18 INFO - Using env: {'DISPLAY': ':0', 13:56:18 INFO - 'HOME': '/home/cltbld', 13:56:18 INFO - 'LANG': 'en_US.UTF-8', 13:56:18 INFO - 'LANGUAGE': 'en_US:en', 13:56:18 INFO - 'LOGNAME': 'cltbld', 13:56:18 INFO - 'MAIL': '/var/mail/cltbld', 13:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:18 INFO - 'MOZ_NO_REMOTE': '1', 13:56:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:18 INFO - 'NO_EM_RESTART': '1', 13:56:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:18 INFO - 'PWD': '/builds/slave/test', 13:56:18 INFO - 'SHELL': '/bin/bash', 13:56:18 INFO - 'SHLVL': '1', 13:56:18 INFO - 'TERM': 'linux', 13:56:18 INFO - 'TMOUT': '86400', 13:56:18 INFO - 'USER': 'cltbld', 13:56:18 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:18 INFO - '_': '/tools/buildbot/bin/python'} 13:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:18 INFO - Downloading/unpacking psutil>=0.7.1 13:56:18 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 13:56:18 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 13:56:18 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 13:56:18 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 13:56:18 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 13:56:18 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 13:56:20 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 13:56:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 13:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:56:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:56:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:56:20 INFO - Installing collected packages: psutil 13:56:20 INFO - Running setup.py install for psutil 13:56:20 INFO - building 'psutil._psutil_linux' extension 13:56:20 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 13:56:20 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 13:56:20 INFO - building 'psutil._psutil_posix' extension 13:56:20 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 13:56:20 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 13:56:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:56:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:56:20 INFO - Successfully installed psutil 13:56:20 INFO - Cleaning up... 13:56:20 INFO - Return code: 0 13:56:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:20 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:20 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 13:56:20 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 13:56:20 INFO - Using env: {'DISPLAY': ':0', 13:56:20 INFO - 'HOME': '/home/cltbld', 13:56:20 INFO - 'LANG': 'en_US.UTF-8', 13:56:20 INFO - 'LANGUAGE': 'en_US:en', 13:56:20 INFO - 'LOGNAME': 'cltbld', 13:56:20 INFO - 'MAIL': '/var/mail/cltbld', 13:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:20 INFO - 'MOZ_NO_REMOTE': '1', 13:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:20 INFO - 'NO_EM_RESTART': '1', 13:56:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:20 INFO - 'PWD': '/builds/slave/test', 13:56:20 INFO - 'SHELL': '/bin/bash', 13:56:20 INFO - 'SHLVL': '1', 13:56:20 INFO - 'TERM': 'linux', 13:56:20 INFO - 'TMOUT': '86400', 13:56:20 INFO - 'USER': 'cltbld', 13:56:20 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:20 INFO - '_': '/tools/buildbot/bin/python'} 13:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:56:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 13:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:56:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:56:22 INFO - Installing collected packages: mozsystemmonitor 13:56:22 INFO - Running setup.py install for mozsystemmonitor 13:56:23 INFO - Successfully installed mozsystemmonitor 13:56:23 INFO - Cleaning up... 13:56:23 INFO - Return code: 0 13:56:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:23 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:23 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 13:56:23 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 13:56:23 INFO - Using env: {'DISPLAY': ':0', 13:56:23 INFO - 'HOME': '/home/cltbld', 13:56:23 INFO - 'LANG': 'en_US.UTF-8', 13:56:23 INFO - 'LANGUAGE': 'en_US:en', 13:56:23 INFO - 'LOGNAME': 'cltbld', 13:56:23 INFO - 'MAIL': '/var/mail/cltbld', 13:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:23 INFO - 'MOZ_NO_REMOTE': '1', 13:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:23 INFO - 'NO_EM_RESTART': '1', 13:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:23 INFO - 'PWD': '/builds/slave/test', 13:56:23 INFO - 'SHELL': '/bin/bash', 13:56:23 INFO - 'SHLVL': '1', 13:56:23 INFO - 'TERM': 'linux', 13:56:23 INFO - 'TMOUT': '86400', 13:56:23 INFO - 'USER': 'cltbld', 13:56:23 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:23 INFO - '_': '/tools/buildbot/bin/python'} 13:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:23 INFO - Downloading/unpacking blobuploader==1.2.4 13:56:23 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 13:56:23 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 13:56:23 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 13:56:23 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 13:56:23 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 13:56:23 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 13:56:24 INFO - Downloading blobuploader-1.2.4.tar.gz 13:56:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 13:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:56:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 13:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:56:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 INFO - Downloading docopt-0.6.1.tar.gz 13:56:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 13:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:56:25 INFO - Installing collected packages: blobuploader, requests, docopt 13:56:25 INFO - Running setup.py install for blobuploader 13:56:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:56:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:56:25 INFO - Running setup.py install for requests 13:56:26 INFO - Running setup.py install for docopt 13:56:26 INFO - Successfully installed blobuploader requests docopt 13:56:26 INFO - Cleaning up... 13:56:26 INFO - Return code: 0 13:56:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:26 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:26 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 13:56:26 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 13:56:26 INFO - Using env: {'DISPLAY': ':0', 13:56:26 INFO - 'HOME': '/home/cltbld', 13:56:26 INFO - 'LANG': 'en_US.UTF-8', 13:56:26 INFO - 'LANGUAGE': 'en_US:en', 13:56:26 INFO - 'LOGNAME': 'cltbld', 13:56:26 INFO - 'MAIL': '/var/mail/cltbld', 13:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:26 INFO - 'MOZ_NO_REMOTE': '1', 13:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:26 INFO - 'NO_EM_RESTART': '1', 13:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:26 INFO - 'PWD': '/builds/slave/test', 13:56:26 INFO - 'SHELL': '/bin/bash', 13:56:26 INFO - 'SHLVL': '1', 13:56:26 INFO - 'TERM': 'linux', 13:56:26 INFO - 'TMOUT': '86400', 13:56:26 INFO - 'USER': 'cltbld', 13:56:26 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:26 INFO - '_': '/tools/buildbot/bin/python'} 13:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:26 INFO - Running setup.py (path:/tmp/pip-SE0di4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:26 INFO - Running setup.py (path:/tmp/pip-KmyXfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:26 INFO - Running setup.py (path:/tmp/pip-RbAnhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:26 INFO - Running setup.py (path:/tmp/pip-QS2wPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:26 INFO - Running setup.py (path:/tmp/pip-4fbE5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:26 INFO - Running setup.py (path:/tmp/pip-87wDem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:26 INFO - Running setup.py (path:/tmp/pip-etSlfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:27 INFO - Running setup.py (path:/tmp/pip-SG5GVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:27 INFO - Running setup.py (path:/tmp/pip-qZUHwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:27 INFO - Running setup.py (path:/tmp/pip-Nw4Xdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:27 INFO - Running setup.py (path:/tmp/pip-f9A8D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:27 INFO - Running setup.py (path:/tmp/pip-h0j0nL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:27 INFO - Running setup.py (path:/tmp/pip-F5dMk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:27 INFO - Running setup.py (path:/tmp/pip-IOsBuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:27 INFO - Running setup.py (path:/tmp/pip-C86XwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:27 INFO - Running setup.py (path:/tmp/pip-LpnbUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:27 INFO - Running setup.py (path:/tmp/pip-qN0yYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:56:27 INFO - Running setup.py install for manifestparser 13:56:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:56:27 INFO - Running setup.py install for mozcrash 13:56:27 INFO - Running setup.py install for mozdebug 13:56:28 INFO - Running setup.py install for mozdevice 13:56:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Running setup.py install for mozfile 13:56:28 INFO - Running setup.py install for mozhttpd 13:56:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Running setup.py install for mozinfo 13:56:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Running setup.py install for mozInstall 13:56:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Running setup.py install for mozleak 13:56:28 INFO - Running setup.py install for mozlog 13:56:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Running setup.py install for moznetwork 13:56:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:56:28 INFO - Running setup.py install for mozprocess 13:56:28 INFO - Running setup.py install for mozprofile 13:56:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:56:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:56:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:56:29 INFO - Running setup.py install for mozrunner 13:56:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:56:29 INFO - Running setup.py install for mozscreenshot 13:56:29 INFO - Running setup.py install for moztest 13:56:29 INFO - Running setup.py install for mozversion 13:56:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:56:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:56:29 INFO - Cleaning up... 13:56:29 INFO - Return code: 0 13:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56: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')]} 13:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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')]} 13:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:29 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:29 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 13:56:29 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 13:56:29 INFO - Using env: {'DISPLAY': ':0', 13:56:29 INFO - 'HOME': '/home/cltbld', 13:56:29 INFO - 'LANG': 'en_US.UTF-8', 13:56:29 INFO - 'LANGUAGE': 'en_US:en', 13:56:29 INFO - 'LOGNAME': 'cltbld', 13:56:29 INFO - 'MAIL': '/var/mail/cltbld', 13:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:29 INFO - 'MOZ_NO_REMOTE': '1', 13:56:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:29 INFO - 'NO_EM_RESTART': '1', 13:56:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:29 INFO - 'PWD': '/builds/slave/test', 13:56:29 INFO - 'SHELL': '/bin/bash', 13:56:29 INFO - 'SHLVL': '1', 13:56:29 INFO - 'TERM': 'linux', 13:56:29 INFO - 'TMOUT': '86400', 13:56:29 INFO - 'USER': 'cltbld', 13:56:29 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:29 INFO - '_': '/tools/buildbot/bin/python'} 13:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:29 INFO - Running setup.py (path:/tmp/pip-OUTu_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56:29 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)) 13:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:29 INFO - Running setup.py (path:/tmp/pip-NeVzqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56:29 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)) 13:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:29 INFO - Running setup.py (path:/tmp/pip-vyy7wO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:29 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)) 13:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:29 INFO - Running setup.py (path:/tmp/pip-KZUuqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:30 INFO - Running setup.py (path:/tmp/pip-Gprt67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:30 INFO - Running setup.py (path:/tmp/pip-ygUZ1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:30 INFO - Running setup.py (path:/tmp/pip-2QDLGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:30 INFO - Running setup.py (path:/tmp/pip-k2rVxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:30 INFO - Running setup.py (path:/tmp/pip-YaZNGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:30 INFO - Running setup.py (path:/tmp/pip-PZdLtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:30 INFO - Running setup.py (path:/tmp/pip-d1JIBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:30 INFO - Running setup.py (path:/tmp/pip-gGnQxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:30 INFO - Running setup.py (path:/tmp/pip-BcR0rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:30 INFO - Running setup.py (path:/tmp/pip-e0SiuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:30 INFO - Running setup.py (path:/tmp/pip-_1WrWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:30 INFO - Running setup.py (path:/tmp/pip-TDWhPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:30 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)) 13:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:30 INFO - Running setup.py (path:/tmp/pip-qYKB5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:30 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)) 13:56:30 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)) 13:56:30 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)) 13:56:30 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)) 13:56:30 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)) 13:56:30 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)) 13:56:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:56:31 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 13:56:31 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 13:56:31 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 13:56:31 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 13:56:31 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 13:56:31 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 13:56:32 INFO - Downloading blessings-1.5.1.tar.gz 13:56:32 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 13:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:56:32 INFO - Installing collected packages: blessings 13:56:32 INFO - Running setup.py install for blessings 13:56:32 INFO - Successfully installed blessings 13:56:32 INFO - Cleaning up... 13:56:32 INFO - Return code: 0 13:56:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:33 INFO - Reading from file tmpfile_stdout 13:56:33 INFO - Current package versions: 13:56:33 INFO - argparse == 1.2.1 13:56:33 INFO - blessings == 1.5.1 13:56:33 INFO - blobuploader == 1.2.4 13:56:33 INFO - docopt == 0.6.1 13:56:33 INFO - manifestparser == 1.1 13:56:33 INFO - mozInstall == 1.12 13:56:33 INFO - mozcrash == 0.16 13:56:33 INFO - mozdebug == 0.1 13:56:33 INFO - mozdevice == 0.47 13:56:33 INFO - mozfile == 1.2 13:56:33 INFO - mozhttpd == 0.7 13:56:33 INFO - mozinfo == 0.9 13:56:33 INFO - mozleak == 0.1 13:56:33 INFO - mozlog == 3.1 13:56:33 INFO - moznetwork == 0.27 13:56:33 INFO - mozprocess == 0.22 13:56:33 INFO - mozprofile == 0.27 13:56:33 INFO - mozrunner == 6.11 13:56:33 INFO - mozscreenshot == 0.1 13:56:33 INFO - mozsystemmonitor == 0.0 13:56:33 INFO - moztest == 0.7 13:56:33 INFO - mozversion == 1.4 13:56:33 INFO - psutil == 3.1.1 13:56:33 INFO - requests == 1.2.3 13:56:33 INFO - wsgiref == 0.1.2 13:56:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:33 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')]} 13:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:33 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')]} 13:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:33 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:33 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 13:56:33 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 13:56:33 INFO - Using env: {'DISPLAY': ':0', 13:56:33 INFO - 'HOME': '/home/cltbld', 13:56:33 INFO - 'LANG': 'en_US.UTF-8', 13:56:33 INFO - 'LANGUAGE': 'en_US:en', 13:56:33 INFO - 'LOGNAME': 'cltbld', 13:56:33 INFO - 'MAIL': '/var/mail/cltbld', 13:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:33 INFO - 'MOZ_NO_REMOTE': '1', 13:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:33 INFO - 'NO_EM_RESTART': '1', 13:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:33 INFO - 'PWD': '/builds/slave/test', 13:56:33 INFO - 'SHELL': '/bin/bash', 13:56:33 INFO - 'SHLVL': '1', 13:56:33 INFO - 'TERM': 'linux', 13:56:33 INFO - 'TMOUT': '86400', 13:56:33 INFO - 'USER': 'cltbld', 13:56:33 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:33 INFO - '_': '/tools/buildbot/bin/python'} 13:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 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)) 13:56:33 INFO - Cleaning up... 13:56:33 INFO - Return code: 0 13:56:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:56:33 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')]} 13:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:33 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')]} 13:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:33 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:33 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 13:56:33 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 13:56:33 INFO - Using env: {'DISPLAY': ':0', 13:56:33 INFO - 'HOME': '/home/cltbld', 13:56:33 INFO - 'LANG': 'en_US.UTF-8', 13:56:33 INFO - 'LANGUAGE': 'en_US:en', 13:56:33 INFO - 'LOGNAME': 'cltbld', 13:56:33 INFO - 'MAIL': '/var/mail/cltbld', 13:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:33 INFO - 'MOZ_NO_REMOTE': '1', 13:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:33 INFO - 'NO_EM_RESTART': '1', 13:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:33 INFO - 'PWD': '/builds/slave/test', 13:56:33 INFO - 'SHELL': '/bin/bash', 13:56:33 INFO - 'SHLVL': '1', 13:56:33 INFO - 'TERM': 'linux', 13:56:33 INFO - 'TMOUT': '86400', 13:56:33 INFO - 'USER': 'cltbld', 13:56:33 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:33 INFO - '_': '/tools/buildbot/bin/python'} 13:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:33 INFO - Downloading/unpacking jsonschema 13:56:33 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 13:56:33 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 13:56:33 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 13:56:33 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 13:56:33 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 13:56:33 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 13:56:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:56:35 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 13:56:35 INFO - Downloading/unpacking functools32 (from jsonschema) 13:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:35 INFO - Downloading functools32-3.2.3-2.tar.gz 13:56:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 13:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:56:35 INFO - warning: no files found matching '*.txt' 13:56:35 INFO - no previously-included directories found matching 'build' 13:56:35 INFO - no previously-included directories found matching 'dist' 13:56:35 INFO - no previously-included directories found matching '.git*' 13:56:35 INFO - Installing collected packages: jsonschema, functools32 13:56:35 INFO - Running setup.py install for functools32 13:56:35 INFO - warning: no files found matching '*.txt' 13:56:35 INFO - no previously-included directories found matching 'build' 13:56:35 INFO - no previously-included directories found matching 'dist' 13:56:35 INFO - no previously-included directories found matching '.git*' 13:56:35 INFO - Successfully installed jsonschema functools32 13:56:35 INFO - Cleaning up... 13:56:35 INFO - Return code: 0 13:56:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:56: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')]} 13:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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')]} 13:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:35 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 0x7ff029a72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cbf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', '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 13:56:35 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 13:56:35 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 13:56:35 INFO - Using env: {'DISPLAY': ':0', 13:56:35 INFO - 'HOME': '/home/cltbld', 13:56:35 INFO - 'LANG': 'en_US.UTF-8', 13:56:35 INFO - 'LANGUAGE': 'en_US:en', 13:56:35 INFO - 'LOGNAME': 'cltbld', 13:56:35 INFO - 'MAIL': '/var/mail/cltbld', 13:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:35 INFO - 'MOZ_NO_REMOTE': '1', 13:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:35 INFO - 'NO_EM_RESTART': '1', 13:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:35 INFO - 'PWD': '/builds/slave/test', 13:56:35 INFO - 'SHELL': '/bin/bash', 13:56:35 INFO - 'SHLVL': '1', 13:56:35 INFO - 'TERM': 'linux', 13:56:35 INFO - 'TMOUT': '86400', 13:56:35 INFO - 'USER': 'cltbld', 13:56:35 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:35 INFO - '_': '/tools/buildbot/bin/python'} 13:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:35 INFO - Downloading/unpacking flake8 13:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:56:37 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 13:56:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:56:37 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 13:56:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:38 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 13:56:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 13:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:56:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:56:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:56:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:56:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:56:38 INFO - no previously-included directories found matching 'docs/_build' 13:56:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:56:38 INFO - Running setup.py install for pep8 13:56:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:56:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:56:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:56:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:56:38 INFO - no previously-included directories found matching 'docs/_build' 13:56:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:56:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:56:38 INFO - Cleaning up... 13:56:38 INFO - Return code: 0 13:56:38 INFO - Running post-action listener: _resource_record_post_action 13:56:38 INFO - Running post-action listener: _start_resource_monitoring 13:56:38 INFO - Starting resource monitoring. 13:56:38 INFO - ##### 13:56:38 INFO - ##### Running install step. 13:56:38 INFO - ##### 13:56:38 INFO - Running pre-action listener: _resource_record_pre_action 13:56:38 INFO - Running main action method: install 13:56:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:39 INFO - Reading from file tmpfile_stdout 13:56:39 INFO - Detecting whether we're running mozinstall >=1.0... 13:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:56:39 INFO - Reading from file tmpfile_stdout 13:56:39 INFO - Output received: 13:56:39 INFO - Usage: mozinstall [options] installer 13:56:39 INFO - Options: 13:56:39 INFO - -h, --help show this help message and exit 13:56:39 INFO - -d DEST, --destination=DEST 13:56:39 INFO - Directory to install application into. [default: 13:56:39 INFO - "/builds/slave/test"] 13:56:39 INFO - --app=APP Application being installed. [default: firefox] 13:56:39 INFO - mkdir: /builds/slave/test/build/application 13:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:56:53 INFO - Reading from file tmpfile_stdout 13:56:53 INFO - Output received: 13:56:53 INFO - /builds/slave/test/build/application/firefox/firefox 13:56:53 INFO - Running post-action listener: _resource_record_post_action 13:56:53 INFO - ##### 13:56:53 INFO - ##### Running run-tests step. 13:56:53 INFO - ##### 13:56:53 INFO - Running pre-action listener: _resource_record_pre_action 13:56:53 INFO - Running main action method: run_tests 13:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:56:53 INFO - Python 2.7.3 13:56:53 INFO - Return code: 0 13:56:53 INFO - grabbing minidump binary from tooltool 13:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:56:53 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 0x1d4e0b0>, '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 0x1d4efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4f440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:56:53 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 13:56:53 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 13:56:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:56:53 INFO - Return code: 0 13:56:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:56:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:56:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:56:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:56:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:56:54 INFO - Return code: 0 13:56:54 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] in /builds/slave/test/build 13:56:54 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost 13:56:54 INFO - Using env: {'DISPLAY': ':0', 13:56:54 INFO - 'HOME': '/home/cltbld', 13:56:54 INFO - 'LANG': 'en_US.UTF-8', 13:56:54 INFO - 'LANGUAGE': 'en_US:en', 13:56:54 INFO - 'LOGNAME': 'cltbld', 13:56:54 INFO - 'MAIL': '/var/mail/cltbld', 13:56:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:56:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:54 INFO - 'MOZ_NO_REMOTE': '1', 13:56:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:54 INFO - 'NO_EM_RESTART': '1', 13:56:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:54 INFO - 'PWD': '/builds/slave/test', 13:56:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:56:54 INFO - 'SHELL': '/bin/bash', 13:56:54 INFO - 'SHLVL': '1', 13:56:54 INFO - 'TERM': 'linux', 13:56:54 INFO - 'TMOUT': '86400', 13:56:54 INFO - 'USER': 'cltbld', 13:56:54 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315', 13:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:54 INFO - '_': '/tools/buildbot/bin/python'} 13:56:54 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] with output_timeout 3600 13:56:55 INFO - mozversion INFO | application_buildid: 20151216124705 13:56:55 INFO - mozversion INFO | application_changeset: 5621bf5d87e491c8856b90e5ff86919c96bf04c6 13:56:55 INFO - mozversion INFO | application_display_name: Nightly 13:56:55 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:56:55 INFO - mozversion INFO | application_name: Firefox 13:56:55 INFO - mozversion INFO | application_remotingname: firefox 13:56:55 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 13:56:55 INFO - mozversion INFO | application_vendor: Mozilla 13:56:55 INFO - mozversion INFO | application_version: 46.0a1 13:56:55 INFO - mozversion INFO | platform_buildid: 20151216124705 13:56:55 INFO - mozversion INFO | platform_changeset: 5621bf5d87e491c8856b90e5ff86919c96bf04c6 13:56:55 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 13:56:55 INFO - mozversion INFO | platform_version: 46.0a1 13:56:55 INFO - 2015-12-16 13:56:55,004 DEBUG : using testdate: 1450303015 13:56:55 INFO - 2015-12-16 13:56:55,004 DEBUG : actual date: 1450303015 13:56:55 INFO - 2015-12-16 13:56:55,012 INFO : starting webserver on 'localhost:53100' 13:56:55 INFO - 2015-12-16 13:56:55,013 INFO : Starting test suite talos-linux64-ix-058 13:56:55 INFO - 2015-12-16 13:56:55,013 INFO : Starting test dromaeo_css 13:56:55 INFO - 2015-12-16 13:56:55,013 DEBUG : operating with platform_type : linux_ 13:56:55 INFO - 2015-12-16 13:56:55,014 INFO : Initialising browser for dromaeo_css test... 13:56:55 INFO - 2015-12-16 13:56:55,031 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEBq4cD/profile http://localhost:53100/getInfo.html 13:56:59 INFO - 2015-12-16 13:56:59,114 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:56:59 INFO - 2015-12-16 13:56:59,114 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:56:59 INFO - 2015-12-16 13:56:59,114 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:56:59 INFO - 2015-12-16 13:56:59,114 DEBUG : BROWSER_OUTPUT: __metrics 13:56:59 INFO - 2015-12-16 13:56:59,263 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 13:56:59 INFO - 2015-12-16 13:56:59,331 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:57:00 INFO - 2015-12-16 13:57:00,651 INFO : Browser initialized. 13:57:00 INFO - 2015-12-16 13:57:00,652 INFO : Running cycle 1/1 for dromaeo_css test... 13:57:00 INFO - 2015-12-16 13:57:00,652 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEBq4cD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:57:12 INFO - 2015-12-16 13:57:12,714 DEBUG : BROWSER_OUTPUT: RSS: Main: 141910016 13:57:12 INFO - 2015-12-16 13:57:12,715 DEBUG : BROWSER_OUTPUT: 13:58:03 INFO - 2015-12-16 13:58:03,982 DEBUG : BROWSER_OUTPUT: 1450303083977 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] 14:00:15 INFO - 2015-12-16 14:00:15,847 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53100/tests/dromaeo/cssquery-ext.html) 14:00:16 INFO - 2015-12-16 14:00:16,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 255221760 14:00:16 INFO - 2015-12-16 14:00:16,959 DEBUG : BROWSER_OUTPUT: 14:03:19 INFO - 2015-12-16 14:03:19,394 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/dromaeo/cssquery-ext.html (next: http://localhost:53100/tests/dromaeo/cssquery-jquery.html) 14:03:19 INFO - 2015-12-16 14:03:19,979 DEBUG : BROWSER_OUTPUT: RSS: Main: 220024832 14:03:19 INFO - 2015-12-16 14:03:19,980 DEBUG : BROWSER_OUTPUT: 14:06:22 INFO - 2015-12-16 14:06:22,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53100/tests/dromaeo/cssquery-mootools.html) 14:06:22 INFO - 2015-12-16 14:06:22,758 DEBUG : BROWSER_OUTPUT: RSS: Main: 218267648 14:06:22 INFO - 2015-12-16 14:06:22,758 DEBUG : BROWSER_OUTPUT: 14:09:24 INFO - 2015-12-16 14:09:24,788 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53100/tests/dromaeo/cssquery-prototype.html) 14:09:25 INFO - 2015-12-16 14:09:25,369 DEBUG : BROWSER_OUTPUT: RSS: Main: 198529024 14:09:25 INFO - 2015-12-16 14:09:25,369 DEBUG : BROWSER_OUTPUT: 14:12:28 INFO - 2015-12-16 14:12:28,153 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53100/tests/dromaeo/cssquery-yui.html) 14:12:28 INFO - 2015-12-16 14:12:28,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 206376960 14:12:28 INFO - 2015-12-16 14:12:28,759 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,463 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/dromaeo/cssquery-yui.html (next: http://localhost:53100/tests/dromaeo/cssquery-dojo.html) 14:15:31 INFO - 2015-12-16 14:15:31,714 DEBUG : BROWSER_OUTPUT: RSS: Main: 251453440 14:15:31 INFO - 2015-12-16 14:15:31,714 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,714 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:15:31 INFO - 2015-12-16 14:15:31,715 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:15:31 INFO - 2015-12-16 14:15:31,715 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:15:31 INFO - 2015-12-16 14:15:31,715 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:15:31 INFO - 2015-12-16 14:15:31,715 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2607.9087231946887;2720.891164353426;2723.9182824515274;2742;2742.0676969830256;4548.317752337151;4689.437267527901;4723.315119307698;4785.856424307268;4787.066522028202;4602.12559613674;4661.976690116553;4714.3164241185095;4726.243800991842;4726.243800991842;4275.144971005796;4297.613214810674;4316.6978311518205;4446.355278484618;4453.910921781562;4178.498580170369;4395.230834603947;4414.22751018572;4473.127740090684;4494.955050449503;4233.745975241495;4291.957080429187;4295.248331541999;4295.506016808075;4310.331898555729;4326.632236259922;4334;4338.631216346615;4368.803403846835;4373.956260437419;4134.751914885116;4219.11398606293;4324.870253892388;4333.2200203963275;4379.058502421963;4572.3827283317;4604.861854144381;4674.135284972291;4680.7191568506;4712.764361781931;5007.799688012512;5023.095842748304;5029.446760856303;5031.044101620717;5071.898562028776;4860.222364421676;4964.6276529260085;5042;5046.873828154289;5064.620153488503;4644.605208557243;4656.115338085787;4688.671792974527;4760.285957106462;4790.137775200427;5170.095233334151;5188.351456067991;5189.325387699575;5190;5236.214567814858;4774.331593576832;4962.106820772224;4966.279889416014;4968.850934471971;4988.347547495608;4970.676906001098;5015.27278544609;5032.547070763649;5072;5116.488351164928;4986.675866068694;5027.145385284511;5029.949700503022;5031.0441016206805;5074.441811400743;4741.691790033637;4994.875128121826;5002.124628190052;5003.074431230234;5003.324551185616;4099.877003689894;4117.464729585135;4149.854755083557;4174.3947127666315;4202.180574787887;3650.3976843821;3661.6338366163704;3664.2305115925956;3678.301122786662;3698.519192504957;4142.399352093929;4347.6304514116655;4348.195583817004;4352.82588696455;4398.934015989763;4933.531314525115;4949.851504454872;4951.257311403247;5007.924881126786;5027.346444962204;4344.674149438804;4347.239233134189;4363.825446982085;4371.147626212858;4413.426254586947;4176.707630465899;4480.081583275421;4483.596476317147;4489;4517.706349087299;4705.788239529161;4709.175894218498;4726.763661816895;4820.1805693031565;4832.395950506187;5069.721165335942;5110.897782044412;5113.130767769562;5114.46298138705;5115.411727651368;4855.053264613366;4860.65975381734;4863.8540843774745;4864.17309057454;4886.95113048865;4828.203346447796;4864.440589332272;4944.332515110414;4946.653734238569;5064.569511591409;5066.265391518135;5092.8472145835685;5094.566961808139;5098.719570423662;5107.233914912793;4911.901761964812;4913.6069114471475;4916.975415122902;4923.064617722621;4944.134776414185;4701.670883038291;4826.95173048265;4831.541003604652;4835.20219163834;4926.58124059459;4783.091212669582;4833.275008748716;4846.273059041031;4847.394075740533;4889.608831293417;4963.851084467472;4974.651774375904;4974.676646017997;4974.726390048582;5003.474635163401;4643.558861908114;4730.905381892412;4770.928436073531;4771.1650461168465;4860.1008813369635 14:15:31 INFO - 2015-12-16 14:15:31,719 DEBUG : BROWSER_OUTPUT: |1;ext.html;565.5955991466103;567.486424785569;572.942705729427;574.494444888498;576.8240686590589;27499.175024749264;36108;36893.446598301045;36907.81546092273;36971.99999999996;40210.59789402105;40442.595574044324;40577;40730.38904416429;40893;7004.159500859904;7127.750528731495;7905.0513938327485;8078.192180781904;8080.232377924088;10159.339642923223;10438.06057454829;10443.84334234987;10453;10454;9864.112229899307;9924.255680823931;10024.14794742444;10029.899701002969;10031.147352475049;7158.964205178967;7331.083614548182;7337.559746415232;7339.9633001834645;10004.59981600735;7086.433085353159;10153.324701424257;10444.060034596923;10447.268691191619;10448.477576121164;37809;38324.80837595794;38438;38447.231055379016;38484.037899052746;2865.5415133578526;2889.6387951506063;2921.4303210873886;2966.569847372119;3491.1097670093964;28300.575491367646;28526.572101418496;29071.273218169503;29233.561496577568;29500.557491637643;3138.419392412411;3283.7208837248863;3302.9009129726146;3342.88299909502;3383.4924761285756;215113;215622;215649;216416;216898;49417;50365;51706.74146629243;53018.20472692913;53262.733686332096;52456.47543524592;52516;52650;52682.209766852764;52795.94408111816;13460.057795954384;13558.525451609146;13613.659658508617;13638.63613638648;13692.794608080887;10138.847917281246;10151.94924025375;10158.39049657023;10175;10302.793944121076;18151.45545633633;18188.453981411727;18213.54466138357;18233.179506921955;18312.08439578016;7256.238095000054;7464.477486575887;7553.1313898901235;7553.773386798405;7586.658600362997;879.7009016934145;906.6056265524518;910.7950711090084;919.1635611593418;978.8923218445965;26513.602295965575;26613.191793893082;26619.20142395731;26702.19893403162;26878.5968210477;995.5669283861398;1003.668789299544;1008.1984822066474;1009.7223263602422;1011.7723512209841;13769.724605507834;14021.439142434188;14222.146671199762;14249.287535623178;14299.92850035764;264715;268054.6597267001;269186;269195;269398.4071513203;45915.77042114835;46210.30684539735;46221.30668039982;46287.768561156976;46302.305465418045;1228.4103630257525;1244.7883859743865;1251.1804767877054;1259.9118061735774;1267.2776517385003;25519.617205741557;26135.869320654037;26759.464810704067;26760.46479070369;26771.19686409411;43330.35004474998;44204.99999999871;44235.55764442443;44261.557384425745;44312.7784361089;229345;234280;340793;351812;352915;28193.295167620974;28273.43453130967;28283.43433131367;28297.57553636738;28313.575296370982;39363.21273574455;40671.38992915049;40796.38805417862;41060.58939410568;41202.79398602988;32078.679213207568;34095.82952085224;34311;34573.13567160741;35011.64988350186;31934.36131277408;33385.666143338254;34318;34343;34606.13484662904;29917;30240.395192095595;30429.847850760605;30626.540601891436;30653.386932260782;28709;29355.85322073376;29534.81860725633;29786.851065744533;29893.70106298909;22947.885260574258;23365.649515256944;23700.8814955931;23791.881040594686;23817.880910595337 14:15:31 INFO - 2015-12-16 14:15:31,719 DEBUG : BROWSER_OUTPUT: |2;jquery.html;326.5330577274498;328.24503641624267;344.0727240087964;344.9534312867764;346.6568097583393;12407.875921240784;12836;14830.925845370772;15130.924345378271;16889.155542222885;15042.172680502568;15207.783377329815;16285.674286514262;16517.33930642776;16518.587035324093;4809.422869255694;4944.456109827916;4950.034743225073;4951.702897826142;5063.164577844651;3428.502867084271;3523.242502861884;5048.7223202723835;5203.739813009353;5206.635535512497;5078;5135.768890399922;5155.587552995751;5160.561352285042;5228.032813929435;5125.282460455539;5158.380994280673;5172.353455818023;5178.197379406198;5190.4031036430915;4951.653384263103;4966.528179822947;4967.081089998362;4977.552020318189;4999.275105109738;16617.584560385967;18275.63448731018;18643.720344194848;18718.344857930042;18770.624587508173;1807.3132209760338;1818.5544541587265;1863.7390765292869;1867.374429566097;1985.0372569303877;8522.446041007315;8753.343499237582;8865.556722163867;9012.774680633036;9024.323175761778;1705.8294170582967;1733.5232810976956;1753.2461041752042;1758.3318339031214;1767.7878654561534;55273.44726552763;57768.71115644437;58121.41878581245;58179.709101454646;58591.121133183464;9560.043995600385;9743.61025558984;9848.458334791656;9861.802763944681;9866.260030497742;12131.93934030342;12228.388580570936;12236;12264.693382665504;12266.202696824677;7264.745733899288;7341.632918354061;7351.3751331137455;7382.6677799498075;7398.371138453184;7099.6450177490915;7116.573005619679;7130.358267755927;7157.212706602374;7191.280871912767;4159.584041595816;4187.413762073312;4209.368594710757;4301.548337424588;4322;1581.5571639940836;1645.2925242145993;1697;1708.9316427343015;1713.0663788235443;822.4695071678734;822.979425514355;856.9400141990001;1001.529281237817;1003.518311210623;14695.559133226021;14822.62943426423;14976.101433914;15142.242887855564;15318.617034574001;856.8286342731478;866.4065115395974;868.4702331577732;872.9825403491894;886.3263919421157;7728.574928378881;9025.962014368431;9056.184943354998;9364;9383.718488445358;516654;524315;524495;533193;535231;60714.392856071754;61727.69136154291;62854.371456285764;73618.6319068412;75581;1057.6562617149457;1081.3187691754144;1105.4804242006244;1106.1593189176283;1107.501624269066;13420.46318147263;13556.186628801908;13842.238676872868;14034.087784293988;14809.407623694931;29523.557146642386;30212.395752085275;30742.385152297276;31300.843495782374;33589.66410335866;39182;39196.34920871387;39583.01042473961;43442;43800.342994854465;8367.288780453486;8522.786930326543;8531.786705332417;8538;8565.528895910784;8182.345412367115;8434.156584341517;9253;9329.626814927327;10406.271560990657;7924.247196516321;7936.920630793618;8186.672533098847;8765.91234087651;9123;8672.436291641023;8867.467951922905;9015.233705134875;9384.108509691569;9396.859047114436;8979.50612716307;9345.345825792128;9369.344145909721;9395.436273823592;9405.905940940504;11716.472758726048;11760.11799115087;12874.806877896652;13242.867571324163;13902.582922512342;8874.467531948103;9275.76810579741;14545.781813272597;14576.979611427098;14598.270086495846 14:15:31 INFO - 2015-12-16 14:15:31,719 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1212.7453234820687;1645.5227983884672;1706.8293170682932;1726.7409888516725;1727.6631056943902;5988.101784732287;6922.065521154643;7306.4520160988;7320.2313757055545;7323.974643549907;6804.931950680491;6897.758578449755;7034.824129396768;7041.331073548017;7046.823829404268;1738.278614375039;1770.884892481991;1773.733939909011;2085.1346691123167;2137.690034944932;1779;1787.3297513432462;2075.9792402075936;2124.617568837609;2125.9893700531475;2054.065400242886;2055.6402629539843;2056.979430205694;2058.4442200605904;2061.051916118587;1986.8708533945248;2023.2109477303864;2024.7873973232745;2025.9161348678385;2028.6247044296779;2068.48287928018;2077.6364136276093;2085.8331333493284;2120.9151633934625;2122.862013969087;7249.746258880914;7325.890111648329;7337.596432196227;7337.963310183468;7340.26597340267;4251.659867210616;4326.848560300375;4340.175366680352;4358.694891357606;4366.061296821199;2704.8106632535732;2727.4272402795436;2774.5144599695172;2794.4690508803264;2825.491411545921;838.2288294768828;851.5486792000188;851.974440766778;866.2160744526151;899.1413200393608;9933.850992235122;9952.651657192037;9960.601575936953;9962.352447090916;9968.501574921225;3937;3981.8407263709705;4020.075382662004;4076.3274059779806;4077.8164982575854;8504.064552899175;8525.40322177454;8526.275266602408;8526.65893364271;8528.445651032664;4309.698321117555;4310.80601372939;4312.5903039211225;4314.482262128565;4318.330658747899;3871.632194941476;3875.2830726315724;3875.9806200968815;3905.7851818149707;3912.217556488714;3499.9825000874835;3511.94732079019;3520.4543295789194;3526.1360966563357;3551.3430015447225;935;967.264878692199;1078.8489611454402;1115.6876074699098;1116.4975760907462;968;969.2197780786535;1013.9657549299673;1037.4034929915329;1224.8285240066398;6941.826454338581;7020.403265722471;7033.683484243222;7035.929640703527;7038.894416583756;1039.3971496531992;1052.273930987616;1055.9260851740457;1069.411823497089;1071.9892801072046;5403.945960540423;5444.863878402993;5448.891022179613;5465.672059676353;5467.4532546745;8584.957075214585;8650.567471626393;8859.24696400813;8898.154675305836;8985.730428087169;8764.517951512727;8789.208971192624;8869.467831930106;8878.378513503993;8894.28845692343;6724.966375168191;6845.5550389223545;6864.5881247124335;6885.793426197222;6917;6575.967120164369;6671.232808227116;6678.799636010927;6680.766173183915;6689.531732778659;7516.5114267572435;7740.380769538536;7882.84234315299;7899.644515996679;7907.9604601976625;7535.208803075818;7695.153533111241;7804.9609751950875;7813.570253635878;8036.437449378487;6406.9359306408205;6407.679616019087;6428.260750013808;6517.4460170886005;6538.574992625464;5743.339515955551;7014;7061.505694601328;7073.893891591733;7126.857462850818;5927.318358388668;6281.214848143982;6617.205935287604;6676.065350850982;6709.899351509829;6581.769638062645;6589.209294884645;6645.767398141042;6671.499637527108;6688.799336019927;6189.96905015472;6384.648844313608;6395.42441180296;6689.163854518185;6689.46484281266;6029.306629737636;6048.60684055535;6076.939230607637;6087.786927457518;6090.634561926298;6203.658798766111;6265.27949285838;6289.40250676185;6297.370262973849;6306.369363063841 14:15:31 INFO - 2015-12-16 14:15:31,720 DEBUG : BROWSER_OUTPUT: |4;prototype.html;303.8374469658733;304.85214670884625;310.7312174968652;314.22229980797545;314.39322108330913;5347.35831700196;5363.249145119681;5363.785448582052;5374.8925021499645;5375.784968601256;5313.282706834576;5318.361796584415;5319.122344813101;5319.12234481311;5323.973380133094;4152.916941661165;4167.749935003894;4169.583041695836;4181.958180418203;4184.163167366523;3945.7040721945827;4027.234825383168;4121.9381709274385;4176.310908700061;4191.643710284629;3887.2419878123637;3993.221321842242;3999.7800120993334;4005.6394924456795;4094.9590504094876;3986.5814089520472;4111.280525907954;4144.046869220066;4147.108371700068;4155.937660935088;3652.945205821915;3654.049947013769;3656.177360093984;3661.505696730934;3662.377395842713;4933.481984391623;5048.3941926969;5072.0870243356185;5132.717700526469;5177.611679124081;4748.287756836467;4752.120857641347;4754.976225118852;4755.2153894607345;4762.214234651313;5268.8155914543195;5597.244372009767;5601.971990140023;5602.1036634138345;5603.635763675389;3796;3858.961410385916;3954.822033008493;3981.6416522513114;3991.2416640838146;5833.51245432412;5855.677937713424;5861.882762344771;5863.120531920246;5868.178455016301;4101.938470922939;4102.630763231324;4112.177564487115;4116.485439320085;4117.382392641128;4082.2447847148374;4176.997520595035;4179.97910010448;4181.686373521937;4184.895377615584;4717.174494463455;4723.976380118077;4729.0541891621815;4729.62163026957;4735.384400027974;5583.106702927512;5587.441255874462;5588.6646801192055;5589.916151257735;5591.916121258103;1454.3092031285266;1471.3231913319778;1471.3820195517908;1471.8528147185195;1472.8158980127484;1000.7190795781412;1009.0363702663939;1012.4785735346303;1029.7888932768915;1032.1278519650882;4918.926216106761;4941.382327209099;4943.2585112233455;4943.555080042814;4945.455999840015;5800.071988481907;5808.157817116495;5813.273340832396;5814.244148260784;5818.418158184148;4922.089413458521;4933.28467372229;4933.432655244621;4933.45731969483;4945.109880221595;3064.4024415238814;3108.5026395776567;3137.074563003919;3138.8430578470984;3142.5129104988764;5511.366192887869;5522;5522.420145884625;5525.11598144303;5527.39198688144;5957.553183511167;5964.433378829004;5965.224520812353;5966.49284810796;5967.403259673998;5512.118061110203;5518.44815518453;5520;5522.5581953443625;5525.778968841281;5627.493525582717;5794.855128621818;5800.564957628279;5802.82591522255;5808.593398462236;5435.782568697208;5553.305836770403;5580.86047848807;5743.684097374685;5747.281589801275;5566.081596536526;5569.888602227852;5573.27547418833;5578.107502799533;5586.972065139648;4063.553009169048;4066.7763273019086;4068.450759147477;4071.063655359225;4077.734947228421;5637.351704554028;5831.620944638585;5840.970795146167;5841.766329346949;5854.531637468907;5626.943730562805;5632.014397480342;5637.943620563742;5638.774449021993;5638.971805140948;5659.575531835211;5669.7165141741925;5670.943290567042;5677.460641239241;5678;5397.595180361567;5526.806561770319;5543.61194716366;5550.056490396723;5553.583481238842;5655.915161272502;5676.20533125371;5694.430556944272;5695.231143795535;5699.715014249354;5541.806036788855;5547.278853748987;5553.528679737478;5554.666719996813;5563.415841336601 14:15:31 INFO - 2015-12-16 14:15:31,721 DEBUG : BROWSER_OUTPUT: |5;yui.html;655.2791928878235;762.2149186338074;762.7749813804934;774.6901239504199;795.3994733975851;12049.638510844678;12171.634850954464;12183.147179697402;12189.451474683627;12249.387530623479;11335.149863760209;11411.54353825848;11499.827502587468;11621.418929053556;11707.941460292686;642.5823214910322;647.6729251727871;806.1817255485687;811.8863359129726;828.6271177969908;820.827626198499;824.0893812337371;828.2587084559316;830.6760363458257;832.4381042796089;862.0861886400436;871.0418539606445;871.2159056848824;871.2507243770352;871.6252011634995;671.7850287907871;678.2166597579803;853.5433543054436;858.0304256190465;862;637.6397335505425;638.8786130635165;639.098870592467;639.284001917855;639.6418005916702;12025.63923082309;12155;12162.087843411691;12168.209066410654;12175.39123043853;11214;11233.157513186461;11285.548578056867;11292.378919159442;11622.941885290604;9307.90692093077;9320.533973301375;9399.201067909236;9416.1525462708;9465.384749991228;3215.3730022645595;3236.1424222581036;3261.951070733941;3293.2425542125206;3325.48454989477;37760.24479510422;37790.622093778984;37978.050548736224;38179.61820381788;38318;9427;9501;9622.085901839315;9634.373765705206;9745.12293893553;15631.140287284308;15684.90205685591;15734.763978540332;15792.60518487047;15794.21028948548;6163.19878415803;6233.532485063638;6249.218847644044;6259.061140828912;6421.8073457796345;3569.660882216185;3572.392693242155;3578.946315805265;3633;3659.213269147146;2537.6574162488196;2553.1702196785745;2560.2063360358347;2570.742925707414;2571.434284457416;470.1490302552391;474.7175430618776;474.9859050926297;475.09020226267;478.167987701404;572.8481952282649;599.4784537452357;617.003539284053;638.3807706524664;640.5932233031965;7622.390208783284;8681.782955426039;8806.559672016374;8883.911160888438;8945.19493245611;520.4301290087349;598.975751464991;601.7021574320652;637.744902039188;640.7981485831948;3560.483729859156;3742.4947632069866;3761.774293542396;3809.504764380613;3891.1439483313625;48121.75939120282;48162;48225.75887120542;48495.75752121217;48535.27197092046;42734.57265427368;43458.7827060869;43733.56266437295;44289.77855110704;44545.33182002272;36079.63920360815;36564.81717591395;36748.81625591855;38872.22255554873;39178.21643567113;7850.960745196237;7956.562389068657;7988.320992715452;8200.917990820015;8438.915610843813;30170.09489715312;30229.546556802106;30276.5458518118;30796;30807.2298192547;38127.23745525129;38455.61544384521;39067.553853613834;39409.605903940595;39410.80294598509;9991.600335986479;10042.89957100449;10050.798984020425;10227.335223210468;10316.381017138996;11173.162012849232;11181.832272516082;11407.781407781342;11629.76740465169;11653.883461165618;10525.473726313807;10670.253082284476;10773.569057237622;11088.33469991803;11441.656750297505;10528.105111065339;10688.625898093529;11139.83290250663;11236.101111911192;11280.2667826591;10411.531481083219;10541;10581.100606448537;10874.02133807961;10903.618373356894;8530.872036919327;8838.779030524289;9049.411788233743;9200.585973631069;9847.95076024644;8706.521141337287;8979.281657467518;9322.673706420243;9451.243900487807;9458.668946586837 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,959 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: __startTimestamp1450304131712__endTimestamp 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4644.66 Median:4783.09 stddev:487.62 (10.2%) stddev-sans-first:462.20 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: Values: 2607.9 2720.9 2723.9 2742.0 2742.1 4548.3 4689.4 4723.3 4785.9 4787.1 4602.1 4662.0 4714.3 4726.2 4726.2 4275.1 4297.6 4316.7 4446.4 4453.9 4178.5 4395.2 4414.2 4473.1 4495.0 4233.7 4292.0 4295.2 4295.5 4310.3 4326.6 4334.0 4338.6 4368.8 4374.0 4134.8 4219.1 4324.9 4333.2 4379.1 4572.4 4604.9 4674.1 4680.7 4712.8 5007.8 5023.1 5029.4 5031.0 5071.9 4860.2 4964.6 5042.0 5046.9 5064.6 4644.6 4656.1 4688.7 4760.3 4790.1 5170.1 5188.4 5189.3 5190.0 5236.2 4774.3 4962.1 4966.3 4968.9 4988.3 4970.7 5015.3 5032.5 5072.0 5116.5 4986.7 5027.1 5029.9 5031.0 5074.4 4741.7 4994.9 5002.1 5003.1 5003.3 4099.9 4117.5 4149.9 4174.4 4202.2 3650.4 3661.6 3664.2 3678.3 3698.5 4142.4 4347.6 4348.2 4352.8 4398.9 4933.5 4949.9 4951.3 5007.9 5027.3 4344.7 4347.2 4363.8 4371.1 4413.4 4176.7 4480.1 4483.6 4489.0 4517.7 4705.8 4709.2 4726.8 4820.2 4832.4 5069.7 5110.9 5113.1 5114.5 5115.4 4855.1 4860.7 4863.9 4864.2 4887.0 4828.2 4864.4 4944.3 4946.7 5064.6 5066.3 5092.8 5094.6 5098.7 5107.2 4911.9 4913.6 4917.0 4923.1 4944.1 4701.7 4827.0 4831.5 4835.2 4926.6 4783.1 4833.3 4846.3 4847.4 4889.6 4963.9 4974.7 4974.7 4974.7 5003.5 4643.6 4730.9 4770.9 4771.2 4860.1 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,722 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42089.59 Median:30335.12 stddev:69643.20 (229.6%) stddev-sans-first:69768.78 14:15:31 INFO - 2015-12-16 14:15:31,723 DEBUG : BROWSER_OUTPUT: Values: 565.6 567.5 572.9 574.5 576.8 27499.2 36108.0 36893.4 36907.8 36972.0 40210.6 40442.6 40577.0 40730.4 40893.0 7004.2 7127.8 7905.1 8078.2 8080.2 10159.3 10438.1 10443.8 10453.0 10454.0 9864.1 9924.3 10024.1 10029.9 10031.1 7159.0 7331.1 7337.6 7340.0 10004.6 7086.4 10153.3 10444.1 10447.3 10448.5 37809.0 38324.8 38438.0 38447.2 38484.0 2865.5 2889.6 2921.4 2966.6 3491.1 28300.6 28526.6 29071.3 29233.6 29500.6 3138.4 3283.7 3302.9 3342.9 3383.5 215113.0 215622.0 215649.0 216416.0 216898.0 49417.0 50365.0 51706.7 53018.2 53262.7 52456.5 52516.0 52650.0 52682.2 52795.9 13460.1 13558.5 13613.7 13638.6 13692.8 10138.8 10151.9 10158.4 10175.0 10302.8 18151.5 18188.5 18213.5 18233.2 18312.1 7256.2 7464.5 7553.1 7553.8 7586.7 879.7 906.6 910.8 919.2 978.9 26513.6 26613.2 26619.2 26702.2 26878.6 995.6 1003.7 1008.2 1009.7 1011.8 13769.7 14021.4 14222.1 14249.3 14299.9 264715.0 268054.7 269186.0 269195.0 269398.4 45915.8 46210.3 46221.3 46287.8 46302.3 1228.4 1244.8 1251.2 1259.9 1267.3 25519.6 26135.9 26759.5 26760.5 26771.2 43330.4 44205.0 44235.6 44261.6 44312.8 229345.0 234280.0 340793.0 351812.0 352915.0 28193.3 28273.4 28283.4 28297.6 28313.6 39363.2 40671.4 40796.4 41060.6 41202.8 32078.7 34095.8 34311.0 34573.1 35011.6 31934.4 33385.7 34318.0 34343.0 34606.1 29917.0 30240.4 30429.8 30626.5 30653.4 28709.0 29355.9 29534.8 29786.9 29893.7 22947.9 23365.6 23700.9 23791.9 23817.9 14:15:31 INFO - 2015-12-16 14:15:31,723 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,723 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26721.19 Median:4988.41 stddev:86023.19 (1724.5%) stddev-sans-first:86241.67 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: Values: 326.5 328.2 344.1 345.0 346.7 12407.9 12836.0 14830.9 15130.9 16889.2 15042.2 15207.8 16285.7 16517.3 16518.6 4809.4 4944.5 4950.0 4951.7 5063.2 3428.5 3523.2 5048.7 5203.7 5206.6 5078.0 5135.8 5155.6 5160.6 5228.0 5125.3 5158.4 5172.4 5178.2 5190.4 4951.7 4966.5 4967.1 4977.6 4999.3 16617.6 18275.6 18643.7 18718.3 18770.6 1807.3 1818.6 1863.7 1867.4 1985.0 8522.4 8753.3 8865.6 9012.8 9024.3 1705.8 1733.5 1753.2 1758.3 1767.8 55273.4 57768.7 58121.4 58179.7 58591.1 9560.0 9743.6 9848.5 9861.8 9866.3 12131.9 12228.4 12236.0 12264.7 12266.2 7264.7 7341.6 7351.4 7382.7 7398.4 7099.6 7116.6 7130.4 7157.2 7191.3 4159.6 4187.4 4209.4 4301.5 4322.0 1581.6 1645.3 1697.0 1708.9 1713.1 822.5 823.0 856.9 1001.5 1003.5 14695.6 14822.6 14976.1 15142.2 15318.6 856.8 866.4 868.5 873.0 886.3 7728.6 9026.0 9056.2 9364.0 9383.7 516654.0 524315.0 524495.0 533193.0 535231.0 60714.4 61727.7 62854.4 73618.6 75581.0 1057.7 1081.3 1105.5 1106.2 1107.5 13420.5 13556.2 13842.2 14034.1 14809.4 29523.6 30212.4 30742.4 31300.8 33589.7 39182.0 39196.3 39583.0 43442.0 43800.3 8367.3 8522.8 8531.8 8538.0 8565.5 8182.3 8434.2 9253.0 9329.6 10406.3 7924.2 7936.9 8186.7 8765.9 9123.0 8672.4 8867.5 9015.2 9384.1 9396.9 8979.5 9345.3 9369.3 9395.4 9405.9 11716.5 11760.1 12874.8 13242.9 13902.6 8874.5 9275.8 14545.8 14577.0 14598.3 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5070.74 Median:6345.51 stddev:2650.33 (41.8%) stddev-sans-first:2641.90 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: Values: 1212.7 1645.5 1706.8 1726.7 1727.7 5988.1 6922.1 7306.5 7320.2 7324.0 6804.9 6897.8 7034.8 7041.3 7046.8 1738.3 1770.9 1773.7 2085.1 2137.7 1779.0 1787.3 2076.0 2124.6 2126.0 2054.1 2055.6 2057.0 2058.4 2061.1 1986.9 2023.2 2024.8 2025.9 2028.6 2068.5 2077.6 2085.8 2120.9 2122.9 7249.7 7325.9 7337.6 7338.0 7340.3 4251.7 4326.8 4340.2 4358.7 4366.1 2704.8 2727.4 2774.5 2794.5 2825.5 838.2 851.5 852.0 866.2 899.1 9933.9 9952.7 9960.6 9962.4 9968.5 3937.0 3981.8 4020.1 4076.3 4077.8 8504.1 8525.4 8526.3 8526.7 8528.4 4309.7 4310.8 4312.6 4314.5 4318.3 3871.6 3875.3 3876.0 3905.8 3912.2 3500.0 3511.9 3520.5 3526.1 3551.3 935.0 967.3 1078.8 1115.7 1116.5 968.0 969.2 1014.0 1037.4 1224.8 6941.8 7020.4 7033.7 7035.9 7038.9 1039.4 1052.3 1055.9 1069.4 1072.0 5403.9 5444.9 5448.9 5465.7 5467.5 8585.0 8650.6 8859.2 8898.2 8985.7 8764.5 8789.2 8869.5 8878.4 8894.3 6725.0 6845.6 6864.6 6885.8 6917.0 6576.0 6671.2 6678.8 6680.8 6689.5 7516.5 7740.4 7882.8 7899.6 7908.0 7535.2 7695.2 7805.0 7813.6 8036.4 6406.9 6407.7 6428.3 6517.4 6538.6 5743.3 7014.0 7061.5 7073.9 7126.9 5927.3 6281.2 6617.2 6676.1 6709.9 6581.8 6589.2 6645.8 6671.5 6688.8 6190.0 6384.6 6395.4 6689.2 6689.5 6029.3 6048.6 6076.9 6087.8 6090.6 6203.7 6265.3 6289.4 6297.4 6306.4 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4664.30 Median:5291.05 stddev:1360.42 (25.7%) stddev-sans-first:1324.29 14:15:31 INFO - 2015-12-16 14:15:31,724 DEBUG : BROWSER_OUTPUT: Values: 303.8 304.9 310.7 314.2 314.4 5347.4 5363.2 5363.8 5374.9 5375.8 5313.3 5318.4 5319.1 5319.1 5324.0 4152.9 4167.7 4169.6 4182.0 4184.2 3945.7 4027.2 4121.9 4176.3 4191.6 3887.2 3993.2 3999.8 4005.6 4095.0 3986.6 4111.3 4144.0 4147.1 4155.9 3652.9 3654.0 3656.2 3661.5 3662.4 4933.5 5048.4 5072.1 5132.7 5177.6 4748.3 4752.1 4755.0 4755.2 4762.2 5268.8 5597.2 5602.0 5602.1 5603.6 3796.0 3859.0 3954.8 3981.6 3991.2 5833.5 5855.7 5861.9 5863.1 5868.2 4101.9 4102.6 4112.2 4116.5 4117.4 4082.2 4177.0 4180.0 4181.7 4184.9 4717.2 4724.0 4729.1 4729.6 4735.4 5583.1 5587.4 5588.7 5589.9 5591.9 1454.3 1471.3 1471.4 1471.9 1472.8 1000.7 1009.0 1012.5 1029.8 1032.1 4918.9 4941.4 4943.3 4943.6 4945.5 5800.1 5808.2 5813.3 5814.2 5818.4 4922.1 4933.3 4933.4 4933.5 4945.1 3064.4 3108.5 3137.1 3138.8 3142.5 5511.4 5522.0 5522.4 5525.1 5527.4 5957.6 5964.4 5965.2 5966.5 5967.4 5512.1 5518.4 5520.0 5522.6 5525.8 5627.5 5794.9 5800.6 5802.8 5808.6 5435.8 5553.3 5580.9 5743.7 5747.3 5566.1 5569.9 5573.3 5578.1 5587.0 4063.6 4066.8 4068.5 4071.1 4077.7 5637.4 5831.6 5841.0 5841.8 5854.5 5626.9 5632.0 5637.9 5638.8 5639.0 5659.6 5669.7 5670.9 5677.5 5678.0 5397.6 5526.8 5543.6 5550.1 5553.6 5655.9 5676.2 5694.4 5695.2 5699.7 5541.8 5547.3 5553.5 5554.7 5563.4 14:15:31 INFO - 2015-12-16 14:15:31,725 DEBUG : BROWSER_OUTPUT: 14:15:31 INFO - 2015-12-16 14:15:31,725 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12042.41 Median:43596.17 stddev:13249.14 (30.4%) stddev-sans-first:13258.71 14:15:31 INFO - 2015-12-16 14:15:31,725 DEBUG : BROWSER_OUTPUT: Values: 655.3 762.2 762.8 774.7 795.4 12049.6 12171.6 12183.1 12189.5 12249.4 11335.1 11411.5 11499.8 11621.4 11707.9 642.6 647.7 806.2 811.9 828.6 820.8 824.1 828.3 830.7 832.4 862.1 871.0 871.2 871.3 871.6 671.8 678.2 853.5 858.0 862.0 637.6 638.9 639.1 639.3 639.6 12025.6 12155.0 12162.1 12168.2 12175.4 11214.0 11233.2 11285.5 11292.4 11622.9 9307.9 9320.5 9399.2 9416.2 9465.4 3215.4 3236.1 3262.0 3293.2 3325.5 37760.2 37790.6 37978.1 38179.6 38318.0 9427.0 9501.0 9622.1 9634.4 9745.1 15631.1 15684.9 15734.8 15792.6 15794.2 6163.2 6233.5 6249.2 6259.1 6421.8 3569.7 3572.4 3578.9 3633.0 3659.2 2537.7 2553.2 2560.2 2570.7 2571.4 470.1 474.7 475.0 475.1 478.2 572.8 599.5 617.0 638.4 640.6 7622.4 8681.8 8806.6 8883.9 8945.2 520.4 599.0 601.7 637.7 640.8 3560.5 3742.5 3761.8 3809.5 3891.1 48121.8 48162.0 48225.8 48495.8 48535.3 42734.6 43458.8 43733.6 44289.8 44545.3 36079.6 36564.8 36748.8 38872.2 39178.2 7851.0 7956.6 7988.3 8200.9 8438.9 30170.1 30229.5 30276.5 30796.0 30807.2 38127.2 38455.6 39067.6 39409.6 39410.8 9991.6 10042.9 10050.8 10227.3 10316.4 11173.2 11181.8 11407.8 11629.8 11653.9 10525.5 10670.3 10773.6 11088.3 11441.7 10528.1 10688.6 11139.8 11236.1 11280.3 10411.5 10541.0 10581.1 10874.0 10903.6 8530.9 8838.8 9049.4 9200.6 9848.0 8706.5 8979.3 9322.7 9451.2 9458.7 14:15:31 INFO - 2015-12-16 14:15:31,725 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:15:31 INFO - 2015-12-16 14:15:31,725 DEBUG : BROWSER_OUTPUT: 14:15:32 INFO - 2015-12-16 14:15:32,445 INFO : Browser exited with error code: 0 14:15:32 INFO - 2015-12-16 14:15:32,466 INFO : Completed test dromaeo_css (00:18:37) 14:15:32 INFO - 2015-12-16 14:15:32,467 INFO : Starting test kraken 14:15:32 INFO - 2015-12-16 14:15:32,467 DEBUG : operating with platform_type : linux_ 14:15:32 INFO - 2015-12-16 14:15:32,467 INFO : Initialising browser for kraken test... 14:15:32 INFO - 2015-12-16 14:15:32,473 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNCbq_o/profile http://localhost:53100/getInfo.html 14:15:36 INFO - 2015-12-16 14:15:36,971 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:15:36 INFO - 2015-12-16 14:15:36,971 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:15:36 INFO - 2015-12-16 14:15:36,971 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:15:36 INFO - 2015-12-16 14:15:36,971 DEBUG : BROWSER_OUTPUT: __metrics 14:15:37 INFO - 2015-12-16 14:15:37,052 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 14:15:37 INFO - 2015-12-16 14:15:37,116 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:15:37 INFO - 2015-12-16 14:15:37,993 INFO : Browser initialized. 14:15:37 INFO - 2015-12-16 14:15:37,993 INFO : Running cycle 1/1 for kraken test... 14:15:37 INFO - 2015-12-16 14:15:37,994 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNCbq_o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:15:49 INFO - 2015-12-16 14:15:49,969 DEBUG : BROWSER_OUTPUT: RSS: Main: 142159872 14:15:49 INFO - 2015-12-16 14:15:49,969 DEBUG : BROWSER_OUTPUT: 14:15:52 INFO - 2015-12-16 14:15:52,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53100/tests/kraken/driver.html?testName=audio-beat-detection) 14:15:53 INFO - 2015-12-16 14:15:53,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 178720768 14:15:53 INFO - 2015-12-16 14:15:53,154 DEBUG : BROWSER_OUTPUT: 14:15:55 INFO - 2015-12-16 14:15:55,897 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53100/tests/kraken/driver.html?testName=audio-dft) 14:15:56 INFO - 2015-12-16 14:15:56,483 DEBUG : BROWSER_OUTPUT: RSS: Main: 168034304 14:15:56 INFO - 2015-12-16 14:15:56,483 DEBUG : BROWSER_OUTPUT: 14:15:59 INFO - 2015-12-16 14:15:59,468 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53100/tests/kraken/driver.html?testName=audio-fft) 14:16:00 INFO - 2015-12-16 14:16:00,034 DEBUG : BROWSER_OUTPUT: RSS: Main: 153964544 14:16:00 INFO - 2015-12-16 14:16:00,034 DEBUG : BROWSER_OUTPUT: 14:16:02 INFO - 2015-12-16 14:16:02,079 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53100/tests/kraken/driver.html?testName=audio-oscillator) 14:16:02 INFO - 2015-12-16 14:16:02,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 155414528 14:16:02 INFO - 2015-12-16 14:16:02,652 DEBUG : BROWSER_OUTPUT: 14:16:04 INFO - 2015-12-16 14:16:04,910 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53100/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:16:05 INFO - 2015-12-16 14:16:05,477 DEBUG : BROWSER_OUTPUT: RSS: Main: 157892608 14:16:05 INFO - 2015-12-16 14:16:05,477 DEBUG : BROWSER_OUTPUT: 14:16:08 INFO - 2015-12-16 14:16:08,456 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53100/tests/kraken/driver.html?testName=imaging-darkroom) 14:16:09 INFO - 2015-12-16 14:16:09,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 157650944 14:16:09 INFO - 2015-12-16 14:16:09,037 DEBUG : BROWSER_OUTPUT: 14:16:12 INFO - 2015-12-16 14:16:12,419 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53100/tests/kraken/driver.html?testName=imaging-desaturate) 14:16:13 INFO - 2015-12-16 14:16:13,000 DEBUG : BROWSER_OUTPUT: RSS: Main: 159047680 14:16:13 INFO - 2015-12-16 14:16:13,001 DEBUG : BROWSER_OUTPUT: 14:16:15 INFO - 2015-12-16 14:16:15,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53100/tests/kraken/driver.html?testName=json-parse-financial) 14:16:16 INFO - 2015-12-16 14:16:16,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 159293440 14:16:16 INFO - 2015-12-16 14:16:16,472 DEBUG : BROWSER_OUTPUT: 14:16:18 INFO - 2015-12-16 14:16:18,249 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53100/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:16:18 INFO - 2015-12-16 14:16:18,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 167641088 14:16:18 INFO - 2015-12-16 14:16:18,815 DEBUG : BROWSER_OUTPUT: 14:16:20 INFO - 2015-12-16 14:16:20,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:16:21 INFO - 2015-12-16 14:16:21,313 DEBUG : BROWSER_OUTPUT: RSS: Main: 166359040 14:16:21 INFO - 2015-12-16 14:16:21,313 DEBUG : BROWSER_OUTPUT: 14:16:23 INFO - 2015-12-16 14:16:23,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:16:24 INFO - 2015-12-16 14:16:24,014 DEBUG : BROWSER_OUTPUT: RSS: Main: 177479680 14:16:24 INFO - 2015-12-16 14:16:24,014 DEBUG : BROWSER_OUTPUT: 14:16:26 INFO - 2015-12-16 14:16:26,570 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:16:27 INFO - 2015-12-16 14:16:27,151 DEBUG : BROWSER_OUTPUT: RSS: Main: 181739520 14:16:27 INFO - 2015-12-16 14:16:27,151 DEBUG : BROWSER_OUTPUT: 14:16:29 INFO - 2015-12-16 14:16:29,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:16:30 INFO - 2015-12-16 14:16:30,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 180084736 14:16:30 INFO - 2015-12-16 14:16:30,320 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53100/tests/kraken/driver.html?testName=ai-astar) 14:16:32 INFO - 2015-12-16 14:16:32,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 238379008 14:16:32 INFO - 2015-12-16 14:16:32,324 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;116;107;113;113;113;102;115;113;119 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;130;150;173;136;155;165;132;169;130 14:16:32 INFO - 2015-12-16 14:16:32,328 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;162;163;163;161;161;162;163;162;163 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;75;86;91;73;74;72;117;94;72 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;96;136;95;95;95;139;95;95;127 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;109;110;110;110;110;110;111;111;110 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;146;147;146;148;146;148;146 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;96 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;73;73;73;73;72;72;82;71;70 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;69;68;67;69;69;68;68;70 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;86;84;85;86;86;85;86;86;86 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;128;120;130;134;122;129;123;121;128 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;138;147;145;147;137;151;146;146;140 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;75;70;70;70;69;70;69;70;69;70 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:16:32 INFO - 2015-12-16 14:16:32,329 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:16:32 INFO - 2015-12-16 14:16:32,330 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,993 14:16:32 INFO - 2015-12-16 14:16:32,330 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:16:32 INFO - 2015-12-16 14:16:32,330 DEBUG : BROWSER_OUTPUT: __startTimestamp1450304192326__endTimestamp 14:16:32 INFO - 2015-12-16 14:16:32,330 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:4.74 (4.2%) stddev-sans-first:5.02 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: Values: 112.0 116.0 107.0 113.0 113.0 113.0 102.0 115.0 113.0 119.0 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,333 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.60 Median:155.50 stddev:16.65 (10.7%) stddev-sans-first:17.50 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: Values: 156.0 130.0 150.0 173.0 136.0 155.0 165.0 132.0 169.0 130.0 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: Values: 162.0 162.0 163.0 163.0 161.0 161.0 162.0 163.0 162.0 163.0 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.60 Median:74.50 stddev:14.74 (19.8%) stddev-sans-first:15.13 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: Values: 72.0 75.0 86.0 91.0 73.0 74.0 72.0 117.0 94.0 72.0 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:95.00 stddev:19.00 (20.0%) stddev-sans-first:19.67 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 136.0 95.0 95.0 95.0 139.0 95.0 95.0 127.0 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: Values: 111.0 109.0 110.0 110.0 110.0 110.0 110.0 111.0 111.0 110.0 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 14:16:32 INFO - 2015-12-16 14:16:32,334 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 146.0 147.0 146.0 148.0 146.0 148.0 146.0 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.10 Median:73.00 stddev:3.28 (4.5%) stddev-sans-first:3.46 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 73.0 73.0 73.0 72.0 72.0 82.0 71.0 70.0 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 14:16:32 INFO - 2015-12-16 14:16:32,335 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 68.0 67.0 69.0 69.0 68.0 68.0 70.0 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:0.73 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: Values: 83.0 86.0 84.0 85.0 86.0 86.0 85.0 86.0 86.0 86.0 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:128.50 stddev:4.67 (3.6%) stddev-sans-first:4.78 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: Values: 130.0 128.0 120.0 130.0 134.0 122.0 129.0 123.0 121.0 128.0 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.40 Median:146.00 stddev:4.97 (3.4%) stddev-sans-first:4.70 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: Values: 137.0 138.0 147.0 145.0 147.0 137.0 151.0 146.0 146.0 140.0 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:1.75 (2.5%) stddev-sans-first:0.50 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: Values: 75.0 70.0 70.0 70.0 69.0 70.0 69.0 70.0 69.0 70.0 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:16:32 INFO - 2015-12-16 14:16:32,336 DEBUG : BROWSER_OUTPUT: 14:16:33 INFO - 2015-12-16 14:16:33,241 INFO : Browser exited with error code: 0 14:16:33 INFO - 2015-12-16 14:16:33,293 INFO : Completed test kraken (00:01:00) 14:16:33 INFO - 2015-12-16 14:16:33,293 INFO : Starting test v8_7 14:16:33 INFO - 2015-12-16 14:16:33,294 DEBUG : operating with platform_type : linux_ 14:16:33 INFO - 2015-12-16 14:16:33,294 INFO : Initialising browser for v8_7 test... 14:16:33 INFO - 2015-12-16 14:16:33,300 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp71L0i9/profile http://localhost:53100/getInfo.html 14:16:37 INFO - 2015-12-16 14:16:37,422 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:16:37 INFO - 2015-12-16 14:16:37,422 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:16:37 INFO - 2015-12-16 14:16:37,422 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:16:37 INFO - 2015-12-16 14:16:37,422 DEBUG : BROWSER_OUTPUT: __metrics 14:16:37 INFO - 2015-12-16 14:16:37,611 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 14:16:37 INFO - 2015-12-16 14:16:37,672 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:16:38 INFO - 2015-12-16 14:16:38,770 INFO : Browser initialized. 14:16:38 INFO - 2015-12-16 14:16:38,770 INFO : Running cycle 1/1 for v8_7 test... 14:16:38 INFO - 2015-12-16 14:16:38,770 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp71L0i9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:16:50 INFO - 2015-12-16 14:16:50,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 142569472 14:16:50 INFO - 2015-12-16 14:16:50,764 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53100/tests/v8_7/run.html (next: http://localhost:53100/tests/v8_7/run.html) 14:17:12 INFO - 2015-12-16 14:17:12,394 DEBUG : BROWSER_OUTPUT: RSS: Main: 442892288 14:17:12 INFO - 2015-12-16 14:17:12,395 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,396 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:17:12 INFO - 2015-12-16 14:17:12,396 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:17:12 INFO - 2015-12-16 14:17:12,396 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:17:12 INFO - 2015-12-16 14:17:12,396 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:17:12 INFO - 2015-12-16 14:17:12,396 DEBUG : BROWSER_OUTPUT: |0;Richards;165.8649859014762 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.39328984156572 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.9321845469705 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1998.0039920159682 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |5;Earley;794.2811755361398 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |6;Boyer;12023.809523809523 14:17:12 INFO - 2015-12-16 14:17:12,397 DEBUG : BROWSER_OUTPUT: |7;RegExp;27216.216216216217 14:17:12 INFO - 2015-12-16 14:17:12,398 DEBUG : BROWSER_OUTPUT: |8;Splay;627.1929824561404 14:17:12 INFO - 2015-12-16 14:17:12,398 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 14:17:12 INFO - 2015-12-16 14:17:12,398 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:17:12 INFO - 2015-12-16 14:17:12,398 DEBUG : BROWSER_OUTPUT: __startTimestamp1450304232395__endTimestamp 14:17:12 INFO - 2015-12-16 14:17:12,398 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,398 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:17:12 INFO - 2015-12-16 14:17:12,399 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:17:12 INFO - 2015-12-16 14:17:12,399 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,400 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.86 Median:165.86 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,400 DEBUG : BROWSER_OUTPUT: Values: 165.9 14:17:12 INFO - 2015-12-16 14:17:12,400 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,400 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.39 Median:186.39 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: Values: 186.4 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.93 Median:277.93 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: Values: 277.9 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,401 DEBUG : BROWSER_OUTPUT: Values: 5186.5 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1998.00 Median:1998.00 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: Values: 1998.0 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:794.28 Median:794.28 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: Values: 794.3 14:17:12 INFO - 2015-12-16 14:17:12,402 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12023.81 Median:12023.81 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: Values: 12023.8 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27216.22 Median:27216.22 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: Values: 27216.2 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:627.19 Median:627.19 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,403 DEBUG : BROWSER_OUTPUT: Values: 627.2 14:17:12 INFO - 2015-12-16 14:17:12,404 DEBUG : BROWSER_OUTPUT: 14:17:12 INFO - 2015-12-16 14:17:12,404 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 14:17:12 INFO - 2015-12-16 14:17:12,404 DEBUG : BROWSER_OUTPUT: Values: 4830.9 14:17:12 INFO - 2015-12-16 14:17:12,404 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:17:12 INFO - 2015-12-16 14:17:12,404 DEBUG : BROWSER_OUTPUT: 14:17:13 INFO - 2015-12-16 14:17:13,293 INFO : Browser exited with error code: 0 14:17:13 INFO - 2015-12-16 14:17:13,310 INFO : Completed test v8_7 (00:00:40) 14:17:13 INFO - 2015-12-16 14:17:13,460 INFO : Completed test suite (00:20:18) 14:17:13 INFO - 2015-12-16 14:17:13,461 DEBUG : Working with test: dromaeo_css 14:17:13 INFO - 2015-12-16 14:17:13,461 DEBUG : Generating results file: dromaeo_css 14:17:13 INFO - 2015-12-16 14:17:13,462 DEBUG : Working with test: kraken 14:17:13 INFO - 2015-12-16 14:17:13,462 DEBUG : Generating results file: kraken 14:17:13 INFO - 2015-12-16 14:17:13,462 INFO : javascript benchmark 14:17:13 INFO - 2015-12-16 14:17:13,462 DEBUG : Working with test: v8_7 14:17:13 INFO - 2015-12-16 14:17:13,463 DEBUG : Generating results file: v8_7 14:17:13 INFO - 2015-12-16 14:17:13,463 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:17:13 INFO - 2015-12-16 14:17:13,520 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,35]] 14:17:13 INFO - 2015-12-16 14:17:13,520 DEBUG : process_Request line: dromaeo_css 6308.59 graph.html#tests=[[72,132,35]] 14:17:13 INFO - 2015-12-16 14:17:13,520 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:17:13 INFO - 2015-12-16 14:17:13,564 DEBUG : process_Request line: kraken 1532.60 graph.html#tests=[[232,132,35]] 14:17:13 INFO - 2015-12-16 14:17:13,564 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:17:13 INFO - 2015-12-16 14:17:13,600 DEBUG : process_Request line: v8_7 19717.58 graph.html#tests=[[230,132,35]] 14:17:13 INFO - 2015-12-16 14:17:13,601 INFO : javascript benchmark 14:17:13 INFO - 2015-12-16 14:17:13,605 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2607.9087231946887, 2720.891164353426, 2723.9182824515274, 2742.0, 2742.0676969830256, 4548.317752337151, 4689.437267527901, 4723.315119307698, 4785.856424307268, 4787.066522028202, 4602.12559613674, 4661.976690116553, 4714.3164241185095, 4726.243800991842, 4726.243800991842, 4275.144971005796, 4297.613214810674, 4316.6978311518205, 4446.355278484618, 4453.910921781562, 4178.498580170369, 4395.230834603947, 4414.22751018572, 4473.127740090684, 4494.955050449503, 4233.745975241495, 4291.957080429187, 4295.248331541999, 4295.506016808075, 4310.331898555729, 4326.632236259922, 4334.0, 4338.631216346615, 4368.803403846835, 4373.956260437419, 4134.751914885116, 4219.11398606293, 4324.870253892388, 4333.2200203963275, 4379.058502421963, 4572.3827283317, 4604.861854144381, 4674.135284972291, 4680.7191568506, 4712.764361781931, 5007.799688012512, 5023.095842748304, 5029.446760856303, 5031.044101620717, 5071.898562028776, 4860.222364421676, 4964.6276529260085, 5042.0, 5046.873828154289, 5064.620153488503, 4644.605208557243, 4656.115338085787, 4688.671792974527, 4760.285957106462, 4790.137775200427, 5170.095233334151, 5188.351456067991, 5189.325387699575, 5190.0, 5236.214567814858, 4774.331593576832, 4962.106820772224, 4966.279889416014, 4968.850934471971, 4988.347547495608, 4970.676906001098, 5015.27278544609, 5032.547070763649, 5072.0, 5116.488351164928, 4986.675866068694, 5027.145385284511, 5029.949700503022, 5031.0441016206805, 5074.441811400743, 4741.691790033637, 4994.875128121826, 5002.124628190052, 5003.074431230234, 5003.324551185616, 4099.877003689894, 4117.464729585135, 4149.854755083557, 4174.3947127666315, 4202.180574787887, 3650.3976843821, 3661.6338366163704, 3664.2305115925956, 3678.301122786662, 3698.519192504957, 4142.399352093929, 4347.6304514116655, 4348.195583817004, 4352.82588696455, 4398.934015989763, 4933.531314525115, 4949.851504454872, 4951.257311403247, 5007.924881126786, 5027.346444962204, 4344.674149438804, 4347.239233134189, 4363.825446982085, 4371.147626212858, 4413.426254586947, 4176.707630465899, 4480.081583275421, 4483.596476317147, 4489.0, 4517.706349087299, 4705.788239529161, 4709.175894218498, 4726.763661816895, 4820.1805693031565, 4832.395950506187, 5069.721165335942, 5110.897782044412, 5113.130767769562, 5114.46298138705, 5115.411727651368, 4855.053264613366, 4860.65975381734, 4863.8540843774745, 4864.17309057454, 4886.95113048865, 4828.203346447796, 4864.440589332272, 4944.332515110414, 4946.653734238569, 5064.569511591409, 5066.265391518135, 5092.8472145835685, 5094.566961808139, 5098.719570423662, 5107.233914912793, 4911.901761964812, 4913.6069114471475, 4916.975415122902, 4923.064617722621, 4944.134776414185, 4701.670883038291, 4826.95173048265, 4831.541003604652, 4835.20219163834, 4926.58124059459, 4783.091212669582, 4833.275008748716, 4846.273059041031, 4847.394075740533, 4889.608831293417, 4963.851084467472, 4974.651774375904, 4974.676646017997, 4974.726390048582, 5003.474635163401, 4643.558861908114, 4730.905381892412, 4770.928436073531, 4771.1650461168465, 4860.1008813369635], "unit": "score", "name": "dojo.html", "value": 4614.356970073887}, {"lowerIsBetter": false, "replicates": [565.5955991466103, 567.486424785569, 572.942705729427, 574.494444888498, 576.8240686590589, 27499.175024749264, 36108.0, 36893.446598301045, 36907.81546092273, 36971.99999999996, 40210.59789402105, 40442.595574044324, 40577.0, 40730.38904416429, 40893.0, 7004.159500859904, 7127.750528731495, 7905.0513938327485, 8078.192180781904, 8080.232377924088, 10159.339642923223, 10438.06057454829, 10443.84334234987, 10453.0, 10454.0, 9864.112229899307, 9924.255680823931, 10024.14794742444, 10029.899701002969, 10031.147352475049, 7158.964205178967, 7331.083614548182, 7337.559746415232, 7339.9633001834645, 10004.59981600735, 7086.433085353159, 10153.324701424257, 10444.060034596923, 10447.268691191619, 10448.477576121164, 37809.0, 38324.80837595794, 38438.0, 38447.231055379016, 38484.037899052746, 2865.5415133578526, 2889.6387951506063, 2921.4303210873886, 2966.569847372119, 3491.1097670093964, 28300.575491367646, 28526.572101418496, 29071.273218169503, 29233.561496577568, 29500.557491637643, 3138.419392412411, 3283.7208837248863, 3302.9009129726146, 3342.88299909502, 3383.4924761285756, 215113.0, 215622.0, 215649.0, 216416.0, 216898.0, 49417.0, 50365.0, 51706.74146629243, 53018.20472692913, 53262.733686332096, 52456.47543524592, 52516.0, 52650.0, 52682.209766852764, 52795.94408111816, 13460.057795954384, 13558.525451609146, 13613.659658508617, 13638.63613638648, 13692.794608080887, 10138.847917281246, 10151.94924025375, 10158.39049657023, 10175.0, 10302.793944121076, 18151.45545633633, 18188.453981411727, 18213.54466138357, 18233.179506921955, 18312.08439578016, 7256.238095000054, 7464.477486575887, 7553.1313898901235, 7553.773386798405, 7586.658600362997, 879.7009016934145, 906.6056265524518, 910.7950711090084, 919.1635611593418, 978.8923218445965, 26513.602295965575, 26613.191793893082, 26619.20142395731, 26702.19893403162, 26878.5968210477, 995.5669283861398, 1003.668789299544, 1008.1984822066474, 1009.7223263602422, 1011.7723512209841, 13769.724605507834, 14021.439142434188, 14222.146671199762, 14249.287535623178, 14299.92850035764, 264715.0, 268054.6597267001, 269186.0, 269195.0, 269398.4071513203, 45915.77042114835, 46210.30684539735, 46221.30668039982, 46287.768561156976, 46302.305465418045, 1228.4103630257525, 1244.7883859743865, 1251.1804767877054, 1259.9118061735774, 1267.2776517385003, 25519.617205741557, 26135.869320654037, 26759.464810704067, 26760.46479070369, 26771.19686409411, 43330.35004474998, 44204.99999999871, 44235.55764442443, 44261.557384425745, 44312.7784361089, 229345.0, 234280.0, 340793.0, 351812.0, 352915.0, 28193.295167620974, 28273.43453130967, 28283.43433131367, 28297.57553636738, 28313.575296370982, 39363.21273574455, 40671.38992915049, 40796.38805417862, 41060.58939410568, 41202.79398602988, 32078.679213207568, 34095.82952085224, 34311.0, 34573.13567160741, 35011.64988350186, 31934.36131277408, 33385.666143338254, 34318.0, 34343.0, 34606.13484662904, 29917.0, 30240.395192095595, 30429.847850760605, 30626.540601891436, 30653.386932260782, 28709.0, 29355.85322073376, 29534.81860725633, 29786.851065744533, 29893.70106298909, 22947.885260574258, 23365.649515256944, 23700.8814955931, 23791.881040594686, 23817.880910595337], "unit": "score", "name": "ext.html", "value": 17218.16408987192}, {"lowerIsBetter": false, "replicates": [326.5330577274498, 328.24503641624267, 344.0727240087964, 344.9534312867764, 346.6568097583393, 12407.875921240784, 12836.0, 14830.925845370772, 15130.924345378271, 16889.155542222885, 15042.172680502568, 15207.783377329815, 16285.674286514262, 16517.33930642776, 16518.587035324093, 4809.422869255694, 4944.456109827916, 4950.034743225073, 4951.702897826142, 5063.164577844651, 3428.502867084271, 3523.242502861884, 5048.7223202723835, 5203.739813009353, 5206.635535512497, 5078.0, 5135.768890399922, 5155.587552995751, 5160.561352285042, 5228.032813929435, 5125.282460455539, 5158.380994280673, 5172.353455818023, 5178.197379406198, 5190.4031036430915, 4951.653384263103, 4966.528179822947, 4967.081089998362, 4977.552020318189, 4999.275105109738, 16617.584560385967, 18275.63448731018, 18643.720344194848, 18718.344857930042, 18770.624587508173, 1807.3132209760338, 1818.5544541587265, 1863.7390765292869, 1867.374429566097, 1985.0372569303877, 8522.446041007315, 8753.343499237582, 8865.556722163867, 9012.774680633036, 9024.323175761778, 1705.8294170582967, 1733.5232810976956, 1753.2461041752042, 1758.3318339031214, 1767.7878654561534, 55273.44726552763, 57768.71115644437, 58121.41878581245, 58179.709101454646, 58591.121133183464, 9560.043995600385, 9743.61025558984, 9848.458334791656, 9861.802763944681, 9866.260030497742, 12131.93934030342, 12228.388580570936, 12236.0, 12264.693382665504, 12266.202696824677, 7264.745733899288, 7341.632918354061, 7351.3751331137455, 7382.6677799498075, 7398.371138453184, 7099.6450177490915, 7116.573005619679, 7130.358267755927, 7157.212706602374, 7191.280871912767, 4159.584041595816, 4187.413762073312, 4209.368594710757, 4301.548337424588, 4322.0, 1581.5571639940836, 1645.2925242145993, 1697.0, 1708.9316427343015, 1713.0663788235443, 822.4695071678734, 822.979425514355, 856.9400141990001, 1001.529281237817, 1003.518311210623, 14695.559133226021, 14822.62943426423, 14976.101433914, 15142.242887855564, 15318.617034574001, 856.8286342731478, 866.4065115395974, 868.4702331577732, 872.9825403491894, 886.3263919421157, 7728.574928378881, 9025.962014368431, 9056.184943354998, 9364.0, 9383.718488445358, 516654.0, 524315.0, 524495.0, 533193.0, 535231.0, 60714.392856071754, 61727.69136154291, 62854.371456285764, 73618.6319068412, 75581.0, 1057.6562617149457, 1081.3187691754144, 1105.4804242006244, 1106.1593189176283, 1107.501624269066, 13420.46318147263, 13556.186628801908, 13842.238676872868, 14034.087784293988, 14809.407623694931, 29523.557146642386, 30212.395752085275, 30742.385152297276, 31300.843495782374, 33589.66410335866, 39182.0, 39196.34920871387, 39583.01042473961, 43442.0, 43800.342994854465, 8367.288780453486, 8522.786930326543, 8531.786705332417, 8538.0, 8565.528895910784, 8182.345412367115, 8434.156584341517, 9253.0, 9329.626814927327, 10406.271560990657, 7924.247196516321, 7936.920630793618, 8186.672533098847, 8765.91234087651, 9123.0, 8672.436291641023, 8867.467951922905, 9015.233705134875, 9384.108509691569, 9396.859047114436, 8979.50612716307, 9345.345825792128, 9369.344145909721, 9395.436273823592, 9405.905940940504, 11716.472758726048, 11760.11799115087, 12874.806877896652, 13242.867571324163, 13902.582922512342, 8874.467531948103, 9275.76810579741, 14545.781813272597, 14576.979611427098, 14598.270086495846], "unit": "score", "name": "jquery.html", "value": 7880.8919288091665}, {"lowerIsBetter": false, "replicates": [1212.7453234820687, 1645.5227983884672, 1706.8293170682932, 1726.7409888516725, 1727.6631056943902, 5988.101784732287, 6922.065521154643, 7306.4520160988, 7320.2313757055545, 7323.974643549907, 6804.931950680491, 6897.758578449755, 7034.824129396768, 7041.331073548017, 7046.823829404268, 1738.278614375039, 1770.884892481991, 1773.733939909011, 2085.1346691123167, 2137.690034944932, 1779.0, 1787.3297513432462, 2075.9792402075936, 2124.617568837609, 2125.9893700531475, 2054.065400242886, 2055.6402629539843, 2056.979430205694, 2058.4442200605904, 2061.051916118587, 1986.8708533945248, 2023.2109477303864, 2024.7873973232745, 2025.9161348678385, 2028.6247044296779, 2068.48287928018, 2077.6364136276093, 2085.8331333493284, 2120.9151633934625, 2122.862013969087, 7249.746258880914, 7325.890111648329, 7337.596432196227, 7337.963310183468, 7340.26597340267, 4251.659867210616, 4326.848560300375, 4340.175366680352, 4358.694891357606, 4366.061296821199, 2704.8106632535732, 2727.4272402795436, 2774.5144599695172, 2794.4690508803264, 2825.491411545921, 838.2288294768828, 851.5486792000188, 851.974440766778, 866.2160744526151, 899.1413200393608, 9933.850992235122, 9952.651657192037, 9960.601575936953, 9962.352447090916, 9968.501574921225, 3937.0, 3981.8407263709705, 4020.075382662004, 4076.3274059779806, 4077.8164982575854, 8504.064552899175, 8525.40322177454, 8526.275266602408, 8526.65893364271, 8528.445651032664, 4309.698321117555, 4310.80601372939, 4312.5903039211225, 4314.482262128565, 4318.330658747899, 3871.632194941476, 3875.2830726315724, 3875.9806200968815, 3905.7851818149707, 3912.217556488714, 3499.9825000874835, 3511.94732079019, 3520.4543295789194, 3526.1360966563357, 3551.3430015447225, 935.0, 967.264878692199, 1078.8489611454402, 1115.6876074699098, 1116.4975760907462, 968.0, 969.2197780786535, 1013.9657549299673, 1037.4034929915329, 1224.8285240066398, 6941.826454338581, 7020.403265722471, 7033.683484243222, 7035.929640703527, 7038.894416583756, 1039.3971496531992, 1052.273930987616, 1055.9260851740457, 1069.411823497089, 1071.9892801072046, 5403.945960540423, 5444.863878402993, 5448.891022179613, 5465.672059676353, 5467.4532546745, 8584.957075214585, 8650.567471626393, 8859.24696400813, 8898.154675305836, 8985.730428087169, 8764.517951512727, 8789.208971192624, 8869.467831930106, 8878.378513503993, 8894.28845692343, 6724.966375168191, 6845.5550389223545, 6864.5881247124335, 6885.793426197222, 6917.0, 6575.967120164369, 6671.232808227116, 6678.799636010927, 6680.766173183915, 6689.531732778659, 7516.5114267572435, 7740.380769538536, 7882.84234315299, 7899.644515996679, 7907.9604601976625, 7535.208803075818, 7695.153533111241, 7804.9609751950875, 7813.570253635878, 8036.437449378487, 6406.9359306408205, 6407.679616019087, 6428.260750013808, 6517.4460170886005, 6538.574992625464, 5743.339515955551, 7014.0, 7061.505694601328, 7073.893891591733, 7126.857462850818, 5927.318358388668, 6281.214848143982, 6617.205935287604, 6676.065350850982, 6709.899351509829, 6581.769638062645, 6589.209294884645, 6645.767398141042, 6671.499637527108, 6688.799336019927, 6189.96905015472, 6384.648844313608, 6395.42441180296, 6689.163854518185, 6689.46484281266, 6029.306629737636, 6048.60684055535, 6076.939230607637, 6087.786927457518, 6090.634561926298, 6203.658798766111, 6265.27949285838, 6289.40250676185, 6297.370262973849, 6306.369363063841], "unit": "score", "name": "mootools.html", "value": 4144.539047087276}, {"lowerIsBetter": false, "replicates": [303.8374469658733, 304.85214670884625, 310.7312174968652, 314.22229980797545, 314.39322108330913, 5347.35831700196, 5363.249145119681, 5363.785448582052, 5374.8925021499645, 5375.784968601256, 5313.282706834576, 5318.361796584415, 5319.122344813101, 5319.12234481311, 5323.973380133094, 4152.916941661165, 4167.749935003894, 4169.583041695836, 4181.958180418203, 4184.163167366523, 3945.7040721945827, 4027.234825383168, 4121.9381709274385, 4176.310908700061, 4191.643710284629, 3887.2419878123637, 3993.221321842242, 3999.7800120993334, 4005.6394924456795, 4094.9590504094876, 3986.5814089520472, 4111.280525907954, 4144.046869220066, 4147.108371700068, 4155.937660935088, 3652.945205821915, 3654.049947013769, 3656.177360093984, 3661.505696730934, 3662.377395842713, 4933.481984391623, 5048.3941926969, 5072.0870243356185, 5132.717700526469, 5177.611679124081, 4748.287756836467, 4752.120857641347, 4754.976225118852, 4755.2153894607345, 4762.214234651313, 5268.8155914543195, 5597.244372009767, 5601.971990140023, 5602.1036634138345, 5603.635763675389, 3796.0, 3858.961410385916, 3954.822033008493, 3981.6416522513114, 3991.2416640838146, 5833.51245432412, 5855.677937713424, 5861.882762344771, 5863.120531920246, 5868.178455016301, 4101.938470922939, 4102.630763231324, 4112.177564487115, 4116.485439320085, 4117.382392641128, 4082.2447847148374, 4176.997520595035, 4179.97910010448, 4181.686373521937, 4184.895377615584, 4717.174494463455, 4723.976380118077, 4729.0541891621815, 4729.62163026957, 4735.384400027974, 5583.106702927512, 5587.441255874462, 5588.6646801192055, 5589.916151257735, 5591.916121258103, 1454.3092031285266, 1471.3231913319778, 1471.3820195517908, 1471.8528147185195, 1472.8158980127484, 1000.7190795781412, 1009.0363702663939, 1012.4785735346303, 1029.7888932768915, 1032.1278519650882, 4918.926216106761, 4941.382327209099, 4943.2585112233455, 4943.555080042814, 4945.455999840015, 5800.071988481907, 5808.157817116495, 5813.273340832396, 5814.244148260784, 5818.418158184148, 4922.089413458521, 4933.28467372229, 4933.432655244621, 4933.45731969483, 4945.109880221595, 3064.4024415238814, 3108.5026395776567, 3137.074563003919, 3138.8430578470984, 3142.5129104988764, 5511.366192887869, 5522.0, 5522.420145884625, 5525.11598144303, 5527.39198688144, 5957.553183511167, 5964.433378829004, 5965.224520812353, 5966.49284810796, 5967.403259673998, 5512.118061110203, 5518.44815518453, 5520.0, 5522.5581953443625, 5525.778968841281, 5627.493525582717, 5794.855128621818, 5800.564957628279, 5802.82591522255, 5808.593398462236, 5435.782568697208, 5553.305836770403, 5580.86047848807, 5743.684097374685, 5747.281589801275, 5566.081596536526, 5569.888602227852, 5573.27547418833, 5578.107502799533, 5586.972065139648, 4063.553009169048, 4066.7763273019086, 4068.450759147477, 4071.063655359225, 4077.734947228421, 5637.351704554028, 5831.620944638585, 5840.970795146167, 5841.766329346949, 5854.531637468907, 5626.943730562805, 5632.014397480342, 5637.943620563742, 5638.774449021993, 5638.971805140948, 5659.575531835211, 5669.7165141741925, 5670.943290567042, 5677.460641239241, 5678.0, 5397.595180361567, 5526.806561770319, 5543.61194716366, 5550.056490396723, 5553.583481238842, 5655.915161272502, 5676.20533125371, 5694.430556944272, 5695.231143795535, 5699.715014249354, 5541.806036788855, 5547.278853748987, 5553.528679737478, 5554.666719996813, 5563.415841336601], "unit": "score", "name": "prototype.html", "value": 4231.120629516495}, {"lowerIsBetter": false, "replicates": [655.2791928878235, 762.2149186338074, 762.7749813804934, 774.6901239504199, 795.3994733975851, 12049.638510844678, 12171.634850954464, 12183.147179697402, 12189.451474683627, 12249.387530623479, 11335.149863760209, 11411.54353825848, 11499.827502587468, 11621.418929053556, 11707.941460292686, 642.5823214910322, 647.6729251727871, 806.1817255485687, 811.8863359129726, 828.6271177969908, 820.827626198499, 824.0893812337371, 828.2587084559316, 830.6760363458257, 832.4381042796089, 862.0861886400436, 871.0418539606445, 871.2159056848824, 871.2507243770352, 871.6252011634995, 671.7850287907871, 678.2166597579803, 853.5433543054436, 858.0304256190465, 862.0, 637.6397335505425, 638.8786130635165, 639.098870592467, 639.284001917855, 639.6418005916702, 12025.63923082309, 12155.0, 12162.087843411691, 12168.209066410654, 12175.39123043853, 11214.0, 11233.157513186461, 11285.548578056867, 11292.378919159442, 11622.941885290604, 9307.90692093077, 9320.533973301375, 9399.201067909236, 9416.1525462708, 9465.384749991228, 3215.3730022645595, 3236.1424222581036, 3261.951070733941, 3293.2425542125206, 3325.48454989477, 37760.24479510422, 37790.622093778984, 37978.050548736224, 38179.61820381788, 38318.0, 9427.0, 9501.0, 9622.085901839315, 9634.373765705206, 9745.12293893553, 15631.140287284308, 15684.90205685591, 15734.763978540332, 15792.60518487047, 15794.21028948548, 6163.19878415803, 6233.532485063638, 6249.218847644044, 6259.061140828912, 6421.8073457796345, 3569.660882216185, 3572.392693242155, 3578.946315805265, 3633.0, 3659.213269147146, 2537.6574162488196, 2553.1702196785745, 2560.2063360358347, 2570.742925707414, 2571.434284457416, 470.1490302552391, 474.7175430618776, 474.9859050926297, 475.09020226267, 478.167987701404, 572.8481952282649, 599.4784537452357, 617.003539284053, 638.3807706524664, 640.5932233031965, 7622.390208783284, 8681.782955426039, 8806.559672016374, 8883.911160888438, 8945.19493245611, 520.4301290087349, 598.975751464991, 601.7021574320652, 637.744902039188, 640.7981485831948, 3560.483729859156, 3742.4947632069866, 3761.774293542396, 3809.504764380613, 3891.1439483313625, 48121.75939120282, 48162.0, 48225.75887120542, 48495.75752121217, 48535.27197092046, 42734.57265427368, 43458.7827060869, 43733.56266437295, 44289.77855110704, 44545.33182002272, 36079.63920360815, 36564.81717591395, 36748.81625591855, 38872.22255554873, 39178.21643567113, 7850.960745196237, 7956.562389068657, 7988.320992715452, 8200.917990820015, 8438.915610843813, 30170.09489715312, 30229.546556802106, 30276.5458518118, 30796.0, 30807.2298192547, 38127.23745525129, 38455.61544384521, 39067.553853613834, 39409.605903940595, 39410.80294598509, 9991.600335986479, 10042.89957100449, 10050.798984020425, 10227.335223210468, 10316.381017138996, 11173.162012849232, 11181.832272516082, 11407.781407781342, 11629.76740465169, 11653.883461165618, 10525.473726313807, 10670.253082284476, 10773.569057237622, 11088.33469991803, 11441.656750297505, 10528.105111065339, 10688.625898093529, 11139.83290250663, 11236.101111911192, 11280.2667826591, 10411.531481083219, 10541.0, 10581.100606448537, 10874.02133807961, 10903.618373356894, 8530.872036919327, 8838.779030524289, 9049.411788233743, 9200.585973631069, 9847.95076024644, 8706.521141337287, 8979.281657467518, 9322.673706420243, 9451.243900487807, 9458.668946586837], "unit": "score", "name": "yui.html", "value": 5740.434760472096}], "name": "dromaeo_css", "value": 6308.59116870414}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 116.0, 107.0, 113.0, 113.0, 113.0, 102.0, 115.0, 113.0, 119.0], "unit": "score", "name": "ai-astar", "value": 112.3}, {"lowerIsBetter": true, "replicates": [156.0, 130.0, 150.0, 173.0, 136.0, 155.0, 165.0, 132.0, 169.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 149.6}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 163.0, 163.0, 161.0, 161.0, 162.0, 163.0, 162.0, 163.0], "unit": "score", "name": "audio-dft", "value": 162.2}, {"lowerIsBetter": true, "replicates": [72.0, 75.0, 86.0, 91.0, 73.0, 74.0, 72.0, 117.0, 94.0, 72.0], "unit": "score", "name": "audio-fft", "value": 82.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 136.0, 95.0, 95.0, 95.0, 139.0, 95.0, 95.0, 127.0], "unit": "score", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [111.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 148.0, 146.0, 148.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 73.0, 73.0, 73.0, 72.0, 72.0, 82.0, 71.0, 70.0], "unit": "score", "name": "json-parse-financial", "value": 73.1}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 68.0, 67.0, 69.0, 69.0, 68.0, 68.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [83.0, 86.0, 84.0, 85.0, 86.0, 86.0, 85.0, 86.0, 86.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [130.0, 128.0, 120.0, 130.0, 134.0, 122.0, 129.0, 123.0, 121.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.5}, {"lowerIsBetter": true, "replicates": [137.0, 138.0, 147.0, 145.0, 147.0, 137.0, 151.0, 146.0, 146.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.4}, {"lowerIsBetter": true, "replicates": [75.0, 70.0, 70.0, 70.0, 69.0, 70.0, 69.0, 70.0, 69.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.2}], "name": "kraken", "value": 1532.6}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.8649859014762], "unit": "score", "name": "Richards", "value": 212.835758}, {"lowerIsBetter": false, "replicates": [186.39328984156572], "unit": "score", "name": "DeltaBlue", "value": 354.7230699999999}, {"lowerIsBetter": false, "replicates": [277.9321845469705], "unit": "score", "name": "Encrypt", "value": 957.7192379999997}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [1998.0039920159682], "unit": "score", "name": "RayTrace", "value": 370.36412487512496}, {"lowerIsBetter": false, "replicates": [794.2811755361398], "unit": "score", "name": "Earley", "value": 839.0769169999999}, {"lowerIsBetter": false, "replicates": [12023.809523809523], "unit": "score", "name": "Boyer", "value": 55.42860594059401}, {"lowerIsBetter": false, "replicates": [27216.216216216217], "unit": "score", "name": "RegExp", "value": 33.472140019860944}, {"lowerIsBetter": false, "replicates": [627.1929824561404], "unit": "score", "name": "Splay", "value": 129.92970629370626}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19717.576340072243}]} 14:17:13 INFO - RETURN: dromaeo_css: 6308.59 14:17:13 INFO - RETURN: kraken: 1532.60 14:17:13 INFO - RETURN: v8_7: 19717.58 14:17:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,35]]", "result": "19717.58"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,35]]", "result": "1532.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,35]]", "result": "6308.59"}}} 14:17:13 INFO - Return code: 0 14:17:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:17:13 INFO - # TBPL SUCCESS # 14:17:13 INFO - Running post-action listener: _resource_record_post_action 14:17:13 INFO - Running post-run listener: _resource_record_post_run 14:17:14 INFO - Total resource usage - Wall time: 1234s; CPU: 13.0%; Read bytes: 4096; Write bytes: 507953152; Read time: 12; Write time: 562548 14:17:14 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 231448576; Read time: 0; Write time: 362380 14:17:14 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 4096; Write bytes: 276176896; Read time: 12; Write time: 200120 14:17:14 INFO - Running post-run listener: _upload_blobber_files 14:17:14 INFO - Blob upload gear active. 14:17:14 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:17:14 INFO - Copying logs to upload dir... 14:17:14 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.703124 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-16 14:17:14.428669) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 14:17:14.432477) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009926 build_url: 'https://queue.taskcluster.net/v1/task/ECHjxHuIQ8Gh5t1g5OizFw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 14:17:14.484438) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 14:17:14.484799) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450302965.607381-111233315 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 14:17:14.535688) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 14:17:14.536091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 14:17:14.536466) ========= ========= Total master_lag: 0.22 =========