builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0356 starttime: 1464086834.6 results: success (0) buildid: 20160524023903 builduid: fb5de9db11fe4b6e8bc867e39dcb1421 revision: 86f8ed93168504977b86c911e5e75688b5a3e475 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.605204) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.605893) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.606285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.628367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.628765) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 03:47:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-05-24 03:47:14 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142694 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.791410) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.791713) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025622 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:14.842557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 03:47:14.842984) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 86f8ed93168504977b86c911e5e75688b5a3e475 --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 86f8ed93168504977b86c911e5e75688b5a3e475 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 03:47:14,922 truncating revision to first 12 chars 2016-05-24 03:47:14,923 Setting DEBUG logging. 2016-05-24 03:47:14,923 attempt 1/10 2016-05-24 03:47:14,923 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/86f8ed931685?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 03:47:15,957 unpacking tar archive at: fx-team-86f8ed931685/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288256 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 03:47:16.149622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:16.149955) ========= script_repo_revision: 86f8ed93168504977b86c911e5e75688b5a3e475 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:16.150379) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:16.150654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 03:47:16.166089) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-24 03:47:16.166680) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:47:16 INFO - MultiFileLogger online at 20160524 03:47:16 in /builds/slave/test 03:47:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:47:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:47:16 INFO - {'append_to_log': False, 03:47:16 INFO - 'base_work_dir': '/builds/slave/test', 03:47:16 INFO - 'blob_upload_branch': 'Fx-Team', 03:47:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:47:16 INFO - 'branch': 'Fx-Team', 03:47:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:47:16 INFO - 'config_files': ('talos/mac_config.py',), 03:47:16 INFO - 'default_actions': ('clobber', 03:47:16 INFO - 'read-buildbot-config', 03:47:16 INFO - 'download-and-extract', 03:47:16 INFO - 'populate-webroot', 03:47:16 INFO - 'create-virtualenv', 03:47:16 INFO - 'install', 03:47:16 INFO - 'run-tests'), 03:47:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:47:16 INFO - 'download_minidump_stackwalk': True, 03:47:16 INFO - 'download_symbols': 'ondemand', 03:47:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:47:16 INFO - 'tooltool.py': '/tools/tooltool.py', 03:47:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:47:16 INFO - '/tools/misc-python/virtualenv.py')}, 03:47:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:47:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:47:16 INFO - 'installer_path': 'installer.exe', 03:47:16 INFO - 'log_level': 'info', 03:47:16 INFO - 'log_name': 'talos', 03:47:16 INFO - 'log_to_console': True, 03:47:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:47:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:47:16 INFO - 'opt_config_files': (), 03:47:16 INFO - 'pip_index': False, 03:47:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:47:16 INFO - 'cmd': ('bash', 03:47:16 INFO - '-c', 03:47:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:47:16 INFO - 'enabled': True, 03:47:16 INFO - 'halt_on_failure': False, 03:47:16 INFO - 'name': 'check_screen_resolution'},), 03:47:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:47:16 INFO - 'cmd': ('bash', 03:47:16 INFO - '-c', 03:47:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:47:16 INFO - 'enabled': True, 03:47:16 INFO - 'halt_on_failure': False, 03:47:16 INFO - 'name': 'check_screen_resolution'},), 03:47:16 INFO - 'run_cmd_checks_enabled': True, 03:47:16 INFO - 'sps_profile': False, 03:47:16 INFO - 'sps_profile_interval': 0, 03:47:16 INFO - 'suite': 'dromaeojs', 03:47:16 INFO - 'system_bits': '32', 03:47:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:47:16 INFO - 'title': 't-yosemite-r7-0356', 03:47:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:47:16 INFO - 'use_talos_json': True, 03:47:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:47:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:47:16 INFO - 'work_dir': 'build'} 03:47:16 INFO - ##### 03:47:16 INFO - ##### Running clobber step. 03:47:16 INFO - ##### 03:47:16 INFO - Running pre-action listener: _resource_record_pre_action 03:47:16 INFO - Running main action method: clobber 03:47:16 INFO - rmtree: /builds/slave/test/build 03:47:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:47:18 INFO - Running post-action listener: _resource_record_post_action 03:47:18 INFO - ##### 03:47:18 INFO - ##### Finished clobber step (success) 03:47:18 INFO - ##### 03:47:18 INFO - ##### 03:47:18 INFO - ##### Running read-buildbot-config step. 03:47:18 INFO - ##### 03:47:18 INFO - Running pre-action listener: _resource_record_pre_action 03:47:18 INFO - Running main action method: read_buildbot_config 03:47:18 INFO - Using buildbot properties: 03:47:18 INFO - { 03:47:18 INFO - "project": "", 03:47:18 INFO - "product": "firefox", 03:47:18 INFO - "script_repo_revision": "production", 03:47:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:47:18 INFO - "repository": "", 03:47:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:47:18 INFO - "buildid": "20160524023903", 03:47:18 INFO - "pgo_build": "False", 03:47:18 INFO - "basedir": "/builds/slave/test", 03:47:18 INFO - "buildnumber": 719, 03:47:18 INFO - "slavename": "t-yosemite-r7-0356", 03:47:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:47:18 INFO - "platform": "yosemite_r7", 03:47:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 03:47:18 INFO - "branch": "fx-team", 03:47:18 INFO - "repo_path": "integration/fx-team", 03:47:18 INFO - "revision": "86f8ed93168504977b86c911e5e75688b5a3e475", 03:47:18 INFO - "stage_platform": "macosx64", 03:47:18 INFO - "builduid": "fb5de9db11fe4b6e8bc867e39dcb1421", 03:47:18 INFO - "slavebuilddir": "test" 03:47:18 INFO - } 03:47:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 03:47:18 INFO - Running post-action listener: _resource_record_post_action 03:47:18 INFO - ##### 03:47:18 INFO - ##### Finished read-buildbot-config step (success) 03:47:18 INFO - ##### 03:47:18 INFO - ##### 03:47:18 INFO - ##### Running download-and-extract step. 03:47:18 INFO - ##### 03:47:18 INFO - Running pre-action listener: _resource_record_pre_action 03:47:18 INFO - Running main action method: download_and_extract 03:47:18 INFO - mkdir: /builds/slave/test/build/tests 03:47: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')]} 03:47:18 INFO - https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:47:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 03:47:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 03:47:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 03:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 03:47:21 INFO - Downloaded 1199 bytes. 03:47:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 03:47:21 INFO - Using the following test package requirements: 03:47:21 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 03:47:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 03:47:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 03:47:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'jsshell-mac.zip'], 03:47:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 03:47:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 03:47:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 03:47:21 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 03:47:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 03:47:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 03:47:21 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 03:47:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:21 INFO - https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:47:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:47:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:47:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:47:22 INFO - Downloaded 18630450 bytes. 03:47:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:47:25 INFO - Return code: 0 03:47:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:25 INFO - https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:47:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:47:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:47:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 03:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:47:26 INFO - Downloaded 18630450 bytes. 03:47:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:47:30 INFO - Return code: 0 03:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:30 INFO - https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:47:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 03:47:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 03:47:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 03:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:47:31 INFO - Downloaded 10840359 bytes. 03:47:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:47:32 INFO - Return code: 0 03:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:32 INFO - https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:47:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:47:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:47:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 03:47:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 03:47:36 INFO - Downloaded 110140387 bytes. 03:47:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:47:36 INFO - mkdir: /builds/slave/test/properties 03:47:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:47:36 INFO - Writing to file /builds/slave/test/properties/build_url 03:47:36 INFO - Contents: 03:47:36 INFO - build_url:https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 03:47:37 INFO - Running post-action listener: _resource_record_post_action 03:47:37 INFO - Running post-action listener: set_extra_try_arguments 03:47:37 INFO - ##### 03:47:37 INFO - ##### Finished download-and-extract step (success) 03:47:37 INFO - ##### 03:47:37 INFO - ##### 03:47:37 INFO - ##### Running populate-webroot step. 03:47:37 INFO - ##### 03:47:37 INFO - Running pre-action listener: _resource_record_pre_action 03:47:37 INFO - Running main action method: populate_webroot 03:47:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:47:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:47:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:47:37 INFO - '--tppagecycles', 03:47:37 INFO - '7'], 03:47:37 INFO - 'tests': ['tsvgm']}}, 03:47:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:47:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:47:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:47:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:47:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['damp', 'tps']}, 03:47:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'tests': ['damp', 'tps']}, 03:47:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['dromaeo_dom']}, 03:47:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:47:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['a11yr', 03:47:37 INFO - 'ts_paint', 03:47:37 INFO - 'tpaint', 03:47:37 INFO - 'sessionrestore', 03:47:37 INFO - 'sessionrestore_no_auto_restore', 03:47:37 INFO - 'tabpaint']}, 03:47:37 INFO - 'other-e10s': {'tests': ['a11yr', 03:47:37 INFO - 'ts_paint', 03:47:37 INFO - 'tpaint', 03:47:37 INFO - 'sessionrestore', 03:47:37 INFO - 'sessionrestore_no_auto_restore', 03:47:37 INFO - 'tabpaint']}, 03:47:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:47:37 INFO - 'ts_paint', 03:47:37 INFO - 'tpaint', 03:47:37 INFO - 'sessionrestore', 03:47:37 INFO - 'sessionrestore_no_auto_restore', 03:47:37 INFO - 'tabpaint']}, 03:47:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:47:37 INFO - 'ts_paint', 03:47:37 INFO - 'tpaint', 03:47:37 INFO - 'sessionrestore', 03:47:37 INFO - 'sessionrestore_no_auto_restore', 03:47:37 INFO - 'tabpaint']}, 03:47:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['a11yr', 03:47:37 INFO - 'ts_paint', 03:47:37 INFO - 'tpaint', 03:47:37 INFO - 'sessionrestore', 03:47:37 INFO - 'sessionrestore_no_auto_restore', 03:47:37 INFO - 'tabpaint']}, 03:47:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['a11yr', 03:47:37 INFO - 'ts_paint', 03:47:37 INFO - 'tpaint', 03:47:37 INFO - 'sessionrestore', 03:47:37 INFO - 'sessionrestore_no_auto_restore', 03:47:37 INFO - 'tabpaint']}, 03:47:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['tsvgx', 03:47:37 INFO - 'tsvgr_opacity', 03:47:37 INFO - 'tart', 03:47:37 INFO - 'tscrollx', 03:47:37 INFO - 'cart']}, 03:47:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:47:37 INFO - 'tsvgr_opacity', 03:47:37 INFO - 'tart', 03:47:37 INFO - 'tscrollx', 03:47:37 INFO - 'cart']}, 03:47:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'talos_options': ['--disable-e10s'], 03:47:37 INFO - 'tests': ['tp5o']}, 03:47:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'tests': ['tp5o']}, 03:47:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'talos_options': ['--disable-e10s', 03:47:37 INFO - '--xperf_path', 03:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:37 INFO - 'tests': ['tp5n']}, 03:47:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:37 INFO - 'talos_options': ['--xperf_path', 03:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:37 INFO - 'tests': ['tp5n']}}, 03:47:37 INFO - 'talos.zip': {'path': '', 03:47:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:47:37 INFO - Running post-action listener: _resource_record_post_action 03:47:37 INFO - ##### 03:47:37 INFO - ##### Finished populate-webroot step (success) 03:47:37 INFO - ##### 03:47:37 INFO - ##### 03:47:37 INFO - ##### Running create-virtualenv step. 03:47:37 INFO - ##### 03:47:37 INFO - Running pre-action listener: _resource_record_pre_action 03:47:37 INFO - Running main action method: create_virtualenv 03:47:37 INFO - Creating virtualenv /builds/slave/test/build/venv 03:47:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:47:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:47:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:47:37 INFO - Using real prefix '/tools/python27' 03:47:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:47:38 INFO - Installing distribute.............................................................................................................................................................................................done. 03:47:41 INFO - Installing pip.................done. 03:47:41 INFO - Return code: 0 03:47:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:41 INFO - 'HOME': '/Users/cltbld', 03:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:41 INFO - 'LOGNAME': 'cltbld', 03:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:41 INFO - 'MOZ_NO_REMOTE': '1', 03:47:41 INFO - 'NO_EM_RESTART': '1', 03:47:41 INFO - 'PAGER': '/bin/cat', 03:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:41 INFO - 'PWD': '/builds/slave/test', 03:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:41 INFO - 'SHELL': '/bin/bash', 03:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:47:41 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:41 INFO - 'USER': 'cltbld', 03:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:41 INFO - 'XPC_FLAGS': '0x0', 03:47:41 INFO - 'XPC_SERVICE_NAME': '0', 03:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:47:41 INFO - Cleaning up... 03:47:41 INFO - Return code: 0 03:47:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 03:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 03:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:41 INFO - 'HOME': '/Users/cltbld', 03:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:41 INFO - 'LOGNAME': 'cltbld', 03:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:41 INFO - 'MOZ_NO_REMOTE': '1', 03:47:41 INFO - 'NO_EM_RESTART': '1', 03:47:41 INFO - 'PAGER': '/bin/cat', 03:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:41 INFO - 'PWD': '/builds/slave/test', 03:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:41 INFO - 'SHELL': '/bin/bash', 03:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:47:41 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:41 INFO - 'USER': 'cltbld', 03:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:41 INFO - 'XPC_FLAGS': '0x0', 03:47:41 INFO - 'XPC_SERVICE_NAME': '0', 03:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:41 INFO - Downloading/unpacking psutil>=3.1.1 03:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:47:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:44 INFO - Installing collected packages: psutil 03:47:44 INFO - Running setup.py install for psutil 03:47:44 INFO - building 'psutil._psutil_osx' extension 03:47:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:47:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:47:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:47:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:47:45 INFO - building 'psutil._psutil_posix' extension 03:47:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:47:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:47:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:47:45 INFO - ^ 03:47:45 INFO - 1 warning generated. 03:47:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:47:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:45 INFO - Successfully installed psutil 03:47:45 INFO - Cleaning up... 03:47:45 INFO - Return code: 0 03:47:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 03:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 03:47:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:47:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:45 INFO - 'HOME': '/Users/cltbld', 03:47:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:45 INFO - 'LOGNAME': 'cltbld', 03:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:45 INFO - 'MOZ_NO_REMOTE': '1', 03:47:45 INFO - 'NO_EM_RESTART': '1', 03:47:45 INFO - 'PAGER': '/bin/cat', 03:47:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:45 INFO - 'PWD': '/builds/slave/test', 03:47:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:45 INFO - 'SHELL': '/bin/bash', 03:47:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:47:45 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:47:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:45 INFO - 'USER': 'cltbld', 03:47:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:45 INFO - 'XPC_FLAGS': '0x0', 03:47:45 INFO - 'XPC_SERVICE_NAME': '0', 03:47:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:47:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:47:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:47:48 INFO - Installing collected packages: mozsystemmonitor 03:47:48 INFO - Running setup.py install for mozsystemmonitor 03:47:48 INFO - Successfully installed mozsystemmonitor 03:47:48 INFO - Cleaning up... 03:47:48 INFO - Return code: 0 03:47:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:47:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:47:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:47:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:47:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:48 INFO - 'HOME': '/Users/cltbld', 03:47:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:48 INFO - 'LOGNAME': 'cltbld', 03:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:48 INFO - 'MOZ_NO_REMOTE': '1', 03:47:48 INFO - 'NO_EM_RESTART': '1', 03:47:48 INFO - 'PAGER': '/bin/cat', 03:47:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:48 INFO - 'PWD': '/builds/slave/test', 03:47:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:48 INFO - 'SHELL': '/bin/bash', 03:47:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:47:48 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:47:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:48 INFO - 'USER': 'cltbld', 03:47:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:48 INFO - 'XPC_FLAGS': '0x0', 03:47:48 INFO - 'XPC_SERVICE_NAME': '0', 03:47:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:51 INFO - Downloading blobuploader-1.2.4.tar.gz 03:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:47:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:47:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:52 INFO - Downloading docopt-0.6.1.tar.gz 03:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:47:52 INFO - Installing collected packages: blobuploader, requests, docopt 03:47:52 INFO - Running setup.py install for blobuploader 03:47:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:47:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:47:53 INFO - Running setup.py install for requests 03:47:53 INFO - Running setup.py install for docopt 03:47:53 INFO - Successfully installed blobuploader requests docopt 03:47:53 INFO - Cleaning up... 03:47:53 INFO - Return code: 0 03:47:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:47:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:47:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:47:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:53 INFO - 'HOME': '/Users/cltbld', 03:47:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:53 INFO - 'LOGNAME': 'cltbld', 03:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:53 INFO - 'MOZ_NO_REMOTE': '1', 03:47:53 INFO - 'NO_EM_RESTART': '1', 03:47:53 INFO - 'PAGER': '/bin/cat', 03:47:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:53 INFO - 'PWD': '/builds/slave/test', 03:47:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:53 INFO - 'SHELL': '/bin/bash', 03:47:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:47:53 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:47:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:53 INFO - 'USER': 'cltbld', 03:47:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:53 INFO - 'XPC_FLAGS': '0x0', 03:47:53 INFO - 'XPC_SERVICE_NAME': '0', 03:47:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-p_4JiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-8b73q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-SxbJFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-35hT7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-pBPlnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ivnxI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-6E5H9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-c4rrW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-YR_AAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-0ZNQTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-68S8VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ayHgX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-qyvqx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-VFlVlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-JODcOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xbMqmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-nEq1Ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:47:55 INFO - Running setup.py install for manifestparser 03:47:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:47:55 INFO - Running setup.py install for mozcrash 03:47:56 INFO - Running setup.py install for mozdebug 03:47:56 INFO - Running setup.py install for mozdevice 03:47:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Running setup.py install for mozfile 03:47:56 INFO - Running setup.py install for mozhttpd 03:47:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Running setup.py install for mozinfo 03:47:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Running setup.py install for mozInstall 03:47:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:47:56 INFO - Running setup.py install for mozleak 03:47:57 INFO - Running setup.py install for mozlog 03:47:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:47:57 INFO - Running setup.py install for moznetwork 03:47:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:47:57 INFO - Running setup.py install for mozprocess 03:47:57 INFO - Running setup.py install for mozprofile 03:47:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:47:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:47:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:47:57 INFO - Running setup.py install for mozrunner 03:47:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:47:57 INFO - Running setup.py install for mozscreenshot 03:47:57 INFO - Running setup.py install for moztest 03:47:58 INFO - Running setup.py install for mozversion 03:47:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:47:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:47:58 INFO - Cleaning up... 03:47:58 INFO - Return code: 0 03:47:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:58 INFO - 'HOME': '/Users/cltbld', 03:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:58 INFO - 'LOGNAME': 'cltbld', 03:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:58 INFO - 'MOZ_NO_REMOTE': '1', 03:47:58 INFO - 'NO_EM_RESTART': '1', 03:47:58 INFO - 'PAGER': '/bin/cat', 03:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:58 INFO - 'PWD': '/builds/slave/test', 03:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:58 INFO - 'SHELL': '/bin/bash', 03:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:47:58 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:58 INFO - 'USER': 'cltbld', 03:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:58 INFO - 'XPC_FLAGS': '0x0', 03:47:58 INFO - 'XPC_SERVICE_NAME': '0', 03:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-cbPHYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-NMYi3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-WMyop_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-70SA10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:58 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-lRqmxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-gG8YK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-5WIr9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-mG2AO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-mjaq_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-1s3rWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Iwa5CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-2vH9xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-6Sjt02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-_oAtHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-vk51ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:48:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-rkTGxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:48:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-il6jA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:48:00 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:48:00 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:48:00 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:48:00 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:48:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:02 INFO - Downloading blessings-1.6.tar.gz 03:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:48:02 INFO - Installing collected packages: blessings 03:48:02 INFO - Running setup.py install for blessings 03:48:03 INFO - Successfully installed blessings 03:48:03 INFO - Cleaning up... 03:48:03 INFO - Return code: 0 03:48:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:48:03 INFO - Reading from file tmpfile_stdout 03:48:03 INFO - Current package versions: 03:48:03 INFO - blessings == 1.6 03:48:03 INFO - blobuploader == 1.2.4 03:48:03 INFO - docopt == 0.6.1 03:48:03 INFO - manifestparser == 1.1 03:48:03 INFO - mozInstall == 1.12 03:48:03 INFO - mozcrash == 0.17 03:48:03 INFO - mozdebug == 0.1 03:48:03 INFO - mozdevice == 0.48 03:48:03 INFO - mozfile == 1.2 03:48:03 INFO - mozhttpd == 0.7 03:48:03 INFO - mozinfo == 0.9 03:48:03 INFO - mozleak == 0.1 03:48:03 INFO - mozlog == 3.2 03:48:03 INFO - moznetwork == 0.27 03:48:03 INFO - mozprocess == 0.22 03:48:03 INFO - mozprofile == 0.28 03:48:03 INFO - mozrunner == 6.11 03:48:03 INFO - mozscreenshot == 0.1 03:48:03 INFO - mozsystemmonitor == 0.3 03:48:03 INFO - moztest == 0.7 03:48:03 INFO - mozversion == 1.4 03:48:03 INFO - psutil == 3.1.1 03:48:03 INFO - requests == 1.2.3 03:48:03 INFO - wsgiref == 0.1.2 03:48:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:03 INFO - 'HOME': '/Users/cltbld', 03:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:03 INFO - 'LOGNAME': 'cltbld', 03:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:03 INFO - 'MOZ_NO_REMOTE': '1', 03:48:03 INFO - 'NO_EM_RESTART': '1', 03:48:03 INFO - 'PAGER': '/bin/cat', 03:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:03 INFO - 'PWD': '/builds/slave/test', 03:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:03 INFO - 'SHELL': '/bin/bash', 03:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:48:03 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:03 INFO - 'USER': 'cltbld', 03:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:03 INFO - 'XPC_FLAGS': '0x0', 03:48:03 INFO - 'XPC_SERVICE_NAME': '0', 03:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:48:03 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 2)) 03:48:03 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 3)) 03:48:03 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 4)) 03:48:03 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 5)) 03:48:03 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 6)) 03:48:03 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 7)) 03:48:03 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 8)) 03:48:03 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 9)) 03:48:03 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:48:03 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 4)) 03:48:03 INFO - Cleaning up... 03:48:03 INFO - Return code: 0 03:48:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:03 INFO - 'HOME': '/Users/cltbld', 03:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:03 INFO - 'LOGNAME': 'cltbld', 03:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:03 INFO - 'MOZ_NO_REMOTE': '1', 03:48:03 INFO - 'NO_EM_RESTART': '1', 03:48:03 INFO - 'PAGER': '/bin/cat', 03:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:03 INFO - 'PWD': '/builds/slave/test', 03:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:03 INFO - 'SHELL': '/bin/bash', 03:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:48:03 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:03 INFO - 'USER': 'cltbld', 03:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:03 INFO - 'XPC_FLAGS': '0x0', 03:48:03 INFO - 'XPC_SERVICE_NAME': '0', 03:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:03 INFO - Downloading/unpacking jsonschema 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:48:06 INFO - Downloading/unpacking functools32 (from jsonschema) 03:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:06 INFO - Downloading functools32-3.2.3-2.tar.gz 03:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:48:06 INFO - warning: no files found matching '*.txt' 03:48:06 INFO - no previously-included directories found matching 'build' 03:48:06 INFO - no previously-included directories found matching 'dist' 03:48:06 INFO - no previously-included directories found matching '.git*' 03:48:06 INFO - Installing collected packages: jsonschema, functools32 03:48:06 INFO - Running setup.py install for functools32 03:48:06 INFO - warning: no files found matching '*.txt' 03:48:06 INFO - no previously-included directories found matching 'build' 03:48:06 INFO - no previously-included directories found matching 'dist' 03:48:06 INFO - no previously-included directories found matching '.git*' 03:48:06 INFO - Successfully installed jsonschema functools32 03:48:06 INFO - Cleaning up... 03:48:07 INFO - Return code: 0 03:48:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:48: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')]} 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10ed5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe43257b720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:48:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:48:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:07 INFO - 'HOME': '/Users/cltbld', 03:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:07 INFO - 'LOGNAME': 'cltbld', 03:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:07 INFO - 'MOZ_NO_REMOTE': '1', 03:48:07 INFO - 'NO_EM_RESTART': '1', 03:48:07 INFO - 'PAGER': '/bin/cat', 03:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:07 INFO - 'PWD': '/builds/slave/test', 03:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:07 INFO - 'SHELL': '/bin/bash', 03:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:48:07 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:07 INFO - 'USER': 'cltbld', 03:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:07 INFO - 'XPC_FLAGS': '0x0', 03:48:07 INFO - 'XPC_SERVICE_NAME': '0', 03:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:07 INFO - Downloading/unpacking flake8 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:48:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:48:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:48:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:48:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:48:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:48:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:48:11 INFO - no previously-included directories found matching 'docs/_build' 03:48:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:48:11 INFO - Running setup.py install for pep8 03:48:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:48:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:48:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:48:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:48:11 INFO - no previously-included directories found matching 'docs/_build' 03:48:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:48:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:48:11 INFO - Cleaning up... 03:48:11 INFO - Return code: 0 03:48:11 INFO - Running post-action listener: _resource_record_post_action 03:48:11 INFO - Running post-action listener: _start_resource_monitoring 03:48:11 INFO - Starting resource monitoring. 03:48:11 INFO - ##### 03:48:11 INFO - ##### Finished create-virtualenv step (success) 03:48:11 INFO - ##### 03:48:11 INFO - ##### 03:48:11 INFO - ##### Running install step. 03:48:11 INFO - ##### 03:48:11 INFO - Running pre-action listener: _resource_record_pre_action 03:48:11 INFO - Running main action method: install 03:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:48:11 INFO - Reading from file tmpfile_stdout 03:48:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:48:11 INFO - Reading from file tmpfile_stdout 03:48:11 INFO - Output received: 03:48:11 INFO - Usage: mozinstall [options] installer 03:48:11 INFO - Options: 03:48:11 INFO - -h, --help show this help message and exit 03:48:11 INFO - -d DEST, --destination=DEST 03:48:11 INFO - Directory to install application into. [default: 03:48:11 INFO - "/builds/slave/test"] 03:48:11 INFO - --app=APP Application being installed. [default: firefox] 03:48:11 INFO - mkdir: /builds/slave/test/build/application 03:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:48:45 INFO - Reading from file tmpfile_stdout 03:48:45 INFO - Output received: 03:48:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:48:45 INFO - Running post-action listener: _resource_record_post_action 03:48:45 INFO - ##### 03:48:45 INFO - ##### Finished install step (success) 03:48:45 INFO - ##### 03:48:45 INFO - ##### 03:48:45 INFO - ##### Running run-tests step. 03:48:45 INFO - ##### 03:48:45 INFO - Running pre-action listener: _resource_record_pre_action 03:48:45 INFO - Running main action method: run_tests 03:48:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:48:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:48:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:48:45 INFO - 2016-05-24 03:48:45.426 screenresolution[1640:7460] starting screenresolution argv=screenresolution get 03:48:45 INFO - 2016-05-24 03:48:45.429 screenresolution[1640:7460] Display 0: 1600x1200x32@60 03:48:45 INFO - 2016-05-24 03:48:45.435 screenresolution[1641:7462] starting screenresolution argv=screenresolution list 03:48:45 INFO - Available Modes on Display 0 03:48:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:48:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:48:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:48:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:48:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:48:45 INFO - Intel Iris: 03:48:45 INFO - Chipset Model: Intel Iris 03:48:45 INFO - Type: GPU 03:48:45 INFO - Bus: Built-In 03:48:45 INFO - VRAM (Dynamic, Max): 1536 MB 03:48:45 INFO - Vendor: Intel (0x8086) 03:48:45 INFO - Device ID: 0x0a2e 03:48:45 INFO - Revision ID: 0x0009 03:48:45 INFO - Displays: 03:48:45 INFO - Display: 03:48:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:48:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:48:45 INFO - Display Serial Number: 1600x1200 60 03:48:45 INFO - Main Display: Yes 03:48:45 INFO - Mirror: Off 03:48:45 INFO - Online: Yes 03:48:45 INFO - Rotation: Supported 03:48:45 INFO - Return code: 0 03:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:48:45 INFO - Python 2.7.3 03:48:45 INFO - Return code: 0 03:48:45 INFO - grabbing minidump binary from tooltool 03:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:45 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02d30>, '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 0x10ea26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:48:45 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:48:45 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:48:45 INFO - Calling ['/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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:48:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:48:45 INFO - Return code: 0 03:48:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:48:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:48:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:48:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:48:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:48:46 INFO - Return code: 0 03:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0356 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render', 03:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:46 INFO - 'HOME': '/Users/cltbld', 03:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:46 INFO - 'LOGNAME': 'cltbld', 03:48:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:48:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:46 INFO - 'MOZ_NO_REMOTE': '1', 03:48:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:48:46 INFO - 'NO_EM_RESTART': '1', 03:48:46 INFO - 'PAGER': '/bin/cat', 03:48:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:46 INFO - 'PWD': '/builds/slave/test', 03:48:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:46 INFO - 'SHELL': '/bin/bash', 03:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners', 03:48:46 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:46 INFO - 'USER': 'cltbld', 03:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:46 INFO - 'XPC_FLAGS': '0x0', 03:48:46 INFO - 'XPC_SERVICE_NAME': '0', 03:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:48:47 INFO - mozversion application_buildid: 20160524023903 03:48:47 INFO - mozversion application_changeset: 86f8ed93168504977b86c911e5e75688b5a3e475 03:48:47 INFO - mozversion application_display_name: Nightly 03:48:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:48:47 INFO - mozversion application_name: Firefox 03:48:47 INFO - mozversion application_remotingname: firefox 03:48:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:48:47 INFO - mozversion application_vendor: Mozilla 03:48:47 INFO - mozversion application_version: 49.0a1 03:48:47 INFO - mozversion platform_buildid: 20160524023903 03:48:47 INFO - mozversion platform_changeset: 86f8ed93168504977b86c911e5e75688b5a3e475 03:48:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:48:47 INFO - mozversion platform_version: 49.0a1 03:48:47 INFO - using testdate: 1464086927 03:48:47 INFO - actual date: 1464086927 03:48:47 INFO - starting webserver on 'localhost:49277' 03:48:47 INFO - SUITE-START | Running 2 tests 03:48:47 INFO - TEST-START | dromaeo_css 03:48:47 INFO - operating with platform_type : mac_ 03:48:47 INFO - Initialising browser for dromaeo_css test... 03:48:47 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmperyzDw/profile http://localhost:49277/getInfo.html) 03:48:49 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 03:48:49 INFO - PROCESS | 1659 | colorDepth:24 03:48:49 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 03:48:49 INFO - PROCESS | 1659 | __metrics 03:48:49 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:48:49 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:48:50 INFO - TEST-INFO | 1659: exit 0 03:48:50 INFO - Browser initialized. 03:48:50 INFO - Running cycle 1/1 for dromaeo_css test... 03:48:50 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmperyzDw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:01 INFO - PROCESS | 1661 | RSS: Main: 238866432 03:49:01 INFO - PROCESS | 1661 | 03:49:53 INFO - PROCESS | 1661 | 1464086993360 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:52:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 03:52:04 INFO - PROCESS | 1661 | RSS: Main: 349265920 03:52:04 INFO - PROCESS | 1661 | 03:55:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 03:55:08 INFO - PROCESS | 1661 | RSS: Main: 373301248 03:55:08 INFO - PROCESS | 1661 | 03:58:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 03:58:10 INFO - PROCESS | 1661 | RSS: Main: 370749440 03:58:10 INFO - PROCESS | 1661 | 04:01:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:01:13 INFO - PROCESS | 1661 | RSS: Main: 364539904 04:01:13 INFO - PROCESS | 1661 | 04:04:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 04:04:17 INFO - PROCESS | 1661 | RSS: Main: 333111296 04:04:17 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 04:07:20 INFO - PROCESS | 1661 | RSS: Main: 375582720 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | __start_tp_report 04:07:20 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 04:07:20 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 04:07:20 INFO - PROCESS | 1661 | |i|pagename|runs| 04:07:20 INFO - PROCESS | 1661 | |0;dojo.html;3487.8315764218987;3498.737594680398;3498.807565583895;3530.3645343838107;3576.7675101118425;5658.519025882805;5679.290088738908;5687.9715601421985;5742.425757424256;5757.971210143949;5374.543163831069;5758.452946970031;5819.505342045931;5866.325372582154;5890.293164820217;5156.53591176794;5194.11700010999;5258.973705131488;5286.04851126797;5368.30212072432;5332.680039197641;5374.381946076192;5388.649737767052;5403.432639572848;5430.321209848769;5192.792288308463;5193.402758682743;5254.133068043769;5266.499682530153;5289.233061206123;5241.816536421207;5245.632805703603;5249.737513124367;5263.999840030382;5296.841094767125;5264.657797243167;5279.52484276417;5282.2076688496645;5283.525666534;5342.973285133549;5522.806701765459;5609.859753506154;5613.46672066153;5651.1523271509195;5669.603127781057;6251.687415629201;6252.812415627493;6268.937310626881;6288.52836037299;6299.307076221612;5608.803691870765;6285.87428251437;6311;6327.209098862642;6328.6835658216905;5695.886082278331;5766.567507436959;5775.682337471395;5831.067029275338;5843.2403787507355;6341.239051313872;6342.5243106767175;6345.873082538324;6347.174867267318;6369.904451433232;6194.194754681953;6200.968995154995;6202.28673702521;6205.937940620626;6206.286277078194;6168.599041062316;6177.227846519185;6234.812955611339;6259.906101408483;6273.749050037947;6221.346758590374;6223.0354295083425;6260.812175634738;6264.5301602379095;6278.246610406781;6279.403456671609;6285.025820997753;6287;6296.401841825019;6303.991361382157;4922.852314430573;4945.950540494621;4955.876103097379;4965.3048573199785;5017.3226614406585;4464.6428285737065;4468.664850136252;4475.1049790042125;4478.731276123443;4495.168393847149;5762.308522977186;5768.9711551442815;5773.307203135567;5781.277340332458;5823.912641310384;6117.370705100086;6196.690165491707;6226.315105338409;6260.092286618415;6310.810675679737;5695.231143795535;5704.401037891044;5709.543236541067;5712.685802280915;5750.108733146369;4587.977060114678;5334.11987022137;5384.703841288767;5405.108157154104;5411.621186516906;6268.247810262797;6285.899967505759;6285.937140628535;6302.527310451643;6317.684115794284;6214.347493513116;6219.533534984985;6260.092286618506;6278.497720182466;6362.650054247061;6282.8115156543545;6360;6363.331850155667;6364.6181229126405;6369.426751592379;6148.9692551536955;6220.191375121386;6228.56400051992;6231.283402408781;6234.1583886174785;5881.647101173943;6125.081237814364;6209.937900620936;6248.625082495065;6298;6023.45788879003;6121.571489995657;6131.816045518642;6136.754529818757;6148.9692551536955;5939.67331796738;6063.605865618897;6144.293406258159;6146.170267014075;6160.199174107516;4943.5797957174045;5335.799445124816;5918.3489816121055;5955.731992060281;6049.971504844099;5955.553333500092;6069.620064267659;6139.969300153471;6160.845978850564;6168.568200226121;5856.765729370777;5878.382769809108;5934.732937017759;5967.254093238345;5993.550483713826 04:07:20 INFO - PROCESS | 1661 | |1;ext.html;496.8745790783874;500.0623830318151;506.0991435245263;514.0772313696914;526.8788178718895;24773.62839557406;31268.687313126862;32550.511742323884;32633.51049734253;43186;44159.33760993588;45280.77359613197;45898;45956;47971.760141199244;9533.380330278544;9725.756856078584;9754.073363030537;9760.658376956808;9776.560054797552;8629.654813807472;9577.664781732641;9706.126448619623;11809.7638047239;12139.635810925687;11725.941370293136;11755.35345555994;11759.647210583697;11863.46614402354;11870;11679.182457227997;11718.121140914465;11718.648440546796;11730.237534560227;11740.882591174064;11725.120615953752;11817.17279790415;11834.526618935231;11837.408129593487;11842;41485.1702965942;41865.162696745894;41871.7906410466;41915.790421048005;41968;3371;3519;3741.7567858089137;3778.924421511582;3798.6011468795655;29680.406391872042;29798.702012980026;30203.546946795817;30817.845910770302;30937.226569335722;2737.890484380622;2738.8493632850186;2740.3149212696826;2758.0760445250667;2797.832130072182;516403;520984;521906;523384.38307808596;523812.9999999962;67724.6613766928;68106;68189.65905170442;68301;68404;71707.28292717111;71810;71900.64049679718;71946.28053719501;71962;16298.185090745652;16363.427280045142;16476.258568364214;16876.987380757193;16915.492535223962;12474.064445166649;12860.549880754128;12951.5466958656;12962.675933101747;12985.480580776852;18211.871515271287;18594.163262653212;18779.24883004692;18787.624247514974;18993.670443069128;6151.231096112986;6237.345078766664;6281.654509002049;6306.180196574416;6312.936870631327;879.560219890055;891.028778631295;950.8003319302885;1023.1150055202196;1031.1802117316752;28510.859565617142;28710.856445717636;28721.281967950967;29177.97877074292;29290.267743306587;1034.4207243943415;1052.2423854824515;1076.0476977874637;1083.31209681851;1097.1003776903024;14263;14790.778138327934;14906.552803415914;14937.402503899939;15372.923135384252;639488;647252;653445;653695;654054;63923.68038159779;64034.67982660057;64191.358086419474;64246.0363094554;64261.67869160624;1415.568251683226;1449.6013596260902;1458.1761304862719;1458.6353411647087;1462.8317743459424;23873.880630596737;26414.603780943686;28614;28688.569671454527;29123;52760.73619632031;53516.46483535115;53799.73100134474;54053.648658783844;54382;573095;576162.1191894014;576414.2358576361;576424;577930;26245;27287.590686139327;27365.86317068402;42422;42705.359419609355;40362.39456408215;46441.30338044865;48082.75958620184;48354.75822620864;48764.512354875995;38734.612653873104;38911.61088389193;39327.2134557313;40023.599764001985;40256.798716007404;38136.42795358016;38314.65898693533;38636.61363386443;38990.2201955965;39038.82883513499;31789.04632861018;38114.047148821504;38205.61794382021;38948.41577376285;39544;30708.539371909887;32456.675433245364;33408.997730068135;34213.657863421045;35763.64236357603;24896.004159833403;25291.74708252894;25430.872845635655;25829.870850645628;25996 04:07:20 INFO - PROCESS | 1661 | |2;jquery.html;380.61747943316965;381.49642471937045;390.63280516314643;399.4707013207501;408.22029922847366;19659.410217693476;21012.369628911118;21166.365009049754;21328.57342853142;21364;20262.49343766403;20931.68602470964;20988.475288117806;21104.366868993915;21164.36506904795;5226.268322434862;5629.915551266734;5972.492338151263;6159.168512250833;6179.598326108811;5986.371430999748;6009.278886533601;6088;6099.451049405553;6099.603525770833;5870.970645146768;5992.520598352143;5998.430149135826;6045.425684559982;6053.364396738345;5902.291724993007;5910.645361278294;6003.249593800797;6031.215941927521;6031.788887388942;4752.809887604491;5067.417247016567;5145.33110695611;5159.974200129013;5222.895542089175;25333.746662533507;25593.36016599581;25907.48185036289;26019.739802601922;26173.083942061938;2093.4557015176165;2214;2224.477247846755;2283.041122728458;2289.496310811619;14686.632834179125;15075.321610527553;15292.694146117126;15368.231588420535;15582.298796554182;1505.3602219056966;1696.4401747423321;1760.8503277221453;1800.9909950450165;1852.2128095559333;96287.51856240674;113506.43246783796;116922.41538792252;117228.41385793017;117479.4126029373;10507.737306567396;10644.361338319726;10735.78528429427;11005.889941100646;11172.832407513893;13606.251656158809;13640.181589104686;13808.723825523433;13878.306084695725;13995.440182392795;8233.217844304781;8381.664733410595;8441.366897482714;8536.74389768308;8543.401961862734;8299.087100418948;8385.580720963928;8395.286400656012;8460.450070745383;8491.23578877904;5758.236046697609;5903.085021821625;6448.967755161194;6753.45972322213;6804.11546498952;1674.1963857348542;1779.6440711857422;1872.2604571194302;1887.509247595635;1902.0014492391606;893.1871996483169;906.4742449379344;927.9582418791035;946.8721722567503;1082.4533610526635;20231.089600967698;20231.393058208276;21197.36407907765;21206.833624150513;21551.89224053901;787.6573690444675;973.2943615878431;991.7024892532212;1050.3435352904435;1087.075985412409;9001.459912405277;10778.514966826511;11138.888611113947;11302.378369189773;11345.602903898323;568596;577588.4567503099;578808;593158;632989;90951.54524227337;112934.36100946207;113570.86429135768;114785.85214147752;119800.4009979962;1020.1481762728057;1034.7257976636197;1037.6679462572056;1053.8682664666917;1056.915446764257;18371.90814045921;18526.444206673823;18548.721769173742;18657.626847463245;18987.71518427252;38781.8365449037;44034.77982610066;46521.30218046664;48436;49127.034918602854;67439.65120697657;67575.66212168908;67725.32274677388;67962.64074718577;68099.65950170218;6936.375726184668;7358.926410735824;7409.740659076906;7866.685332586632;8343.207395297437;12007.099467539794;12452;12616.55842045524;13052;13202.603921882359;8902.55487225649;10938.015578597964;11036.779264414827;11130;11267.830982535432;9868.457234851865;10490.31812932157;10761.139108871115;10942.343459392143;11023.889761102608;11024.062954648945;11233.831492527455;11508.654740357802;11570.59502917394;11697.590584329508;8008.439409241297;8096.190380962093;8121.918780812116;8386.287165590993;8388.874166887623;10256.897431025893;10922.12622990143;11230.606928757454;11282.492287846902;11324.32054076758 04:07:20 INFO - PROCESS | 1661 | |3;mootools.html;1420.7229590229908;1588.4519840654973;1603.8476344747248;1858.711899655553;1997.8102080302376;7866.842663146734;8597.484150950948;8815.206631403173;8818.514981676006;8825.382223244376;8532.317414606832;8793.604287807048;8795.076516965722;8796.252318552915;8797.692080777175;2033.5729496805689;2325.5232677301115;2372.5254949010223;2401.267613377919;2407.927762167129;2402.159244264511;2429.210506585358;2429.331933718233;2430.6718592989987;2431.331383869441;2374.8218883583713;2394.090245706629;2409.722881868581;2410.276916924924;2413.1071503543712;2097.3498215553077;2310.595645761985;2350.15394457995;2384.070212617081;2388.450656349032;2377.0729415527785;2379.1553998330514;2404.4349577849193;2407.1935901473025;2409.831311808174;8649.610767515476;8658.480491170487;8691.956540217321;8718.041015488292;8801.075887031833;5106;5162.303089082963;5226.55574276187;5230.372355317349;5308.495692909168;3363.1928337199142;3457.6023757267985;3497.8600855965733;3518.5249991251367;3528.6294939031545;995.875515560555;997.379258704605;998.1266391907081;1003.3528374198601;1018.9592416303338;11566.710832229262;11600.4199790011;11603.6518904433;11613.070954323719;11632.418379081097;4601.585857272795;4719.905601887943;4797.4482934462985;4801.279808028824;4802.567768900815;9758.365706229073;9778.31551791382;9824.4105353679;9839.458829764431;9852.556634951445;5254.868628284247;5333.306670132858;5337.546308563816;5339.893202135935;5340.626156169031;4393.472783265965;4539.659525535532;4743.241081426955;4877.3903262092235;4883.975580122076;3452.7755695879687;3885.7759805661126;3935.547412047651;3949.4668219790533;4041.5150181977765;1281.4489769399247;1292.8383952006;1296.163974236631;1308.6335825968745;1314.750197462479;816.5957850863861;862.4307956748515;896.251629889037;927.3369540778376;927.7958849053198;5482.945170548243;5516.475934786269;5580.86047848807;5697.601167918288;5755.884882302331;968.2399316536485;1012.6354512375539;1032.4270030133198;1037.1080870451406;1038.4081073787868;3654.908627284286;3727.9254414912093;3734.682551983112;3759.3609086455367;3765.736398452082;8824.338174636929;10538.104261137889;10752.247342686092;10757.408342541235;10764.515596798163;10579.10077643409;10621;10711.196660250513;10728.94635526833;10750.516226769814;6778.7288508459105;7982.68069277234;8050.436469447082;8119.309858661962;8247.092819789817;5650.943490565206;5768.134779783066;5811.593188476935;5835.008048631658;5901.380355062828;9489.478078705737;9743.951280243553;9751.756206094904;9966.651167209113;9993.150582200595;10088.092071713581;10210.489475526343;10250.487475626338;10255.589776409159;10265.692029239135;6869.931300686929;6965.616891071039;7367.705291788268;7436.73971410998;7534.171241163577;8268.214519620626;8293.917060829315;8309.293710034715;8399.454035487674;8405.07544170153;6929.203141638574;7226.313500217471;7340.376068034277;7454.58999754997;7481.738139165103;7138.607376594129;7266.7093316266755;7299.817504562428;7352.228016058237;7400.592967386845;6830.975353696986;6866.759663411756;6939.167299924042;6949.096617439701;6949.444044476329;4617.7921993511045;4649.674522783373;4710.411198600175;4768.713877167381;4855.34452848875;4636.675432719677;4722.456917454399;4725.2203386442225;4806.351142595705;4835.032993401404 04:07:20 INFO - PROCESS | 1661 | |4;prototype.html;253.80964276792406;257.36045925874197;317.7616787409442;322.88376184573565;323.466280636949;4493.7417523093545;5141.305923700299;5256.763445644946;5294.497022782834;5324.41431442541;4342.500612429571;4402.0066354878745;5100.566451851588;5271.657342272759;5286.330403146415;3332.6500717424683;3508.1405055761247;3992.760434373932;4048.3927410888455;4055.472788537501;3239.7732158748895;3602.9279414411826;3753.7747735135845;3774.2451509698035;3914.178022615254;3964.8501934438978;4015.5103835621953;4056.904635748346;4067.532233793122;4074.246264441073;3445.9310813783586;3476.4100744944926;4055.6755459563165;4059.573744756802;4090.754554726726;3008.6540047894337;3420.3159368126476;3433.399155147864;3516.94724579129;3525.206828463575;4135.958640413587;4861.034768791034;5026.89946201074;5078.365204349457;5104.719240441774;3535.9292814143832;3899.0642245861072;4213.557576454459;4615.261558150713;4651.2557990721325;4879.121758083544;5339.9733001335135;5519.530839878616;5588.524975377098;5616.634918730269;3840.8463661453507;3853.2871418787613;3857.9421308680394;3868.9806550967346;3874.9031274218087;5977.731002104916;5996.340402555716;6003.789867354665;6005.369436209223;6046.576739628228;3793.886183414502;3910.3605066579944;3914.784686842251;3925.862594809168;3942.743721658083;3378.8817391391676;3663.3405986922075;3967.266055779632;3972.841086356513;3980.7014473914574;3944.112574670676;4127.7936103195325;4229.217594744982;4507.3689683444345;4633.72197668141;4591.4260717410325;5101.591872650179;5614.101743720985;5663.490285874291;5748.425157484302;1213.0598785940967;1352.7970804379422;1353.5939218234491;1353.8559916870427;1360.8026836108709;745.5005146551823;756.8032311599023;803.6382364659298;823.3948048184579;827.913069127745;3911.4328422378594;3991.5808840071954;4051.6353528182603;4220.683448741357;4506.909861802811;4123.0516981094515;4585.830613193614;4753.857384278477;4940.1601727706375;4952.975235123873;4209.926753469923;4459.576340247671;4461.308497182942;4462.496939902161;4488.922658561987;2452.7924867619286;2463;2551.4769472258145;2605.4137818990575;2627.973720262811;4573.839915602938;4818.831525813398;4897.32700474644;4897.755112244374;5002.724305302125;4475.418195634612;4902.877428064256;5028.385239675348;5050.520200580939;5067.797288108508;4513.862865308644;4603.516630753723;4658.417697787777;5127.4872512748425;5128.6153538485505;4163.812628431668;4620.930686039645;4681.461631912373;4950.183219768842;4991.425986011655;4190.455240818674;4295.153084173835;4357.847475338348;4527.7509736964785;4863.392075990501;3907.824147913294;4561.931571026504;5161.967606478795;5209.869753256199;5267.631265811509;3128.2804954860053;3148.9370212595163;3201.551782750463;3772.8115643572187;3901.2002539479904;5731.398203188605;5737.885242295048;5752.252207213036;5821.126830975388;5833.737481813244;4746.7389293589185;5082.949170508247;5155.633757054458;5170.482951704799;5280.683159010472;4992.6255530834605;5266.499047771366;5378.946210537844;5585.832425027255;5616.9327827712605;4636.420447444069;5177.505184000709;5277.551408130198;5509.669419834822;5538.667679939216;4712;4823.469418364048;5409.834427490889;5616.1856530803625;5684.0958278977005;5416.423122461514;5497.725113744376;5510.9448905510435;5515.200295956984;5517.641353312022 04:07:20 INFO - PROCESS | 1661 | |5;yui.html;648.8540078482342;675.8580698053408;720.4812534974823;757.3108471291126;794.2414993681035;14931.850681493182;15056.849431505681;15386.307616157272;15637.530874073767;15647.45233916813;13769.931150344235;14445.78331325031;14454.927725361358;14456.060356076874;14488.348024338904;721.678852910455;747.3087394160407;889.3330002498126;897.1118592593318;911.5396724654073;909.6315992023241;918.2516249256868;924.9907500925005;926.865604487349;932.202966463674;944.0748066894448;973.3478569358547;974.1962880623514;974.2546951582045;974.8245315843145;757.4394947738662;925.9444433333954;958.4488918871676;963.5086106085877;970.2529052629471;687.7970998555388;695.5513693667607;696.8188271049513;706.9434445244368;708.0158579574396;14855.182964936925;14938.70122597542;14950.32723527433;14953.476628317956;14987.625309367244;12051.93974030133;12101.515939362414;12212.633620991384;12375.690607734789;12474.313912734797;10474.26680132391;10631;10714.571417143383;11132.49903754333;11226.32642041472;3955.8022098895226;3967.6429121378924;3984.442178095069;4076.8980775480554;4097.528784189797;80602;81239.5938020312;82805.17194828035;82940;83554;9208.079192080872;9338.95330523343;9358.859617105749;9547.379420337656;9736.318457707892;21187.364379068345;21200.893995529925;21231.469213269793;21232.78767212339;21271.8936405317;7344.816379590447;7346.853062938711;7389.076365454318;7395.556266624019;7529.3600043995775;3927.6857851371824;3939.625735555118;3987.362022076512;4042.2319759245647;4115.34154535273;3276.295596446728;3406;3412.010516950106;3422.2984288220855;3428.074419906611;427.88447119277623;598.61090291311;611.8470382404399;622.1321256846763;636.9777057802954;471.36130543114274;561.5507593924843;566.7477972302369;567.29088639201;580.506569415993;8161.224683655043;8228.177182281843;8250.46371985819;8308.29980256407;8408;564.2044716949082;575.2320651929637;583.7023118209744;589.3899813692893;599.4215581963426;3432.828358582061;3609.242059167552;3915.256101340736;3963.6829053675638;3963.7027222958395;102730;104409;105054;105468.94531054591;107229.46385268023;86932.56533717418;86993.5650321757;89176;90439.28163769809;90560;78558.6072069636;79882;80193;80373.59813200896;80998;7662.0422447194105;7732.188120247293;7835.84328313442;7899.368050555827;7910.5649189295145;46756.766216168704;48867.5113248863;49224.50775492199;49244.50755492399;49409.258861116396;59010.40989590049;60689;70058.64970675114;70401.9439708415;70451.29548704447;10483.947580262049;10813.537146252966;11070.5571777128;11163.44182790841;11524.423778811193;11822.881771182178;11828.46771895284;11844.111691622991;11849.940750296193;12024.158308918291;9568.378055426376;11481.253718508271;11650.825237621273;11651.708707282385;11862.40687965581;11219.439028048728;11338.036266917405;11530.76938461243;11581.710457238636;11869.854488560712;11008.669739907815;11273;11287.209895307578;11339.206255562032;11558.711032224262;7886.526808391515;9366.344355895022;9391.29565282593;9542.045795420403;9683.806323873343;9216.400933939272;9625.422474651543;9625.855612165964;9706.56320465595;9853.753656158653 04:07:20 INFO - PROCESS | 1661 | __end_tp_report 04:07:20 INFO - PROCESS | 1661 | __start_cc_report 04:07:20 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1107 04:07:20 INFO - PROCESS | 1661 | __end_cc_report 04:07:20 INFO - PROCESS | 1661 | __startTimestamp1464088040358__endTimestamp 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | ------- Summary: start ------- 04:07:20 INFO - PROCESS | 1661 | Number of tests: 6 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5759.72 Median:5934.73 stddev:624.58 (10.5%) stddev-sans-first:600.53 04:07:20 INFO - PROCESS | 1661 | Values: 3487.8 3498.7 3498.8 3530.4 3576.8 5658.5 5679.3 5688.0 5742.4 5758.0 5374.5 5758.5 5819.5 5866.3 5890.3 5156.5 5194.1 5259.0 5286.0 5368.3 5332.7 5374.4 5388.6 5403.4 5430.3 5192.8 5193.4 5254.1 5266.5 5289.2 5241.8 5245.6 5249.7 5264.0 5296.8 5264.7 5279.5 5282.2 5283.5 5343.0 5522.8 5609.9 5613.5 5651.2 5669.6 6251.7 6252.8 6268.9 6288.5 6299.3 5608.8 6285.9 6311.0 6327.2 6328.7 5695.9 5766.6 5775.7 5831.1 5843.2 6341.2 6342.5 6345.9 6347.2 6369.9 6194.2 6201.0 6202.3 6205.9 6206.3 6168.6 6177.2 6234.8 6259.9 6273.7 6221.3 6223.0 6260.8 6264.5 6278.2 6279.4 6285.0 6287.0 6296.4 6304.0 4922.9 4946.0 4955.9 4965.3 5017.3 4464.6 4468.7 4475.1 4478.7 4495.2 5762.3 5769.0 5773.3 5781.3 5823.9 6117.4 6196.7 6226.3 6260.1 6310.8 5695.2 5704.4 5709.5 5712.7 5750.1 4588.0 5334.1 5384.7 5405.1 5411.6 6268.2 6285.9 6285.9 6302.5 6317.7 6214.3 6219.5 6260.1 6278.5 6362.7 6282.8 6360.0 6363.3 6364.6 6369.4 6149.0 6220.2 6228.6 6231.3 6234.2 5881.6 6125.1 6209.9 6248.6 6298.0 6023.5 6121.6 6131.8 6136.8 6149.0 5939.7 6063.6 6144.3 6146.2 6160.2 4943.6 5335.8 5918.3 5955.7 6050.0 5955.6 6069.6 6140.0 6160.8 6168.6 5856.8 5878.4 5934.7 5967.3 5993.6 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:72239.05 Median:3788.76 stddev:156174.62 (4122.0%) stddev-sans-first:156519.84 04:07:20 INFO - PROCESS | 1661 | Values: 496.9 500.1 506.1 514.1 526.9 24773.6 31268.7 32550.5 32633.5 43186.0 44159.3 45280.8 45898.0 45956.0 47971.8 9533.4 9725.8 9754.1 9760.7 9776.6 8629.7 9577.7 9706.1 11809.8 12139.6 11725.9 11755.4 11759.6 11863.5 11870.0 11679.2 11718.1 11718.6 11730.2 11740.9 11725.1 11817.2 11834.5 11837.4 11842.0 41485.2 41865.2 41871.8 41915.8 41968.0 3371.0 3519.0 3741.8 3778.9 3798.6 29680.4 29798.7 30203.5 30817.8 30937.2 2737.9 2738.8 2740.3 2758.1 2797.8 516403.0 520984.0 521906.0 523384.4 523813.0 67724.7 68106.0 68189.7 68301.0 68404.0 71707.3 71810.0 71900.6 71946.3 71962.0 16298.2 16363.4 16476.3 16877.0 16915.5 12474.1 12860.5 12951.5 12962.7 12985.5 18211.9 18594.2 18779.2 18787.6 18993.7 6151.2 6237.3 6281.7 6306.2 6312.9 879.6 891.0 950.8 1023.1 1031.2 28510.9 28710.9 28721.3 29178.0 29290.3 1034.4 1052.2 1076.0 1083.3 1097.1 14263.0 14790.8 14906.6 14937.4 15372.9 639488.0 647252.0 653445.0 653695.0 654054.0 63923.7 64034.7 64191.4 64246.0 64261.7 1415.6 1449.6 1458.2 1458.6 1462.8 23873.9 26414.6 28614.0 28688.6 29123.0 52760.7 53516.5 53799.7 54053.6 54382.0 573095.0 576162.1 576414.2 576424.0 577930.0 26245.0 27287.6 27365.9 42422.0 42705.4 40362.4 46441.3 48082.8 48354.8 48764.5 38734.6 38911.6 39327.2 40023.6 40256.8 38136.4 38314.7 38636.6 38990.2 39038.8 31789.0 38114.0 38205.6 38948.4 39544.0 30708.5 32456.7 33409.0 34213.7 35763.6 24896.0 25291.7 25430.9 25829.9 25996.0 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33806.55 Median:2253.76 stddev:97950.98 (4346.1%) stddev-sans-first:98193.60 04:07:20 INFO - PROCESS | 1661 | Values: 380.6 381.5 390.6 399.5 408.2 19659.4 21012.4 21166.4 21328.6 21364.0 20262.5 20931.7 20988.5 21104.4 21164.4 5226.3 5629.9 5972.5 6159.2 6179.6 5986.4 6009.3 6088.0 6099.5 6099.6 5871.0 5992.5 5998.4 6045.4 6053.4 5902.3 5910.6 6003.2 6031.2 6031.8 4752.8 5067.4 5145.3 5160.0 5222.9 25333.7 25593.4 25907.5 26019.7 26173.1 2093.5 2214.0 2224.5 2283.0 2289.5 14686.6 15075.3 15292.7 15368.2 15582.3 1505.4 1696.4 1760.9 1801.0 1852.2 96287.5 113506.4 116922.4 117228.4 117479.4 10507.7 10644.4 10735.8 11005.9 11172.8 13606.3 13640.2 13808.7 13878.3 13995.4 8233.2 8381.7 8441.4 8536.7 8543.4 8299.1 8385.6 8395.3 8460.5 8491.2 5758.2 5903.1 6449.0 6753.5 6804.1 1674.2 1779.6 1872.3 1887.5 1902.0 893.2 906.5 928.0 946.9 1082.5 20231.1 20231.4 21197.4 21206.8 21551.9 787.7 973.3 991.7 1050.3 1087.1 9001.5 10778.5 11138.9 11302.4 11345.6 568596.0 577588.5 578808.0 593158.0 632989.0 90951.5 112934.4 113570.9 114785.9 119800.4 1020.1 1034.7 1037.7 1053.9 1056.9 18371.9 18526.4 18548.7 18657.6 18987.7 38781.8 44034.8 46521.3 48436.0 49127.0 67439.7 67575.7 67725.3 67962.6 68099.7 6936.4 7358.9 7409.7 7866.7 8343.2 12007.1 12452.0 12616.6 13052.0 13202.6 8902.6 10938.0 11036.8 11130.0 11267.8 9868.5 10490.3 10761.1 10942.3 11023.9 11024.1 11233.8 11508.7 11570.6 11697.6 8008.4 8096.2 8121.9 8386.3 8388.9 10256.9 10922.1 11230.6 11282.5 11324.3 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5637.76 Median:4820.69 stddev:3161.66 (65.6%) stddev-sans-first:3154.65 04:07:20 INFO - PROCESS | 1661 | Values: 1420.7 1588.5 1603.8 1858.7 1997.8 7866.8 8597.5 8815.2 8818.5 8825.4 8532.3 8793.6 8795.1 8796.3 8797.7 2033.6 2325.5 2372.5 2401.3 2407.9 2402.2 2429.2 2429.3 2430.7 2431.3 2374.8 2394.1 2409.7 2410.3 2413.1 2097.3 2310.6 2350.2 2384.1 2388.5 2377.1 2379.2 2404.4 2407.2 2409.8 8649.6 8658.5 8692.0 8718.0 8801.1 5106.0 5162.3 5226.6 5230.4 5308.5 3363.2 3457.6 3497.9 3518.5 3528.6 995.9 997.4 998.1 1003.4 1019.0 11566.7 11600.4 11603.7 11613.1 11632.4 4601.6 4719.9 4797.4 4801.3 4802.6 9758.4 9778.3 9824.4 9839.5 9852.6 5254.9 5333.3 5337.5 5339.9 5340.6 4393.5 4539.7 4743.2 4877.4 4884.0 3452.8 3885.8 3935.5 3949.5 4041.5 1281.4 1292.8 1296.2 1308.6 1314.8 816.6 862.4 896.3 927.3 927.8 5482.9 5516.5 5580.9 5697.6 5755.9 968.2 1012.6 1032.4 1037.1 1038.4 3654.9 3727.9 3734.7 3759.4 3765.7 8824.3 10538.1 10752.2 10757.4 10764.5 10579.1 10621.0 10711.2 10728.9 10750.5 6778.7 7982.7 8050.4 8119.3 8247.1 5650.9 5768.1 5811.6 5835.0 5901.4 9489.5 9744.0 9751.8 9966.7 9993.2 10088.1 10210.5 10250.5 10255.6 10265.7 6869.9 6965.6 7367.7 7436.7 7534.2 8268.2 8293.9 8309.3 8399.5 8405.1 6929.2 7226.3 7340.4 7454.6 7481.7 7138.6 7266.7 7299.8 7352.2 7400.6 6831.0 6866.8 6939.2 6949.1 6949.4 4617.8 4649.7 4710.4 4768.7 4855.3 4636.7 4722.5 4725.2 4806.4 4835.0 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4238.27 Median:4567.89 stddev:1304.21 (28.6%) stddev-sans-first:1273.12 04:07:20 INFO - PROCESS | 1661 | Values: 253.8 257.4 317.8 322.9 323.5 4493.7 5141.3 5256.8 5294.5 5324.4 4342.5 4402.0 5100.6 5271.7 5286.3 3332.7 3508.1 3992.8 4048.4 4055.5 3239.8 3602.9 3753.8 3774.2 3914.2 3964.9 4015.5 4056.9 4067.5 4074.2 3445.9 3476.4 4055.7 4059.6 4090.8 3008.7 3420.3 3433.4 3516.9 3525.2 4136.0 4861.0 5026.9 5078.4 5104.7 3535.9 3899.1 4213.6 4615.3 4651.3 4879.1 5340.0 5519.5 5588.5 5616.6 3840.8 3853.3 3857.9 3869.0 3874.9 5977.7 5996.3 6003.8 6005.4 6046.6 3793.9 3910.4 3914.8 3925.9 3942.7 3378.9 3663.3 3967.3 3972.8 3980.7 3944.1 4127.8 4229.2 4507.4 4633.7 4591.4 5101.6 5614.1 5663.5 5748.4 1213.1 1352.8 1353.6 1353.9 1360.8 745.5 756.8 803.6 823.4 827.9 3911.4 3991.6 4051.6 4220.7 4506.9 4123.1 4585.8 4753.9 4940.2 4953.0 4209.9 4459.6 4461.3 4462.5 4488.9 2452.8 2463.0 2551.5 2605.4 2628.0 4573.8 4818.8 4897.3 4897.8 5002.7 4475.4 4902.9 5028.4 5050.5 5067.8 4513.9 4603.5 4658.4 5127.5 5128.6 4163.8 4620.9 4681.5 4950.2 4991.4 4190.5 4295.2 4357.8 4527.8 4863.4 3907.8 4561.9 5162.0 5209.9 5267.6 3128.3 3148.9 3201.6 3772.8 3901.2 5731.4 5737.9 5752.3 5821.1 5833.7 4746.7 5082.9 5155.6 5170.5 5280.7 4992.6 5266.5 5378.9 5585.8 5616.9 4636.4 5177.5 5277.6 5509.7 5538.7 4712.0 4823.5 5409.8 5616.2 5684.1 5416.4 5497.7 5510.9 5515.2 5517.6 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:19175.76 Median:577.87 stddev:28044.24 (4853.0%) stddev-sans-first:28088.41 04:07:20 INFO - PROCESS | 1661 | Values: 648.9 675.9 720.5 757.3 794.2 14931.9 15056.8 15386.3 15637.5 15647.5 13769.9 14445.8 14454.9 14456.1 14488.3 721.7 747.3 889.3 897.1 911.5 909.6 918.3 925.0 926.9 932.2 944.1 973.3 974.2 974.3 974.8 757.4 925.9 958.4 963.5 970.3 687.8 695.6 696.8 706.9 708.0 14855.2 14938.7 14950.3 14953.5 14987.6 12051.9 12101.5 12212.6 12375.7 12474.3 10474.3 10631.0 10714.6 11132.5 11226.3 3955.8 3967.6 3984.4 4076.9 4097.5 80602.0 81239.6 82805.2 82940.0 83554.0 9208.1 9339.0 9358.9 9547.4 9736.3 21187.4 21200.9 21231.5 21232.8 21271.9 7344.8 7346.9 7389.1 7395.6 7529.4 3927.7 3939.6 3987.4 4042.2 4115.3 3276.3 3406.0 3412.0 3422.3 3428.1 427.9 598.6 611.8 622.1 637.0 471.4 561.6 566.7 567.3 580.5 8161.2 8228.2 8250.5 8308.3 8408.0 564.2 575.2 583.7 589.4 599.4 3432.8 3609.2 3915.3 3963.7 3963.7 102730.0 104409.0 105054.0 105468.9 107229.5 86932.6 86993.6 89176.0 90439.3 90560.0 78558.6 79882.0 80193.0 80373.6 80998.0 7662.0 7732.2 7835.8 7899.4 7910.6 46756.8 48867.5 49224.5 49244.5 49409.3 59010.4 60689.0 70058.6 70401.9 70451.3 10483.9 10813.5 11070.6 11163.4 11524.4 11822.9 11828.5 11844.1 11849.9 12024.2 9568.4 11481.3 11650.8 11651.7 11862.4 11219.4 11338.0 11530.8 11581.7 11869.9 11008.7 11273.0 11287.2 11339.2 11558.7 7886.5 9366.3 9391.3 9542.0 9683.8 9216.4 9625.4 9625.9 9706.6 9853.8 04:07:20 INFO - PROCESS | 1661 | -------- Summary: end -------- 04:07:20 INFO - PROCESS | 1661 | 04:07:20 INFO - TEST-INFO | 1661: exit 0 04:07:20 INFO - TEST-OK | dromaeo_css | took 1113813ms 04:07:20 INFO - TEST-START | kraken 04:07:20 INFO - operating with platform_type : mac_ 04:07:20 INFO - Initialising browser for kraken test... 04:07:20 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpXkFuaJ/profile http://localhost:49277/getInfo.html) 04:07:23 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 04:07:23 INFO - PROCESS | 1675 | colorDepth:24 04:07:23 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 04:07:23 INFO - PROCESS | 1675 | __metrics 04:07:23 INFO - PROCESS | 1675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:07:23 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:07:23 INFO - TEST-INFO | 1675: exit 0 04:07:23 INFO - Browser initialized. 04:07:23 INFO - Running cycle 1/1 for kraken test... 04:07:23 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpXkFuaJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:07:35 INFO - PROCESS | 1677 | RSS: Main: 238841856 04:07:35 INFO - PROCESS | 1677 | 04:07:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 04:07:39 INFO - PROCESS | 1677 | RSS: Main: 287531008 04:07:39 INFO - PROCESS | 1677 | 04:07:42 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:07:42 INFO - PROCESS | 1677 | RSS: Main: 295526400 04:07:42 INFO - PROCESS | 1677 | 04:07:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:07:46 INFO - PROCESS | 1677 | RSS: Main: 386027520 04:07:46 INFO - PROCESS | 1677 | 04:07:48 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:07:49 INFO - PROCESS | 1677 | RSS: Main: 285052928 04:07:49 INFO - PROCESS | 1677 | 04:07:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:07:52 INFO - PROCESS | 1677 | RSS: Main: 288702464 04:07:52 INFO - PROCESS | 1677 | 04:07:55 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:07:55 INFO - PROCESS | 1677 | RSS: Main: 325410816 04:07:55 INFO - PROCESS | 1677 | 04:07:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:07:59 INFO - PROCESS | 1677 | RSS: Main: 330072064 04:07:59 INFO - PROCESS | 1677 | 04:08:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:08:03 INFO - PROCESS | 1677 | RSS: Main: 328531968 04:08:03 INFO - PROCESS | 1677 | 04:08:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:08:05 INFO - PROCESS | 1677 | RSS: Main: 315527168 04:08:05 INFO - PROCESS | 1677 | 04:08:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:08:07 INFO - PROCESS | 1677 | RSS: Main: 321548288 04:08:07 INFO - PROCESS | 1677 | 04:08:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:08:10 INFO - PROCESS | 1677 | RSS: Main: 325521408 04:08:10 INFO - PROCESS | 1677 | 04:08:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:08:14 INFO - PROCESS | 1677 | RSS: Main: 330240000 04:08:14 INFO - PROCESS | 1677 | 04:08:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:08:17 INFO - PROCESS | 1677 | RSS: Main: 328032256 04:08:17 INFO - PROCESS | 1677 | 04:08:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:08:20 INFO - PROCESS | 1677 | RSS: Main: 374747136 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | __start_tp_report 04:08:20 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 04:08:20 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 04:08:20 INFO - PROCESS | 1677 | |i|pagename|runs| 04:08:20 INFO - PROCESS | 1677 | |0;ai-astar;111;106;111;111;112;111;111;112;110;111 04:08:20 INFO - PROCESS | 1677 | |1;audio-beat-detection;156;122;114;195;118;118;135;117;146;117 04:08:20 INFO - PROCESS | 1677 | |2;audio-dft;182;184;183;184;183;180;182;182;182;183 04:08:20 INFO - PROCESS | 1677 | |3;audio-fft;77;57;61;139;56;56;63;65;81;55 04:08:20 INFO - PROCESS | 1677 | |4;audio-oscillator;95;76;138;80;82;74;100;78;76;103 04:08:20 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;86;84;85;87;88;84;87;83;86;83 04:08:20 INFO - PROCESS | 1677 | |6;imaging-darkroom;92;92;92;92;92;92;93;93;92;92 04:08:20 INFO - PROCESS | 1677 | |7;imaging-desaturate;83;83;84;85;86;85;84;85;83;83 04:08:20 INFO - PROCESS | 1677 | |8;json-parse-financial;58;58;59;58;59;57;56;116;55;55 04:08:20 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;65;73;69;74;73;70;69;70;69;69 04:08:20 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;85;85;85;84;85;85;84;85;85;87 04:08:20 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;139;136;143;136;135;136;135;135;215;138 04:08:20 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;165;164;165;163;163;163;164;165;161;159 04:08:20 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;66;66;66;87;83;81;80;78;64;66 04:08:20 INFO - PROCESS | 1677 | __end_tp_report 04:08:20 INFO - PROCESS | 1677 | __start_cc_report 04:08:20 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2219 04:08:20 INFO - PROCESS | 1677 | __end_cc_report 04:08:20 INFO - PROCESS | 1677 | __startTimestamp1464088100029__endTimestamp 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | ------- Summary: start ------- 04:08:20 INFO - PROCESS | 1677 | Number of tests: 14 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.71 (1.5%) stddev-sans-first:1.81 04:08:20 INFO - PROCESS | 1677 | Values: 111.0 106.0 111.0 111.0 112.0 111.0 111.0 112.0 110.0 111.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:128.50 stddev:25.78 (20.1%) stddev-sans-first:26.07 04:08:20 INFO - PROCESS | 1677 | Values: 156.0 122.0 114.0 195.0 118.0 118.0 135.0 117.0 146.0 117.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.18 (0.6%) stddev-sans-first:1.24 04:08:20 INFO - PROCESS | 1677 | Values: 182.0 184.0 183.0 184.0 183.0 180.0 182.0 182.0 182.0 183.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:71.00 Median:62.00 stddev:25.52 (41.2%) stddev-sans-first:26.98 04:08:20 INFO - PROCESS | 1677 | Values: 77.0 57.0 61.0 139.0 56.0 56.0 63.0 65.0 81.0 55.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:77.00 stddev:19.87 (25.8%) stddev-sans-first:21.00 04:08:20 INFO - PROCESS | 1677 | Values: 95.0 76.0 138.0 80.0 82.0 74.0 100.0 78.0 76.0 103.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.77 (2.1%) stddev-sans-first:1.86 04:08:20 INFO - PROCESS | 1677 | Values: 86.0 84.0 85.0 87.0 88.0 84.0 87.0 83.0 86.0 83.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:92.20 Median:92.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 04:08:20 INFO - PROCESS | 1677 | Values: 92.0 92.0 92.0 92.0 92.0 92.0 93.0 93.0 92.0 92.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.50 stddev:1.10 (1.3%) stddev-sans-first:1.09 04:08:20 INFO - PROCESS | 1677 | Values: 83.0 83.0 84.0 85.0 86.0 85.0 84.0 85.0 83.0 83.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:58.00 stddev:18.65 (32.1%) stddev-sans-first:19.69 04:08:20 INFO - PROCESS | 1677 | Values: 58.0 58.0 59.0 58.0 59.0 57.0 56.0 116.0 55.0 55.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:2.64 (3.8%) stddev-sans-first:2.06 04:08:20 INFO - PROCESS | 1677 | Values: 65.0 73.0 69.0 74.0 73.0 70.0 69.0 70.0 69.0 69.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 04:08:20 INFO - PROCESS | 1677 | Values: 85.0 85.0 85.0 84.0 85.0 85.0 84.0 85.0 85.0 87.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:137.00 stddev:24.79 (18.1%) stddev-sans-first:26.21 04:08:20 INFO - PROCESS | 1677 | Values: 139.0 136.0 143.0 136.0 135.0 136.0 135.0 135.0 215.0 138.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:164.00 stddev:1.93 (1.2%) stddev-sans-first:1.94 04:08:20 INFO - PROCESS | 1677 | Values: 165.0 164.0 165.0 163.0 163.0 163.0 164.0 165.0 161.0 159.0 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:79.00 stddev:8.86 (11.2%) stddev-sans-first:8.95 04:08:20 INFO - PROCESS | 1677 | Values: 66.0 66.0 66.0 87.0 83.0 81.0 80.0 78.0 64.0 66.0 04:08:20 INFO - PROCESS | 1677 | -------- Summary: end -------- 04:08:20 INFO - PROCESS | 1677 | 04:08:20 INFO - TEST-INFO | 1677: exit 0 04:08:20 INFO - TEST-OK | kraken | took 59920ms 04:08:20 INFO - SUITE-END | took 1173s 04:08:21 INFO - Completed test suite (00:19:34) 04:08:21 INFO - javascript benchmark 04:08:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3487.8315764218987, 3498.737594680398, 3498.807565583895, 3530.3645343838107, 3576.7675101118425, 5658.519025882805, 5679.290088738908, 5687.9715601421985, 5742.425757424256, 5757.971210143949, 5374.543163831069, 5758.452946970031, 5819.505342045931, 5866.325372582154, 5890.293164820217, 5156.53591176794, 5194.11700010999, 5258.973705131488, 5286.04851126797, 5368.30212072432, 5332.680039197641, 5374.381946076192, 5388.649737767052, 5403.432639572848, 5430.321209848769, 5192.792288308463, 5193.402758682743, 5254.133068043769, 5266.499682530153, 5289.233061206123, 5241.816536421207, 5245.632805703603, 5249.737513124367, 5263.999840030382, 5296.841094767125, 5264.657797243167, 5279.52484276417, 5282.2076688496645, 5283.525666534, 5342.973285133549, 5522.806701765459, 5609.859753506154, 5613.46672066153, 5651.1523271509195, 5669.603127781057, 6251.687415629201, 6252.812415627493, 6268.937310626881, 6288.52836037299, 6299.307076221612, 5608.803691870765, 6285.87428251437, 6311.0, 6327.209098862642, 6328.6835658216905, 5695.886082278331, 5766.567507436959, 5775.682337471395, 5831.067029275338, 5843.2403787507355, 6341.239051313872, 6342.5243106767175, 6345.873082538324, 6347.174867267318, 6369.904451433232, 6194.194754681953, 6200.968995154995, 6202.28673702521, 6205.937940620626, 6206.286277078194, 6168.599041062316, 6177.227846519185, 6234.812955611339, 6259.906101408483, 6273.749050037947, 6221.346758590374, 6223.0354295083425, 6260.812175634738, 6264.5301602379095, 6278.246610406781, 6279.403456671609, 6285.025820997753, 6287.0, 6296.401841825019, 6303.991361382157, 4922.852314430573, 4945.950540494621, 4955.876103097379, 4965.3048573199785, 5017.3226614406585, 4464.6428285737065, 4468.664850136252, 4475.1049790042125, 4478.731276123443, 4495.168393847149, 5762.308522977186, 5768.9711551442815, 5773.307203135567, 5781.277340332458, 5823.912641310384, 6117.370705100086, 6196.690165491707, 6226.315105338409, 6260.092286618415, 6310.810675679737, 5695.231143795535, 5704.401037891044, 5709.543236541067, 5712.685802280915, 5750.108733146369, 4587.977060114678, 5334.11987022137, 5384.703841288767, 5405.108157154104, 5411.621186516906, 6268.247810262797, 6285.899967505759, 6285.937140628535, 6302.527310451643, 6317.684115794284, 6214.347493513116, 6219.533534984985, 6260.092286618506, 6278.497720182466, 6362.650054247061, 6282.8115156543545, 6360.0, 6363.331850155667, 6364.6181229126405, 6369.426751592379, 6148.9692551536955, 6220.191375121386, 6228.56400051992, 6231.283402408781, 6234.1583886174785, 5881.647101173943, 6125.081237814364, 6209.937900620936, 6248.625082495065, 6298.0, 6023.45788879003, 6121.571489995657, 6131.816045518642, 6136.754529818757, 6148.9692551536955, 5939.67331796738, 6063.605865618897, 6144.293406258159, 6146.170267014075, 6160.199174107516, 4943.5797957174045, 5335.799445124816, 5918.3489816121055, 5955.731992060281, 6049.971504844099, 5955.553333500092, 6069.620064267659, 6139.969300153471, 6160.845978850564, 6168.568200226121, 5856.765729370777, 5878.382769809108, 5934.732937017759, 5967.254093238345, 5993.550483713826], "value": 5721.863222894358, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.8745790783874, 500.0623830318151, 506.0991435245263, 514.0772313696914, 526.8788178718895, 24773.62839557406, 31268.687313126862, 32550.511742323884, 32633.51049734253, 43186.0, 44159.33760993588, 45280.77359613197, 45898.0, 45956.0, 47971.760141199244, 9533.380330278544, 9725.756856078584, 9754.073363030537, 9760.658376956808, 9776.560054797552, 8629.654813807472, 9577.664781732641, 9706.126448619623, 11809.7638047239, 12139.635810925687, 11725.941370293136, 11755.35345555994, 11759.647210583697, 11863.46614402354, 11870.0, 11679.182457227997, 11718.121140914465, 11718.648440546796, 11730.237534560227, 11740.882591174064, 11725.120615953752, 11817.17279790415, 11834.526618935231, 11837.408129593487, 11842.0, 41485.1702965942, 41865.162696745894, 41871.7906410466, 41915.790421048005, 41968.0, 3371.0, 3519.0, 3741.7567858089137, 3778.924421511582, 3798.6011468795655, 29680.406391872042, 29798.702012980026, 30203.546946795817, 30817.845910770302, 30937.226569335722, 2737.890484380622, 2738.8493632850186, 2740.3149212696826, 2758.0760445250667, 2797.832130072182, 516403.0, 520984.0, 521906.0, 523384.38307808596, 523812.9999999962, 67724.6613766928, 68106.0, 68189.65905170442, 68301.0, 68404.0, 71707.28292717111, 71810.0, 71900.64049679718, 71946.28053719501, 71962.0, 16298.185090745652, 16363.427280045142, 16476.258568364214, 16876.987380757193, 16915.492535223962, 12474.064445166649, 12860.549880754128, 12951.5466958656, 12962.675933101747, 12985.480580776852, 18211.871515271287, 18594.163262653212, 18779.24883004692, 18787.624247514974, 18993.670443069128, 6151.231096112986, 6237.345078766664, 6281.654509002049, 6306.180196574416, 6312.936870631327, 879.560219890055, 891.028778631295, 950.8003319302885, 1023.1150055202196, 1031.1802117316752, 28510.859565617142, 28710.856445717636, 28721.281967950967, 29177.97877074292, 29290.267743306587, 1034.4207243943415, 1052.2423854824515, 1076.0476977874637, 1083.31209681851, 1097.1003776903024, 14263.0, 14790.778138327934, 14906.552803415914, 14937.402503899939, 15372.923135384252, 639488.0, 647252.0, 653445.0, 653695.0, 654054.0, 63923.68038159779, 64034.67982660057, 64191.358086419474, 64246.0363094554, 64261.67869160624, 1415.568251683226, 1449.6013596260902, 1458.1761304862719, 1458.6353411647087, 1462.8317743459424, 23873.880630596737, 26414.603780943686, 28614.0, 28688.569671454527, 29123.0, 52760.73619632031, 53516.46483535115, 53799.73100134474, 54053.648658783844, 54382.0, 573095.0, 576162.1191894014, 576414.2358576361, 576424.0, 577930.0, 26245.0, 27287.590686139327, 27365.86317068402, 42422.0, 42705.359419609355, 40362.39456408215, 46441.30338044865, 48082.75958620184, 48354.75822620864, 48764.512354875995, 38734.612653873104, 38911.61088389193, 39327.2134557313, 40023.599764001985, 40256.798716007404, 38136.42795358016, 38314.65898693533, 38636.61363386443, 38990.2201955965, 39038.82883513499, 31789.04632861018, 38114.047148821504, 38205.61794382021, 38948.41577376285, 39544.0, 30708.539371909887, 32456.675433245364, 33408.997730068135, 34213.657863421045, 35763.64236357603, 24896.004159833403, 25291.74708252894, 25430.872845635655, 25829.870850645628, 25996.0], "value": 20510.430697524018, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.61747943316965, 381.49642471937045, 390.63280516314643, 399.4707013207501, 408.22029922847366, 19659.410217693476, 21012.369628911118, 21166.365009049754, 21328.57342853142, 21364.0, 20262.49343766403, 20931.68602470964, 20988.475288117806, 21104.366868993915, 21164.36506904795, 5226.268322434862, 5629.915551266734, 5972.492338151263, 6159.168512250833, 6179.598326108811, 5986.371430999748, 6009.278886533601, 6088.0, 6099.451049405553, 6099.603525770833, 5870.970645146768, 5992.520598352143, 5998.430149135826, 6045.425684559982, 6053.364396738345, 5902.291724993007, 5910.645361278294, 6003.249593800797, 6031.215941927521, 6031.788887388942, 4752.809887604491, 5067.417247016567, 5145.33110695611, 5159.974200129013, 5222.895542089175, 25333.746662533507, 25593.36016599581, 25907.48185036289, 26019.739802601922, 26173.083942061938, 2093.4557015176165, 2214.0, 2224.477247846755, 2283.041122728458, 2289.496310811619, 14686.632834179125, 15075.321610527553, 15292.694146117126, 15368.231588420535, 15582.298796554182, 1505.3602219056966, 1696.4401747423321, 1760.8503277221453, 1800.9909950450165, 1852.2128095559333, 96287.51856240674, 113506.43246783796, 116922.41538792252, 117228.41385793017, 117479.4126029373, 10507.737306567396, 10644.361338319726, 10735.78528429427, 11005.889941100646, 11172.832407513893, 13606.251656158809, 13640.181589104686, 13808.723825523433, 13878.306084695725, 13995.440182392795, 8233.217844304781, 8381.664733410595, 8441.366897482714, 8536.74389768308, 8543.401961862734, 8299.087100418948, 8385.580720963928, 8395.286400656012, 8460.450070745383, 8491.23578877904, 5758.236046697609, 5903.085021821625, 6448.967755161194, 6753.45972322213, 6804.11546498952, 1674.1963857348542, 1779.6440711857422, 1872.2604571194302, 1887.509247595635, 1902.0014492391606, 893.1871996483169, 906.4742449379344, 927.9582418791035, 946.8721722567503, 1082.4533610526635, 20231.089600967698, 20231.393058208276, 21197.36407907765, 21206.833624150513, 21551.89224053901, 787.6573690444675, 973.2943615878431, 991.7024892532212, 1050.3435352904435, 1087.075985412409, 9001.459912405277, 10778.514966826511, 11138.888611113947, 11302.378369189773, 11345.602903898323, 568596.0, 577588.4567503099, 578808.0, 593158.0, 632989.0, 90951.54524227337, 112934.36100946207, 113570.86429135768, 114785.85214147752, 119800.4009979962, 1020.1481762728057, 1034.7257976636197, 1037.6679462572056, 1053.8682664666917, 1056.915446764257, 18371.90814045921, 18526.444206673823, 18548.721769173742, 18657.626847463245, 18987.71518427252, 38781.8365449037, 44034.77982610066, 46521.30218046664, 48436.0, 49127.034918602854, 67439.65120697657, 67575.66212168908, 67725.32274677388, 67962.64074718577, 68099.65950170218, 6936.375726184668, 7358.926410735824, 7409.740659076906, 7866.685332586632, 8343.207395297437, 12007.099467539794, 12452.0, 12616.55842045524, 13052.0, 13202.603921882359, 8902.55487225649, 10938.015578597964, 11036.779264414827, 11130.0, 11267.830982535432, 9868.457234851865, 10490.31812932157, 10761.139108871115, 10942.343459392143, 11023.889761102608, 11024.062954648945, 11233.831492527455, 11508.654740357802, 11570.59502917394, 11697.590584329508, 8008.439409241297, 8096.190380962093, 8121.918780812116, 8386.287165590993, 8388.874166887623, 10256.897431025893, 10922.12622990143, 11230.606928757454, 11282.492287846902, 11324.32054076758], "value": 9569.708423599197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.7229590229908, 1588.4519840654973, 1603.8476344747248, 1858.711899655553, 1997.8102080302376, 7866.842663146734, 8597.484150950948, 8815.206631403173, 8818.514981676006, 8825.382223244376, 8532.317414606832, 8793.604287807048, 8795.076516965722, 8796.252318552915, 8797.692080777175, 2033.5729496805689, 2325.5232677301115, 2372.5254949010223, 2401.267613377919, 2407.927762167129, 2402.159244264511, 2429.210506585358, 2429.331933718233, 2430.6718592989987, 2431.331383869441, 2374.8218883583713, 2394.090245706629, 2409.722881868581, 2410.276916924924, 2413.1071503543712, 2097.3498215553077, 2310.595645761985, 2350.15394457995, 2384.070212617081, 2388.450656349032, 2377.0729415527785, 2379.1553998330514, 2404.4349577849193, 2407.1935901473025, 2409.831311808174, 8649.610767515476, 8658.480491170487, 8691.956540217321, 8718.041015488292, 8801.075887031833, 5106.0, 5162.303089082963, 5226.55574276187, 5230.372355317349, 5308.495692909168, 3363.1928337199142, 3457.6023757267985, 3497.8600855965733, 3518.5249991251367, 3528.6294939031545, 995.875515560555, 997.379258704605, 998.1266391907081, 1003.3528374198601, 1018.9592416303338, 11566.710832229262, 11600.4199790011, 11603.6518904433, 11613.070954323719, 11632.418379081097, 4601.585857272795, 4719.905601887943, 4797.4482934462985, 4801.279808028824, 4802.567768900815, 9758.365706229073, 9778.31551791382, 9824.4105353679, 9839.458829764431, 9852.556634951445, 5254.868628284247, 5333.306670132858, 5337.546308563816, 5339.893202135935, 5340.626156169031, 4393.472783265965, 4539.659525535532, 4743.241081426955, 4877.3903262092235, 4883.975580122076, 3452.7755695879687, 3885.7759805661126, 3935.547412047651, 3949.4668219790533, 4041.5150181977765, 1281.4489769399247, 1292.8383952006, 1296.163974236631, 1308.6335825968745, 1314.750197462479, 816.5957850863861, 862.4307956748515, 896.251629889037, 927.3369540778376, 927.7958849053198, 5482.945170548243, 5516.475934786269, 5580.86047848807, 5697.601167918288, 5755.884882302331, 968.2399316536485, 1012.6354512375539, 1032.4270030133198, 1037.1080870451406, 1038.4081073787868, 3654.908627284286, 3727.9254414912093, 3734.682551983112, 3759.3609086455367, 3765.736398452082, 8824.338174636929, 10538.104261137889, 10752.247342686092, 10757.408342541235, 10764.515596798163, 10579.10077643409, 10621.0, 10711.196660250513, 10728.94635526833, 10750.516226769814, 6778.7288508459105, 7982.68069277234, 8050.436469447082, 8119.309858661962, 8247.092819789817, 5650.943490565206, 5768.134779783066, 5811.593188476935, 5835.008048631658, 5901.380355062828, 9489.478078705737, 9743.951280243553, 9751.756206094904, 9966.651167209113, 9993.150582200595, 10088.092071713581, 10210.489475526343, 10250.487475626338, 10255.589776409159, 10265.692029239135, 6869.931300686929, 6965.616891071039, 7367.705291788268, 7436.73971410998, 7534.171241163577, 8268.214519620626, 8293.917060829315, 8309.293710034715, 8399.454035487674, 8405.07544170153, 6929.203141638574, 7226.313500217471, 7340.376068034277, 7454.58999754997, 7481.738139165103, 7138.607376594129, 7266.7093316266755, 7299.817504562428, 7352.228016058237, 7400.592967386845, 6830.975353696986, 6866.759663411756, 6939.167299924042, 6949.096617439701, 6949.444044476329, 4617.7921993511045, 4649.674522783373, 4710.411198600175, 4768.713877167381, 4855.34452848875, 4636.675432719677, 4722.456917454399, 4725.2203386442225, 4806.351142595705, 4835.032993401404], "value": 4545.490158004484, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.80964276792406, 257.36045925874197, 317.7616787409442, 322.88376184573565, 323.466280636949, 4493.7417523093545, 5141.305923700299, 5256.763445644946, 5294.497022782834, 5324.41431442541, 4342.500612429571, 4402.0066354878745, 5100.566451851588, 5271.657342272759, 5286.330403146415, 3332.6500717424683, 3508.1405055761247, 3992.760434373932, 4048.3927410888455, 4055.472788537501, 3239.7732158748895, 3602.9279414411826, 3753.7747735135845, 3774.2451509698035, 3914.178022615254, 3964.8501934438978, 4015.5103835621953, 4056.904635748346, 4067.532233793122, 4074.246264441073, 3445.9310813783586, 3476.4100744944926, 4055.6755459563165, 4059.573744756802, 4090.754554726726, 3008.6540047894337, 3420.3159368126476, 3433.399155147864, 3516.94724579129, 3525.206828463575, 4135.958640413587, 4861.034768791034, 5026.89946201074, 5078.365204349457, 5104.719240441774, 3535.9292814143832, 3899.0642245861072, 4213.557576454459, 4615.261558150713, 4651.2557990721325, 4879.121758083544, 5339.9733001335135, 5519.530839878616, 5588.524975377098, 5616.634918730269, 3840.8463661453507, 3853.2871418787613, 3857.9421308680394, 3868.9806550967346, 3874.9031274218087, 5977.731002104916, 5996.340402555716, 6003.789867354665, 6005.369436209223, 6046.576739628228, 3793.886183414502, 3910.3605066579944, 3914.784686842251, 3925.862594809168, 3942.743721658083, 3378.8817391391676, 3663.3405986922075, 3967.266055779632, 3972.841086356513, 3980.7014473914574, 3944.112574670676, 4127.7936103195325, 4229.217594744982, 4507.3689683444345, 4633.72197668141, 4591.4260717410325, 5101.591872650179, 5614.101743720985, 5663.490285874291, 5748.425157484302, 1213.0598785940967, 1352.7970804379422, 1353.5939218234491, 1353.8559916870427, 1360.8026836108709, 745.5005146551823, 756.8032311599023, 803.6382364659298, 823.3948048184579, 827.913069127745, 3911.4328422378594, 3991.5808840071954, 4051.6353528182603, 4220.683448741357, 4506.909861802811, 4123.0516981094515, 4585.830613193614, 4753.857384278477, 4940.1601727706375, 4952.975235123873, 4209.926753469923, 4459.576340247671, 4461.308497182942, 4462.496939902161, 4488.922658561987, 2452.7924867619286, 2463.0, 2551.4769472258145, 2605.4137818990575, 2627.973720262811, 4573.839915602938, 4818.831525813398, 4897.32700474644, 4897.755112244374, 5002.724305302125, 4475.418195634612, 4902.877428064256, 5028.385239675348, 5050.520200580939, 5067.797288108508, 4513.862865308644, 4603.516630753723, 4658.417697787777, 5127.4872512748425, 5128.6153538485505, 4163.812628431668, 4620.930686039645, 4681.461631912373, 4950.183219768842, 4991.425986011655, 4190.455240818674, 4295.153084173835, 4357.847475338348, 4527.7509736964785, 4863.392075990501, 3907.824147913294, 4561.931571026504, 5161.967606478795, 5209.869753256199, 5267.631265811509, 3128.2804954860053, 3148.9370212595163, 3201.551782750463, 3772.8115643572187, 3901.2002539479904, 5731.398203188605, 5737.885242295048, 5752.252207213036, 5821.126830975388, 5833.737481813244, 4746.7389293589185, 5082.949170508247, 5155.633757054458, 5170.482951704799, 5280.683159010472, 4992.6255530834605, 5266.499047771366, 5378.946210537844, 5585.832425027255, 5616.9327827712605, 4636.420447444069, 5177.505184000709, 5277.551408130198, 5509.669419834822, 5538.667679939216, 4712.0, 4823.469418364048, 5409.834427490889, 5616.1856530803625, 5684.0958278977005, 5416.423122461514, 5497.725113744376, 5510.9448905510435, 5515.200295956984, 5517.641353312022], "value": 3828.021455058785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.8540078482342, 675.8580698053408, 720.4812534974823, 757.3108471291126, 794.2414993681035, 14931.850681493182, 15056.849431505681, 15386.307616157272, 15637.530874073767, 15647.45233916813, 13769.931150344235, 14445.78331325031, 14454.927725361358, 14456.060356076874, 14488.348024338904, 721.678852910455, 747.3087394160407, 889.3330002498126, 897.1118592593318, 911.5396724654073, 909.6315992023241, 918.2516249256868, 924.9907500925005, 926.865604487349, 932.202966463674, 944.0748066894448, 973.3478569358547, 974.1962880623514, 974.2546951582045, 974.8245315843145, 757.4394947738662, 925.9444433333954, 958.4488918871676, 963.5086106085877, 970.2529052629471, 687.7970998555388, 695.5513693667607, 696.8188271049513, 706.9434445244368, 708.0158579574396, 14855.182964936925, 14938.70122597542, 14950.32723527433, 14953.476628317956, 14987.625309367244, 12051.93974030133, 12101.515939362414, 12212.633620991384, 12375.690607734789, 12474.313912734797, 10474.26680132391, 10631.0, 10714.571417143383, 11132.49903754333, 11226.32642041472, 3955.8022098895226, 3967.6429121378924, 3984.442178095069, 4076.8980775480554, 4097.528784189797, 80602.0, 81239.5938020312, 82805.17194828035, 82940.0, 83554.0, 9208.079192080872, 9338.95330523343, 9358.859617105749, 9547.379420337656, 9736.318457707892, 21187.364379068345, 21200.893995529925, 21231.469213269793, 21232.78767212339, 21271.8936405317, 7344.816379590447, 7346.853062938711, 7389.076365454318, 7395.556266624019, 7529.3600043995775, 3927.6857851371824, 3939.625735555118, 3987.362022076512, 4042.2319759245647, 4115.34154535273, 3276.295596446728, 3406.0, 3412.010516950106, 3422.2984288220855, 3428.074419906611, 427.88447119277623, 598.61090291311, 611.8470382404399, 622.1321256846763, 636.9777057802954, 471.36130543114274, 561.5507593924843, 566.7477972302369, 567.29088639201, 580.506569415993, 8161.224683655043, 8228.177182281843, 8250.46371985819, 8308.29980256407, 8408.0, 564.2044716949082, 575.2320651929637, 583.7023118209744, 589.3899813692893, 599.4215581963426, 3432.828358582061, 3609.242059167552, 3915.256101340736, 3963.6829053675638, 3963.7027222958395, 102730.0, 104409.0, 105054.0, 105468.94531054591, 107229.46385268023, 86932.56533717418, 86993.5650321757, 89176.0, 90439.28163769809, 90560.0, 78558.6072069636, 79882.0, 80193.0, 80373.59813200896, 80998.0, 7662.0422447194105, 7732.188120247293, 7835.84328313442, 7899.368050555827, 7910.5649189295145, 46756.766216168704, 48867.5113248863, 49224.50775492199, 49244.50755492399, 49409.258861116396, 59010.40989590049, 60689.0, 70058.64970675114, 70401.9439708415, 70451.29548704447, 10483.947580262049, 10813.537146252966, 11070.5571777128, 11163.44182790841, 11524.423778811193, 11822.881771182178, 11828.46771895284, 11844.111691622991, 11849.940750296193, 12024.158308918291, 9568.378055426376, 11481.253718508271, 11650.825237621273, 11651.708707282385, 11862.40687965581, 11219.439028048728, 11338.036266917405, 11530.76938461243, 11581.710457238636, 11869.854488560712, 11008.669739907815, 11273.0, 11287.209895307578, 11339.206255562032, 11558.711032224262, 7886.526808391515, 9366.344355895022, 9391.29565282593, 9542.045795420403, 9683.806323873343, 9216.400933939272, 9625.422474651543, 9625.855612165964, 9706.56320465595, 9853.753656158653], "value": 6928.117896670274, "unit": "score"}], "name": "dromaeo_css", "value": 7165.920196065236, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 111.0, 111.0, 112.0, 111.0, 111.0, 112.0, 110.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 114.0, 195.0, 118.0, 118.0, 135.0, 117.0, 146.0, 117.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 184.0, 183.0, 180.0, 182.0, 182.0, 182.0, 183.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 61.0, 139.0, 56.0, 56.0, 63.0, 65.0, 81.0, 55.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 76.0, 138.0, 80.0, 82.0, 74.0, 100.0, 78.0, 76.0, 103.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 87.0, 88.0, 84.0, 87.0, 83.0, 86.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 92.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 85.0, 86.0, 85.0, 84.0, 85.0, 83.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 59.0, 57.0, 56.0, 116.0, 55.0, 55.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 69.0, 74.0, 73.0, 70.0, 69.0, 70.0, 69.0, 69.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 85.0, 85.0, 84.0, 85.0, 85.0, 87.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 143.0, 136.0, 135.0, 136.0, 135.0, 135.0, 215.0, 138.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 165.0, 163.0, 163.0, 163.0, 164.0, 165.0, 161.0, 159.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 87.0, 83.0, 81.0, 80.0, 78.0, 64.0, 66.0], "value": 73.7, "unit": "score"}], "name": "kraken", "value": 1449.6000000000001, "alertThreshold": 2.0}]} 04:08:21 INFO - Return code: 0 04:08:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:08:21 ERROR - # TBPL SUCCESS # 04:08:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:08:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:08:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:08:21 INFO - 2016-05-24 04:08:21.326 screenresolution[1681:9908] starting screenresolution argv=screenresolution get 04:08:21 INFO - 2016-05-24 04:08:21.329 screenresolution[1681:9908] Display 0: 1600x1200x32@60 04:08:21 INFO - 2016-05-24 04:08:21.335 screenresolution[1682:9911] starting screenresolution argv=screenresolution list 04:08:21 INFO - Available Modes on Display 0 04:08:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:08:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:08:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:08:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:08:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:08:21 INFO - Intel Iris: 04:08:21 INFO - Chipset Model: Intel Iris 04:08:21 INFO - Type: GPU 04:08:21 INFO - Bus: Built-In 04:08:21 INFO - VRAM (Dynamic, Max): 1536 MB 04:08:21 INFO - Vendor: Intel (0x8086) 04:08:21 INFO - Device ID: 0x0a2e 04:08:21 INFO - Revision ID: 0x0009 04:08:21 INFO - Displays: 04:08:21 INFO - Display: 04:08:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:08:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:08:21 INFO - Display Serial Number: 1600x1200 60 04:08:21 INFO - Main Display: Yes 04:08:21 INFO - Mirror: Off 04:08:21 INFO - Online: Yes 04:08:21 INFO - Rotation: Supported 04:08:21 INFO - Return code: 0 04:08:21 INFO - Running post-action listener: _resource_record_post_action 04:08:21 INFO - ##### 04:08:21 INFO - ##### Finished run-tests step (success) 04:08:21 INFO - ##### 04:08:21 INFO - Running post-run listener: _resource_record_post_run 04:08:21 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 36750848; Write bytes: 696510976; Read time: 247; Write time: 1889 04:08:21 INFO - TinderboxPrint: CPU usage
53.9% 04:08:21 INFO - TinderboxPrint: I/O read bytes / time
36,750,848 / 247 04:08:21 INFO - TinderboxPrint: I/O write bytes / time
696,510,976 / 1,889 04:08:21 INFO - TinderboxPrint: CPU idle
2,228.7 (46.1%) 04:08:21 INFO - TinderboxPrint: CPU system
966.7 (20.0%) 04:08:21 INFO - TinderboxPrint: CPU user
1,642.2 (33.9%) 04:08:21 INFO - TinderboxPrint: Swap in / out
433,459,200 / 0 04:08:21 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375550464; Write bytes: 369829376; Read time: 28022; Write time: 751 04:08:21 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35788288; Write bytes: 297563136; Read time: 233; Write time: 1068 04:08:22 INFO - Running post-run listener: _upload_blobber_files 04:08:22 INFO - Blob upload gear active. 04:08:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:08:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:08:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:08:22 INFO - (blobuploader) - INFO - Open directory for files ... 04:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:08:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:08:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:08:23 INFO - (blobuploader) - INFO - Done attempting. 04:08:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:08:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:08:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:08:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:08:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:08:27 INFO - (blobuploader) - INFO - Done attempting. 04:08:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:08:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:08:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:08:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:08:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:08:30 INFO - (blobuploader) - INFO - Done attempting. 04:08:30 INFO - (blobuploader) - INFO - Iteration through files over. 04:08:30 INFO - Return code: 0 04:08:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:08:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:08:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ee9604cadbf3b7bd073018ff402d2cad93219acc012ed14942da78a01363a2c5bc4fe734f4c65375a3d5aec25099af10d9b0ee20267381d1dbd21fe0d372fb1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b40dca17836c6bf5f838ac64bf9f562e41b72e842bdc57616a6c64d0f167146eb90eefd3e669904ed575b265b1c6046431393ad4a21103920c2fca42e296627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab730430bce917c6b24061e66ad4d352cadf8e768babf81233a3341a669d98edf4d4e26cfd303f0a40ae577675a7fd6a4a5564921bd2937d03d661718141b1b4"} 04:08:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:08:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:08:30 INFO - Contents: 04:08:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ee9604cadbf3b7bd073018ff402d2cad93219acc012ed14942da78a01363a2c5bc4fe734f4c65375a3d5aec25099af10d9b0ee20267381d1dbd21fe0d372fb1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b40dca17836c6bf5f838ac64bf9f562e41b72e842bdc57616a6c64d0f167146eb90eefd3e669904ed575b265b1c6046431393ad4a21103920c2fca42e296627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab730430bce917c6b24061e66ad4d352cadf8e768babf81233a3341a669d98edf4d4e26cfd303f0a40ae577675a7fd6a4a5564921bd2937d03d661718141b1b4"} 04:08:30 INFO - Running post-run listener: copy_logs_to_upload_dir 04:08:30 INFO - Copying logs to upload dir... 04:08:30 INFO - mkdir: /builds/slave/test/build/upload/logs 04:08:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.208209 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-24 04:08:30.411117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 04:08:30.414170) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ee9604cadbf3b7bd073018ff402d2cad93219acc012ed14942da78a01363a2c5bc4fe734f4c65375a3d5aec25099af10d9b0ee20267381d1dbd21fe0d372fb1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b40dca17836c6bf5f838ac64bf9f562e41b72e842bdc57616a6c64d0f167146eb90eefd3e669904ed575b265b1c6046431393ad4a21103920c2fca42e296627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab730430bce917c6b24061e66ad4d352cadf8e768babf81233a3341a669d98edf4d4e26cfd303f0a40ae577675a7fd6a4a5564921bd2937d03d661718141b1b4"} build_url:https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010889 build_url: 'https://queue.taskcluster.net/v1/task/b-mWmQw1RPS7YP5UQtF_8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ee9604cadbf3b7bd073018ff402d2cad93219acc012ed14942da78a01363a2c5bc4fe734f4c65375a3d5aec25099af10d9b0ee20267381d1dbd21fe0d372fb1f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b40dca17836c6bf5f838ac64bf9f562e41b72e842bdc57616a6c64d0f167146eb90eefd3e669904ed575b265b1c6046431393ad4a21103920c2fca42e296627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab730430bce917c6b24061e66ad4d352cadf8e768babf81233a3341a669d98edf4d4e26cfd303f0a40ae577675a7fd6a4a5564921bd2937d03d661718141b1b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 04:08:30.442906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:08:30.443241) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cq0U9H7FiJ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UMhP7If7vP/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004586 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:08:30.491844) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 04:08:30.492149) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 04:08:30.492575) ========= ========= Total master_lag: 0.16 =========