builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0235 starttime: 1464319093.69 results: success (0) buildid: 20160526183855 builduid: af71300b1d844a9e84e63549d2063e2d revision: ef5addc3db7d60f63d9880da296f6eebbc160535 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:13.685911) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:13.686880) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-05-26 20:18:13.687614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-05-26 20:18:16.224322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:16.224692) ========= 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.njahb95NxW/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.yiF5S0iUo4/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-26 20:18:16-- 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% 143M=0s 2016-05-26 20:18:16 (143 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.475095 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:16.784606) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:16.784941) ========= 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.njahb95NxW/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.yiF5S0iUo4/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.020580 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:16.832754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:16.833099) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ef5addc3db7d60f63d9880da296f6eebbc160535 --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 ef5addc3db7d60f63d9880da296f6eebbc160535 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.njahb95NxW/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.yiF5S0iUo4/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-26 20:18:16,886 truncating revision to first 12 chars 2016-05-26 20:18:16,886 Setting DEBUG logging. 2016-05-26 20:18:16,886 attempt 1/10 2016-05-26 20:18:16,886 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ef5addc3db7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 20:18:17,563 unpacking tar archive at: fx-team-ef5addc3db7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.941740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:17.794126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:17.794443) ========= script_repo_revision: ef5addc3db7d60f63d9880da296f6eebbc160535 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:17.794836) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:17.795098) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 20:18:17.811608) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-26 20:18:17.811920) ========= /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.njahb95NxW/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.yiF5S0iUo4/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:18:17 INFO - MultiFileLogger online at 20160526 20:18:17 in /builds/slave/test 20:18:17 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 20:18:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:18:17 INFO - {'append_to_log': False, 20:18:17 INFO - 'base_work_dir': '/builds/slave/test', 20:18:17 INFO - 'blob_upload_branch': 'Fx-Team', 20:18:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:18:17 INFO - 'branch': 'Fx-Team', 20:18:17 INFO - 'buildbot_json_path': 'buildprops.json', 20:18:17 INFO - 'config_files': ('talos/mac_config.py',), 20:18:17 INFO - 'default_actions': ('clobber', 20:18:17 INFO - 'read-buildbot-config', 20:18:17 INFO - 'download-and-extract', 20:18:17 INFO - 'populate-webroot', 20:18:17 INFO - 'create-virtualenv', 20:18:17 INFO - 'install', 20:18:17 INFO - 'run-tests'), 20:18:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:18:17 INFO - 'download_minidump_stackwalk': True, 20:18:17 INFO - 'download_symbols': 'ondemand', 20:18:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:18:17 INFO - 'tooltool.py': '/tools/tooltool.py', 20:18:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:18:17 INFO - '/tools/misc-python/virtualenv.py')}, 20:18:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:18:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:18:17 INFO - 'installer_path': 'installer.exe', 20:18:17 INFO - 'log_level': 'info', 20:18:17 INFO - 'log_name': 'talos', 20:18:17 INFO - 'log_to_console': True, 20:18:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:18:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:18:17 INFO - 'opt_config_files': (), 20:18:17 INFO - 'pip_index': False, 20:18:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:18:17 INFO - 'cmd': ('bash', 20:18:17 INFO - '-c', 20:18:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:18:17 INFO - 'enabled': True, 20:18:17 INFO - 'halt_on_failure': False, 20:18:17 INFO - 'name': 'check_screen_resolution'},), 20:18:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:18:17 INFO - 'cmd': ('bash', 20:18:17 INFO - '-c', 20:18:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:18:17 INFO - 'enabled': True, 20:18:17 INFO - 'halt_on_failure': False, 20:18:17 INFO - 'name': 'check_screen_resolution'},), 20:18:17 INFO - 'run_cmd_checks_enabled': True, 20:18:17 INFO - 'sps_profile': False, 20:18:17 INFO - 'sps_profile_interval': 0, 20:18:17 INFO - 'suite': 'dromaeojs', 20:18:17 INFO - 'system_bits': '32', 20:18:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:18:17 INFO - 'title': 't-yosemite-r7-0235', 20:18:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:18:17 INFO - 'use_talos_json': True, 20:18:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:18:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:18:17 INFO - 'work_dir': 'build'} 20:18:17 INFO - ##### 20:18:17 INFO - ##### Running clobber step. 20:18:17 INFO - ##### 20:18:17 INFO - Running pre-action listener: _resource_record_pre_action 20:18:17 INFO - Running main action method: clobber 20:18:17 INFO - rmtree: /builds/slave/test/build 20:18:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:18:18 INFO - Running post-action listener: _resource_record_post_action 20:18:18 INFO - ##### 20:18:18 INFO - ##### Finished clobber step (success) 20:18:18 INFO - ##### 20:18:18 INFO - ##### 20:18:18 INFO - ##### Running read-buildbot-config step. 20:18:18 INFO - ##### 20:18:18 INFO - Running pre-action listener: _resource_record_pre_action 20:18:18 INFO - Running main action method: read_buildbot_config 20:18:18 INFO - Using buildbot properties: 20:18:18 INFO - { 20:18:18 INFO - "project": "", 20:18:18 INFO - "product": "firefox", 20:18:18 INFO - "script_repo_revision": "production", 20:18:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:18:18 INFO - "repository": "", 20:18:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:18:18 INFO - "buildid": "20160526183855", 20:18:18 INFO - "pgo_build": "False", 20:18:18 INFO - "basedir": "/builds/slave/test", 20:18:18 INFO - "buildnumber": 34, 20:18:18 INFO - "slavename": "t-yosemite-r7-0235", 20:18:18 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 20:18:18 INFO - "platform": "yosemite_r7", 20:18:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:18:18 INFO - "branch": "fx-team", 20:18:18 INFO - "repo_path": "integration/fx-team", 20:18:18 INFO - "revision": "ef5addc3db7d60f63d9880da296f6eebbc160535", 20:18:18 INFO - "stage_platform": "macosx64", 20:18:18 INFO - "builduid": "af71300b1d844a9e84e63549d2063e2d", 20:18:18 INFO - "slavebuilddir": "test" 20:18:18 INFO - } 20:18:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:18:18 INFO - Running post-action listener: _resource_record_post_action 20:18:18 INFO - ##### 20:18:18 INFO - ##### Finished read-buildbot-config step (success) 20:18:18 INFO - ##### 20:18:18 INFO - ##### 20:18:18 INFO - ##### Running download-and-extract step. 20:18:18 INFO - ##### 20:18:18 INFO - Running pre-action listener: _resource_record_pre_action 20:18:18 INFO - Running main action method: download_and_extract 20:18:18 INFO - mkdir: /builds/slave/test/build/tests 20:18: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')]} 20:18:18 INFO - https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:18:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:18:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:18:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:25 INFO - Downloaded 1199 bytes. 20:18:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:18:25 INFO - Using the following test package requirements: 20:18:25 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:18:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:18:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:18:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'jsshell-mac.zip'], 20:18:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:18:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:18:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:18:25 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:18:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:18:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:18:25 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:18:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:18: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')]} 20:18:25 INFO - https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:18:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:18:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:18:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:26 INFO - Downloaded 18622003 bytes. 20:18: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'] 20:18: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 20:18:29 INFO - Return code: 0 20:18:29 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 20:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:29 INFO - https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:18:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:18:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:18:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:32 INFO - Downloaded 18622003 bytes. 20:18:32 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'] 20:18:32 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 20:18:36 INFO - Return code: 0 20:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:36 INFO - https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:18:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:18:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:18:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:37 INFO - Downloaded 10834034 bytes. 20:18:37 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'] 20:18:37 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 20:18:37 INFO - Return code: 0 20:18:37 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')]} 20:18:37 INFO - https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:18:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:18:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:18:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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 20:18:40 INFO - Downloaded 110433953 bytes. 20:18:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:18:40 INFO - mkdir: /builds/slave/test/properties 20:18:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:18:40 INFO - Writing to file /builds/slave/test/properties/build_url 20:18:40 INFO - Contents: 20:18:40 INFO - build_url:https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:18:42 INFO - Running post-action listener: _resource_record_post_action 20:18:42 INFO - Running post-action listener: set_extra_try_arguments 20:18:42 INFO - ##### 20:18:42 INFO - ##### Finished download-and-extract step (success) 20:18:42 INFO - ##### 20:18:42 INFO - ##### 20:18:42 INFO - ##### Running populate-webroot step. 20:18:42 INFO - ##### 20:18:42 INFO - Running pre-action listener: _resource_record_pre_action 20:18:42 INFO - Running main action method: populate_webroot 20:18:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:18:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:18:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:18:42 INFO - '--tppagecycles', 20:18:42 INFO - '7'], 20:18:42 INFO - 'tests': ['tsvgm']}}, 20:18:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:18:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:18:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:18:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:18:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:18:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:18:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['damp', 'tps']}, 20:18:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'tests': ['damp', 'tps']}, 20:18:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['dromaeo_dom']}, 20:18:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:18:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['a11yr', 20:18:42 INFO - 'ts_paint', 20:18:42 INFO - 'tpaint', 20:18:42 INFO - 'sessionrestore', 20:18:42 INFO - 'sessionrestore_no_auto_restore', 20:18:42 INFO - 'tabpaint']}, 20:18:42 INFO - 'other-e10s': {'tests': ['a11yr', 20:18:42 INFO - 'ts_paint', 20:18:42 INFO - 'tpaint', 20:18:42 INFO - 'sessionrestore', 20:18:42 INFO - 'sessionrestore_no_auto_restore', 20:18:42 INFO - 'tabpaint']}, 20:18:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:18:42 INFO - 'ts_paint', 20:18:42 INFO - 'tpaint', 20:18:42 INFO - 'sessionrestore', 20:18:42 INFO - 'sessionrestore_no_auto_restore', 20:18:42 INFO - 'tabpaint']}, 20:18:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:18:42 INFO - 'ts_paint', 20:18:42 INFO - 'tpaint', 20:18:42 INFO - 'sessionrestore', 20:18:42 INFO - 'sessionrestore_no_auto_restore', 20:18:42 INFO - 'tabpaint']}, 20:18:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['a11yr', 20:18:42 INFO - 'ts_paint', 20:18:42 INFO - 'tpaint', 20:18:42 INFO - 'sessionrestore', 20:18:42 INFO - 'sessionrestore_no_auto_restore', 20:18:42 INFO - 'tabpaint']}, 20:18:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['a11yr', 20:18:42 INFO - 'ts_paint', 20:18:42 INFO - 'tpaint', 20:18:42 INFO - 'sessionrestore', 20:18:42 INFO - 'sessionrestore_no_auto_restore', 20:18:42 INFO - 'tabpaint']}, 20:18:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['tsvgx', 20:18:42 INFO - 'tsvgr_opacity', 20:18:42 INFO - 'tart', 20:18:42 INFO - 'tscrollx', 20:18:42 INFO - 'cart']}, 20:18:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:18:42 INFO - 'tsvgr_opacity', 20:18:42 INFO - 'tart', 20:18:42 INFO - 'tscrollx', 20:18:42 INFO - 'cart']}, 20:18:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'talos_options': ['--disable-e10s'], 20:18:42 INFO - 'tests': ['tp5o']}, 20:18:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'tests': ['tp5o']}, 20:18:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'talos_options': ['--disable-e10s', 20:18:42 INFO - '--xperf_path', 20:18:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:42 INFO - 'tests': ['tp5n']}, 20:18:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:42 INFO - 'talos_options': ['--xperf_path', 20:18:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:42 INFO - 'tests': ['tp5n']}}, 20:18:42 INFO - 'talos.zip': {'path': '', 20:18:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:18:42 INFO - Running post-action listener: _resource_record_post_action 20:18:42 INFO - ##### 20:18:42 INFO - ##### Finished populate-webroot step (success) 20:18:42 INFO - ##### 20:18:42 INFO - ##### 20:18:42 INFO - ##### Running create-virtualenv step. 20:18:42 INFO - ##### 20:18:42 INFO - Running pre-action listener: _resource_record_pre_action 20:18:42 INFO - Running main action method: create_virtualenv 20:18:42 INFO - Creating virtualenv /builds/slave/test/build/venv 20:18:42 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 20:18:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:18:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:18:42 INFO - Using real prefix '/tools/python27' 20:18:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:18:42 INFO - Installing distribute.............................................................................................................................................................................................done. 20:18:45 INFO - Installing pip.................done. 20:18:45 INFO - Return code: 0 20:18:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:18: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')]} 20:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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')]} 20:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:18: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', 'pip>=1.5'] in /builds/slave/test/build 20:18: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 pip>=1.5 20:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:45 INFO - 'HOME': '/Users/cltbld', 20:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:45 INFO - 'LOGNAME': 'cltbld', 20:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:45 INFO - 'MOZ_NO_REMOTE': '1', 20:18:45 INFO - 'NO_EM_RESTART': '1', 20:18:45 INFO - 'PAGER': '/bin/cat', 20:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:45 INFO - 'PWD': '/builds/slave/test', 20:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:45 INFO - 'SHELL': '/bin/bash', 20:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:18:45 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:45 INFO - 'USER': 'cltbld', 20:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:45 INFO - 'XPC_FLAGS': '0x0', 20:18:45 INFO - 'XPC_SERVICE_NAME': '0', 20:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:46 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 20:18:46 INFO - Cleaning up... 20:18:46 INFO - Return code: 0 20:18:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:46 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:18:46 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 20:18:46 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 20:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:46 INFO - 'HOME': '/Users/cltbld', 20:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:46 INFO - 'LOGNAME': 'cltbld', 20:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:46 INFO - 'MOZ_NO_REMOTE': '1', 20:18:46 INFO - 'NO_EM_RESTART': '1', 20:18:46 INFO - 'PAGER': '/bin/cat', 20:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:46 INFO - 'PWD': '/builds/slave/test', 20:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:46 INFO - 'SHELL': '/bin/bash', 20:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:18:46 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:46 INFO - 'USER': 'cltbld', 20:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:46 INFO - 'XPC_FLAGS': '0x0', 20:18:46 INFO - 'XPC_SERVICE_NAME': '0', 20:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:46 INFO - Downloading/unpacking psutil>=3.1.1 20:18: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 20:18: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 20:18: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 20:18: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 20:18: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 20:18: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 20:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:18:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:18:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:18:49 INFO - Installing collected packages: psutil 20:18:49 INFO - Running setup.py install for psutil 20:18:49 INFO - building 'psutil._psutil_osx' extension 20:18:49 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 20:18:50 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 20:18:50 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 20:18:50 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 20:18:50 INFO - building 'psutil._psutil_posix' extension 20:18:50 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 20:18:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:18:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:18:50 INFO - ^ 20:18:50 INFO - 1 warning generated. 20:18:50 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 20:18:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:18:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:18:50 INFO - Successfully installed psutil 20:18:50 INFO - Cleaning up... 20:18:50 INFO - Return code: 0 20:18:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:50 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:18:50 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 20:18:50 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 20:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:50 INFO - 'HOME': '/Users/cltbld', 20:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:50 INFO - 'LOGNAME': 'cltbld', 20:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:50 INFO - 'MOZ_NO_REMOTE': '1', 20:18:50 INFO - 'NO_EM_RESTART': '1', 20:18:50 INFO - 'PAGER': '/bin/cat', 20:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:50 INFO - 'PWD': '/builds/slave/test', 20:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:50 INFO - 'SHELL': '/bin/bash', 20:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:18:50 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:50 INFO - 'USER': 'cltbld', 20:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:50 INFO - 'XPC_FLAGS': '0x0', 20:18:50 INFO - 'XPC_SERVICE_NAME': '0', 20:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:18:50 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 20:18:50 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 20:18:50 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 20:18:50 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 20:18:50 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 20:18:50 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 20:18:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:18:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:18:53 INFO - Installing collected packages: mozsystemmonitor 20:18:53 INFO - Running setup.py install for mozsystemmonitor 20:18:53 INFO - Successfully installed mozsystemmonitor 20:18:53 INFO - Cleaning up... 20:18:53 INFO - Return code: 0 20:18:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:18: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')]} 20:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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')]} 20:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:53 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:18:53 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 20:18:53 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 20:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:53 INFO - 'HOME': '/Users/cltbld', 20:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:53 INFO - 'LOGNAME': 'cltbld', 20:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:53 INFO - 'MOZ_NO_REMOTE': '1', 20:18:53 INFO - 'NO_EM_RESTART': '1', 20:18:53 INFO - 'PAGER': '/bin/cat', 20:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:53 INFO - 'PWD': '/builds/slave/test', 20:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:53 INFO - 'SHELL': '/bin/bash', 20:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:18:53 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:53 INFO - 'USER': 'cltbld', 20:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:53 INFO - 'XPC_FLAGS': '0x0', 20:18:53 INFO - 'XPC_SERVICE_NAME': '0', 20:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:53 INFO - Downloading/unpacking blobuploader==1.2.4 20:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:56 INFO - Downloading blobuploader-1.2.4.tar.gz 20:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:18:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:18:56 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 20:18:56 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 20:18:56 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 20:18:56 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 20:18:56 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 20:18:56 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 20:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:18:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:57 INFO - Downloading docopt-0.6.1.tar.gz 20:18:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:18:57 INFO - Installing collected packages: blobuploader, requests, docopt 20:18:57 INFO - Running setup.py install for blobuploader 20:18:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:18:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:18:57 INFO - Running setup.py install for requests 20:18:58 INFO - Running setup.py install for docopt 20:18:58 INFO - Successfully installed blobuploader requests docopt 20:18:58 INFO - Cleaning up... 20:18:58 INFO - Return code: 0 20:18:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:18: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')]} 20:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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')]} 20:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:58 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:18:58 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 20:18:58 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 20:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:58 INFO - 'HOME': '/Users/cltbld', 20:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:58 INFO - 'LOGNAME': 'cltbld', 20:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:58 INFO - 'MOZ_NO_REMOTE': '1', 20:18:58 INFO - 'NO_EM_RESTART': '1', 20:18:58 INFO - 'PAGER': '/bin/cat', 20:18:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:58 INFO - 'PWD': '/builds/slave/test', 20:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:58 INFO - 'SHELL': '/bin/bash', 20:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:18:58 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:58 INFO - 'USER': 'cltbld', 20:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:58 INFO - 'XPC_FLAGS': '0x0', 20:18:58 INFO - 'XPC_SERVICE_NAME': '0', 20:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:18:58 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-imyMBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:18:58 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-EZDn_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:18:58 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-jckv_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:18:58 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-XQXFIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-FCOmXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-hFOJpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-MF29Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Yf81zH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-XfsLaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Lxl6eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-iqPN9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-TsjNoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:18:59 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-bee9Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:19:00 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-2Z040m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:00 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-FyEiIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:19:00 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-rGgFEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:19:00 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-OYnEnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:19:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:19:00 INFO - Running setup.py install for manifestparser 20:19:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:19:00 INFO - Running setup.py install for mozcrash 20:19:00 INFO - Running setup.py install for mozdebug 20:19:00 INFO - Running setup.py install for mozdevice 20:19:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Running setup.py install for mozfile 20:19:01 INFO - Running setup.py install for mozhttpd 20:19:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Running setup.py install for mozinfo 20:19:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Running setup.py install for mozInstall 20:19:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Running setup.py install for mozleak 20:19:01 INFO - Running setup.py install for mozlog 20:19:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:19:01 INFO - Running setup.py install for moznetwork 20:19:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:19:02 INFO - Running setup.py install for mozprocess 20:19:02 INFO - Running setup.py install for mozprofile 20:19:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:19:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:19:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:19:02 INFO - Running setup.py install for mozrunner 20:19:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:19:02 INFO - Running setup.py install for mozscreenshot 20:19:02 INFO - Running setup.py install for moztest 20:19:02 INFO - Running setup.py install for mozversion 20:19:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:19:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:19:03 INFO - Cleaning up... 20:19:03 INFO - Return code: 0 20:19:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19: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')]} 20:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19: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')]} 20:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:03 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:19:03 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 20:19:03 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 20:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:03 INFO - 'HOME': '/Users/cltbld', 20:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:03 INFO - 'LOGNAME': 'cltbld', 20:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:03 INFO - 'MOZ_NO_REMOTE': '1', 20:19:03 INFO - 'NO_EM_RESTART': '1', 20:19:03 INFO - 'PAGER': '/bin/cat', 20:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:03 INFO - 'PWD': '/builds/slave/test', 20:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:03 INFO - 'SHELL': '/bin/bash', 20:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:19:03 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:03 INFO - 'USER': 'cltbld', 20:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:03 INFO - 'XPC_FLAGS': '0x0', 20:19:03 INFO - 'XPC_SERVICE_NAME': '0', 20:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-gp0g_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:19:03 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)) 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-q9XQrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:19:03 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)) 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-1q43Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:19:03 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)) 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-f90Q1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:19:03 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)) 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-wt03PZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:19:03 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)) 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ZsCbhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:19:03 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)) 20:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:19:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-s2BRs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-U0F_mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-9xbKJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-s69LRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:19:04 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)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-s58Py9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Lpc7mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:19:04 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)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-zvCpue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:19:04 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)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-214GdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:19:04 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)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-vrLISX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:04 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)) 20:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:19:04 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-mzgSrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:19:05 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-9sdVHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:19:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:07 INFO - Downloading blessings-1.6.tar.gz 20:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:19:07 INFO - Installing collected packages: blessings 20:19:07 INFO - Running setup.py install for blessings 20:19:07 INFO - Successfully installed blessings 20:19:07 INFO - Cleaning up... 20:19:07 INFO - Return code: 0 20:19:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:19:08 INFO - Reading from file tmpfile_stdout 20:19:08 INFO - Current package versions: 20:19:08 INFO - blessings == 1.6 20:19:08 INFO - blobuploader == 1.2.4 20:19:08 INFO - docopt == 0.6.1 20:19:08 INFO - manifestparser == 1.1 20:19:08 INFO - mozInstall == 1.12 20:19:08 INFO - mozcrash == 0.17 20:19:08 INFO - mozdebug == 0.1 20:19:08 INFO - mozdevice == 0.48 20:19:08 INFO - mozfile == 1.2 20:19:08 INFO - mozhttpd == 0.7 20:19:08 INFO - mozinfo == 0.9 20:19:08 INFO - mozleak == 0.1 20:19:08 INFO - mozlog == 3.2 20:19:08 INFO - moznetwork == 0.27 20:19:08 INFO - mozprocess == 0.22 20:19:08 INFO - mozprofile == 0.28 20:19:08 INFO - mozrunner == 6.11 20:19:08 INFO - mozscreenshot == 0.1 20:19:08 INFO - mozsystemmonitor == 0.3 20:19:08 INFO - moztest == 0.7 20:19:08 INFO - mozversion == 1.4 20:19:08 INFO - psutil == 3.1.1 20:19:08 INFO - requests == 1.2.3 20:19:08 INFO - wsgiref == 0.1.2 20:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:08 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:19:08 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 20:19:08 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 20:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:08 INFO - 'HOME': '/Users/cltbld', 20:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:08 INFO - 'LOGNAME': 'cltbld', 20:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:08 INFO - 'MOZ_NO_REMOTE': '1', 20:19:08 INFO - 'NO_EM_RESTART': '1', 20:19:08 INFO - 'PAGER': '/bin/cat', 20:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:08 INFO - 'PWD': '/builds/slave/test', 20:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:08 INFO - 'SHELL': '/bin/bash', 20:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:19:08 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:08 INFO - 'USER': 'cltbld', 20:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:08 INFO - 'XPC_FLAGS': '0x0', 20:19:08 INFO - 'XPC_SERVICE_NAME': '0', 20:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 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)) 20:19:08 INFO - Cleaning up... 20:19:08 INFO - Return code: 0 20:19:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:08 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:19:08 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 20:19:08 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 20:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:08 INFO - 'HOME': '/Users/cltbld', 20:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:08 INFO - 'LOGNAME': 'cltbld', 20:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:08 INFO - 'MOZ_NO_REMOTE': '1', 20:19:08 INFO - 'NO_EM_RESTART': '1', 20:19:08 INFO - 'PAGER': '/bin/cat', 20:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:08 INFO - 'PWD': '/builds/slave/test', 20:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:08 INFO - 'SHELL': '/bin/bash', 20:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:19:08 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:08 INFO - 'USER': 'cltbld', 20:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:08 INFO - 'XPC_FLAGS': '0x0', 20:19:08 INFO - 'XPC_SERVICE_NAME': '0', 20:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:08 INFO - Downloading/unpacking jsonschema 20:19:08 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 20:19:08 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 20:19:08 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 20:19:08 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 20:19:08 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 20:19:08 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 20:19:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:19:11 INFO - Downloading/unpacking functools32 (from jsonschema) 20:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:11 INFO - Downloading functools32-3.2.3-2.tar.gz 20:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:19:11 INFO - warning: no files found matching '*.txt' 20:19:11 INFO - no previously-included directories found matching 'build' 20:19:11 INFO - no previously-included directories found matching 'dist' 20:19:11 INFO - no previously-included directories found matching '.git*' 20:19:11 INFO - Installing collected packages: jsonschema, functools32 20:19:11 INFO - Running setup.py install for functools32 20:19:11 INFO - warning: no files found matching '*.txt' 20:19:11 INFO - no previously-included directories found matching 'build' 20:19:11 INFO - no previously-included directories found matching 'dist' 20:19:11 INFO - no previously-included directories found matching '.git*' 20:19:11 INFO - Successfully installed jsonschema functools32 20:19:11 INFO - Cleaning up... 20:19:12 INFO - Return code: 0 20:19:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:12 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 0x1031e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3ac74e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, '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.njahb95NxW/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.yiF5S0iUo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 20:19:12 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 20:19:12 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 20:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:12 INFO - 'HOME': '/Users/cltbld', 20:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:12 INFO - 'LOGNAME': 'cltbld', 20:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:12 INFO - 'MOZ_NO_REMOTE': '1', 20:19:12 INFO - 'NO_EM_RESTART': '1', 20:19:12 INFO - 'PAGER': '/bin/cat', 20:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:12 INFO - 'PWD': '/builds/slave/test', 20:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:12 INFO - 'SHELL': '/bin/bash', 20:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:19:12 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:12 INFO - 'USER': 'cltbld', 20:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:12 INFO - 'XPC_FLAGS': '0x0', 20:19:12 INFO - 'XPC_SERVICE_NAME': '0', 20:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:12 INFO - Downloading/unpacking flake8 20:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:19:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:19:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 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 20:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:19:15 INFO - no previously-included directories found matching 'docs/_build' 20:19:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:19:16 INFO - Running setup.py install for pep8 20:19:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:19:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:19:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:19:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:19:16 INFO - no previously-included directories found matching 'docs/_build' 20:19:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:19:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:19:16 INFO - Cleaning up... 20:19:16 INFO - Return code: 0 20:19:16 INFO - Running post-action listener: _resource_record_post_action 20:19:16 INFO - Running post-action listener: _start_resource_monitoring 20:19:16 INFO - Starting resource monitoring. 20:19:16 INFO - ##### 20:19:16 INFO - ##### Finished create-virtualenv step (success) 20:19:16 INFO - ##### 20:19:16 INFO - ##### 20:19:16 INFO - ##### Running install step. 20:19:16 INFO - ##### 20:19:16 INFO - Running pre-action listener: _resource_record_pre_action 20:19:16 INFO - Running main action method: install 20:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:19:16 INFO - Reading from file tmpfile_stdout 20:19:16 INFO - Detecting whether we're running mozinstall >=1.0... 20:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:19:16 INFO - Reading from file tmpfile_stdout 20:19:16 INFO - Output received: 20:19:16 INFO - Usage: mozinstall [options] installer 20:19:16 INFO - Options: 20:19:16 INFO - -h, --help show this help message and exit 20:19:16 INFO - -d DEST, --destination=DEST 20:19:16 INFO - Directory to install application into. [default: 20:19:16 INFO - "/builds/slave/test"] 20:19:16 INFO - --app=APP Application being installed. [default: firefox] 20:19:16 INFO - mkdir: /builds/slave/test/build/application 20:19:16 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'] 20:19:16 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 20:19:50 INFO - Reading from file tmpfile_stdout 20:19:50 INFO - Output received: 20:19:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:19:50 INFO - Running post-action listener: _resource_record_post_action 20:19:50 INFO - ##### 20:19:50 INFO - ##### Finished install step (success) 20:19:50 INFO - ##### 20:19:50 INFO - ##### 20:19:50 INFO - ##### Running run-tests step. 20:19:50 INFO - ##### 20:19:50 INFO - Running pre-action listener: _resource_record_pre_action 20:19:50 INFO - Running main action method: run_tests 20:19:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:19:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:19:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:19:50 INFO - 2016-05-26 20:19:50.176 screenresolution[3343:37580] starting screenresolution argv=screenresolution get 20:19:50 INFO - 2016-05-26 20:19:50.179 screenresolution[3343:37580] Display 0: 1600x1200x32@60 20:19:50 INFO - 2016-05-26 20:19:50.185 screenresolution[3344:37582] starting screenresolution argv=screenresolution list 20:19:50 INFO - Available Modes on Display 0 20:19:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:19:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:19:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:19:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:19:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:19:50 INFO - Intel Iris: 20:19:50 INFO - Chipset Model: Intel Iris 20:19:50 INFO - Type: GPU 20:19:50 INFO - Bus: Built-In 20:19:50 INFO - VRAM (Dynamic, Max): 1536 MB 20:19:50 INFO - Vendor: Intel (0x8086) 20:19:50 INFO - Device ID: 0x0a2e 20:19:50 INFO - Revision ID: 0x0009 20:19:50 INFO - Displays: 20:19:50 INFO - Display: 20:19:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:19:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:19:50 INFO - Display Serial Number: 1600x1200 60 20:19:50 INFO - Main Display: Yes 20:19:50 INFO - Mirror: Off 20:19:50 INFO - Online: Yes 20:19:50 INFO - Rotation: Supported 20:19:50 INFO - Return code: 0 20:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:19:50 INFO - Python 2.7.3 20:19:50 INFO - Return code: 0 20:19:50 INFO - grabbing minidump binary from tooltool 20:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:50 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 0x10308dd30>, '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 0x102eb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103084b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:19:50 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 20:19:50 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 20:19:50 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 20:19:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:19:50 INFO - Return code: 0 20:19:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:19:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:19:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:19:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:19:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:19:51 INFO - Return code: 0 20:19:51 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/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 20:19:51 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/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0235 --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 20:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.njahb95NxW/Render', 20:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:51 INFO - 'HOME': '/Users/cltbld', 20:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:51 INFO - 'LOGNAME': 'cltbld', 20:19:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:19:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:51 INFO - 'MOZ_NO_REMOTE': '1', 20:19:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:19:51 INFO - 'NO_EM_RESTART': '1', 20:19:51 INFO - 'PAGER': '/bin/cat', 20:19:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:51 INFO - 'PWD': '/builds/slave/test', 20:19:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:51 INFO - 'SHELL': '/bin/bash', 20:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yiF5S0iUo4/Listeners', 20:19:51 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 20:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:51 INFO - 'USER': 'cltbld', 20:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:51 INFO - 'XPC_FLAGS': '0x0', 20:19:51 INFO - 'XPC_SERVICE_NAME': '0', 20:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:51 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/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 20:19:51 INFO - mozversion application_buildid: 20160526183855 20:19:51 INFO - mozversion application_changeset: ef5addc3db7d60f63d9880da296f6eebbc160535 20:19:51 INFO - mozversion application_display_name: Nightly 20:19:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:19:51 INFO - mozversion application_name: Firefox 20:19:51 INFO - mozversion application_remotingname: firefox 20:19:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:19:51 INFO - mozversion application_vendor: Mozilla 20:19:51 INFO - mozversion application_version: 49.0a1 20:19:51 INFO - mozversion platform_buildid: 20160526183855 20:19:51 INFO - mozversion platform_changeset: ef5addc3db7d60f63d9880da296f6eebbc160535 20:19:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:19:51 INFO - mozversion platform_version: 49.0a1 20:19:51 INFO - using testdate: 1464319191 20:19:51 INFO - actual date: 1464319191 20:19:51 INFO - starting webserver on 'localhost:61670' 20:19:51 INFO - SUITE-START | Running 2 tests 20:19:51 INFO - TEST-START | dromaeo_css 20:19:51 INFO - operating with platform_type : mac_ 20:19:51 INFO - Initialising browser for dromaeo_css test... 20:19:51 INFO - TEST-INFO | started process 3362 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmppEHRyc/profile http://localhost:61670/getInfo.html) 20:19:54 INFO - PROCESS | 3362 | __metrics Screen width/height:1600/1200 20:19:54 INFO - PROCESS | 3362 | colorDepth:24 20:19:54 INFO - PROCESS | 3362 | Browser inner width/height: 1024/689 20:19:54 INFO - PROCESS | 3362 | __metrics 20:19:54 INFO - PROCESS | 3362 | 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" 20:19:54 ERROR - PROCESS | 3362 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:19:54 INFO - TEST-INFO | 3362: exit 0 20:19:54 INFO - Browser initialized. 20:19:54 INFO - Running cycle 1/1 for dromaeo_css test... 20:19:54 INFO - TEST-INFO | started process 3364 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmppEHRyc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:20:06 INFO - PROCESS | 3364 | RSS: Main: 241369088 20:20:06 INFO - PROCESS | 3364 | 20:20:58 INFO - PROCESS | 3364 | 1464319258236 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] 20:23:08 INFO - PROCESS | 3364 | Cycle 1(1): loaded http://localhost:61670/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61670/tests/dromaeo/cssquery-ext.html) 20:23:09 INFO - PROCESS | 3364 | RSS: Main: 367575040 20:23:09 INFO - PROCESS | 3364 | 20:26:13 INFO - PROCESS | 3364 | Cycle 1(1): loaded http://localhost:61670/tests/dromaeo/cssquery-ext.html (next: http://localhost:61670/tests/dromaeo/cssquery-jquery.html) 20:26:13 INFO - PROCESS | 3364 | RSS: Main: 373485568 20:26:13 INFO - PROCESS | 3364 | 20:29:16 INFO - PROCESS | 3364 | Cycle 1(1): loaded http://localhost:61670/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61670/tests/dromaeo/cssquery-mootools.html) 20:29:16 INFO - PROCESS | 3364 | RSS: Main: 372703232 20:29:16 INFO - PROCESS | 3364 | 20:32:18 INFO - PROCESS | 3364 | Cycle 1(1): loaded http://localhost:61670/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61670/tests/dromaeo/cssquery-prototype.html) 20:32:19 INFO - PROCESS | 3364 | RSS: Main: 372563968 20:32:19 INFO - PROCESS | 3364 | 20:35:22 INFO - PROCESS | 3364 | Cycle 1(1): loaded http://localhost:61670/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61670/tests/dromaeo/cssquery-yui.html) 20:35:23 INFO - PROCESS | 3364 | RSS: Main: 346808320 20:35:23 INFO - PROCESS | 3364 | 20:38:26 INFO - PROCESS | 3364 | Cycle 1(1): loaded http://localhost:61670/tests/dromaeo/cssquery-yui.html (next: http://localhost:61670/tests/dromaeo/cssquery-dojo.html) 20:38:27 INFO - PROCESS | 3364 | RSS: Main: 375386112 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | __start_tp_report 20:38:27 INFO - PROCESS | 3364 | _x_x_mozilla_page_load 20:38:27 INFO - PROCESS | 3364 | _x_x_mozilla_page_load_details 20:38:27 INFO - PROCESS | 3364 | |i|pagename|runs| 20:38:27 INFO - PROCESS | 3364 | |0;dojo.html;3373;3389.8135602541865;3406.6593340665922;3413.590369155702;3467.5838899332066;5546.251256080428;5603.859903502414;5648.887022259562;5683.573731970098;5720.427957204282;5634.04221282382;5663.292088488939;5754.079347304432;5767.625104368213;5802.448767367094;5051.419086805009;5069.2649565812935;5128.358955130609;5162.174052151638;5233.764480598363;5215.6609820361555;5217.34783152106;5223.817166399177;5250.3699556053325;5275.235090911816;5075.187969924831;5106.872328191825;5117.283580298761;5141.871453213662;5165.845024649267;5077.974610126926;5100;5125.282460455539;5145.536901678829;5153.252778347156;5099.15863882459;5137.51193636604;5150;5166.43169251382;5220.29526013987;4613;5455.263539422207;5542.002439560877;5578.888422231573;5620.988222120017;6219.40915613016;6225.501959843202;6225.875482490324;6237.563370564062;6251.156093927307;6260.655663938482;6315.336889626615;6316.178896743394;6321.557490975633;6322.620642761403;5651.095824668033;5706.600537962344;5774.68239246837;5778.248827652378;5817.0692689169955;5281.551068159172;6286.182796236551;6298.338674439209;6299.023651334051;6328.620282782956;6113.38866113394;6115.805478700918;6122.418370254819;6136.134235431649;6138.631682099089;6103.883205879467;6157.291911430153;6189.876202475925;6197.690115494297;6264.2796078451565;6121.265448146251;6133;6156.322804491502;6189.412005859436;6211.409916057967;6045.848853778691;6222.471089957405;6240.469560087444;6241.937580624226;6251.28110267316;4820.927686084711;4838;4870.269459581091;4900.240462728283;4903.877403064952;4370.650347972155;4389.6927215095275;4391.7584532851;4394.318880573516;4437.156940181355;4670.626349892001;5568.554515638739;5680.715964201773;5730.512906402919;5745;6136.304638670978;6136.67225816756;6191.532741664682;6228.564000520011;6299.874002519924;5731.083026715705;5731.656100633975;5742.397048309951;5754.992876246723;5799.014167591436;5316.282301889196;5346.786128554814;5347.625666203407;5348.973255133699;5355.143177091647;6210.658413787285;6243.656598887014;6263.279722831932;6263.937360626335;6358.982562789932;6203.379662033761;6259.467945224525;6276.340984196594;6284.654344011124;6285.088662144055;6219.222597175353;6325.778597749057;6337.714802833976;6352.968235158794;6359.332270111571;6091.573589848668;6119.929102025492;6124.203853499017;6177.567570270038;6214.533909956681;6260.154879091447;6261.24865016201;6279.466245369194;6282.874342513215;6285.5600107992;5809.709514524341;6061.787837425669;6075.453209211016;6086.847828804315;6102.084687296941;5770.163326317745;5874.970625146847;5980.760769569342;6008.639481631116;6025.698715064317;5850.12248162779;5860.238169037998;5868.237129173351;5877.970610146922;5927.585069045125;5927.644341339534;6052.515798736179;6055.455009049207;6113.29697084841;6133.417325354084;5803.30360356743;5865.501432378125;5871.6183448075735;5955.166276721349;5966.224390829165 20:38:27 INFO - PROCESS | 3364 | |1;ext.html;494.6339708615624;497.22681230686277;514.7477735909406;516.2823675091621;548.9039418101831;29829;31200.84399578002;42195.78902105489;43242.783786081105;44273.33589996152;43405.78297108518;43965.900852478706;46264.76867615657;46328.07343853121;46348.30477542831;8723.345749068845;9649.855252171223;9877.111060004598;9896;9939.20486361093;11841.94079029608;12159.696007599792;12169.513219471211;12327.568535101273;12356.93821530891;11991.520339186422;12088;12097.879021209807;12098.274103553771;12106.81839772404;12031.338276394796;12082.033437324993;12149.210301330386;12150.817737733942;12151.967082797884;11028.558857645685;11145.052670523015;12010.159288849783;12121.212121212093;12150.4532296046;42497.362539561625;42884.356734649;42893.35659965103;42919.35620965688;43043;3120.8595613197463;3260.836958152083;3267.068885367659;3475.6871881530783;3834.9233015339814;29946.55080173799;30073.248168795737;30092.097237082922;30284.24289392783;30299.242518936982;2726.263908744648;2739.4384151248946;2758.434520923206;2767.9861600691866;2830.6461692288462;525001;527915.9999999962;528736;529895;530083;63890;64083.35916640774;64132.35867641357;64139.67930160413;64244;67575;67827;67838.66080669664;67858;67937.3206267931;16758.413455528997;17404.216810243317;17410.564735881704;17439.912800435915;17466;12183.208091474025;12426.316552589513;12459.563915262923;12607.684807879694;12628.747425051448;14491.637709057148;14842.18367989771;15990.840091598935;17639.82360176382;18028.729569056475;6316.557840951181;7172.892406613905;7605;7722.729704460429;7756.30193282596;952.885653721544;1034.1984961654628;1070.5182667799365;1109.7225693576606;1119.3489602835753;30071.066192227765;30263.32216390608;30389.39221215563;30499;30617.846910765304;1028.6267832443623;1040.5109598488698;1045.9738506537396;1126.4367816091954;1135.8296255561568;15466;15597.064176149468;15626.374945002073;15721.056127366495;15815.683686326209;672957;680025;680410;680772;681680;61266;62849;63087.05369419463;63090.68454657697;63263;1323.3251041968472;1363.822703048617;1440.1503113163285;1470.713210923881;1492.2986196487634;24134.87932560326;29383.7061629381;30877;31145.065648030595;31361.52957705682;51720.48279517156;52520.47479525156;53118.468815312895;53152;53252.73373633107;487294;578802;580115;582330;583633;34837.303253935286;40878.1824363517;40904.79547602243;41330.586694133875;41952.16095678131;44485;45012.09975800531;46462.535374647174;48132.27801583049;48260.27609585929;39086.70046294962;39210;39312.410313844746;40058.39912401258;40206.99482512961;29820;38478;38837.64068257598;39337.40993885037;40080;37618.24763504769;37874.242515150094;38855.222895542494;38888.611113888495;39227.019324515975;26201;33475.33049339048;34486;34549.65450345565;34671;24491.877540612182;24853.130140445;25182.74817251877;25476.490470190864;26167.86916065484 20:38:27 INFO - PROCESS | 3364 | |2;jquery.html;385.3410667758133;386.40686546151653;391.70915103438796;392.992140157197;415.2214597629445;13805.283828946396;19267.132979015943;19498.317558885443;20786;20826.479338016557;19754.111065002075;20372.69440958383;20670.37988860333;20794;20832.166713331488;4995.950040499594;5371.274877891474;5374.7850085996515;5397.271368365279;5422.620416570841;5327.2808170897015;5349.919751203735;5437.293151890269;5448.237246785453;5452;5207.791688332462;5325.068113080215;5350.438203988564;5380.300560927074;5394.352677678665;5348.358197016344;5350.8127215547465;5360.865978350534;5377.274068000809;5933.397982544732;5081.5426611605135;5216.608754343401;5260.736963151865;5264.2893209416625;5279.736013199363;13454.730905381837;13797.862021379759;25467.36331591725;25754.48491030169;26475.867620661775;2031.9187232510683;2088.603165398569;2106.4628519727526;2111.7360329958756;2757.531219692637;9839.606415743361;10285.022922822394;10320.432376219305;12968.935155324258;13923;1726.1714377099063;1758.4109323376683;1786.973195402057;1798.2537247042462;1804.8237476808906;93532.53233733856;119561.40219298935;126622.3668881659;127215.36392317979;128136;11354.205205635526;11709;11712.355820429788;11729.648110556696;11906.107041971714;13072.281024543741;13613.86386136126;13703.520376786964;14706.926465367605;14708.779368309484;8353.95823020881;8401.453905496246;8752.649894004297;8865.379423440427;8877.866831997526;8640.956795215983;8647.135286471303;8745.344099192585;8786.16531429513;8791.472511649194;3801.9429708554394;4524.9547504524535;4591.449026116888;4602.539746025371;4652.906941861143;1606.8071831380075;1943.2227109156452;1946.8734532255357;1983.3157560641555;1989.7711763147133;891.7815135291766;909.4634165842185;941.3128416256035;951.2152474208806;971.825071487125;16866;19664.41006769799;19722.309719160046;20316.18735250603;20467.385978420374;926.2543652359781;936.3679516326453;950.7813202963218;952.3619175152664;963.9518024098767;10357.326773759682;10580.894191058145;11011.394373309546;11021.724456888642;11052.668419947415;591220;594376.1687149393;608778;615666;618618;124417.75582244243;128242;129390.35304823604;132047.23033525472;141906;913.7162286986661;960.2078285414422;980.6861804222723;987.0820137272262;989.317371013998;16684.332626694795;18957.810421895603;19015.23939042478;19081;19132.330368437037;33957.49063764095;38604.80697596494;49925;52584.73707631438;55388.61528461821;81349;83327.5833620828;84303.5784821072;86057.56971215105;86154.56922715346;8045.597720113854;8048.396370272136;8121.4721043132;8143.633536490754;8371.455855369382;8855.158759917798;11710.707232318919;12501.24992500453;12827.10210285271;12980.286084265455;5453.563714902878;10415.114715249289;10848.186386020921;11166.609168679057;11216.214864959375;9953.850692239766;10618.1505479563;10914.617988370368;11146.331220126494;11236.55053797839;11131;11192.38441885704;11283.66149015531;11379.260348077349;11527.711807204887;7471.095750764521;8090.393220508367;8259.587020649064;8276;8279.668813247643;8114.553699546582;9940.701778946643;11027.61403350879;11318.151138664798;11375.544978201111 20:38:27 INFO - PROCESS | 3364 | |3;mootools.html;1377.2493990775026;1469.8309694385146;1521.041743701468;1757.9912100439496;1942.5240816000073;6216.813495595128;7766.961165194173;8191.590420478982;8192.83614327713;8206.343492520598;8330.708425205119;8614.612342444589;8628;8756.474611523297;8758.817559629448;2345.0854166874933;2357.257463898871;2386.7493913139133;2390.103711108334;2398.8560686358874;2340.9882950585225;2378.2746262389965;2385.2367242482414;2388.36708272308;2398.0287983366766;2338.263447014185;2359.6696462495265;2365.9053637854463;2371.8102551795814;2376.95246095078;2328.918487852917;2341.379534423379;2368.7749663781915;2373.5252949410014;2376;2366.4438856868624;2368.7039120109985;2395.3772019275116;2398.9040438382444;2402.771736685012;8643.524606146659;8711.390202685814;8717.389782715212;8723.476591404537;8727.127287271285;5128.512791284822;5132.9743351283005;5289.629725919187;5316.787328506855;5322.733863306819;3413.8634454621783;3434.261633748756;3523.9823800881086;3567.500549923013;3573.160307327776;987.7481242283244;1009.6819501856892;1023.161007973462;1027.8920713325067;1038.8078205531924;11434.942825285903;11538.826917596243;11556.884431155664;11565.826512602318;11577.479013444416;4007.6393124618926;4494.438195225597;4584.770761461914;4585.335126406652;4630.166570017362;8973.820523589491;9009.054049324857;9102.089791020844;9109.089091090971;9153.267738580898;5187.63686541946;5192.662476938987;5192.766325515361;5201.2198170274005;5205.6616319939085;3991.461152744342;4159.812808423628;4376.037271800199;4521.118381915495;4602.217623004098;3741.6819570336825;3785.7539259948016;3795.9240815183543;3830.559485659129;3847.942280865789;1128.4921785196693;1130.2709752209778;1130.728625129963;1139.7777433400408;1144.5765066925123;757.7916073079837;886.2024178239636;902.6434558349415;933.0622724162174;952.8237276103943;5480.835574932758;5770.16332631766;5779.884402312015;5825.94174058254;5859.853503662443;923.3351986569659;933.4306073295285;934.1499235695485;950.9049095090436;974.4494360686332;3192.425363434604;3750.3249415105543;3818.4081467372603;3871.05159235989;3932;10280.383177009404;10605.151587872951;10679.679609611723;10683.679489615324;10709.518071686793;10217.693469195936;10555.63055293061;10647.467626618638;10657.57369705203;10693.9465302673;8000.199980001953;8032.879506807402;8071.313938315309;8141.43009989295;8196.795080123045;5578.944210557843;5620.9156862647915;5686.545076393796;5750.7987220447085;5759.769609215585;9799.216062715108;9902.801943960936;9946.005399459997;9947.403155810674;9965.451900145561;9774.266929980422;9912.058354456316;10048.447335396624;10091.344062636199;10183.7454063649;6827.863442731217;6850.760223392157;6956.756513522002;6973.79078627642;6980.965095174492;8001.8399632008195;8078.151794061539;8085.63614637331;8109.189081091844;8115.634796434293;7309.159446663702;7359.448041397024;7390.260973902567;7449.366803821519;7455.664495097625;7194.388476979515;7205.171405288249;7245.456590755609;7350.044494215717;7385.150707668687;6826.180858297036;6900.275471075665;6961.930380696128;7005.264447233171;7016.298370162943;4768.57082862544;4807.711537307773;4827.25177597473;4870.732109733999;4935.457099718957;4929.433115191799;4951.777170027285;4978.352814134139;4988.775505102351;5115.9232611510115 20:38:27 INFO - PROCESS | 3364 | |4;prototype.html;308.6358097445015;313.75840602735894;318.0759892512252;322.159164580445;326.0690727971642;4461.933071003938;5082.484170335684;5132.43543210247;5138.584865113391;5189.714565698885;4555.362249285095;4789.299798571509;5099.566536844363;5129.743512824362;5147.7426128693605;3181.5704879841155;3191.377681352137;3653.506776585167;3767.321882061228;3804.2771873344113;3345.4145524533233;3412.8225762112033;3813.4089216171537;3852.595477474867;3964.4846169998004;3371.7808342457784;3540.6813386795184;3902.570717221103;3925.5878132796074;3939.330313846653;3263.820489873056;3431.6225215226304;3923.1172986078045;3942.6648734857285;3952.209558088394;3210.5344725014975;3238.2713889374936;3385.542951701513;3410.2497450561086;3418.8290585470877;4255.425517555122;4644.373009643722;4895;4935.703857768545;4948.490710333347;4445.955540444586;4470.831646651372;4474.261746811772;4477.350784136314;4494.528074552158;5374.7850085996515;5389.407165211824;5403.648762830403;5407.270018547485;5415.729213539346;3760.3983362661843;3775;3777.7166712496673;3784.924301513954;3804.4673745675627;4439.356293337481;4591.51789062147;4862.246351815439;5274.945010997816;5443.346798384179;3290.341931613639;3308.6856748608843;3408.7784294020807;3695.2609478104487;3707.1844194277755;3309.619393769699;3381.9684996076435;3860.1627803564356;3861.0308742541506;3895.4351619015083;3673.669369756735;3911.1004468972296;4190.832366705358;4285.657147428198;4443.777811109496;4668.856130248043;4731.432228132646;4892.217245240744;5105.7191854448365;5427.565794736412;1207.7342984543386;1291.3865913690922;1293.3080801771196;1294.838145231846;1309.2864388908067;669.7086767256259;755.3768141283354;768.7347864986571;776.3982913242282;797.5254723439544;3811.6378944000276;3977.5823538528616;4324.794354878972;4375.868723938287;4385.421248350592;4290.2921018031675;4649.95350046502;4736.810527578928;4749.691270067435;4768.950830817214;4089.848803464486;4222;4243.405923170759;4245.853619522712;4253.468316460442;2141.496748264157;2181.451169669552;2205.8014778669676;2444.6088625819784;2462.581361168606;4198.958010419918;4321.135772845443;4900.803967841246;4907.159815069349;4918.549028036738;4440.755758433317;4605.539446055434;4712.52874712526;4738.19450693383;4807.735574543433;4160.708750387505;4228.640565551963;4375.496817865923;4417.845375411845;4760.619150468024;4240.512341080692;4541.640025575484;4642.58401187641;4690.788914498924;4774.4509381421585;4542.318652202196;4831.613470922248;5151.793928242828;5211;5410.540104091039;4937;5556.527695145958;5821.79623713168;5828.213191219131;5875.177475153397;3144.6226452824894;3181.554582358518;3524.7708898921487;3681;3717.275131349361;4718.056388722201;4861.784553861535;5487.75305111279;5523.8895222096135;5557.666540007613;5369.838904832861;5374.865628359323;5378.853370778139;5395.946040539544;5400.783968641211;5016.495051484613;5359.490848369399;5365.812196573102;5376.919346209888;5394.865128371665;4270.807813648331;5017.54842064216;5264.842054738364;5293.444188360167;5298.496642818926;5362.812301569426;5365.149023586856;5373.167159090347;5383;5417.593680473901;4709.7174169549935;4921.630877684116;5261.316028916216;5357.491038351351;5358.812441564526 20:38:27 INFO - PROCESS | 3364 | |5;yui.html;675.2336098528169;686.9484788640854;723.6092510044576;728.4864170759615;777.3586790897511;14780.26098695065;15097.245137743124;15511.767323490156;15523.844761552382;15815.367385304575;14458;14536.273186340668;14553.927230363834;14556.781648275284;14557.636059098528;709.2411120101493;735.4484136897327;860.148453031499;891.888513935758;895.1317222294397;863.2187869977649;888.8755574219614;889.3996552327186;891.9063498332647;892.2282225874604;946.3044662173297;956.9521523923811;956.9903751542121;957.301170145794;959.390786850348;739.7669734033816;768.3315515501549;768.9692412303501;950.8668786369914;954.4941181173954;571.5656101362953;693.5180049865346;713.1192976673804;716.6667499612653;719.6329871765384;15060;15070.321835517429;15081.773773393408;15083.622909427242;15087.170205638686;12211.511539538407;12215;12365.629031129081;12456.875431245662;12464.563740269046;10667.519961601747;10763.3541987481;10782.13742900574;11192.552297908074;11299.887001129966;3945.487086678742;3947;3960.089179488734;3998.10042740384;4117.3618089196225;83548.99999999939;83940.58029709873;84672;84743.57628211881;85075.5746221265;9407.388519746195;9462.148406643435;9471.147596716328;9495.097965693249;9818.116369526777;19852.60294794096;20667.58664826695;21645.783542164692;21697.783022169577;21701;7324.816879577947;7528.698852045857;7546.849063018709;7570.318671319497;7696.653650585625;3983.2830090583393;4024.4164596133983;4058.736182148151;4086.5300490443383;4330.761808100522;3499.3176330615283;3588.2823435313044;3605.4591811228524;3607;3607.8196090195383;623.1774058243166;634.4202934692674;637.7959053102962;652.5660435810228;663.5090033375365;486.53049806936474;515.520565873741;525.7187404738446;552.8617845538615;603.6921170203228;7424.0987894520795;7435.25647435263;7456.51532650376;7628.429699274359;7698.34564062061;482.81894289641383;483.93224948507236;527.6412039812964;534.0947094674567;547.5209191957038;3077.692230776949;3573.6962358199326;3706.6478684524927;3782.3380908340932;3845.192509573021;73753;78984.02539936433;89118;89659.55170224237;92807.14385712249;60460.69769651123;70507;76956;78582;80312.59843700744;70293.64853175804;70694.64652676704;70927.64536177285;71349.6432517834;72258.63870680613;7571.886421703569;7594.088709354913;7731.265529774662;7759.573223472537;7985.960070199844;42727;44475.88810279769;44565.777171113936;44667.55332446634;46481.76759116318;59744.20767376986;61781.14656560311;61961.380386197365;62229.68885155545;62464.37535624586;8540.316774657887;9967.451790151612;10160.644377446753;10254.384736915808;10502.947485262524;9915.702528924145;10928.929285728316;11000;11002.50488727993;11942.34317112567;9586.376885502421;10249.692509224735;10654.414007229676;10682.679519614425;10788.622398216025;11365.54537818478;11508.194426390073;11779.411029448322;12309.93845030769;12393.814092788796;11147.83278250843;11157.497912594115;11189.328640281608;11441.370724609893;11527.308361498337;9387.718368448957;9432.151106400457;9483.905160948303;9686.951565242129;9886.110250077527;8810.603522841358;8901.510416926872;9023.981267425404;9040.547972601475;9122 20:38:27 INFO - PROCESS | 3364 | __end_tp_report 20:38:27 INFO - PROCESS | 3364 | __start_cc_report 20:38:27 INFO - PROCESS | 3364 | _x_x_mozilla_cycle_collect,1295 20:38:27 INFO - PROCESS | 3364 | __end_cc_report 20:38:27 INFO - PROCESS | 3364 | __startTimestamp1464320307002__endTimestamp 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | ------- Summary: start ------- 20:38:27 INFO - PROCESS | 3364 | Number of tests: 6 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | [#0] dojo.html Cycles:165 Average:5698.25 Median:5868.24 stddev:649.61 (11.1%) stddev-sans-first:625.47 20:38:27 INFO - PROCESS | 3364 | Values: 3373.0 3389.8 3406.7 3413.6 3467.6 5546.3 5603.9 5648.9 5683.6 5720.4 5634.0 5663.3 5754.1 5767.6 5802.4 5051.4 5069.3 5128.4 5162.2 5233.8 5215.7 5217.3 5223.8 5250.4 5275.2 5075.2 5106.9 5117.3 5141.9 5165.8 5078.0 5100.0 5125.3 5145.5 5153.3 5099.2 5137.5 5150.0 5166.4 5220.3 4613.0 5455.3 5542.0 5578.9 5621.0 6219.4 6225.5 6225.9 6237.6 6251.2 6260.7 6315.3 6316.2 6321.6 6322.6 5651.1 5706.6 5774.7 5778.2 5817.1 5281.6 6286.2 6298.3 6299.0 6328.6 6113.4 6115.8 6122.4 6136.1 6138.6 6103.9 6157.3 6189.9 6197.7 6264.3 6121.3 6133.0 6156.3 6189.4 6211.4 6045.8 6222.5 6240.5 6241.9 6251.3 4820.9 4838.0 4870.3 4900.2 4903.9 4370.7 4389.7 4391.8 4394.3 4437.2 4670.6 5568.6 5680.7 5730.5 5745.0 6136.3 6136.7 6191.5 6228.6 6299.9 5731.1 5731.7 5742.4 5755.0 5799.0 5316.3 5346.8 5347.6 5349.0 5355.1 6210.7 6243.7 6263.3 6263.9 6359.0 6203.4 6259.5 6276.3 6284.7 6285.1 6219.2 6325.8 6337.7 6353.0 6359.3 6091.6 6119.9 6124.2 6177.6 6214.5 6260.2 6261.2 6279.5 6282.9 6285.6 5809.7 6061.8 6075.5 6086.8 6102.1 5770.2 5875.0 5980.8 6008.6 6025.7 5850.1 5860.2 5868.2 5878.0 5927.6 5927.6 6052.5 6055.5 6113.3 6133.4 5803.3 5865.5 5871.6 5955.2 5966.2 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | [#1] ext.html Cycles:180 Average:73055.31 Median:19156.50 stddev:158658.74 (828.2%) stddev-sans-first:159010.28 20:38:27 INFO - PROCESS | 3364 | Values: 494.6 497.2 514.7 516.3 548.9 29829.0 31200.8 42195.8 43242.8 44273.3 43405.8 43965.9 46264.8 46328.1 46348.3 8723.3 9649.9 9877.1 9896.0 9939.2 11841.9 12159.7 12169.5 12327.6 12356.9 11991.5 12088.0 12097.9 12098.3 12106.8 12031.3 12082.0 12149.2 12150.8 12152.0 11028.6 11145.1 12010.2 12121.2 12150.5 42497.4 42884.4 42893.4 42919.4 43043.0 3120.9 3260.8 3267.1 3475.7 3834.9 29946.6 30073.2 30092.1 30284.2 30299.2 2726.3 2739.4 2758.4 2768.0 2830.6 525001.0 527916.0 528736.0 529895.0 530083.0 63890.0 64083.4 64132.4 64139.7 64244.0 67575.0 67827.0 67838.7 67858.0 67937.3 16758.4 17404.2 17410.6 17439.9 17466.0 12183.2 12426.3 12459.6 12607.7 12628.7 14491.6 14842.2 15990.8 17639.8 18028.7 6316.6 7172.9 7605.0 7722.7 7756.3 952.9 1034.2 1070.5 1109.7 1119.3 30071.1 30263.3 30389.4 30499.0 30617.8 1028.6 1040.5 1046.0 1126.4 1135.8 15466.0 15597.1 15626.4 15721.1 15815.7 672957.0 680025.0 680410.0 680772.0 681680.0 61266.0 62849.0 63087.1 63090.7 63263.0 1323.3 1363.8 1440.2 1470.7 1492.3 24134.9 29383.7 30877.0 31145.1 31361.5 51720.5 52520.5 53118.5 53152.0 53252.7 487294.0 578802.0 580115.0 582330.0 583633.0 34837.3 40878.2 40904.8 41330.6 41952.2 44485.0 45012.1 46462.5 48132.3 48260.3 39086.7 39210.0 39312.4 40058.4 40207.0 29820.0 38478.0 38837.6 39337.4 40080.0 37618.2 37874.2 38855.2 38888.6 39227.0 26201.0 33475.3 34486.0 34549.7 34671.0 24491.9 24853.1 25182.7 25476.5 26167.9 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | [#2] jquery.html Cycles:180 Average:35011.38 Median:385.87 stddev:101304.71 (26253.3%) stddev-sans-first:101555.53 20:38:27 INFO - PROCESS | 3364 | Values: 385.3 386.4 391.7 393.0 415.2 13805.3 19267.1 19498.3 20786.0 20826.5 19754.1 20372.7 20670.4 20794.0 20832.2 4996.0 5371.3 5374.8 5397.3 5422.6 5327.3 5349.9 5437.3 5448.2 5452.0 5207.8 5325.1 5350.4 5380.3 5394.4 5348.4 5350.8 5360.9 5377.3 5933.4 5081.5 5216.6 5260.7 5264.3 5279.7 13454.7 13797.9 25467.4 25754.5 26475.9 2031.9 2088.6 2106.5 2111.7 2757.5 9839.6 10285.0 10320.4 12968.9 13923.0 1726.2 1758.4 1787.0 1798.3 1804.8 93532.5 119561.4 126622.4 127215.4 128136.0 11354.2 11709.0 11712.4 11729.6 11906.1 13072.3 13613.9 13703.5 14706.9 14708.8 8354.0 8401.5 8752.6 8865.4 8877.9 8641.0 8647.1 8745.3 8786.2 8791.5 3801.9 4525.0 4591.4 4602.5 4652.9 1606.8 1943.2 1946.9 1983.3 1989.8 891.8 909.5 941.3 951.2 971.8 16866.0 19664.4 19722.3 20316.2 20467.4 926.3 936.4 950.8 952.4 964.0 10357.3 10580.9 11011.4 11021.7 11052.7 591220.0 594376.2 608778.0 615666.0 618618.0 124417.8 128242.0 129390.4 132047.2 141906.0 913.7 960.2 980.7 987.1 989.3 16684.3 18957.8 19015.2 19081.0 19132.3 33957.5 38604.8 49925.0 52584.7 55388.6 81349.0 83327.6 84303.6 86057.6 86154.6 8045.6 8048.4 8121.5 8143.6 8371.5 8855.2 11710.7 12501.2 12827.1 12980.3 5453.6 10415.1 10848.2 11166.6 11216.2 9953.9 10618.2 10914.6 11146.3 11236.6 11131.0 11192.4 11283.7 11379.3 11527.7 7471.1 8090.4 8259.6 8276.0 8279.7 8114.6 9940.7 11027.6 11318.2 11375.5 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | [#3] mootools.html Cycles:180 Average:5565.32 Median:5052.35 stddev:3122.11 (61.8%) stddev-sans-first:3115.01 20:38:27 INFO - PROCESS | 3364 | Values: 1377.2 1469.8 1521.0 1758.0 1942.5 6216.8 7767.0 8191.6 8192.8 8206.3 8330.7 8614.6 8628.0 8756.5 8758.8 2345.1 2357.3 2386.7 2390.1 2398.9 2341.0 2378.3 2385.2 2388.4 2398.0 2338.3 2359.7 2365.9 2371.8 2377.0 2328.9 2341.4 2368.8 2373.5 2376.0 2366.4 2368.7 2395.4 2398.9 2402.8 8643.5 8711.4 8717.4 8723.5 8727.1 5128.5 5133.0 5289.6 5316.8 5322.7 3413.9 3434.3 3524.0 3567.5 3573.2 987.7 1009.7 1023.2 1027.9 1038.8 11434.9 11538.8 11556.9 11565.8 11577.5 4007.6 4494.4 4584.8 4585.3 4630.2 8973.8 9009.1 9102.1 9109.1 9153.3 5187.6 5192.7 5192.8 5201.2 5205.7 3991.5 4159.8 4376.0 4521.1 4602.2 3741.7 3785.8 3795.9 3830.6 3847.9 1128.5 1130.3 1130.7 1139.8 1144.6 757.8 886.2 902.6 933.1 952.8 5480.8 5770.2 5779.9 5825.9 5859.9 923.3 933.4 934.1 950.9 974.4 3192.4 3750.3 3818.4 3871.1 3932.0 10280.4 10605.2 10679.7 10683.7 10709.5 10217.7 10555.6 10647.5 10657.6 10693.9 8000.2 8032.9 8071.3 8141.4 8196.8 5578.9 5620.9 5686.5 5750.8 5759.8 9799.2 9902.8 9946.0 9947.4 9965.5 9774.3 9912.1 10048.4 10091.3 10183.7 6827.9 6850.8 6956.8 6973.8 6981.0 8001.8 8078.2 8085.6 8109.2 8115.6 7309.2 7359.4 7390.3 7449.4 7455.7 7194.4 7205.2 7245.5 7350.0 7385.2 6826.2 6900.3 6961.9 7005.3 7016.3 4768.6 4807.7 4827.3 4870.7 4935.5 4929.4 4951.8 4978.4 4988.8 5115.9 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | [#4] prototype.html Cycles:180 Average:4136.22 Median:4475.81 stddev:1278.34 (28.6%) stddev-sans-first:1249.23 20:38:27 INFO - PROCESS | 3364 | Values: 308.6 313.8 318.1 322.2 326.1 4461.9 5082.5 5132.4 5138.6 5189.7 4555.4 4789.3 5099.6 5129.7 5147.7 3181.6 3191.4 3653.5 3767.3 3804.3 3345.4 3412.8 3813.4 3852.6 3964.5 3371.8 3540.7 3902.6 3925.6 3939.3 3263.8 3431.6 3923.1 3942.7 3952.2 3210.5 3238.3 3385.5 3410.2 3418.8 4255.4 4644.4 4895.0 4935.7 4948.5 4446.0 4470.8 4474.3 4477.4 4494.5 5374.8 5389.4 5403.6 5407.3 5415.7 3760.4 3775.0 3777.7 3784.9 3804.5 4439.4 4591.5 4862.2 5274.9 5443.3 3290.3 3308.7 3408.8 3695.3 3707.2 3309.6 3382.0 3860.2 3861.0 3895.4 3673.7 3911.1 4190.8 4285.7 4443.8 4668.9 4731.4 4892.2 5105.7 5427.6 1207.7 1291.4 1293.3 1294.8 1309.3 669.7 755.4 768.7 776.4 797.5 3811.6 3977.6 4324.8 4375.9 4385.4 4290.3 4650.0 4736.8 4749.7 4769.0 4089.8 4222.0 4243.4 4245.9 4253.5 2141.5 2181.5 2205.8 2444.6 2462.6 4199.0 4321.1 4900.8 4907.2 4918.5 4440.8 4605.5 4712.5 4738.2 4807.7 4160.7 4228.6 4375.5 4417.8 4760.6 4240.5 4541.6 4642.6 4690.8 4774.5 4542.3 4831.6 5151.8 5211.0 5410.5 4937.0 5556.5 5821.8 5828.2 5875.2 3144.6 3181.6 3524.8 3681.0 3717.3 4718.1 4861.8 5487.8 5523.9 5557.7 5369.8 5374.9 5378.9 5395.9 5400.8 5016.5 5359.5 5365.8 5376.9 5394.9 4270.8 5017.5 5264.8 5293.4 5298.5 5362.8 5365.1 5373.2 5383.0 5417.6 4709.7 4921.6 5261.3 5357.5 5358.8 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - PROCESS | 3364 | [#5] yui.html Cycles:180 Average:17678.78 Median:62095.53 stddev:24730.07 (39.8%) stddev-sans-first:24766.49 20:38:27 INFO - PROCESS | 3364 | Values: 675.2 686.9 723.6 728.5 777.4 14780.3 15097.2 15511.8 15523.8 15815.4 14458.0 14536.3 14553.9 14556.8 14557.6 709.2 735.4 860.1 891.9 895.1 863.2 888.9 889.4 891.9 892.2 946.3 957.0 957.0 957.3 959.4 739.8 768.3 769.0 950.9 954.5 571.6 693.5 713.1 716.7 719.6 15060.0 15070.3 15081.8 15083.6 15087.2 12211.5 12215.0 12365.6 12456.9 12464.6 10667.5 10763.4 10782.1 11192.6 11299.9 3945.5 3947.0 3960.1 3998.1 4117.4 83549.0 83940.6 84672.0 84743.6 85075.6 9407.4 9462.1 9471.1 9495.1 9818.1 19852.6 20667.6 21645.8 21697.8 21701.0 7324.8 7528.7 7546.8 7570.3 7696.7 3983.3 4024.4 4058.7 4086.5 4330.8 3499.3 3588.3 3605.5 3607.0 3607.8 623.2 634.4 637.8 652.6 663.5 486.5 515.5 525.7 552.9 603.7 7424.1 7435.3 7456.5 7628.4 7698.3 482.8 483.9 527.6 534.1 547.5 3077.7 3573.7 3706.6 3782.3 3845.2 73753.0 78984.0 89118.0 89659.6 92807.1 60460.7 70507.0 76956.0 78582.0 80312.6 70293.6 70694.6 70927.6 71349.6 72258.6 7571.9 7594.1 7731.3 7759.6 7986.0 42727.0 44475.9 44565.8 44667.6 46481.8 59744.2 61781.1 61961.4 62229.7 62464.4 8540.3 9967.5 10160.6 10254.4 10502.9 9915.7 10928.9 11000.0 11002.5 11942.3 9586.4 10249.7 10654.4 10682.7 10788.6 11365.5 11508.2 11779.4 12309.9 12393.8 11147.8 11157.5 11189.3 11441.4 11527.3 9387.7 9432.2 9483.9 9687.0 9886.1 8810.6 8901.5 9024.0 9040.5 9122.0 20:38:27 INFO - PROCESS | 3364 | -------- Summary: end -------- 20:38:27 INFO - PROCESS | 3364 | 20:38:27 INFO - TEST-INFO | 3364: exit 0 20:38:27 INFO - TEST-OK | dromaeo_css | took 1115721ms 20:38:27 INFO - TEST-START | kraken 20:38:27 INFO - operating with platform_type : mac_ 20:38:27 INFO - Initialising browser for kraken test... 20:38:27 INFO - TEST-INFO | started process 3479 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpkvZZtr/profile http://localhost:61670/getInfo.html) 20:38:29 INFO - PROCESS | 3479 | __metrics Screen width/height:1600/1200 20:38:29 INFO - PROCESS | 3479 | colorDepth:24 20:38:29 INFO - PROCESS | 3479 | Browser inner width/height: 1024/689 20:38:29 INFO - PROCESS | 3479 | __metrics 20:38:29 INFO - PROCESS | 3479 | 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" 20:38:29 ERROR - PROCESS | 3479 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:38:30 INFO - TEST-INFO | 3479: exit 0 20:38:30 INFO - Browser initialized. 20:38:30 INFO - Running cycle 1/1 for kraken test... 20:38:30 INFO - TEST-INFO | started process 3480 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpkvZZtr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:38:42 INFO - PROCESS | 3480 | RSS: Main: 241201152 20:38:42 INFO - PROCESS | 3480 | 20:38:45 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61670/tests/kraken/driver.html?testName=audio-beat-detection) 20:38:45 INFO - PROCESS | 3480 | RSS: Main: 289292288 20:38:45 INFO - PROCESS | 3480 | 20:38:48 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61670/tests/kraken/driver.html?testName=audio-dft) 20:38:49 INFO - PROCESS | 3480 | RSS: Main: 297701376 20:38:49 INFO - PROCESS | 3480 | 20:38:52 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61670/tests/kraken/driver.html?testName=audio-fft) 20:38:53 INFO - PROCESS | 3480 | RSS: Main: 387461120 20:38:53 INFO - PROCESS | 3480 | 20:38:55 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61670/tests/kraken/driver.html?testName=audio-oscillator) 20:38:56 INFO - PROCESS | 3480 | RSS: Main: 287735808 20:38:56 INFO - PROCESS | 3480 | 20:38:58 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61670/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:38:58 INFO - PROCESS | 3480 | RSS: Main: 291373056 20:38:58 INFO - PROCESS | 3480 | 20:39:01 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61670/tests/kraken/driver.html?testName=imaging-darkroom) 20:39:02 INFO - PROCESS | 3480 | RSS: Main: 329478144 20:39:02 INFO - PROCESS | 3480 | 20:39:05 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61670/tests/kraken/driver.html?testName=imaging-desaturate) 20:39:05 INFO - PROCESS | 3480 | RSS: Main: 330063872 20:39:05 INFO - PROCESS | 3480 | 20:39:08 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61670/tests/kraken/driver.html?testName=json-parse-financial) 20:39:09 INFO - PROCESS | 3480 | RSS: Main: 332312576 20:39:09 INFO - PROCESS | 3480 | 20:39:10 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61670/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:39:11 INFO - PROCESS | 3480 | RSS: Main: 317263872 20:39:11 INFO - PROCESS | 3480 | 20:39:13 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:39:14 INFO - PROCESS | 3480 | RSS: Main: 323932160 20:39:14 INFO - PROCESS | 3480 | 20:39:16 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:39:17 INFO - PROCESS | 3480 | RSS: Main: 326111232 20:39:17 INFO - PROCESS | 3480 | 20:39:19 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:39:20 INFO - PROCESS | 3480 | RSS: Main: 330264576 20:39:20 INFO - PROCESS | 3480 | 20:39:23 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:39:24 INFO - PROCESS | 3480 | RSS: Main: 327987200 20:39:24 INFO - PROCESS | 3480 | 20:39:25 INFO - PROCESS | 3480 | Cycle 1(1): loaded http://localhost:61670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61670/tests/kraken/driver.html?testName=ai-astar) 20:39:26 INFO - PROCESS | 3480 | RSS: Main: 375656448 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | __start_tp_report 20:39:26 INFO - PROCESS | 3480 | _x_x_mozilla_page_load 20:39:26 INFO - PROCESS | 3480 | _x_x_mozilla_page_load_details 20:39:26 INFO - PROCESS | 3480 | |i|pagename|runs| 20:39:26 INFO - PROCESS | 3480 | |0;ai-astar;113;108;112;113;112;113;112;112;112;111 20:39:26 INFO - PROCESS | 3480 | |1;audio-beat-detection;151;130;143;178;116;164;166;142;166;117 20:39:26 INFO - PROCESS | 3480 | |2;audio-dft;184;185;185;183;185;185;183;184;180;183 20:39:26 INFO - PROCESS | 3480 | |3;audio-fft;81;57;66;103;81;81;82;64;81;57 20:39:26 INFO - PROCESS | 3480 | |4;audio-oscillator;86;69;103;69;71;69;94;70;69;99 20:39:26 INFO - PROCESS | 3480 | |5;imaging-gaussian-blur;85;83;87;83;87;83;87;83;86;83 20:39:26 INFO - PROCESS | 3480 | |6;imaging-darkroom;92;92;93;92;92;113;151;96;92;92 20:39:26 INFO - PROCESS | 3480 | |7;imaging-desaturate;86;84;125;96;85;83;85;83;85;85 20:39:26 INFO - PROCESS | 3480 | |8;json-parse-financial;57;58;59;57;56;56;57;76;58;55 20:39:26 INFO - PROCESS | 3480 | |9;json-stringify-tinderbox;66;69;77;76;71;70;70;69;70;71 20:39:26 INFO - PROCESS | 3480 | |10;stanford-crypto-aes;85;86;86;87;84;85;80;90;88;89 20:39:26 INFO - PROCESS | 3480 | |11;stanford-crypto-ccm;144;141;147;147;148;137;139;138;189;158 20:39:26 INFO - PROCESS | 3480 | |12;stanford-crypto-pbkdf2;168;224;167;169;164;166;166;168;161;163 20:39:26 INFO - PROCESS | 3480 | |13;stanford-crypto-sha256-iterative;66;71;67;66;68;65;68;64;68;77 20:39:26 INFO - PROCESS | 3480 | __end_tp_report 20:39:26 INFO - PROCESS | 3480 | __start_cc_report 20:39:26 INFO - PROCESS | 3480 | _x_x_mozilla_cycle_collect,2066 20:39:26 INFO - PROCESS | 3480 | __end_cc_report 20:39:26 INFO - PROCESS | 3480 | __startTimestamp1464320366103__endTimestamp 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | ------- Summary: start ------- 20:39:26 INFO - PROCESS | 3480 | Number of tests: 14 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:1.48 (1.3%) stddev-sans-first:1.50 20:39:26 INFO - PROCESS | 3480 | Values: 113.0 108.0 112.0 113.0 112.0 113.0 112.0 112.0 112.0 111.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:157.50 stddev:21.55 (13.7%) stddev-sans-first:22.81 20:39:26 INFO - PROCESS | 3480 | Values: 151.0 130.0 143.0 178.0 116.0 164.0 166.0 142.0 166.0 117.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.57 (0.8%) stddev-sans-first:1.66 20:39:26 INFO - PROCESS | 3480 | Values: 184.0 185.0 185.0 183.0 185.0 185.0 183.0 184.0 180.0 183.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#3] audio-fft Cycles:10 Average:75.30 Median:81.00 stddev:14.24 (17.6%) stddev-sans-first:14.96 20:39:26 INFO - PROCESS | 3480 | Values: 81.0 57.0 66.0 103.0 81.0 81.0 82.0 64.0 81.0 57.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:70.50 stddev:14.09 (20.0%) stddev-sans-first:14.77 20:39:26 INFO - PROCESS | 3480 | Values: 86.0 69.0 103.0 69.0 71.0 69.0 94.0 70.0 69.0 99.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:85.50 stddev:1.89 (2.2%) stddev-sans-first:2.00 20:39:26 INFO - PROCESS | 3480 | Values: 85.0 83.0 87.0 83.0 87.0 83.0 87.0 83.0 86.0 83.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:92.00 stddev:18.90 (20.5%) stddev-sans-first:19.80 20:39:26 INFO - PROCESS | 3480 | Values: 92.0 92.0 93.0 92.0 92.0 113.0 151.0 96.0 92.0 92.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#7] imaging-desaturate Cycles:10 Average:89.70 Median:85.00 stddev:12.95 (15.2%) stddev-sans-first:13.67 20:39:26 INFO - PROCESS | 3480 | Values: 86.0 84.0 125.0 96.0 85.0 83.0 85.0 83.0 85.0 85.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:57.50 stddev:6.12 (10.6%) stddev-sans-first:6.45 20:39:26 INFO - PROCESS | 3480 | Values: 57.0 58.0 59.0 57.0 56.0 56.0 57.0 76.0 58.0 55.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:3.28 (4.7%) stddev-sans-first:2.96 20:39:26 INFO - PROCESS | 3480 | Values: 66.0 69.0 77.0 76.0 71.0 70.0 70.0 69.0 70.0 71.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.50 stddev:2.83 (3.3%) stddev-sans-first:2.98 20:39:26 INFO - PROCESS | 3480 | Values: 85.0 86.0 86.0 87.0 84.0 85.0 80.0 90.0 88.0 89.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:147.00 stddev:15.43 (10.5%) stddev-sans-first:16.27 20:39:26 INFO - PROCESS | 3480 | Values: 144.0 141.0 147.0 147.0 148.0 137.0 139.0 138.0 189.0 158.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:167.50 stddev:18.58 (11.1%) stddev-sans-first:19.66 20:39:26 INFO - PROCESS | 3480 | Values: 168.0 224.0 167.0 169.0 164.0 166.0 166.0 168.0 161.0 163.0 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - PROCESS | 3480 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:3.71 (5.5%) stddev-sans-first:3.87 20:39:26 INFO - PROCESS | 3480 | Values: 66.0 71.0 67.0 66.0 68.0 65.0 68.0 64.0 68.0 77.0 20:39:26 INFO - PROCESS | 3480 | -------- Summary: end -------- 20:39:26 INFO - PROCESS | 3480 | 20:39:26 INFO - TEST-INFO | 3480: exit 0 20:39:26 INFO - TEST-OK | kraken | took 59331ms 20:39:26 INFO - SUITE-END | took 1175s 20:39:27 INFO - Completed test suite (00:19:35) 20:39:27 INFO - javascript benchmark 20:39:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3373.0, 3389.8135602541865, 3406.6593340665922, 3413.590369155702, 3467.5838899332066, 5546.251256080428, 5603.859903502414, 5648.887022259562, 5683.573731970098, 5720.427957204282, 5634.04221282382, 5663.292088488939, 5754.079347304432, 5767.625104368213, 5802.448767367094, 5051.419086805009, 5069.2649565812935, 5128.358955130609, 5162.174052151638, 5233.764480598363, 5215.6609820361555, 5217.34783152106, 5223.817166399177, 5250.3699556053325, 5275.235090911816, 5075.187969924831, 5106.872328191825, 5117.283580298761, 5141.871453213662, 5165.845024649267, 5077.974610126926, 5100.0, 5125.282460455539, 5145.536901678829, 5153.252778347156, 5099.15863882459, 5137.51193636604, 5150.0, 5166.43169251382, 5220.29526013987, 4613.0, 5455.263539422207, 5542.002439560877, 5578.888422231573, 5620.988222120017, 6219.40915613016, 6225.501959843202, 6225.875482490324, 6237.563370564062, 6251.156093927307, 6260.655663938482, 6315.336889626615, 6316.178896743394, 6321.557490975633, 6322.620642761403, 5651.095824668033, 5706.600537962344, 5774.68239246837, 5778.248827652378, 5817.0692689169955, 5281.551068159172, 6286.182796236551, 6298.338674439209, 6299.023651334051, 6328.620282782956, 6113.38866113394, 6115.805478700918, 6122.418370254819, 6136.134235431649, 6138.631682099089, 6103.883205879467, 6157.291911430153, 6189.876202475925, 6197.690115494297, 6264.2796078451565, 6121.265448146251, 6133.0, 6156.322804491502, 6189.412005859436, 6211.409916057967, 6045.848853778691, 6222.471089957405, 6240.469560087444, 6241.937580624226, 6251.28110267316, 4820.927686084711, 4838.0, 4870.269459581091, 4900.240462728283, 4903.877403064952, 4370.650347972155, 4389.6927215095275, 4391.7584532851, 4394.318880573516, 4437.156940181355, 4670.626349892001, 5568.554515638739, 5680.715964201773, 5730.512906402919, 5745.0, 6136.304638670978, 6136.67225816756, 6191.532741664682, 6228.564000520011, 6299.874002519924, 5731.083026715705, 5731.656100633975, 5742.397048309951, 5754.992876246723, 5799.014167591436, 5316.282301889196, 5346.786128554814, 5347.625666203407, 5348.973255133699, 5355.143177091647, 6210.658413787285, 6243.656598887014, 6263.279722831932, 6263.937360626335, 6358.982562789932, 6203.379662033761, 6259.467945224525, 6276.340984196594, 6284.654344011124, 6285.088662144055, 6219.222597175353, 6325.778597749057, 6337.714802833976, 6352.968235158794, 6359.332270111571, 6091.573589848668, 6119.929102025492, 6124.203853499017, 6177.567570270038, 6214.533909956681, 6260.154879091447, 6261.24865016201, 6279.466245369194, 6282.874342513215, 6285.5600107992, 5809.709514524341, 6061.787837425669, 6075.453209211016, 6086.847828804315, 6102.084687296941, 5770.163326317745, 5874.970625146847, 5980.760769569342, 6008.639481631116, 6025.698715064317, 5850.12248162779, 5860.238169037998, 5868.237129173351, 5877.970610146922, 5927.585069045125, 5927.644341339534, 6052.515798736179, 6055.455009049207, 6113.29697084841, 6133.417325354084, 5803.30360356743, 5865.501432378125, 5871.6183448075735, 5955.166276721349, 5966.224390829165], "value": 5656.914720144499, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.6339708615624, 497.22681230686277, 514.7477735909406, 516.2823675091621, 548.9039418101831, 29829.0, 31200.84399578002, 42195.78902105489, 43242.783786081105, 44273.33589996152, 43405.78297108518, 43965.900852478706, 46264.76867615657, 46328.07343853121, 46348.30477542831, 8723.345749068845, 9649.855252171223, 9877.111060004598, 9896.0, 9939.20486361093, 11841.94079029608, 12159.696007599792, 12169.513219471211, 12327.568535101273, 12356.93821530891, 11991.520339186422, 12088.0, 12097.879021209807, 12098.274103553771, 12106.81839772404, 12031.338276394796, 12082.033437324993, 12149.210301330386, 12150.817737733942, 12151.967082797884, 11028.558857645685, 11145.052670523015, 12010.159288849783, 12121.212121212093, 12150.4532296046, 42497.362539561625, 42884.356734649, 42893.35659965103, 42919.35620965688, 43043.0, 3120.8595613197463, 3260.836958152083, 3267.068885367659, 3475.6871881530783, 3834.9233015339814, 29946.55080173799, 30073.248168795737, 30092.097237082922, 30284.24289392783, 30299.242518936982, 2726.263908744648, 2739.4384151248946, 2758.434520923206, 2767.9861600691866, 2830.6461692288462, 525001.0, 527915.9999999962, 528736.0, 529895.0, 530083.0, 63890.0, 64083.35916640774, 64132.35867641357, 64139.67930160413, 64244.0, 67575.0, 67827.0, 67838.66080669664, 67858.0, 67937.3206267931, 16758.413455528997, 17404.216810243317, 17410.564735881704, 17439.912800435915, 17466.0, 12183.208091474025, 12426.316552589513, 12459.563915262923, 12607.684807879694, 12628.747425051448, 14491.637709057148, 14842.18367989771, 15990.840091598935, 17639.82360176382, 18028.729569056475, 6316.557840951181, 7172.892406613905, 7605.0, 7722.729704460429, 7756.30193282596, 952.885653721544, 1034.1984961654628, 1070.5182667799365, 1109.7225693576606, 1119.3489602835753, 30071.066192227765, 30263.32216390608, 30389.39221215563, 30499.0, 30617.846910765304, 1028.6267832443623, 1040.5109598488698, 1045.9738506537396, 1126.4367816091954, 1135.8296255561568, 15466.0, 15597.064176149468, 15626.374945002073, 15721.056127366495, 15815.683686326209, 672957.0, 680025.0, 680410.0, 680772.0, 681680.0, 61266.0, 62849.0, 63087.05369419463, 63090.68454657697, 63263.0, 1323.3251041968472, 1363.822703048617, 1440.1503113163285, 1470.713210923881, 1492.2986196487634, 24134.87932560326, 29383.7061629381, 30877.0, 31145.065648030595, 31361.52957705682, 51720.48279517156, 52520.47479525156, 53118.468815312895, 53152.0, 53252.73373633107, 487294.0, 578802.0, 580115.0, 582330.0, 583633.0, 34837.303253935286, 40878.1824363517, 40904.79547602243, 41330.586694133875, 41952.16095678131, 44485.0, 45012.09975800531, 46462.535374647174, 48132.27801583049, 48260.27609585929, 39086.70046294962, 39210.0, 39312.410313844746, 40058.39912401258, 40206.99482512961, 29820.0, 38478.0, 38837.64068257598, 39337.40993885037, 40080.0, 37618.24763504769, 37874.242515150094, 38855.222895542494, 38888.611113888495, 39227.019324515975, 26201.0, 33475.33049339048, 34486.0, 34549.65450345565, 34671.0, 24491.877540612182, 24853.130140445, 25182.74817251877, 25476.490470190864, 26167.86916065484], "value": 20862.472346513438, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.3410667758133, 386.40686546151653, 391.70915103438796, 392.992140157197, 415.2214597629445, 13805.283828946396, 19267.132979015943, 19498.317558885443, 20786.0, 20826.479338016557, 19754.111065002075, 20372.69440958383, 20670.37988860333, 20794.0, 20832.166713331488, 4995.950040499594, 5371.274877891474, 5374.7850085996515, 5397.271368365279, 5422.620416570841, 5327.2808170897015, 5349.919751203735, 5437.293151890269, 5448.237246785453, 5452.0, 5207.791688332462, 5325.068113080215, 5350.438203988564, 5380.300560927074, 5394.352677678665, 5348.358197016344, 5350.8127215547465, 5360.865978350534, 5377.274068000809, 5933.397982544732, 5081.5426611605135, 5216.608754343401, 5260.736963151865, 5264.2893209416625, 5279.736013199363, 13454.730905381837, 13797.862021379759, 25467.36331591725, 25754.48491030169, 26475.867620661775, 2031.9187232510683, 2088.603165398569, 2106.4628519727526, 2111.7360329958756, 2757.531219692637, 9839.606415743361, 10285.022922822394, 10320.432376219305, 12968.935155324258, 13923.0, 1726.1714377099063, 1758.4109323376683, 1786.973195402057, 1798.2537247042462, 1804.8237476808906, 93532.53233733856, 119561.40219298935, 126622.3668881659, 127215.36392317979, 128136.0, 11354.205205635526, 11709.0, 11712.355820429788, 11729.648110556696, 11906.107041971714, 13072.281024543741, 13613.86386136126, 13703.520376786964, 14706.926465367605, 14708.779368309484, 8353.95823020881, 8401.453905496246, 8752.649894004297, 8865.379423440427, 8877.866831997526, 8640.956795215983, 8647.135286471303, 8745.344099192585, 8786.16531429513, 8791.472511649194, 3801.9429708554394, 4524.9547504524535, 4591.449026116888, 4602.539746025371, 4652.906941861143, 1606.8071831380075, 1943.2227109156452, 1946.8734532255357, 1983.3157560641555, 1989.7711763147133, 891.7815135291766, 909.4634165842185, 941.3128416256035, 951.2152474208806, 971.825071487125, 16866.0, 19664.41006769799, 19722.309719160046, 20316.18735250603, 20467.385978420374, 926.2543652359781, 936.3679516326453, 950.7813202963218, 952.3619175152664, 963.9518024098767, 10357.326773759682, 10580.894191058145, 11011.394373309546, 11021.724456888642, 11052.668419947415, 591220.0, 594376.1687149393, 608778.0, 615666.0, 618618.0, 124417.75582244243, 128242.0, 129390.35304823604, 132047.23033525472, 141906.0, 913.7162286986661, 960.2078285414422, 980.6861804222723, 987.0820137272262, 989.317371013998, 16684.332626694795, 18957.810421895603, 19015.23939042478, 19081.0, 19132.330368437037, 33957.49063764095, 38604.80697596494, 49925.0, 52584.73707631438, 55388.61528461821, 81349.0, 83327.5833620828, 84303.5784821072, 86057.56971215105, 86154.56922715346, 8045.597720113854, 8048.396370272136, 8121.4721043132, 8143.633536490754, 8371.455855369382, 8855.158759917798, 11710.707232318919, 12501.24992500453, 12827.10210285271, 12980.286084265455, 5453.563714902878, 10415.114715249289, 10848.186386020921, 11166.609168679057, 11216.214864959375, 9953.850692239766, 10618.1505479563, 10914.617988370368, 11146.331220126494, 11236.55053797839, 11131.0, 11192.38441885704, 11283.66149015531, 11379.260348077349, 11527.711807204887, 7471.095750764521, 8090.393220508367, 8259.587020649064, 8276.0, 8279.668813247643, 8114.553699546582, 9940.701778946643, 11027.61403350879, 11318.151138664798, 11375.544978201111], "value": 9311.788162944009, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.2493990775026, 1469.8309694385146, 1521.041743701468, 1757.9912100439496, 1942.5240816000073, 6216.813495595128, 7766.961165194173, 8191.590420478982, 8192.83614327713, 8206.343492520598, 8330.708425205119, 8614.612342444589, 8628.0, 8756.474611523297, 8758.817559629448, 2345.0854166874933, 2357.257463898871, 2386.7493913139133, 2390.103711108334, 2398.8560686358874, 2340.9882950585225, 2378.2746262389965, 2385.2367242482414, 2388.36708272308, 2398.0287983366766, 2338.263447014185, 2359.6696462495265, 2365.9053637854463, 2371.8102551795814, 2376.95246095078, 2328.918487852917, 2341.379534423379, 2368.7749663781915, 2373.5252949410014, 2376.0, 2366.4438856868624, 2368.7039120109985, 2395.3772019275116, 2398.9040438382444, 2402.771736685012, 8643.524606146659, 8711.390202685814, 8717.389782715212, 8723.476591404537, 8727.127287271285, 5128.512791284822, 5132.9743351283005, 5289.629725919187, 5316.787328506855, 5322.733863306819, 3413.8634454621783, 3434.261633748756, 3523.9823800881086, 3567.500549923013, 3573.160307327776, 987.7481242283244, 1009.6819501856892, 1023.161007973462, 1027.8920713325067, 1038.8078205531924, 11434.942825285903, 11538.826917596243, 11556.884431155664, 11565.826512602318, 11577.479013444416, 4007.6393124618926, 4494.438195225597, 4584.770761461914, 4585.335126406652, 4630.166570017362, 8973.820523589491, 9009.054049324857, 9102.089791020844, 9109.089091090971, 9153.267738580898, 5187.63686541946, 5192.662476938987, 5192.766325515361, 5201.2198170274005, 5205.6616319939085, 3991.461152744342, 4159.812808423628, 4376.037271800199, 4521.118381915495, 4602.217623004098, 3741.6819570336825, 3785.7539259948016, 3795.9240815183543, 3830.559485659129, 3847.942280865789, 1128.4921785196693, 1130.2709752209778, 1130.728625129963, 1139.7777433400408, 1144.5765066925123, 757.7916073079837, 886.2024178239636, 902.6434558349415, 933.0622724162174, 952.8237276103943, 5480.835574932758, 5770.16332631766, 5779.884402312015, 5825.94174058254, 5859.853503662443, 923.3351986569659, 933.4306073295285, 934.1499235695485, 950.9049095090436, 974.4494360686332, 3192.425363434604, 3750.3249415105543, 3818.4081467372603, 3871.05159235989, 3932.0, 10280.383177009404, 10605.151587872951, 10679.679609611723, 10683.679489615324, 10709.518071686793, 10217.693469195936, 10555.63055293061, 10647.467626618638, 10657.57369705203, 10693.9465302673, 8000.199980001953, 8032.879506807402, 8071.313938315309, 8141.43009989295, 8196.795080123045, 5578.944210557843, 5620.9156862647915, 5686.545076393796, 5750.7987220447085, 5759.769609215585, 9799.216062715108, 9902.801943960936, 9946.005399459997, 9947.403155810674, 9965.451900145561, 9774.266929980422, 9912.058354456316, 10048.447335396624, 10091.344062636199, 10183.7454063649, 6827.863442731217, 6850.760223392157, 6956.756513522002, 6973.79078627642, 6980.965095174492, 8001.8399632008195, 8078.151794061539, 8085.63614637331, 8109.189081091844, 8115.634796434293, 7309.159446663702, 7359.448041397024, 7390.260973902567, 7449.366803821519, 7455.664495097625, 7194.388476979515, 7205.171405288249, 7245.456590755609, 7350.044494215717, 7385.150707668687, 6826.180858297036, 6900.275471075665, 6961.930380696128, 7005.264447233171, 7016.298370162943, 4768.57082862544, 4807.711537307773, 4827.25177597473, 4870.732109733999, 4935.457099718957, 4929.433115191799, 4951.777170027285, 4978.352814134139, 4988.775505102351, 5115.9232611510115], "value": 4476.848892172848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.6358097445015, 313.75840602735894, 318.0759892512252, 322.159164580445, 326.0690727971642, 4461.933071003938, 5082.484170335684, 5132.43543210247, 5138.584865113391, 5189.714565698885, 4555.362249285095, 4789.299798571509, 5099.566536844363, 5129.743512824362, 5147.7426128693605, 3181.5704879841155, 3191.377681352137, 3653.506776585167, 3767.321882061228, 3804.2771873344113, 3345.4145524533233, 3412.8225762112033, 3813.4089216171537, 3852.595477474867, 3964.4846169998004, 3371.7808342457784, 3540.6813386795184, 3902.570717221103, 3925.5878132796074, 3939.330313846653, 3263.820489873056, 3431.6225215226304, 3923.1172986078045, 3942.6648734857285, 3952.209558088394, 3210.5344725014975, 3238.2713889374936, 3385.542951701513, 3410.2497450561086, 3418.8290585470877, 4255.425517555122, 4644.373009643722, 4895.0, 4935.703857768545, 4948.490710333347, 4445.955540444586, 4470.831646651372, 4474.261746811772, 4477.350784136314, 4494.528074552158, 5374.7850085996515, 5389.407165211824, 5403.648762830403, 5407.270018547485, 5415.729213539346, 3760.3983362661843, 3775.0, 3777.7166712496673, 3784.924301513954, 3804.4673745675627, 4439.356293337481, 4591.51789062147, 4862.246351815439, 5274.945010997816, 5443.346798384179, 3290.341931613639, 3308.6856748608843, 3408.7784294020807, 3695.2609478104487, 3707.1844194277755, 3309.619393769699, 3381.9684996076435, 3860.1627803564356, 3861.0308742541506, 3895.4351619015083, 3673.669369756735, 3911.1004468972296, 4190.832366705358, 4285.657147428198, 4443.777811109496, 4668.856130248043, 4731.432228132646, 4892.217245240744, 5105.7191854448365, 5427.565794736412, 1207.7342984543386, 1291.3865913690922, 1293.3080801771196, 1294.838145231846, 1309.2864388908067, 669.7086767256259, 755.3768141283354, 768.7347864986571, 776.3982913242282, 797.5254723439544, 3811.6378944000276, 3977.5823538528616, 4324.794354878972, 4375.868723938287, 4385.421248350592, 4290.2921018031675, 4649.95350046502, 4736.810527578928, 4749.691270067435, 4768.950830817214, 4089.848803464486, 4222.0, 4243.405923170759, 4245.853619522712, 4253.468316460442, 2141.496748264157, 2181.451169669552, 2205.8014778669676, 2444.6088625819784, 2462.581361168606, 4198.958010419918, 4321.135772845443, 4900.803967841246, 4907.159815069349, 4918.549028036738, 4440.755758433317, 4605.539446055434, 4712.52874712526, 4738.19450693383, 4807.735574543433, 4160.708750387505, 4228.640565551963, 4375.496817865923, 4417.845375411845, 4760.619150468024, 4240.512341080692, 4541.640025575484, 4642.58401187641, 4690.788914498924, 4774.4509381421585, 4542.318652202196, 4831.613470922248, 5151.793928242828, 5211.0, 5410.540104091039, 4937.0, 5556.527695145958, 5821.79623713168, 5828.213191219131, 5875.177475153397, 3144.6226452824894, 3181.554582358518, 3524.7708898921487, 3681.0, 3717.275131349361, 4718.056388722201, 4861.784553861535, 5487.75305111279, 5523.8895222096135, 5557.666540007613, 5369.838904832861, 5374.865628359323, 5378.853370778139, 5395.946040539544, 5400.783968641211, 5016.495051484613, 5359.490848369399, 5365.812196573102, 5376.919346209888, 5394.865128371665, 4270.807813648331, 5017.54842064216, 5264.842054738364, 5293.444188360167, 5298.496642818926, 5362.812301569426, 5365.149023586856, 5373.167159090347, 5383.0, 5417.593680473901, 4709.7174169549935, 4921.630877684116, 5261.316028916216, 5357.491038351351, 5358.812441564526], "value": 3737.296962683587, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.2336098528169, 686.9484788640854, 723.6092510044576, 728.4864170759615, 777.3586790897511, 14780.26098695065, 15097.245137743124, 15511.767323490156, 15523.844761552382, 15815.367385304575, 14458.0, 14536.273186340668, 14553.927230363834, 14556.781648275284, 14557.636059098528, 709.2411120101493, 735.4484136897327, 860.148453031499, 891.888513935758, 895.1317222294397, 863.2187869977649, 888.8755574219614, 889.3996552327186, 891.9063498332647, 892.2282225874604, 946.3044662173297, 956.9521523923811, 956.9903751542121, 957.301170145794, 959.390786850348, 739.7669734033816, 768.3315515501549, 768.9692412303501, 950.8668786369914, 954.4941181173954, 571.5656101362953, 693.5180049865346, 713.1192976673804, 716.6667499612653, 719.6329871765384, 15060.0, 15070.321835517429, 15081.773773393408, 15083.622909427242, 15087.170205638686, 12211.511539538407, 12215.0, 12365.629031129081, 12456.875431245662, 12464.563740269046, 10667.519961601747, 10763.3541987481, 10782.13742900574, 11192.552297908074, 11299.887001129966, 3945.487086678742, 3947.0, 3960.089179488734, 3998.10042740384, 4117.3618089196225, 83548.99999999939, 83940.58029709873, 84672.0, 84743.57628211881, 85075.5746221265, 9407.388519746195, 9462.148406643435, 9471.147596716328, 9495.097965693249, 9818.116369526777, 19852.60294794096, 20667.58664826695, 21645.783542164692, 21697.783022169577, 21701.0, 7324.816879577947, 7528.698852045857, 7546.849063018709, 7570.318671319497, 7696.653650585625, 3983.2830090583393, 4024.4164596133983, 4058.736182148151, 4086.5300490443383, 4330.761808100522, 3499.3176330615283, 3588.2823435313044, 3605.4591811228524, 3607.0, 3607.8196090195383, 623.1774058243166, 634.4202934692674, 637.7959053102962, 652.5660435810228, 663.5090033375365, 486.53049806936474, 515.520565873741, 525.7187404738446, 552.8617845538615, 603.6921170203228, 7424.0987894520795, 7435.25647435263, 7456.51532650376, 7628.429699274359, 7698.34564062061, 482.81894289641383, 483.93224948507236, 527.6412039812964, 534.0947094674567, 547.5209191957038, 3077.692230776949, 3573.6962358199326, 3706.6478684524927, 3782.3380908340932, 3845.192509573021, 73753.0, 78984.02539936433, 89118.0, 89659.55170224237, 92807.14385712249, 60460.69769651123, 70507.0, 76956.0, 78582.0, 80312.59843700744, 70293.64853175804, 70694.64652676704, 70927.64536177285, 71349.6432517834, 72258.63870680613, 7571.886421703569, 7594.088709354913, 7731.265529774662, 7759.573223472537, 7985.960070199844, 42727.0, 44475.88810279769, 44565.777171113936, 44667.55332446634, 46481.76759116318, 59744.20767376986, 61781.14656560311, 61961.380386197365, 62229.68885155545, 62464.37535624586, 8540.316774657887, 9967.451790151612, 10160.644377446753, 10254.384736915808, 10502.947485262524, 9915.702528924145, 10928.929285728316, 11000.0, 11002.50488727993, 11942.34317112567, 9586.376885502421, 10249.692509224735, 10654.414007229676, 10682.679519614425, 10788.622398216025, 11365.54537818478, 11508.194426390073, 11779.411029448322, 12309.93845030769, 12393.814092788796, 11147.83278250843, 11157.497912594115, 11189.328640281608, 11441.370724609893, 11527.308361498337, 9387.718368448957, 9432.151106400457, 9483.905160948303, 9686.951565242129, 9886.110250077527, 8810.603522841358, 8901.510416926872, 9023.981267425404, 9040.547972601475, 9122.0], "value": 6708.391610496649, "unit": "score"}], "name": "dromaeo_css", "value": 7055.535231241105, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 112.0, 113.0, 112.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 130.0, 143.0, 178.0, 116.0, 164.0, 166.0, 142.0, 166.0, 117.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 183.0, 185.0, 185.0, 183.0, 184.0, 180.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 66.0, 103.0, 81.0, 81.0, 82.0, 64.0, 81.0, 57.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 103.0, 69.0, 71.0, 69.0, 94.0, 70.0, 69.0, 99.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 87.0, 83.0, 87.0, 83.0, 87.0, 83.0, 86.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 92.0, 92.0, 113.0, 151.0, 96.0, 92.0, 92.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 125.0, 96.0, 85.0, 83.0, 85.0, 83.0, 85.0, 85.0], "value": 89.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 57.0, 56.0, 56.0, 57.0, 76.0, 58.0, 55.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 77.0, 76.0, 71.0, 70.0, 70.0, 69.0, 70.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 87.0, 84.0, 85.0, 80.0, 90.0, 88.0, 89.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 141.0, 147.0, 147.0, 148.0, 137.0, 139.0, 138.0, 189.0, 158.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 224.0, 167.0, 169.0, 164.0, 166.0, 166.0, 168.0, 161.0, 163.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 67.0, 66.0, 68.0, 65.0, 68.0, 64.0, 68.0, 77.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1477.1, "alertThreshold": 2.0}]} 20:39:27 INFO - Return code: 0 20:39:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:39:27 ERROR - # TBPL SUCCESS # 20:39:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:39:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:39:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:39:27 INFO - 2016-05-26 20:39:27.396 screenresolution[3490:39618] starting screenresolution argv=screenresolution get 20:39:27 INFO - 2016-05-26 20:39:27.399 screenresolution[3490:39618] Display 0: 1600x1200x32@60 20:39:27 INFO - 2016-05-26 20:39:27.405 screenresolution[3491:39621] starting screenresolution argv=screenresolution list 20:39:27 INFO - Available Modes on Display 0 20:39:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:39:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:39:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:39:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:39:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:39:27 INFO - Intel Iris: 20:39:27 INFO - Chipset Model: Intel Iris 20:39:27 INFO - Type: GPU 20:39:27 INFO - Bus: Built-In 20:39:27 INFO - VRAM (Dynamic, Max): 1536 MB 20:39:27 INFO - Vendor: Intel (0x8086) 20:39:27 INFO - Device ID: 0x0a2e 20:39:27 INFO - Revision ID: 0x0009 20:39:27 INFO - Displays: 20:39:27 INFO - Display: 20:39:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:39:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:39:27 INFO - Display Serial Number: 1600x1200 60 20:39:27 INFO - Main Display: Yes 20:39:27 INFO - Mirror: Off 20:39:27 INFO - Online: Yes 20:39:27 INFO - Rotation: Supported 20:39:27 INFO - Return code: 0 20:39:27 INFO - Running post-action listener: _resource_record_post_action 20:39:27 INFO - ##### 20:39:27 INFO - ##### Finished run-tests step (success) 20:39:27 INFO - ##### 20:39:27 INFO - Running post-run listener: _resource_record_post_run 20:39:27 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 0; Write bytes: 695219712; Read time: 0; Write time: 1945 20:39:27 INFO - TinderboxPrint: CPU usage
53.9% 20:39:27 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:39:27 INFO - TinderboxPrint: I/O write bytes / time
695,219,712 / 1,945 20:39:27 INFO - TinderboxPrint: CPU idle
2,234.3 (46.1%) 20:39:27 INFO - TinderboxPrint: CPU system
971.6 (20.1%) 20:39:27 INFO - TinderboxPrint: CPU user
1,635.6 (33.8%) 20:39:27 INFO - TinderboxPrint: Swap in / out
396,554,240 / 0 20:39:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380535296; Write bytes: 374842880; Read time: 28406; Write time: 811 20:39:27 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 0; Write bytes: 295419904; Read time: 0; Write time: 1072 20:39:28 INFO - Running post-run listener: _upload_blobber_files 20:39:28 INFO - Blob upload gear active. 20:39:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:39:28 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 20:39:28 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'] 20:39:28 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 20:39:28 INFO - (blobuploader) - INFO - Open directory for files ... 20:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:29 INFO - (blobuploader) - INFO - Done attempting. 20:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:39:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:30 INFO - (blobuploader) - INFO - Done attempting. 20:39:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:39:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:32 INFO - (blobuploader) - INFO - Done attempting. 20:39:32 INFO - (blobuploader) - INFO - Iteration through files over. 20:39:32 INFO - Return code: 0 20:39:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:39:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:39:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e0964dac7e16382ffb7f0fb04826e754be71a545e42ced4f2d8090f6d8ffd7949a5a83cacb082f018fd15f869cf7dc43b8e3ccb818ab9322513c236718e5772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/675a8e424db1a07640b0279e10ef94f029a66ced5cf824c9586bbff725a16ac465eb11172ad612c343c5f9f779e10602db1461ca437c1e6c83f50c070905b02e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e78db90b8cecfb25bbd26adcce6b9ba4cfdc5499388da9325b444a4de5dd3d52bee7ddc858d92de535a638a1fb7a0b7c1bc6890e4d353e56ac6306dbb6446086"} 20:39:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:39:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:39:32 INFO - Contents: 20:39:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e0964dac7e16382ffb7f0fb04826e754be71a545e42ced4f2d8090f6d8ffd7949a5a83cacb082f018fd15f869cf7dc43b8e3ccb818ab9322513c236718e5772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/675a8e424db1a07640b0279e10ef94f029a66ced5cf824c9586bbff725a16ac465eb11172ad612c343c5f9f779e10602db1461ca437c1e6c83f50c070905b02e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e78db90b8cecfb25bbd26adcce6b9ba4cfdc5499388da9325b444a4de5dd3d52bee7ddc858d92de535a638a1fb7a0b7c1bc6890e4d353e56ac6306dbb6446086"} 20:39:32 INFO - Running post-run listener: copy_logs_to_upload_dir 20:39:32 INFO - Copying logs to upload dir... 20:39:32 INFO - mkdir: /builds/slave/test/build/upload/logs 20:39:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.229916 ========= master_lag: 2.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-26 20:39:35.226265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 20:39:35.229953) ========= 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.njahb95NxW/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.yiF5S0iUo4/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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/6e0964dac7e16382ffb7f0fb04826e754be71a545e42ced4f2d8090f6d8ffd7949a5a83cacb082f018fd15f869cf7dc43b8e3ccb818ab9322513c236718e5772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/675a8e424db1a07640b0279e10ef94f029a66ced5cf824c9586bbff725a16ac465eb11172ad612c343c5f9f779e10602db1461ca437c1e6c83f50c070905b02e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e78db90b8cecfb25bbd26adcce6b9ba4cfdc5499388da9325b444a4de5dd3d52bee7ddc858d92de535a638a1fb7a0b7c1bc6890e4d353e56ac6306dbb6446086"} build_url:https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011418 build_url: 'https://queue.taskcluster.net/v1/task/LI5kbfKXS4Gi9mOCeZQXdQ/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/6e0964dac7e16382ffb7f0fb04826e754be71a545e42ced4f2d8090f6d8ffd7949a5a83cacb082f018fd15f869cf7dc43b8e3ccb818ab9322513c236718e5772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/675a8e424db1a07640b0279e10ef94f029a66ced5cf824c9586bbff725a16ac465eb11172ad612c343c5f9f779e10602db1461ca437c1e6c83f50c070905b02e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e78db90b8cecfb25bbd26adcce6b9ba4cfdc5499388da9325b444a4de5dd3d52bee7ddc858d92de535a638a1fb7a0b7c1bc6890e4d353e56ac6306dbb6446086"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 20:39:35.261461) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:39:35.261819) ========= 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.njahb95NxW/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.yiF5S0iUo4/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.005394 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 20:39:35.310555) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 20:39:35.310877) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 20:39:35.311219) ========= ========= Total master_lag: 2.38 =========