builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1463621482.18 results: success (0) buildid: 20160518172845 builduid: 213804cc4b3045a08e311bdc6ec89854 revision: 5bd03d0a58768dbe3184fa9f001372291bd679b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.175737) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.176166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.176436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.197128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.197488) ========= 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.03GSEV5OvA/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.BLIGs3ifs0/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-18 18:31:22-- 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% 64.7M=0s 2016-05-18 18:31:22 (64.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.612680 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.827353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.827679) ========= 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.03GSEV5OvA/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.BLIGs3ifs0/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.020978 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:22.879462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 18:31:22.879873) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5bd03d0a58768dbe3184fa9f001372291bd679b8 --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 5bd03d0a58768dbe3184fa9f001372291bd679b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.03GSEV5OvA/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.BLIGs3ifs0/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-18 18:31:22,952 truncating revision to first 12 chars 2016-05-18 18:31:22,953 Setting DEBUG logging. 2016-05-18 18:31:22,953 attempt 1/10 2016-05-18 18:31:22,953 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5bd03d0a5876?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 18:31:24,290 unpacking tar archive at: fx-team-5bd03d0a5876/testing/mozharness/ program finished with exit code 0 elapsedTime=1.567629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 18:31:24.469558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:24.469909) ========= script_repo_revision: 5bd03d0a58768dbe3184fa9f001372291bd679b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:24.470287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:24.470572) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 18:31:24.483759) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-18 18:31:24.484041) ========= /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.03GSEV5OvA/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.BLIGs3ifs0/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:31:24 INFO - MultiFileLogger online at 20160518 18:31:24 in /builds/slave/test 18:31:24 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 18:31:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:31:24 INFO - {'append_to_log': False, 18:31:24 INFO - 'base_work_dir': '/builds/slave/test', 18:31:24 INFO - 'blob_upload_branch': 'Fx-Team', 18:31:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:31:24 INFO - 'branch': 'Fx-Team', 18:31:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:31:24 INFO - 'config_files': ('talos/mac_config.py',), 18:31:24 INFO - 'default_actions': ('clobber', 18:31:24 INFO - 'read-buildbot-config', 18:31:24 INFO - 'download-and-extract', 18:31:24 INFO - 'populate-webroot', 18:31:24 INFO - 'create-virtualenv', 18:31:24 INFO - 'install', 18:31:24 INFO - 'run-tests'), 18:31:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:31:24 INFO - 'download_minidump_stackwalk': True, 18:31:24 INFO - 'download_symbols': 'ondemand', 18:31:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:31:24 INFO - 'tooltool.py': '/tools/tooltool.py', 18:31:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:31:24 INFO - '/tools/misc-python/virtualenv.py')}, 18:31:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:31:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:31:24 INFO - 'installer_path': 'installer.exe', 18:31:24 INFO - 'log_level': 'info', 18:31:24 INFO - 'log_name': 'talos', 18:31:24 INFO - 'log_to_console': True, 18:31:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:31:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:31:24 INFO - 'opt_config_files': (), 18:31:24 INFO - 'pip_index': False, 18:31:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:31:24 INFO - 'cmd': ('bash', 18:31:24 INFO - '-c', 18:31:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:31:24 INFO - 'enabled': True, 18:31:24 INFO - 'halt_on_failure': False, 18:31:24 INFO - 'name': 'check_screen_resolution'},), 18:31:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:31:24 INFO - 'cmd': ('bash', 18:31:24 INFO - '-c', 18:31:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:31:24 INFO - 'enabled': True, 18:31:24 INFO - 'halt_on_failure': False, 18:31:24 INFO - 'name': 'check_screen_resolution'},), 18:31:24 INFO - 'run_cmd_checks_enabled': True, 18:31:24 INFO - 'sps_profile': False, 18:31:24 INFO - 'sps_profile_interval': 0, 18:31:24 INFO - 'suite': 'dromaeojs', 18:31:24 INFO - 'system_bits': '32', 18:31:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:31:24 INFO - 'title': 't-yosemite-r7-0094', 18:31:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:31:24 INFO - 'use_talos_json': True, 18:31:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:31:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:31:24 INFO - 'work_dir': 'build'} 18:31:24 INFO - ##### 18:31:24 INFO - ##### Running clobber step. 18:31:24 INFO - ##### 18:31:24 INFO - Running pre-action listener: _resource_record_pre_action 18:31:24 INFO - Running main action method: clobber 18:31:24 INFO - rmtree: /builds/slave/test/build 18:31:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:31:26 INFO - Running post-action listener: _resource_record_post_action 18:31:26 INFO - ##### 18:31:26 INFO - ##### Finished clobber step (success) 18:31:26 INFO - ##### 18:31:26 INFO - ##### 18:31:26 INFO - ##### Running read-buildbot-config step. 18:31:26 INFO - ##### 18:31:26 INFO - Running pre-action listener: _resource_record_pre_action 18:31:26 INFO - Running main action method: read_buildbot_config 18:31:26 INFO - Using buildbot properties: 18:31:26 INFO - { 18:31:26 INFO - "project": "", 18:31:26 INFO - "product": "firefox", 18:31:26 INFO - "script_repo_revision": "production", 18:31:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:31:26 INFO - "repository": "", 18:31:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:31:26 INFO - "buildid": "20160518172845", 18:31:26 INFO - "pgo_build": "False", 18:31:26 INFO - "basedir": "/builds/slave/test", 18:31:26 INFO - "buildnumber": 710, 18:31:26 INFO - "slavename": "t-yosemite-r7-0094", 18:31:26 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:31:26 INFO - "platform": "yosemite_r7", 18:31:26 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:31:26 INFO - "branch": "fx-team", 18:31:26 INFO - "repo_path": "integration/fx-team", 18:31:26 INFO - "revision": "5bd03d0a58768dbe3184fa9f001372291bd679b8", 18:31:26 INFO - "stage_platform": "macosx64", 18:31:26 INFO - "builduid": "213804cc4b3045a08e311bdc6ec89854", 18:31:26 INFO - "slavebuilddir": "test" 18:31:26 INFO - } 18:31:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:31:26 INFO - Running post-action listener: _resource_record_post_action 18:31:26 INFO - ##### 18:31:26 INFO - ##### Finished read-buildbot-config step (success) 18:31:26 INFO - ##### 18:31:26 INFO - ##### 18:31:26 INFO - ##### Running download-and-extract step. 18:31:26 INFO - ##### 18:31:26 INFO - Running pre-action listener: _resource_record_pre_action 18:31:26 INFO - Running main action method: download_and_extract 18:31:26 INFO - mkdir: /builds/slave/test/build/tests 18:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:26 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:31:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:31:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:31:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:31 INFO - Downloaded 1199 bytes. 18:31:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:31:31 INFO - Using the following test package requirements: 18:31:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:31:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:31:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:31:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'jsshell-mac.zip'], 18:31:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:31:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:31:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:31:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:31:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:31:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:31:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:31:31 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')]} 18:31:31 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:31:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:35 INFO - Downloaded 18628945 bytes. 18:31:35 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'] 18:31:35 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 18:31:38 INFO - Return code: 0 18:31:38 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 18:31:38 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')]} 18:31:38 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:31:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:40 INFO - Downloaded 18628945 bytes. 18:31:40 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'] 18:31:40 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 18:31:43 INFO - Return code: 0 18:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:43 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:44 INFO - Downloaded 10840194 bytes. 18:31:44 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'] 18:31:44 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 18:31:45 INFO - Return code: 0 18:31: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')]} 18:31:45 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:31:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 18:31:50 INFO - Downloaded 110307193 bytes. 18:31:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:50 INFO - mkdir: /builds/slave/test/properties 18:31:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:50 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:50 INFO - Contents: 18:31:50 INFO - build_url:https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:51 INFO - Running post-action listener: _resource_record_post_action 18:31:51 INFO - Running post-action listener: set_extra_try_arguments 18:31:51 INFO - ##### 18:31:51 INFO - ##### Finished download-and-extract step (success) 18:31:51 INFO - ##### 18:31:51 INFO - ##### 18:31:51 INFO - ##### Running populate-webroot step. 18:31:51 INFO - ##### 18:31:51 INFO - Running pre-action listener: _resource_record_pre_action 18:31:51 INFO - Running main action method: populate_webroot 18:31:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:51 INFO - '--tppagecycles', 18:31:51 INFO - '7'], 18:31:51 INFO - 'tests': ['tsvgm']}}, 18:31:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:31:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:31:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['damp', 'tps']}, 18:31:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'tests': ['damp', 'tps']}, 18:31:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['dromaeo_dom']}, 18:31:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['a11yr', 18:31:51 INFO - 'ts_paint', 18:31:51 INFO - 'tpaint', 18:31:51 INFO - 'sessionrestore', 18:31:51 INFO - 'sessionrestore_no_auto_restore', 18:31:51 INFO - 'tabpaint']}, 18:31:51 INFO - 'other-e10s': {'tests': ['a11yr', 18:31:51 INFO - 'ts_paint', 18:31:51 INFO - 'tpaint', 18:31:51 INFO - 'sessionrestore', 18:31:51 INFO - 'sessionrestore_no_auto_restore', 18:31:51 INFO - 'tabpaint']}, 18:31:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:31:51 INFO - 'ts_paint', 18:31:51 INFO - 'tpaint', 18:31:51 INFO - 'sessionrestore', 18:31:51 INFO - 'sessionrestore_no_auto_restore', 18:31:51 INFO - 'tabpaint']}, 18:31:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:31:51 INFO - 'ts_paint', 18:31:51 INFO - 'tpaint', 18:31:51 INFO - 'sessionrestore', 18:31:51 INFO - 'sessionrestore_no_auto_restore', 18:31:51 INFO - 'tabpaint']}, 18:31:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['a11yr', 18:31:51 INFO - 'ts_paint', 18:31:51 INFO - 'tpaint', 18:31:51 INFO - 'sessionrestore', 18:31:51 INFO - 'sessionrestore_no_auto_restore', 18:31:51 INFO - 'tabpaint']}, 18:31:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['a11yr', 18:31:51 INFO - 'ts_paint', 18:31:51 INFO - 'tpaint', 18:31:51 INFO - 'sessionrestore', 18:31:51 INFO - 'sessionrestore_no_auto_restore', 18:31:51 INFO - 'tabpaint']}, 18:31:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['tsvgx', 18:31:51 INFO - 'tsvgr_opacity', 18:31:51 INFO - 'tart', 18:31:51 INFO - 'tscrollx', 18:31:51 INFO - 'cart']}, 18:31:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:31:51 INFO - 'tsvgr_opacity', 18:31:51 INFO - 'tart', 18:31:51 INFO - 'tscrollx', 18:31:51 INFO - 'cart']}, 18:31:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'talos_options': ['--disable-e10s'], 18:31:51 INFO - 'tests': ['tp5o']}, 18:31:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'tests': ['tp5o']}, 18:31:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'talos_options': ['--disable-e10s', 18:31:51 INFO - '--xperf_path', 18:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:51 INFO - 'tests': ['tp5n']}, 18:31:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:51 INFO - 'talos_options': ['--xperf_path', 18:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:51 INFO - 'tests': ['tp5n']}}, 18:31:51 INFO - 'talos.zip': {'path': '', 18:31:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:51 INFO - Running post-action listener: _resource_record_post_action 18:31:51 INFO - ##### 18:31:51 INFO - ##### Finished populate-webroot step (success) 18:31:51 INFO - ##### 18:31:51 INFO - ##### 18:31:51 INFO - ##### Running create-virtualenv step. 18:31:51 INFO - ##### 18:31:51 INFO - Running pre-action listener: _resource_record_pre_action 18:31:51 INFO - Running main action method: create_virtualenv 18:31:51 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:51 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 18:31:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:51 INFO - Using real prefix '/tools/python27' 18:31:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:52 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:55 INFO - Installing pip.................done. 18:31:55 INFO - Return code: 0 18:31:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:55 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:31:55 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 18:31:55 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 18:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:55 INFO - 'HOME': '/Users/cltbld', 18:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:55 INFO - 'LOGNAME': 'cltbld', 18:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:55 INFO - 'MOZ_NO_REMOTE': '1', 18:31:55 INFO - 'NO_EM_RESTART': '1', 18:31:55 INFO - 'PAGER': '/bin/cat', 18:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:55 INFO - 'PWD': '/builds/slave/test', 18:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:55 INFO - 'SHELL': '/bin/bash', 18:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:31:55 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:55 INFO - 'USER': 'cltbld', 18:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:55 INFO - 'XPC_FLAGS': '0x0', 18:31:55 INFO - 'XPC_SERVICE_NAME': '0', 18:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:55 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 18:31:55 INFO - Cleaning up... 18:31:55 INFO - Return code: 0 18:31:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:55 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:31:55 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 18:31:55 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 18:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:55 INFO - 'HOME': '/Users/cltbld', 18:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:55 INFO - 'LOGNAME': 'cltbld', 18:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:55 INFO - 'MOZ_NO_REMOTE': '1', 18:31:55 INFO - 'NO_EM_RESTART': '1', 18:31:55 INFO - 'PAGER': '/bin/cat', 18:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:55 INFO - 'PWD': '/builds/slave/test', 18:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:55 INFO - 'SHELL': '/bin/bash', 18:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:31:55 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:55 INFO - 'USER': 'cltbld', 18:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:55 INFO - 'XPC_FLAGS': '0x0', 18:31:55 INFO - 'XPC_SERVICE_NAME': '0', 18:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:55 INFO - Downloading/unpacking psutil>=3.1.1 18:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:58 INFO - Installing collected packages: psutil 18:31:58 INFO - Running setup.py install for psutil 18:31:58 INFO - building 'psutil._psutil_osx' extension 18:31:58 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 18:31:59 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 18:31:59 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 18:31:59 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 18:31:59 INFO - building 'psutil._psutil_posix' extension 18:31:59 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 18:31:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:31:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:31:59 INFO - ^ 18:31:59 INFO - 1 warning generated. 18:31:59 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 18:31:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:59 INFO - Successfully installed psutil 18:31:59 INFO - Cleaning up... 18:31:59 INFO - Return code: 0 18:31:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:59 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:31:59 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 18:31:59 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 18:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:59 INFO - 'HOME': '/Users/cltbld', 18:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:59 INFO - 'LOGNAME': 'cltbld', 18:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:59 INFO - 'MOZ_NO_REMOTE': '1', 18:31:59 INFO - 'NO_EM_RESTART': '1', 18:31:59 INFO - 'PAGER': '/bin/cat', 18:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:59 INFO - 'PWD': '/builds/slave/test', 18:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:59 INFO - 'SHELL': '/bin/bash', 18:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:31:59 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:59 INFO - 'USER': 'cltbld', 18:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:59 INFO - 'XPC_FLAGS': '0x0', 18:31:59 INFO - 'XPC_SERVICE_NAME': '0', 18:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:32:02 INFO - Installing collected packages: mozsystemmonitor 18:32:02 INFO - Running setup.py install for mozsystemmonitor 18:32:02 INFO - Successfully installed mozsystemmonitor 18:32:02 INFO - Cleaning up... 18:32:02 INFO - Return code: 0 18:32:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:02 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:32:02 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 18:32:02 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 18:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:02 INFO - 'HOME': '/Users/cltbld', 18:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:02 INFO - 'LOGNAME': 'cltbld', 18:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:02 INFO - 'MOZ_NO_REMOTE': '1', 18:32:02 INFO - 'NO_EM_RESTART': '1', 18:32:02 INFO - 'PAGER': '/bin/cat', 18:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:02 INFO - 'PWD': '/builds/slave/test', 18:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:02 INFO - 'SHELL': '/bin/bash', 18:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:32:02 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:02 INFO - 'USER': 'cltbld', 18:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:02 INFO - 'XPC_FLAGS': '0x0', 18:32:02 INFO - 'XPC_SERVICE_NAME': '0', 18:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:32:02 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 18:32:02 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 18:32:02 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 18:32:02 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 18:32:02 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 18:32:02 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 18:32:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:32:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:32:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:06 INFO - Downloading docopt-0.6.1.tar.gz 18:32:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:32:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:32:06 INFO - Running setup.py install for blobuploader 18:32:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:32:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:32:06 INFO - Running setup.py install for requests 18:32:07 INFO - Running setup.py install for docopt 18:32:07 INFO - Successfully installed blobuploader requests docopt 18:32:07 INFO - Cleaning up... 18:32:07 INFO - Return code: 0 18:32:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:07 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:32:07 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 18:32:07 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 18:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:07 INFO - 'HOME': '/Users/cltbld', 18:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:07 INFO - 'LOGNAME': 'cltbld', 18:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:07 INFO - 'MOZ_NO_REMOTE': '1', 18:32:07 INFO - 'NO_EM_RESTART': '1', 18:32:07 INFO - 'PAGER': '/bin/cat', 18:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:07 INFO - 'PWD': '/builds/slave/test', 18:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:07 INFO - 'SHELL': '/bin/bash', 18:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:32:07 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:07 INFO - 'USER': 'cltbld', 18:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:07 INFO - 'XPC_FLAGS': '0x0', 18:32:07 INFO - 'XPC_SERVICE_NAME': '0', 18:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:32:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-x3OaSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:32:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-rAZYjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:32:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-whgAK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-rLeeA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wgGqUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-g5hx8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-K5HpyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-mOxbDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fqkPir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-4yTgf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9HH2DW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_mWJEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:32:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ro33T3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:32:09 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-CFp504-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:09 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-QJBGIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:32:09 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3J86Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:32:09 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-WyiUf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:32:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:32:09 INFO - Running setup.py install for manifestparser 18:32:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Running setup.py install for mozcrash 18:32:09 INFO - Running setup.py install for mozdebug 18:32:09 INFO - Running setup.py install for mozdevice 18:32:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Running setup.py install for mozfile 18:32:10 INFO - Running setup.py install for mozhttpd 18:32:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Running setup.py install for mozinfo 18:32:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Running setup.py install for mozInstall 18:32:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Running setup.py install for mozleak 18:32:10 INFO - Running setup.py install for mozlog 18:32:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Running setup.py install for moznetwork 18:32:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:32:11 INFO - Running setup.py install for mozprocess 18:32:11 INFO - Running setup.py install for mozprofile 18:32:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:32:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:32:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:32:11 INFO - Running setup.py install for mozrunner 18:32:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:32:11 INFO - Running setup.py install for mozscreenshot 18:32:11 INFO - Running setup.py install for moztest 18:32:11 INFO - Running setup.py install for mozversion 18:32:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:32:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:32:12 INFO - Cleaning up... 18:32:12 INFO - Return code: 0 18:32:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32: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')]} 18:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32: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')]} 18:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:12 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:32:12 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 18:32:12 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 18:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:12 INFO - 'HOME': '/Users/cltbld', 18:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:12 INFO - 'LOGNAME': 'cltbld', 18:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:12 INFO - 'MOZ_NO_REMOTE': '1', 18:32:12 INFO - 'NO_EM_RESTART': '1', 18:32:12 INFO - 'PAGER': '/bin/cat', 18:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:12 INFO - 'PWD': '/builds/slave/test', 18:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:12 INFO - 'SHELL': '/bin/bash', 18:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:32:12 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:12 INFO - 'USER': 'cltbld', 18:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:12 INFO - 'XPC_FLAGS': '0x0', 18:32:12 INFO - 'XPC_SERVICE_NAME': '0', 18:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-TcVgoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:32:12 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)) 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-FrcWLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:32:12 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)) 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-paUmdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:32:12 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)) 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-V76IIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:32:12 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)) 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-yBoZAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:32:12 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)) 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-rw7kp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:32:12 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)) 18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:32:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Nt5wbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-N10MK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BfwLa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gMpt2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-6MEDgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gyXUJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-uT_2mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9Z8GJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-HFFESg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-D68ji_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:32:13 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)) 18:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:32:13 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-a0hXnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:32:14 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)) 18:32:14 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)) 18:32:14 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)) 18:32:14 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)) 18:32:14 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)) 18:32:14 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)) 18:32:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32: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 18:32:16 INFO - Downloading blessings-1.6.tar.gz 18:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:32:16 INFO - Installing collected packages: blessings 18:32:16 INFO - Running setup.py install for blessings 18:32:16 INFO - Successfully installed blessings 18:32:16 INFO - Cleaning up... 18:32:16 INFO - Return code: 0 18:32:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:32:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:17 INFO - Reading from file tmpfile_stdout 18:32:17 INFO - Current package versions: 18:32:17 INFO - blessings == 1.6 18:32:17 INFO - blobuploader == 1.2.4 18:32:17 INFO - docopt == 0.6.1 18:32:17 INFO - manifestparser == 1.1 18:32:17 INFO - mozInstall == 1.12 18:32:17 INFO - mozcrash == 0.17 18:32:17 INFO - mozdebug == 0.1 18:32:17 INFO - mozdevice == 0.48 18:32:17 INFO - mozfile == 1.2 18:32:17 INFO - mozhttpd == 0.7 18:32:17 INFO - mozinfo == 0.9 18:32:17 INFO - mozleak == 0.1 18:32:17 INFO - mozlog == 3.2 18:32:17 INFO - moznetwork == 0.27 18:32:17 INFO - mozprocess == 0.22 18:32:17 INFO - mozprofile == 0.28 18:32:17 INFO - mozrunner == 6.11 18:32:17 INFO - mozscreenshot == 0.1 18:32:17 INFO - mozsystemmonitor == 0.3 18:32:17 INFO - moztest == 0.7 18:32:17 INFO - mozversion == 1.4 18:32:17 INFO - psutil == 3.1.1 18:32:17 INFO - requests == 1.2.3 18:32:17 INFO - wsgiref == 0.1.2 18:32:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32:17 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')]} 18:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:17 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')]} 18:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:17 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:32:17 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 18:32:17 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 18:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:17 INFO - 'HOME': '/Users/cltbld', 18:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:17 INFO - 'LOGNAME': 'cltbld', 18:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:17 INFO - 'MOZ_NO_REMOTE': '1', 18:32:17 INFO - 'NO_EM_RESTART': '1', 18:32:17 INFO - 'PAGER': '/bin/cat', 18:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:17 INFO - 'PWD': '/builds/slave/test', 18:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:17 INFO - 'SHELL': '/bin/bash', 18:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:32:17 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:17 INFO - 'USER': 'cltbld', 18:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:17 INFO - 'XPC_FLAGS': '0x0', 18:32:17 INFO - 'XPC_SERVICE_NAME': '0', 18:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 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)) 18:32:17 INFO - Cleaning up... 18:32:17 INFO - Return code: 0 18:32:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:32:17 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')]} 18:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:17 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')]} 18:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:17 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:32:17 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 18:32:17 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 18:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:17 INFO - 'HOME': '/Users/cltbld', 18:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:17 INFO - 'LOGNAME': 'cltbld', 18:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:17 INFO - 'MOZ_NO_REMOTE': '1', 18:32:17 INFO - 'NO_EM_RESTART': '1', 18:32:17 INFO - 'PAGER': '/bin/cat', 18:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:17 INFO - 'PWD': '/builds/slave/test', 18:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:17 INFO - 'SHELL': '/bin/bash', 18:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:32:17 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:17 INFO - 'USER': 'cltbld', 18:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:17 INFO - 'XPC_FLAGS': '0x0', 18:32:17 INFO - 'XPC_SERVICE_NAME': '0', 18:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:17 INFO - Downloading/unpacking jsonschema 18:32:17 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 18:32:17 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 18:32:17 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 18:32:17 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 18:32:17 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 18:32:17 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 18:32:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:32:20 INFO - Downloading/unpacking functools32 (from jsonschema) 18:32:20 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 18:32:20 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 18:32:20 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 18:32:20 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 18:32:20 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 18:32:20 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 18:32:20 INFO - Downloading functools32-3.2.3-2.tar.gz 18:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:32:20 INFO - warning: no files found matching '*.txt' 18:32:20 INFO - no previously-included directories found matching 'build' 18:32:20 INFO - no previously-included directories found matching 'dist' 18:32:20 INFO - no previously-included directories found matching '.git*' 18:32:20 INFO - Installing collected packages: jsonschema, functools32 18:32:20 INFO - Running setup.py install for functools32 18:32:20 INFO - warning: no files found matching '*.txt' 18:32:20 INFO - no previously-included directories found matching 'build' 18:32:20 INFO - no previously-included directories found matching 'dist' 18:32:20 INFO - no previously-included directories found matching '.git*' 18:32:20 INFO - Successfully installed jsonschema functools32 18:32:20 INFO - Cleaning up... 18:32:21 INFO - Return code: 0 18:32:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:21 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 0x10785da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107863570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff26b4a0de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, '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.03GSEV5OvA/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.BLIGs3ifs0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 18:32:21 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 18:32:21 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 18:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:21 INFO - 'HOME': '/Users/cltbld', 18:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:21 INFO - 'LOGNAME': 'cltbld', 18:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:21 INFO - 'MOZ_NO_REMOTE': '1', 18:32:21 INFO - 'NO_EM_RESTART': '1', 18:32:21 INFO - 'PAGER': '/bin/cat', 18:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:21 INFO - 'PWD': '/builds/slave/test', 18:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:21 INFO - 'SHELL': '/bin/bash', 18:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:32:21 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:21 INFO - 'USER': 'cltbld', 18:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:21 INFO - 'XPC_FLAGS': '0x0', 18:32:21 INFO - 'XPC_SERVICE_NAME': '0', 18:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:21 INFO - Downloading/unpacking flake8 18:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:32:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:32:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 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 18:32:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:32:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:25 INFO - no previously-included directories found matching 'docs/_build' 18:32:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:32:25 INFO - Running setup.py install for pep8 18:32:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:25 INFO - no previously-included directories found matching 'docs/_build' 18:32:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:32:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:32:25 INFO - Cleaning up... 18:32:25 INFO - Return code: 0 18:32:25 INFO - Running post-action listener: _resource_record_post_action 18:32:25 INFO - Running post-action listener: _start_resource_monitoring 18:32:25 INFO - Starting resource monitoring. 18:32:25 INFO - ##### 18:32:25 INFO - ##### Finished create-virtualenv step (success) 18:32:25 INFO - ##### 18:32:25 INFO - ##### 18:32:25 INFO - ##### Running install step. 18:32:25 INFO - ##### 18:32:25 INFO - Running pre-action listener: _resource_record_pre_action 18:32:25 INFO - Running main action method: install 18:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:25 INFO - Reading from file tmpfile_stdout 18:32:25 INFO - Detecting whether we're running mozinstall >=1.0... 18:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:32:25 INFO - Reading from file tmpfile_stdout 18:32:25 INFO - Output received: 18:32:25 INFO - Usage: mozinstall [options] installer 18:32:25 INFO - Options: 18:32:25 INFO - -h, --help show this help message and exit 18:32:25 INFO - -d DEST, --destination=DEST 18:32:25 INFO - Directory to install application into. [default: 18:32:25 INFO - "/builds/slave/test"] 18:32:25 INFO - --app=APP Application being installed. [default: firefox] 18:32:25 INFO - mkdir: /builds/slave/test/build/application 18:32:25 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'] 18:32:25 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 18:32:59 INFO - Reading from file tmpfile_stdout 18:32:59 INFO - Output received: 18:32:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:32:59 INFO - Running post-action listener: _resource_record_post_action 18:32:59 INFO - ##### 18:32:59 INFO - ##### Finished install step (success) 18:32:59 INFO - ##### 18:32:59 INFO - ##### 18:32:59 INFO - ##### Running run-tests step. 18:32:59 INFO - ##### 18:32:59 INFO - Running pre-action listener: _resource_record_pre_action 18:32:59 INFO - Running main action method: run_tests 18:32:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:32:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:32:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:32:59 INFO - 2016-05-18 18:32:59.241 screenresolution[2203:32114] starting screenresolution argv=screenresolution get 18:32:59 INFO - 2016-05-18 18:32:59.244 screenresolution[2203:32114] Display 0: 1600x1200x32@60 18:32:59 INFO - 2016-05-18 18:32:59.250 screenresolution[2204:32117] starting screenresolution argv=screenresolution list 18:32:59 INFO - Available Modes on Display 0 18:32:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:32:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:32:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:32:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:32:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:32:59 INFO - Intel Iris: 18:32:59 INFO - Chipset Model: Intel Iris 18:32:59 INFO - Type: GPU 18:32:59 INFO - Bus: Built-In 18:32:59 INFO - VRAM (Dynamic, Max): 1536 MB 18:32:59 INFO - Vendor: Intel (0x8086) 18:32:59 INFO - Device ID: 0x0a2e 18:32:59 INFO - Revision ID: 0x0009 18:32:59 INFO - Displays: 18:32:59 INFO - Display: 18:32:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:32:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:32:59 INFO - Display Serial Number: 1600x1200 60 18:32:59 INFO - Main Display: Yes 18:32:59 INFO - Mirror: Off 18:32:59 INFO - Online: Yes 18:32:59 INFO - Rotation: Supported 18:32:59 INFO - Return code: 0 18:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:59 INFO - Python 2.7.3 18:32:59 INFO - Return code: 0 18:32:59 INFO - grabbing minidump binary from tooltool 18:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:59 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 0x107701d30>, '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 0x107525db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:59 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 18:32:59 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 18:32:59 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 18:32:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:59 INFO - Return code: 0 18:32:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:32:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:32:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:33:00 INFO - Return code: 0 18:33:00 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/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 18:33:00 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/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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 18:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03GSEV5OvA/Render', 18:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:33:00 INFO - 'HOME': '/Users/cltbld', 18:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:33:00 INFO - 'LOGNAME': 'cltbld', 18:33:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:33:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:00 INFO - 'MOZ_NO_REMOTE': '1', 18:33:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:33:00 INFO - 'NO_EM_RESTART': '1', 18:33:00 INFO - 'PAGER': '/bin/cat', 18:33:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:00 INFO - 'PWD': '/builds/slave/test', 18:33:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:33:00 INFO - 'SHELL': '/bin/bash', 18:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BLIGs3ifs0/Listeners', 18:33:00 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 18:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:33:00 INFO - 'USER': 'cltbld', 18:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:00 INFO - 'XPC_FLAGS': '0x0', 18:33:00 INFO - 'XPC_SERVICE_NAME': '0', 18:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:33:00 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/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 18:33:00 INFO - mozversion application_buildid: 20160518172845 18:33:00 INFO - mozversion application_changeset: 5bd03d0a58768dbe3184fa9f001372291bd679b8 18:33:00 INFO - mozversion application_display_name: Nightly 18:33:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:33:00 INFO - mozversion application_name: Firefox 18:33:00 INFO - mozversion application_remotingname: firefox 18:33:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:33:00 INFO - mozversion application_vendor: Mozilla 18:33:00 INFO - mozversion application_version: 49.0a1 18:33:00 INFO - mozversion platform_buildid: 20160518172845 18:33:00 INFO - mozversion platform_changeset: 5bd03d0a58768dbe3184fa9f001372291bd679b8 18:33:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:33:00 INFO - mozversion platform_version: 49.0a1 18:33:00 INFO - using testdate: 1463621580 18:33:00 INFO - actual date: 1463621580 18:33:00 INFO - starting webserver on 'localhost:55834' 18:33:00 INFO - SUITE-START | Running 2 tests 18:33:00 INFO - TEST-START | dromaeo_css 18:33:00 INFO - operating with platform_type : mac_ 18:33:00 INFO - Initialising browser for dromaeo_css test... 18:33:00 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpSFulQh/profile http://localhost:55834/getInfo.html) 18:33:03 INFO - PROCESS | 2222 | __metrics Screen width/height:1600/1200 18:33:03 INFO - PROCESS | 2222 | colorDepth:24 18:33:03 INFO - PROCESS | 2222 | Browser inner width/height: 1024/689 18:33:03 INFO - PROCESS | 2222 | __metrics 18:33:03 INFO - PROCESS | 2222 | 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" 18:33:03 ERROR - PROCESS | 2222 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:33:04 INFO - TEST-INFO | 2222: exit 0 18:33:04 INFO - Browser initialized. 18:33:04 INFO - Running cycle 1/1 for dromaeo_css test... 18:33:04 INFO - TEST-INFO | started process 2224 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpSFulQh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:16 INFO - PROCESS | 2224 | RSS: Main: 238911488 18:33:16 INFO - PROCESS | 2224 | 18:34:07 INFO - PROCESS | 2224 | 1463621647540 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] 18:36:17 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:55834/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55834/tests/dromaeo/cssquery-ext.html) 18:36:19 INFO - PROCESS | 2224 | RSS: Main: 343457792 18:36:19 INFO - PROCESS | 2224 | 18:39:21 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:55834/tests/dromaeo/cssquery-ext.html (next: http://localhost:55834/tests/dromaeo/cssquery-jquery.html) 18:39:22 INFO - PROCESS | 2224 | RSS: Main: 374534144 18:39:22 INFO - PROCESS | 2224 | 18:42:24 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:55834/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55834/tests/dromaeo/cssquery-mootools.html) 18:42:25 INFO - PROCESS | 2224 | RSS: Main: 373297152 18:42:25 INFO - PROCESS | 2224 | 18:45:27 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:55834/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55834/tests/dromaeo/cssquery-prototype.html) 18:45:27 INFO - PROCESS | 2224 | RSS: Main: 368250880 18:45:27 INFO - PROCESS | 2224 | 18:48:31 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:55834/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55834/tests/dromaeo/cssquery-yui.html) 18:48:31 INFO - PROCESS | 2224 | RSS: Main: 337932288 18:48:31 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:55834/tests/dromaeo/cssquery-yui.html (next: http://localhost:55834/tests/dromaeo/cssquery-dojo.html) 18:51:35 INFO - PROCESS | 2224 | RSS: Main: 379613184 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | __start_tp_report 18:51:35 INFO - PROCESS | 2224 | _x_x_mozilla_page_load 18:51:35 INFO - PROCESS | 2224 | _x_x_mozilla_page_load_details 18:51:35 INFO - PROCESS | 2224 | |i|pagename|runs| 18:51:35 INFO - PROCESS | 2224 | |0;dojo.html;3398.422268214403;3429.365567370036;3437.810920399377;3447.724182065435;3492.388831954409;5671.092625179972;5710.2862142232225;5718.799842005531;5783.334916484606;5868.559858010651;5753.453421924921;5797.71011449428;5883.205767221424;5897.823065308037;5930.406959304068;5105.719185444808;5141.537261646451;5215.061288967984;5224.764885580158;5307.230451584537;5259;5302.257683924235;5313.681179129246;5318.547923426494;5333.759980800854;5155.7679904404395;5198.194279886624;5205.531502164786;5214.999999999981;5233.947660523384;4317.417148684919;4401.823927042915;5171.198464238011;5185.55922746567;5299;5178.844634660929;5216.26972223889;5222.92165617516;5247.501487358695;5250.7374631268285;5220.582353411756;5519.448055194489;5573.526250268732;5672.773089076432;5753.884922301572;6267.584166629198;6275.905861412037;6287.371262873722;6318.526110541728;6324.494040476751;6228.906566401508;6230.345813689542;6269.341719119518;6277.968610156966;6290.402411770874;5779.682117483537;5798.9420105799245;5820.73806678692;5864.589478736448;5936.851578710523;6234.781782637586;6261.154744109578;6264.718087686065;6269.216347956505;6270.843228919313;6217.720202590895;6223.96888015557;6224.346443623354;6242.719077641517;6244.906326405107;6183.381661833871;6225.28409232935;6227.626342419469;6281.560290779693;6285.8742825143245;6202.255729312511;6264.843378915563;6287.811365659038;6297.527685423612;6298.181236439325;6250.74997000124;6286.151369565142;6316.2736285326855;6321.494280457552;6331.651759153198;4919.163742163841;4970.65205435616;4972.925406118911;5011.974940125276;5024.874378140576;4315.870523884289;4546.590806827336;4564.52072532386;4567.223571992769;4578.9542104579195;5718.142278658234;5786.508146807569;5812.912806307909;5840;5854.882902342015;6219.038560615025;6240.843978900564;6266.185395898595;6270.028145637433;6278.372162783777;5629.605927585111;5768.798092066758;5776.393478684761;5782.3639399666;5790.276215473066;5380.219868119101;5405.918911216335;5474.479924407175;5475.178723191473;5484.067708489566;6370.362963703593;6378.712957916905;6378.712957916905;6387.712552935036;6390.680465976589;6161.137440758387;6207.565470417027;6215.406995571222;6263.154474146114;6294.244690637152;5335.439778823167;6234.37656234391;6300.905486417616;6381.87236255263;6382.521310901794;6266.655333956493;6270.090836828543;6270.49836013127;6282.403171698681;6289.182406287154;6167.784127555514;6178.289496707936;6284.151639528605;6299.464545513682;6304.211973503312;6110.877782444415;6180.351063138485;6180.47465965398;6183.536234782319;6195.845103872439;6140.9078863816185;6164.4143806338325;6181.072839074175;6202.906956395748;6213.130161777264;5793.304803423448;5987.790427335022;6051.0923361496125;6064.241969753781;6072.362401947732;6134.969325153345;6175.413335733098;6185.195924529782;6200.81397558074;6220.409061139185;6068.120122582289;6104.847378815565;6105;6105.880475544477;6107.006761825686 18:51:35 INFO - PROCESS | 2224 | |1;ext.html;495.13351634639383;501.44339782841064;517.8161752577835;532.7762339817276;566.5892228134603;31225;32053.51919721206;41445.99999999996;42215.36676949848;42222;36116.63883361166;36434.63565364352;43709.34435983463;44187.244084252794;44225.55774442255;9535.523223838814;9751.024897510264;9761.560729767141;9762.511874406287;9782.97278785728;8899.37704360695;9598.616055357777;9624.951875240615;9628.614855405775;9650.179734722557;9627.422354658707;9653.951730241373;9660.710178694615;9681.56432960515;9684.08001239881;8330.208630180123;9578.281628877827;9643.421394716339;9649.035096490365;9652.565634546463;9604.279679024032;9651.324407291493;9678.322517423783;9681.177099946513;9681.854772178423;40518;40904.38643420351;40931.1813763726;40983.79508102441;41047;2939.250491124771;3226.322472280824;3297.3899828531557;3518.9472157917653;3938.9561766131997;29453.410931781244;29602;29621.259468513243;30113.548296775567;30351.392972140653;2744.0395861448533;2748.6564179477564;2770.4459108178444;2882.7405533502088;2927.312081660808;410497;484590.577047116;485441;485757;485850;60780.78438431295;61384.07923881146;61645.38354616396;61714;61715.69142154261;36917.63082369196;38215;38351.42472862909;38415.807920960215;63997.680011600576;16814.991100534007;16968.74546881798;16994.49016529506;17005.23476443563;17019.659606807796;10832.837507437394;12831.871681283068;12973.935130324287;12985.220886746825;12988.415521301564;16407.671846563;19709.507262318555;19828.90085549563;19845.30541431043;19855;7060.964695176492;7572.4699271050495;7769.7669069928;7902.920970790334;7970.521768693897;956.3066776587058;963.7735132243918;976.4873441443299;1105.237386203517;1121.6018313498753;24727.62908556373;26222.868885655833;29743.85128074389;30050.647720852176;30182;1112.6606385052476;1142.7885841119253;1143.7883991461606;1175.1480176871871;1188.8811118888216;15040.774388384183;15127.16800575957;15218.619534511505;15434.15112168819;15613.3754649815;735504;736549;746900;749145;753021;59624;60382;60476;60496;60503.69748151319;1326.2109045118139;1452.847451017649;1467.5230550071055;1492.9626759330886;1509.0342181004385;26829.463410732067;30309.696903030686;30354.544681830233;30545.694543055175;30978.69021309758;49796;50072;50292.99414011773;50575.74712126416;50644.746776265885;522839;523019;523090;523979;525628;21222.893885530473;26533;27696.723032769416;27702.44595108127;28512.150859633683;46054;46662.53337466582;47097.2935405976;47110.76444617755;47920.2811957814;36249.637503624625;37867.81066094652;37954.620453795105;38509;38626.6137338623;36930.44604330883;37188.44217336688;37565.055485954086;37576.248475030894;37932;31849.68150318467;36106.187640650496;36949.63050369569;37069;37781.4332785014;31176.68823311738;33230.83384583062;33411.164720882174;33464.66535334616;33589;24232.273031809073;24484.755152448248;24593.385165371008;24628.876855615606;25207.621885671364 18:51:35 INFO - PROCESS | 2224 | |2;jquery.html;343.4401924862474;396.54991584551533;396.9761814291142;403.6783963137271;419.07593755768534;19706;20139;21071.36785896422;21126.788732112655;21262;20043;20611.278605248783;21063.2627858025;21064.684029739565;21111;4687.20317546018;5054.898902021967;5424.647397919142;5424.945750542503;5475.206095116206;5032.220005899091;5428.375736790261;5518.944810551883;5577.247071645337;5579.163125531162;5538.584606154534;5569.498745112939;5572.275604171452;5574.052411090124;5587.692676902769;5285.550728188108;5553.916691249614;5585.664860108366;5591.860203494904;5592.217089607459;5419.891602167934;5437.048516509595;5442.0476416627325;5443.618946673734;5446.2375267462585;23708.40728981772;24412;25131;26324.73675263242;26330.6050409244;2053.301877361704;2127.1491403438595;2137.818285445739;2170.4356867214638;2253.8196944244423;10320.535575899028;10332.12176964959;10400.323978941344;10731.409772462512;13073.15024523403;1648.826873178323;1687.527492302145;1749.8775085744003;1804.927802887883;1873.100911562444;81717.59141204256;128718.99999999907;131608.36783264377;132780.33609831985;133329.66670333268;11714.88285117138;11716.23844450108;11746.765064698659;11762.764744705059;11828.349440780668;15449.613759656098;15498.690026199412;15528.14595197275;15544;15544.689106217813;8780.912190878009;8849.911500885037;8852.247558957431;8855.64577416909;8970.86543701845;7433.442491813135;8026.839463210703;8694.391392602583;8843.823123537493;8856.64573417069;3984.322665146932;4261.637760790368;4693.741844198534;4703.764811759398;4762.761861906891;1676.924538395775;1834.7981722010568;1971.7929617389968;1989.5225145964864;1991.3627639155332;905.3029167541089;920.070728564155;941.5904081724472;988.5650313861878;1056.2553399853084;18585.628287434447;18963.43109706711;19746.210151593776;19858.60282794365;20639.96800159986;928.2063835420753;929.9581518831534;938.901415351392;957.9952100239454;997.6057462091039;10476.109530689977;10647.254692171473;10650.307729997525;10664.360138391721;10665.680029599125;613258;618156;626078;626873;634561;130911.34544327218;132711;134387;135078.99999999802;137823;994.2493417469908;1001.3641337750436;1016.1667432705259;1024.293237666023;1031.7162780235344;17372.30510779605;17864.46406607804;18001.189946452178;18040;18219.908900455943;45062.549374505834;53152.46847531475;54355.72822135864;54916.72541637266;55868;72364;75265.37792615459;78604.60697696474;82301;83623.5818820902;7654.15804261542;7954.721584744506;8115.4319197655595;8251.381146414164;8421.663133474593;10362.481875906326;10438.53026613789;12276.631701048982;12396.31820249895;12595.87404125947;9480.383775054599;9891.752706182404;10751.19366047534;10952.178586605876;11036.392998385167;10561.313514621525;10603.94698026505;10650.360978341325;10722.678319650424;10885.45572721345;10132.392056476636;10427.478626068514;10881.183911206534;11090.667279981613;11228.494717737885;7638.770836874903;7952.681892724227;8109.229623185788;8293.940268503236;8322.916770832215;7271.672774725044;9508;10967.670969870916;11285.71785705364;11297.66107016758 18:51:35 INFO - PROCESS | 2224 | |3;mootools.html;1443.6607397261635;1602.0788046873047;1616.5473667373135;1864.7762268527779;1984.5038740314922;8041.437099403044;8810.911890881089;9039.72880813575;9050.547472626375;9196.540172991341;8725.083866194054;8998.460092394462;9011.909880901188;9016.36885418021;9022.639094436214;2159.287435146398;2479.566075936713;2513.40934880303;2514.635377870208;2517.924462266135;2020.7069974853712;2444.2746337833364;2458.9508209835794;2499.4126380300613;2508.184839927022;2448.0574978633267;2460.1020627470934;2475.7029156501244;2488.8631125288102;2488.962665560018;2433.4281443860677;2446.2416650838295;2468.9259322220364;2475.2945410557995;2478.4547399572066;2391.5934291170543;2456.9385765355833;2467.580511313063;2467.753224677536;2468.1114798672465;8034.517928924283;8271.503709777433;8944.68693595721;9018.098190180996;9038.457692538403;5328.254044433782;5487.75305111271;5508.256385388001;5573.442655734394;5587.38538760736;3495.3358861816173;3625.5105560749225;3701.8704345347783;3712.053426376257;3712.517372741553;1052.41590917041;1063.4310643805527;1064.7817197474499;1068.0921216965594;1069.7486090768664;11985.101117416229;12128.878711212776;12145.02839772816;12152.149349545536;12158.5744498943;4679.742614156186;4705.458872229738;4835.661503694778;4837.37114175155;4838.298446725205;9430.528473576294;9450.43297402158;9461.668841590648;9461.716148515556;9472.668456603986;5362.088444964365;5404.54061404785;5408.8377348679605;5417.133258678593;5417.5395091416885;4898.289747986522;5006.974965125151;5026.472220416877;5086.669366491166;5109.054824857413;3806.276807406584;3831.04223944014;3836.635519625631;3894.1043559981363;3915.882523524299;1195.3664557784389;1204.3315959642482;1205.69254840017;1218.5125949620224;1220.408102070495;832.4173078844833;886.0607755778766;914.6478605736706;923.7736754495191;927.2072378116748;5933.6736479493175;6088.512918966472;6187.288461826857;6216.316205217422;6352.555321127569;947.0245646983618;1050.422267752733;1056.6777132974516;1061.9575216991389;1116.659418877234;3482.0250329907158;3667.2298817248143;4005.239004589119;4045.615666511677;4090.9181816363507;8948.373613846969;11047.944760276147;11066.667999960015;11091.611793587186;11168.106551475863;10518.368897866152;10944.179186560881;11093.667189984313;11108.777824443467;11173.664790056311;8261.628226729812;8433.662653493791;8507.319414446953;8513.74458766238;8527.147285271423;5916.556258280732;5924.851878703067;6029.125776762257;6139.140520327247;6202.472789812917;9857.35927164732;10247.38515689061;10354;10356.482175891326;10412.895871041193;10499.790004200026;10596.523156457824;10623.415712135908;10625.362478251329;10633.468326583485;7607.543547387175;7660.655270512952;7704.730334438268;7755.573443460664;7807.960960195162;8317.833643326978;8361.122082181284;8432.5783710813;8434.536100514286;8481.533515656698;7393.07586551681;7532.962335188289;7557.244275572399;7659.119201291923;7724.034495688039;7366.337029667356;7407.481476296607;7433.442491813027;7534.698612055457;7597.430192735677;7064.399526040344;7103.857922841617;7143.285671432815;7151.570905745672;7153.642317884189;4411.338299255138;4807.399075115611;4986.80052797884;5000.049990501793;5092.8472145835685;4957.652964292465;5055.241713742821;5069;5096.643234973516;5104.2088476286235 18:51:35 INFO - PROCESS | 2224 | |4;prototype.html;309.83888378043406;316.5900159293715;324.02953155299866;328.44820701221937;331.6418268270268;5005.923726398823;5424.728763561826;5441.83674489765;5471.6443431176995;5495.505404513593;5073.670211436254;5103.897922041566;5215.800365915841;5268.288781014562;5354.089804733195;3980.034874018853;4108.890599538138;4110.260153172428;4118.938215926763;4134.917301653965;3592.6407359264126;4126.277901367263;4129.648979836703;4131.421600975866;4150.070217348947;3851.499305090348;3918.94121588175;4089.6114869087387;4109.363048727452;4127.669786417079;3858.942115868236;4042.555318914917;4065.8780236592947;4076.1236334188143;4095.1604920991126;2903.912882613525;3177.9364412711616;3217.7264932480766;3434.3131373725355;3594.3350480160993;4359.433273674434;4958.157113290749;5085.491450854922;5093.872653183663;5098.337216161875;4702.835400760991;4705.6470764692785;4707.646926480494;4736.431628204603;4749.501302363271;4685.4143232095985;5521.254630624854;5573.27547418833;5630.915536266959;5655.886882262332;3397.9830100849586;3415.351083294191;3977.2840888640035;3989.122393073548;3990.2817492851277;5066.164082926312;5212.29633999409;5544.1960915667405;5552.694601796899;5670.518005969498;3981.6615587675374;4004.9599504004586;4026.697997650188;4045.9797701011307;4054.5945405459806;3775.320442320354;3932;4109.712320137622;4125.443065186221;4147.543770185277;3627.2745450909924;4156.462109019682;4291.451061874361;4617.653675974316;4699.368353721927;5520.585956053312;5570.465438225598;5572.359178694421;5581.6930068846605;5599.356074051536;1294.6698591859015;1325.2181213084327;1370.712150448417;1372.3344178073626;1373.7939309103715;850.434461083385;954.7660823098386;974.6198982396729;981.40134517944;983.7589790501373;4639.489656137822;4694.81220751173;4871.147549178951;5068.695878247317;5215.973920130451;5152.587792976553;5381.300430943952;5725.570582206351;5787.247657804459;5793.623414478046;4359.825606975685;4365.563443655673;4899.559039686445;5106.617003724736;5115.74421278942;2365.716114066323;2578.046122934526;2966.7626589872757;3004.684508126615;3012.6686064532882;4492.775361231991;4741.336212930192;5672.432756724294;6039.63762174271;6099.237595300588;5110.897782044338;5195.220557001986;5867.178594996704;5940.198073260141;5975.655477517524;5140.768665409991;6015.308239552507;6024.608400453956;6035.336113027563;6045.8488537786025;4524.230880750215;5353.571714262772;5354.598405119709;5762.30852297727;5808.970955145197;5161.432242453252;5639.887202256014;5672.092465205547;5706.172604972173;5723.284589426322;4652;5427.348718153688;5682.119271512922;5833.737481813244;6019.939800601938;3899.980500097481;3908.511436070491;3913.8434462621176;3934.2328245991757;3940.389239667856;5339.638392210117;5345.492178243061;5843.29880414353;5908.675022873783;5939.277609493199;4756.881077973078;5393.649412788156;5594.9720251398485;5629.4652008059165;5642.407547207484;5294.285271488454;5508.256385388082;5528.530074943514;5577.916331254954;5584.43116166568;5381.811636592701;5393.919091213714;5395.56835453155;5402.108652072364;5405.48643381805;4684.4378674559275;5637.43625637433;5637.57718171147;5642.181883626933;5664.122061080539;5189.169732842727;5496.945030549643;5504.697241651747;5515.393306736336;5535.612507124462 18:51:35 INFO - PROCESS | 2224 | |5;yui.html;654.7970129259928;675.4360109308728;726.9672864721088;750.3397010630645;762.8550575390672;14905.031172973762;15374;15407.998480098786;15410.69178616427;15426.537203883887;13722.31388430577;14424.567262982102;14473.565793026226;14475.059121157117;14566.126032438062;748.4648476339416;774.8760198368262;921.4425272710031;925.7824411263381;944.3483996042709;939.8073394954018;943.9852158929144;944.2304521814733;945.9668911588096;947.1144479911305;1003.2275148135931;1003.8694969653972;1004.1715584642662;1007.808516381885;1008.1430783833756;778.9805254868617;806.4314658166008;807.8263173417685;810.3071873548149;962.1917589225085;731.1591669580025;731.6305265840716;732.5714457042583;733.0250766480622;741.8108382362517;14990.175540345277;15023.399064037425;15071;15100.848991510164;15108.773368399483;12125.636230913087;12144.939275303655;12167.330796806173;12317.815232771516;12430.192037517532;10836;10902.945485272603;10907.945460272727;11368.317900925971;11481.942590286995;4021.617946295097;4025.2955732746946;4030.133521292936;4162.7502349859105;4171;79915.99999999942;82003.17996820014;82020;85814;85841;9446.291528135418;9470.715878523655;9613.615455381707;9697.321187516805;9781.070798274153;21681.891590541945;21695.783042169693;21715;21736.239231626816;21761.020754066107;7493.700251989969;7528.736494222676;7667.654955526903;7719.150893401721;7721.92278077212;4037.858674946363;4105.568915263854;4106.014556506417;4106.630403263721;4165.271077561415;3465.9653403466145;3556.5376501054698;3571.2143328467696;3574.12433953683;3576.088097535112;611.9938800612026;616.8149555133442;632.11503894548;638.3616383616384;640.8397900524869;458.431544884341;567.2087438023933;582.266344406043;589.6845187824493;597.3160730963023;7776.766696998986;7805.882911756328;7820.882686759704;7981.960090199512;8045.115037345888;552.1744991238124;580.8867270882138;588.608575297431;592.329630442149;596.2844586496221;3790.677792387623;3807.2956503047026;3820.1977584706965;3827.9043023924623;3879.553851307079;77435.61282193553;95638;97038.51480742551;98601.50699246458;99059.50470247603;83400.3319933598;85376.57311713402;85732.57133714292;86180.1381986172;86576;74385;74466.51066978739;75288.62355688296;75289.24710752822;75529;6291.213598300213;6582.308857569887;7171.175314838861;7283.8543229136185;7638.618069096634;46108.53891461043;46966.53033469622;47036;47263.52736472591;47477.287840681725;62467.06299405603;64346;65238.673806630664;65542.34457655363;65696.6715166421;8933.553322333728;9896.901030989598;9997.55011024491;10104.646337378157;10654.62708805188;10488.26582139243;10664.200184986003;10827.729306767078;10895.291806032583;11018.1185505161;10012.299139060196;10024.097831195228;10089.243306751876;10319.380837149794;10446.164306855282;10123.341982771097;10123.64567240143;10385.63650272237;10444.529996150344;10448.2163837714;9661.275404344562;9882.011798820347;9921.40471571708;10158.13655839262;10274.33216840903;8053.6778528858185;8380.916190838014;8533.487990720576;8664.826703466022;8930.598123084346;7335.559866408033;8393.118722534045;8460.386267371734;8991.775205619912;9005.459672419676 18:51:35 INFO - PROCESS | 2224 | __end_tp_report 18:51:35 INFO - PROCESS | 2224 | __start_cc_report 18:51:35 INFO - PROCESS | 2224 | _x_x_mozilla_cycle_collect,1065 18:51:35 INFO - PROCESS | 2224 | __end_cc_report 18:51:35 INFO - PROCESS | 2224 | __startTimestamp1463622695464__endTimestamp 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | ------- Summary: start ------- 18:51:35 INFO - PROCESS | 2224 | Number of tests: 6 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | [#0] dojo.html Cycles:165 Average:5779.52 Median:6104.85 stddev:653.32 (10.7%) stddev-sans-first:628.06 18:51:35 INFO - PROCESS | 2224 | Values: 3398.4 3429.4 3437.8 3447.7 3492.4 5671.1 5710.3 5718.8 5783.3 5868.6 5753.5 5797.7 5883.2 5897.8 5930.4 5105.7 5141.5 5215.1 5224.8 5307.2 5259.0 5302.3 5313.7 5318.5 5333.8 5155.8 5198.2 5205.5 5215.0 5233.9 4317.4 4401.8 5171.2 5185.6 5299.0 5178.8 5216.3 5222.9 5247.5 5250.7 5220.6 5519.4 5573.5 5672.8 5753.9 6267.6 6275.9 6287.4 6318.5 6324.5 6228.9 6230.3 6269.3 6278.0 6290.4 5779.7 5798.9 5820.7 5864.6 5936.9 6234.8 6261.2 6264.7 6269.2 6270.8 6217.7 6224.0 6224.3 6242.7 6244.9 6183.4 6225.3 6227.6 6281.6 6285.9 6202.3 6264.8 6287.8 6297.5 6298.2 6250.7 6286.2 6316.3 6321.5 6331.7 4919.2 4970.7 4972.9 5012.0 5024.9 4315.9 4546.6 4564.5 4567.2 4579.0 5718.1 5786.5 5812.9 5840.0 5854.9 6219.0 6240.8 6266.2 6270.0 6278.4 5629.6 5768.8 5776.4 5782.4 5790.3 5380.2 5405.9 5474.5 5475.2 5484.1 6370.4 6378.7 6378.7 6387.7 6390.7 6161.1 6207.6 6215.4 6263.2 6294.2 5335.4 6234.4 6300.9 6381.9 6382.5 6266.7 6270.1 6270.5 6282.4 6289.2 6167.8 6178.3 6284.2 6299.5 6304.2 6110.9 6180.4 6180.5 6183.5 6195.8 6140.9 6164.4 6181.1 6202.9 6213.1 5793.3 5987.8 6051.1 6064.2 6072.4 6135.0 6175.4 6185.2 6200.8 6220.4 6068.1 6104.8 6105.0 6105.9 6107.0 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | [#1] ext.html Cycles:180 Average:70359.85 Median:38383.62 stddev:158713.95 (413.5%) stddev-sans-first:159072.50 18:51:35 INFO - PROCESS | 2224 | Values: 495.1 501.4 517.8 532.8 566.6 31225.0 32053.5 41446.0 42215.4 42222.0 36116.6 36434.6 43709.3 44187.2 44225.6 9535.5 9751.0 9761.6 9762.5 9783.0 8899.4 9598.6 9625.0 9628.6 9650.2 9627.4 9654.0 9660.7 9681.6 9684.1 8330.2 9578.3 9643.4 9649.0 9652.6 9604.3 9651.3 9678.3 9681.2 9681.9 40518.0 40904.4 40931.2 40983.8 41047.0 2939.3 3226.3 3297.4 3518.9 3939.0 29453.4 29602.0 29621.3 30113.5 30351.4 2744.0 2748.7 2770.4 2882.7 2927.3 410497.0 484590.6 485441.0 485757.0 485850.0 60780.8 61384.1 61645.4 61714.0 61715.7 36917.6 38215.0 38351.4 38415.8 63997.7 16815.0 16968.7 16994.5 17005.2 17019.7 10832.8 12831.9 12973.9 12985.2 12988.4 16407.7 19709.5 19828.9 19845.3 19855.0 7061.0 7572.5 7769.8 7902.9 7970.5 956.3 963.8 976.5 1105.2 1121.6 24727.6 26222.9 29743.9 30050.6 30182.0 1112.7 1142.8 1143.8 1175.1 1188.9 15040.8 15127.2 15218.6 15434.2 15613.4 735504.0 736549.0 746900.0 749145.0 753021.0 59624.0 60382.0 60476.0 60496.0 60503.7 1326.2 1452.8 1467.5 1493.0 1509.0 26829.5 30309.7 30354.5 30545.7 30978.7 49796.0 50072.0 50293.0 50575.7 50644.7 522839.0 523019.0 523090.0 523979.0 525628.0 21222.9 26533.0 27696.7 27702.4 28512.2 46054.0 46662.5 47097.3 47110.8 47920.3 36249.6 37867.8 37954.6 38509.0 38626.6 36930.4 37188.4 37565.1 37576.2 37932.0 31849.7 36106.2 36949.6 37069.0 37781.4 31176.7 33230.8 33411.2 33464.7 33589.0 24232.3 24484.8 24593.4 24628.9 25207.6 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | [#2] jquery.html Cycles:180 Average:35869.93 Median:24060.20 stddev:104220.76 (433.2%) stddev-sans-first:104478.99 18:51:35 INFO - PROCESS | 2224 | Values: 343.4 396.5 397.0 403.7 419.1 19706.0 20139.0 21071.4 21126.8 21262.0 20043.0 20611.3 21063.3 21064.7 21111.0 4687.2 5054.9 5424.6 5424.9 5475.2 5032.2 5428.4 5518.9 5577.2 5579.2 5538.6 5569.5 5572.3 5574.1 5587.7 5285.6 5553.9 5585.7 5591.9 5592.2 5419.9 5437.0 5442.0 5443.6 5446.2 23708.4 24412.0 25131.0 26324.7 26330.6 2053.3 2127.1 2137.8 2170.4 2253.8 10320.5 10332.1 10400.3 10731.4 13073.2 1648.8 1687.5 1749.9 1804.9 1873.1 81717.6 128719.0 131608.4 132780.3 133329.7 11714.9 11716.2 11746.8 11762.8 11828.3 15449.6 15498.7 15528.1 15544.0 15544.7 8780.9 8849.9 8852.2 8855.6 8970.9 7433.4 8026.8 8694.4 8843.8 8856.6 3984.3 4261.6 4693.7 4703.8 4762.8 1676.9 1834.8 1971.8 1989.5 1991.4 905.3 920.1 941.6 988.6 1056.3 18585.6 18963.4 19746.2 19858.6 20640.0 928.2 930.0 938.9 958.0 997.6 10476.1 10647.3 10650.3 10664.4 10665.7 613258.0 618156.0 626078.0 626873.0 634561.0 130911.3 132711.0 134387.0 135079.0 137823.0 994.2 1001.4 1016.2 1024.3 1031.7 17372.3 17864.5 18001.2 18040.0 18219.9 45062.5 53152.5 54355.7 54916.7 55868.0 72364.0 75265.4 78604.6 82301.0 83623.6 7654.2 7954.7 8115.4 8251.4 8421.7 10362.5 10438.5 12276.6 12396.3 12595.9 9480.4 9891.8 10751.2 10952.2 11036.4 10561.3 10603.9 10650.4 10722.7 10885.5 10132.4 10427.5 10881.2 11090.7 11228.5 7638.8 7952.7 8109.2 8293.9 8322.9 7271.7 9508.0 10967.7 11285.7 11297.7 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | [#3] mootools.html Cycles:180 Average:5820.01 Median:4972.23 stddev:3255.11 (65.5%) stddev-sans-first:3247.62 18:51:35 INFO - PROCESS | 2224 | Values: 1443.7 1602.1 1616.5 1864.8 1984.5 8041.4 8810.9 9039.7 9050.5 9196.5 8725.1 8998.5 9011.9 9016.4 9022.6 2159.3 2479.6 2513.4 2514.6 2517.9 2020.7 2444.3 2459.0 2499.4 2508.2 2448.1 2460.1 2475.7 2488.9 2489.0 2433.4 2446.2 2468.9 2475.3 2478.5 2391.6 2456.9 2467.6 2467.8 2468.1 8034.5 8271.5 8944.7 9018.1 9038.5 5328.3 5487.8 5508.3 5573.4 5587.4 3495.3 3625.5 3701.9 3712.1 3712.5 1052.4 1063.4 1064.8 1068.1 1069.7 11985.1 12128.9 12145.0 12152.1 12158.6 4679.7 4705.5 4835.7 4837.4 4838.3 9430.5 9450.4 9461.7 9461.7 9472.7 5362.1 5404.5 5408.8 5417.1 5417.5 4898.3 5007.0 5026.5 5086.7 5109.1 3806.3 3831.0 3836.6 3894.1 3915.9 1195.4 1204.3 1205.7 1218.5 1220.4 832.4 886.1 914.6 923.8 927.2 5933.7 6088.5 6187.3 6216.3 6352.6 947.0 1050.4 1056.7 1062.0 1116.7 3482.0 3667.2 4005.2 4045.6 4090.9 8948.4 11047.9 11066.7 11091.6 11168.1 10518.4 10944.2 11093.7 11108.8 11173.7 8261.6 8433.7 8507.3 8513.7 8527.1 5916.6 5924.9 6029.1 6139.1 6202.5 9857.4 10247.4 10354.0 10356.5 10412.9 10499.8 10596.5 10623.4 10625.4 10633.5 7607.5 7660.7 7704.7 7755.6 7808.0 8317.8 8361.1 8432.6 8434.5 8481.5 7393.1 7533.0 7557.2 7659.1 7724.0 7366.3 7407.5 7433.4 7534.7 7597.4 7064.4 7103.9 7143.3 7151.6 7153.6 4411.3 4807.4 4986.8 5000.0 5092.8 4957.7 5055.2 5069.0 5096.6 5104.2 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | [#4] prototype.html Cycles:180 Average:4524.50 Median:5096.10 stddev:1373.78 (27.0%) stddev-sans-first:1340.72 18:51:35 INFO - PROCESS | 2224 | Values: 309.8 316.6 324.0 328.4 331.6 5005.9 5424.7 5441.8 5471.6 5495.5 5073.7 5103.9 5215.8 5268.3 5354.1 3980.0 4108.9 4110.3 4118.9 4134.9 3592.6 4126.3 4129.6 4131.4 4150.1 3851.5 3918.9 4089.6 4109.4 4127.7 3858.9 4042.6 4065.9 4076.1 4095.2 2903.9 3177.9 3217.7 3434.3 3594.3 4359.4 4958.2 5085.5 5093.9 5098.3 4702.8 4705.6 4707.6 4736.4 4749.5 4685.4 5521.3 5573.3 5630.9 5655.9 3398.0 3415.4 3977.3 3989.1 3990.3 5066.2 5212.3 5544.2 5552.7 5670.5 3981.7 4005.0 4026.7 4046.0 4054.6 3775.3 3932.0 4109.7 4125.4 4147.5 3627.3 4156.5 4291.5 4617.7 4699.4 5520.6 5570.5 5572.4 5581.7 5599.4 1294.7 1325.2 1370.7 1372.3 1373.8 850.4 954.8 974.6 981.4 983.8 4639.5 4694.8 4871.1 5068.7 5216.0 5152.6 5381.3 5725.6 5787.2 5793.6 4359.8 4365.6 4899.6 5106.6 5115.7 2365.7 2578.0 2966.8 3004.7 3012.7 4492.8 4741.3 5672.4 6039.6 6099.2 5110.9 5195.2 5867.2 5940.2 5975.7 5140.8 6015.3 6024.6 6035.3 6045.8 4524.2 5353.6 5354.6 5762.3 5809.0 5161.4 5639.9 5672.1 5706.2 5723.3 4652.0 5427.3 5682.1 5833.7 6019.9 3900.0 3908.5 3913.8 3934.2 3940.4 5339.6 5345.5 5843.3 5908.7 5939.3 4756.9 5393.6 5595.0 5629.5 5642.4 5294.3 5508.3 5528.5 5577.9 5584.4 5381.8 5393.9 5395.6 5402.1 5405.5 4684.4 5637.4 5637.6 5642.2 5664.1 5189.2 5496.9 5504.7 5515.4 5535.6 18:51:35 INFO - PROCESS | 2224 | 18:51:35 INFO - PROCESS | 2224 | [#5] yui.html Cycles:180 Average:18364.92 Median:31541.94 stddev:26591.77 (84.3%) stddev-sans-first:26633.12 18:51:35 INFO - PROCESS | 2224 | Values: 654.8 675.4 727.0 750.3 762.9 14905.0 15374.0 15408.0 15410.7 15426.5 13722.3 14424.6 14473.6 14475.1 14566.1 748.5 774.9 921.4 925.8 944.3 939.8 944.0 944.2 946.0 947.1 1003.2 1003.9 1004.2 1007.8 1008.1 779.0 806.4 807.8 810.3 962.2 731.2 731.6 732.6 733.0 741.8 14990.2 15023.4 15071.0 15100.8 15108.8 12125.6 12144.9 12167.3 12317.8 12430.2 10836.0 10902.9 10907.9 11368.3 11481.9 4021.6 4025.3 4030.1 4162.8 4171.0 79916.0 82003.2 82020.0 85814.0 85841.0 9446.3 9470.7 9613.6 9697.3 9781.1 21681.9 21695.8 21715.0 21736.2 21761.0 7493.7 7528.7 7667.7 7719.2 7721.9 4037.9 4105.6 4106.0 4106.6 4165.3 3466.0 3556.5 3571.2 3574.1 3576.1 612.0 616.8 632.1 638.4 640.8 458.4 567.2 582.3 589.7 597.3 7776.8 7805.9 7820.9 7982.0 8045.1 552.2 580.9 588.6 592.3 596.3 3790.7 3807.3 3820.2 3827.9 3879.6 77435.6 95638.0 97038.5 98601.5 99059.5 83400.3 85376.6 85732.6 86180.1 86576.0 74385.0 74466.5 75288.6 75289.2 75529.0 6291.2 6582.3 7171.2 7283.9 7638.6 46108.5 46966.5 47036.0 47263.5 47477.3 62467.1 64346.0 65238.7 65542.3 65696.7 8933.6 9896.9 9997.6 10104.6 10654.6 10488.3 10664.2 10827.7 10895.3 11018.1 10012.3 10024.1 10089.2 10319.4 10446.2 10123.3 10123.6 10385.6 10444.5 10448.2 9661.3 9882.0 9921.4 10158.1 10274.3 8053.7 8380.9 8533.5 8664.8 8930.6 7335.6 8393.1 8460.4 8991.8 9005.5 18:51:35 INFO - PROCESS | 2224 | -------- Summary: end -------- 18:51:35 INFO - PROCESS | 2224 | 18:51:36 INFO - TEST-INFO | 2224: exit 0 18:51:36 INFO - TEST-OK | dromaeo_css | took 1115140ms 18:51:36 INFO - TEST-START | kraken 18:51:36 INFO - operating with platform_type : mac_ 18:51:36 INFO - Initialising browser for kraken test... 18:51:36 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp9mIeoO/profile http://localhost:55834/getInfo.html) 18:51:38 INFO - PROCESS | 2230 | __metrics Screen width/height:1600/1200 18:51:38 INFO - PROCESS | 2230 | colorDepth:24 18:51:38 INFO - PROCESS | 2230 | Browser inner width/height: 1024/689 18:51:38 INFO - PROCESS | 2230 | __metrics 18:51:38 INFO - PROCESS | 2230 | 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" 18:51:38 ERROR - PROCESS | 2230 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:51:39 INFO - TEST-INFO | 2230: exit 0 18:51:39 INFO - Browser initialized. 18:51:39 INFO - Running cycle 1/1 for kraken test... 18:51:39 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp9mIeoO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:51 INFO - PROCESS | 2232 | RSS: Main: 238878720 18:51:51 INFO - PROCESS | 2232 | 18:51:53 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55834/tests/kraken/driver.html?testName=audio-beat-detection) 18:51:54 INFO - PROCESS | 2232 | RSS: Main: 287109120 18:51:54 INFO - PROCESS | 2232 | 18:51:57 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55834/tests/kraken/driver.html?testName=audio-dft) 18:51:58 INFO - PROCESS | 2232 | RSS: Main: 296140800 18:51:58 INFO - PROCESS | 2232 | 18:52:01 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55834/tests/kraken/driver.html?testName=audio-fft) 18:52:02 INFO - PROCESS | 2232 | RSS: Main: 383610880 18:52:02 INFO - PROCESS | 2232 | 18:52:04 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55834/tests/kraken/driver.html?testName=audio-oscillator) 18:52:05 INFO - PROCESS | 2232 | RSS: Main: 283164672 18:52:05 INFO - PROCESS | 2232 | 18:52:07 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55834/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:52:08 INFO - PROCESS | 2232 | RSS: Main: 287195136 18:52:08 INFO - PROCESS | 2232 | 18:52:11 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55834/tests/kraken/driver.html?testName=imaging-darkroom) 18:52:11 INFO - PROCESS | 2232 | RSS: Main: 325246976 18:52:11 INFO - PROCESS | 2232 | 18:52:14 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55834/tests/kraken/driver.html?testName=imaging-desaturate) 18:52:15 INFO - PROCESS | 2232 | RSS: Main: 329011200 18:52:15 INFO - PROCESS | 2232 | 18:52:18 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55834/tests/kraken/driver.html?testName=json-parse-financial) 18:52:19 INFO - PROCESS | 2232 | RSS: Main: 326635520 18:52:19 INFO - PROCESS | 2232 | 18:52:20 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55834/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:52:21 INFO - PROCESS | 2232 | RSS: Main: 316473344 18:52:21 INFO - PROCESS | 2232 | 18:52:23 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:52:24 INFO - PROCESS | 2232 | RSS: Main: 323080192 18:52:24 INFO - PROCESS | 2232 | 18:52:26 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:52:26 INFO - PROCESS | 2232 | RSS: Main: 326037504 18:52:26 INFO - PROCESS | 2232 | 18:52:30 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:52:30 INFO - PROCESS | 2232 | RSS: Main: 330649600 18:52:30 INFO - PROCESS | 2232 | 18:52:33 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:52:34 INFO - PROCESS | 2232 | RSS: Main: 328413184 18:52:34 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:55834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55834/tests/kraken/driver.html?testName=ai-astar) 18:52:36 INFO - PROCESS | 2232 | RSS: Main: 375017472 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | __start_tp_report 18:52:36 INFO - PROCESS | 2232 | _x_x_mozilla_page_load 18:52:36 INFO - PROCESS | 2232 | _x_x_mozilla_page_load_details 18:52:36 INFO - PROCESS | 2232 | |i|pagename|runs| 18:52:36 INFO - PROCESS | 2232 | |0;ai-astar;110;108;111;110;110;110;110;111;110;111 18:52:36 INFO - PROCESS | 2232 | |1;audio-beat-detection;158;122;140;199;118;141;140;118;169;116 18:52:36 INFO - PROCESS | 2232 | |2;audio-dft;252;250;185;184;182;185;186;181;181;185 18:52:36 INFO - PROCESS | 2232 | |3;audio-fft;79;74;57;126;56;62;56;58;113;55 18:52:36 INFO - PROCESS | 2232 | |4;audio-oscillator;92;100;134;81;79;76;103;79;79;100 18:52:36 INFO - PROCESS | 2232 | |5;imaging-gaussian-blur;100;129;87;85;86;83;87;84;87;84 18:52:36 INFO - PROCESS | 2232 | |6;imaging-darkroom;93;92;93;94;93;102;125;94;93;92 18:52:36 INFO - PROCESS | 2232 | |7;imaging-desaturate;126;85;70;130;129;111;83;86;86;83 18:52:36 INFO - PROCESS | 2232 | |8;json-parse-financial;55;55;54;56;56;55;55;119;55;54 18:52:36 INFO - PROCESS | 2232 | |9;json-stringify-tinderbox;64;69;74;73;70;66;66;66;66;67 18:52:36 INFO - PROCESS | 2232 | |10;stanford-crypto-aes;84;83;85;84;85;84;85;83;83;86 18:52:36 INFO - PROCESS | 2232 | |11;stanford-crypto-ccm;156;143;139;135;135;144;136;137;227;179 18:52:36 INFO - PROCESS | 2232 | |12;stanford-crypto-pbkdf2;162;163;163;161;161;161;166;162;160;160 18:52:36 INFO - PROCESS | 2232 | |13;stanford-crypto-sha256-iterative;59;64;84;67;60;57;82;84;82;65 18:52:36 INFO - PROCESS | 2232 | __end_tp_report 18:52:36 INFO - PROCESS | 2232 | __start_cc_report 18:52:36 INFO - PROCESS | 2232 | _x_x_mozilla_cycle_collect,2266 18:52:36 INFO - PROCESS | 2232 | __end_cc_report 18:52:36 INFO - PROCESS | 2232 | __startTimestamp1463622756287__endTimestamp 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | ------- Summary: start ------- 18:52:36 INFO - PROCESS | 2232 | Number of tests: 14 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 18:52:36 INFO - PROCESS | 2232 | Values: 110.0 108.0 111.0 110.0 110.0 110.0 110.0 111.0 110.0 111.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:140.50 stddev:26.81 (19.1%) stddev-sans-first:27.82 18:52:36 INFO - PROCESS | 2232 | Values: 158.0 122.0 140.0 199.0 118.0 141.0 140.0 118.0 169.0 116.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#2] audio-dft Cycles:10 Average:197.10 Median:185.00 stddev:28.47 (15.4%) stddev-sans-first:22.20 18:52:36 INFO - PROCESS | 2232 | Values: 252.0 250.0 185.0 184.0 182.0 185.0 186.0 181.0 181.0 185.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#3] audio-fft Cycles:10 Average:73.60 Median:57.50 stddev:25.70 (44.7%) stddev-sans-first:27.19 18:52:36 INFO - PROCESS | 2232 | Values: 79.0 74.0 57.0 126.0 56.0 62.0 56.0 58.0 113.0 55.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:79.00 stddev:17.94 (22.7%) stddev-sans-first:19.03 18:52:36 INFO - PROCESS | 2232 | Values: 92.0 100.0 134.0 81.0 79.0 76.0 103.0 79.0 79.0 100.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:85.50 stddev:14.12 (16.5%) stddev-sans-first:14.62 18:52:36 INFO - PROCESS | 2232 | Values: 100.0 129.0 87.0 85.0 86.0 83.0 87.0 84.0 87.0 84.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:93.00 stddev:10.22 (11.0%) stddev-sans-first:10.74 18:52:36 INFO - PROCESS | 2232 | Values: 93.0 92.0 93.0 94.0 93.0 102.0 125.0 94.0 93.0 92.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:83.00 stddev:22.66 (27.3%) stddev-sans-first:21.81 18:52:36 INFO - PROCESS | 2232 | Values: 126.0 85.0 70.0 130.0 129.0 111.0 83.0 86.0 86.0 83.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:55.00 stddev:20.25 (36.8%) stddev-sans-first:21.35 18:52:36 INFO - PROCESS | 2232 | Values: 55.0 55.0 54.0 56.0 56.0 55.0 55.0 119.0 55.0 54.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:3.31 (4.9%) stddev-sans-first:3.17 18:52:36 INFO - PROCESS | 2232 | Values: 64.0 69.0 74.0 73.0 70.0 66.0 66.0 66.0 66.0 67.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:1.03 (1.2%) stddev-sans-first:1.09 18:52:36 INFO - PROCESS | 2232 | Values: 84.0 83.0 85.0 84.0 85.0 84.0 85.0 83.0 83.0 86.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:143.50 stddev:29.31 (20.4%) stddev-sans-first:31.07 18:52:36 INFO - PROCESS | 2232 | Values: 156.0 143.0 139.0 135.0 135.0 144.0 136.0 137.0 227.0 179.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.00 stddev:1.79 (1.1%) stddev-sans-first:1.90 18:52:36 INFO - PROCESS | 2232 | Values: 162.0 163.0 163.0 161.0 161.0 161.0 166.0 162.0 160.0 160.0 18:52:36 INFO - PROCESS | 2232 | 18:52:36 INFO - PROCESS | 2232 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:74.50 stddev:11.25 (15.1%) stddev-sans-first:11.15 18:52:36 INFO - PROCESS | 2232 | Values: 59.0 64.0 84.0 67.0 60.0 57.0 82.0 84.0 82.0 65.0 18:52:36 INFO - PROCESS | 2232 | -------- Summary: end -------- 18:52:36 INFO - PROCESS | 2232 | 18:52:37 INFO - TEST-INFO | 2232: exit 0 18:52:37 INFO - TEST-OK | kraken | took 61102ms 18:52:37 INFO - SUITE-END | took 1176s 18:52:37 INFO - Completed test suite (00:19:36) 18:52:37 INFO - javascript benchmark 18:52:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.422268214403, 3429.365567370036, 3437.810920399377, 3447.724182065435, 3492.388831954409, 5671.092625179972, 5710.2862142232225, 5718.799842005531, 5783.334916484606, 5868.559858010651, 5753.453421924921, 5797.71011449428, 5883.205767221424, 5897.823065308037, 5930.406959304068, 5105.719185444808, 5141.537261646451, 5215.061288967984, 5224.764885580158, 5307.230451584537, 5259.0, 5302.257683924235, 5313.681179129246, 5318.547923426494, 5333.759980800854, 5155.7679904404395, 5198.194279886624, 5205.531502164786, 5214.999999999981, 5233.947660523384, 4317.417148684919, 4401.823927042915, 5171.198464238011, 5185.55922746567, 5299.0, 5178.844634660929, 5216.26972223889, 5222.92165617516, 5247.501487358695, 5250.7374631268285, 5220.582353411756, 5519.448055194489, 5573.526250268732, 5672.773089076432, 5753.884922301572, 6267.584166629198, 6275.905861412037, 6287.371262873722, 6318.526110541728, 6324.494040476751, 6228.906566401508, 6230.345813689542, 6269.341719119518, 6277.968610156966, 6290.402411770874, 5779.682117483537, 5798.9420105799245, 5820.73806678692, 5864.589478736448, 5936.851578710523, 6234.781782637586, 6261.154744109578, 6264.718087686065, 6269.216347956505, 6270.843228919313, 6217.720202590895, 6223.96888015557, 6224.346443623354, 6242.719077641517, 6244.906326405107, 6183.381661833871, 6225.28409232935, 6227.626342419469, 6281.560290779693, 6285.8742825143245, 6202.255729312511, 6264.843378915563, 6287.811365659038, 6297.527685423612, 6298.181236439325, 6250.74997000124, 6286.151369565142, 6316.2736285326855, 6321.494280457552, 6331.651759153198, 4919.163742163841, 4970.65205435616, 4972.925406118911, 5011.974940125276, 5024.874378140576, 4315.870523884289, 4546.590806827336, 4564.52072532386, 4567.223571992769, 4578.9542104579195, 5718.142278658234, 5786.508146807569, 5812.912806307909, 5840.0, 5854.882902342015, 6219.038560615025, 6240.843978900564, 6266.185395898595, 6270.028145637433, 6278.372162783777, 5629.605927585111, 5768.798092066758, 5776.393478684761, 5782.3639399666, 5790.276215473066, 5380.219868119101, 5405.918911216335, 5474.479924407175, 5475.178723191473, 5484.067708489566, 6370.362963703593, 6378.712957916905, 6378.712957916905, 6387.712552935036, 6390.680465976589, 6161.137440758387, 6207.565470417027, 6215.406995571222, 6263.154474146114, 6294.244690637152, 5335.439778823167, 6234.37656234391, 6300.905486417616, 6381.87236255263, 6382.521310901794, 6266.655333956493, 6270.090836828543, 6270.49836013127, 6282.403171698681, 6289.182406287154, 6167.784127555514, 6178.289496707936, 6284.151639528605, 6299.464545513682, 6304.211973503312, 6110.877782444415, 6180.351063138485, 6180.47465965398, 6183.536234782319, 6195.845103872439, 6140.9078863816185, 6164.4143806338325, 6181.072839074175, 6202.906956395748, 6213.130161777264, 5793.304803423448, 5987.790427335022, 6051.0923361496125, 6064.241969753781, 6072.362401947732, 6134.969325153345, 6175.413335733098, 6185.195924529782, 6200.81397558074, 6220.409061139185, 6068.120122582289, 6104.847378815565, 6105.0, 6105.880475544477, 6107.006761825686], "value": 5737.606665521521, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.13351634639383, 501.44339782841064, 517.8161752577835, 532.7762339817276, 566.5892228134603, 31225.0, 32053.51919721206, 41445.99999999996, 42215.36676949848, 42222.0, 36116.63883361166, 36434.63565364352, 43709.34435983463, 44187.244084252794, 44225.55774442255, 9535.523223838814, 9751.024897510264, 9761.560729767141, 9762.511874406287, 9782.97278785728, 8899.37704360695, 9598.616055357777, 9624.951875240615, 9628.614855405775, 9650.179734722557, 9627.422354658707, 9653.951730241373, 9660.710178694615, 9681.56432960515, 9684.08001239881, 8330.208630180123, 9578.281628877827, 9643.421394716339, 9649.035096490365, 9652.565634546463, 9604.279679024032, 9651.324407291493, 9678.322517423783, 9681.177099946513, 9681.854772178423, 40518.0, 40904.38643420351, 40931.1813763726, 40983.79508102441, 41047.0, 2939.250491124771, 3226.322472280824, 3297.3899828531557, 3518.9472157917653, 3938.9561766131997, 29453.410931781244, 29602.0, 29621.259468513243, 30113.548296775567, 30351.392972140653, 2744.0395861448533, 2748.6564179477564, 2770.4459108178444, 2882.7405533502088, 2927.312081660808, 410497.0, 484590.577047116, 485441.0, 485757.0, 485850.0, 60780.78438431295, 61384.07923881146, 61645.38354616396, 61714.0, 61715.69142154261, 36917.63082369196, 38215.0, 38351.42472862909, 38415.807920960215, 63997.680011600576, 16814.991100534007, 16968.74546881798, 16994.49016529506, 17005.23476443563, 17019.659606807796, 10832.837507437394, 12831.871681283068, 12973.935130324287, 12985.220886746825, 12988.415521301564, 16407.671846563, 19709.507262318555, 19828.90085549563, 19845.30541431043, 19855.0, 7060.964695176492, 7572.4699271050495, 7769.7669069928, 7902.920970790334, 7970.521768693897, 956.3066776587058, 963.7735132243918, 976.4873441443299, 1105.237386203517, 1121.6018313498753, 24727.62908556373, 26222.868885655833, 29743.85128074389, 30050.647720852176, 30182.0, 1112.6606385052476, 1142.7885841119253, 1143.7883991461606, 1175.1480176871871, 1188.8811118888216, 15040.774388384183, 15127.16800575957, 15218.619534511505, 15434.15112168819, 15613.3754649815, 735504.0, 736549.0, 746900.0, 749145.0, 753021.0, 59624.0, 60382.0, 60476.0, 60496.0, 60503.69748151319, 1326.2109045118139, 1452.847451017649, 1467.5230550071055, 1492.9626759330886, 1509.0342181004385, 26829.463410732067, 30309.696903030686, 30354.544681830233, 30545.694543055175, 30978.69021309758, 49796.0, 50072.0, 50292.99414011773, 50575.74712126416, 50644.746776265885, 522839.0, 523019.0, 523090.0, 523979.0, 525628.0, 21222.893885530473, 26533.0, 27696.723032769416, 27702.44595108127, 28512.150859633683, 46054.0, 46662.53337466582, 47097.2935405976, 47110.76444617755, 47920.2811957814, 36249.637503624625, 37867.81066094652, 37954.620453795105, 38509.0, 38626.6137338623, 36930.44604330883, 37188.44217336688, 37565.055485954086, 37576.248475030894, 37932.0, 31849.68150318467, 36106.187640650496, 36949.63050369569, 37069.0, 37781.4332785014, 31176.68823311738, 33230.83384583062, 33411.164720882174, 33464.66535334616, 33589.0, 24232.273031809073, 24484.755152448248, 24593.385165371008, 24628.876855615606, 25207.621885671364], "value": 19691.58335018805, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.4401924862474, 396.54991584551533, 396.9761814291142, 403.6783963137271, 419.07593755768534, 19706.0, 20139.0, 21071.36785896422, 21126.788732112655, 21262.0, 20043.0, 20611.278605248783, 21063.2627858025, 21064.684029739565, 21111.0, 4687.20317546018, 5054.898902021967, 5424.647397919142, 5424.945750542503, 5475.206095116206, 5032.220005899091, 5428.375736790261, 5518.944810551883, 5577.247071645337, 5579.163125531162, 5538.584606154534, 5569.498745112939, 5572.275604171452, 5574.052411090124, 5587.692676902769, 5285.550728188108, 5553.916691249614, 5585.664860108366, 5591.860203494904, 5592.217089607459, 5419.891602167934, 5437.048516509595, 5442.0476416627325, 5443.618946673734, 5446.2375267462585, 23708.40728981772, 24412.0, 25131.0, 26324.73675263242, 26330.6050409244, 2053.301877361704, 2127.1491403438595, 2137.818285445739, 2170.4356867214638, 2253.8196944244423, 10320.535575899028, 10332.12176964959, 10400.323978941344, 10731.409772462512, 13073.15024523403, 1648.826873178323, 1687.527492302145, 1749.8775085744003, 1804.927802887883, 1873.100911562444, 81717.59141204256, 128718.99999999907, 131608.36783264377, 132780.33609831985, 133329.66670333268, 11714.88285117138, 11716.23844450108, 11746.765064698659, 11762.764744705059, 11828.349440780668, 15449.613759656098, 15498.690026199412, 15528.14595197275, 15544.0, 15544.689106217813, 8780.912190878009, 8849.911500885037, 8852.247558957431, 8855.64577416909, 8970.86543701845, 7433.442491813135, 8026.839463210703, 8694.391392602583, 8843.823123537493, 8856.64573417069, 3984.322665146932, 4261.637760790368, 4693.741844198534, 4703.764811759398, 4762.761861906891, 1676.924538395775, 1834.7981722010568, 1971.7929617389968, 1989.5225145964864, 1991.3627639155332, 905.3029167541089, 920.070728564155, 941.5904081724472, 988.5650313861878, 1056.2553399853084, 18585.628287434447, 18963.43109706711, 19746.210151593776, 19858.60282794365, 20639.96800159986, 928.2063835420753, 929.9581518831534, 938.901415351392, 957.9952100239454, 997.6057462091039, 10476.109530689977, 10647.254692171473, 10650.307729997525, 10664.360138391721, 10665.680029599125, 613258.0, 618156.0, 626078.0, 626873.0, 634561.0, 130911.34544327218, 132711.0, 134387.0, 135078.99999999802, 137823.0, 994.2493417469908, 1001.3641337750436, 1016.1667432705259, 1024.293237666023, 1031.7162780235344, 17372.30510779605, 17864.46406607804, 18001.189946452178, 18040.0, 18219.908900455943, 45062.549374505834, 53152.46847531475, 54355.72822135864, 54916.72541637266, 55868.0, 72364.0, 75265.37792615459, 78604.60697696474, 82301.0, 83623.5818820902, 7654.15804261542, 7954.721584744506, 8115.4319197655595, 8251.381146414164, 8421.663133474593, 10362.481875906326, 10438.53026613789, 12276.631701048982, 12396.31820249895, 12595.87404125947, 9480.383775054599, 9891.752706182404, 10751.19366047534, 10952.178586605876, 11036.392998385167, 10561.313514621525, 10603.94698026505, 10650.360978341325, 10722.678319650424, 10885.45572721345, 10132.392056476636, 10427.478626068514, 10881.183911206534, 11090.667279981613, 11228.494717737885, 7638.770836874903, 7952.681892724227, 8109.229623185788, 8293.940268503236, 8322.916770832215, 7271.672774725044, 9508.0, 10967.670969870916, 11285.71785705364, 11297.66107016758], "value": 9441.69825516214, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.6607397261635, 1602.0788046873047, 1616.5473667373135, 1864.7762268527779, 1984.5038740314922, 8041.437099403044, 8810.911890881089, 9039.72880813575, 9050.547472626375, 9196.540172991341, 8725.083866194054, 8998.460092394462, 9011.909880901188, 9016.36885418021, 9022.639094436214, 2159.287435146398, 2479.566075936713, 2513.40934880303, 2514.635377870208, 2517.924462266135, 2020.7069974853712, 2444.2746337833364, 2458.9508209835794, 2499.4126380300613, 2508.184839927022, 2448.0574978633267, 2460.1020627470934, 2475.7029156501244, 2488.8631125288102, 2488.962665560018, 2433.4281443860677, 2446.2416650838295, 2468.9259322220364, 2475.2945410557995, 2478.4547399572066, 2391.5934291170543, 2456.9385765355833, 2467.580511313063, 2467.753224677536, 2468.1114798672465, 8034.517928924283, 8271.503709777433, 8944.68693595721, 9018.098190180996, 9038.457692538403, 5328.254044433782, 5487.75305111271, 5508.256385388001, 5573.442655734394, 5587.38538760736, 3495.3358861816173, 3625.5105560749225, 3701.8704345347783, 3712.053426376257, 3712.517372741553, 1052.41590917041, 1063.4310643805527, 1064.7817197474499, 1068.0921216965594, 1069.7486090768664, 11985.101117416229, 12128.878711212776, 12145.02839772816, 12152.149349545536, 12158.5744498943, 4679.742614156186, 4705.458872229738, 4835.661503694778, 4837.37114175155, 4838.298446725205, 9430.528473576294, 9450.43297402158, 9461.668841590648, 9461.716148515556, 9472.668456603986, 5362.088444964365, 5404.54061404785, 5408.8377348679605, 5417.133258678593, 5417.5395091416885, 4898.289747986522, 5006.974965125151, 5026.472220416877, 5086.669366491166, 5109.054824857413, 3806.276807406584, 3831.04223944014, 3836.635519625631, 3894.1043559981363, 3915.882523524299, 1195.3664557784389, 1204.3315959642482, 1205.69254840017, 1218.5125949620224, 1220.408102070495, 832.4173078844833, 886.0607755778766, 914.6478605736706, 923.7736754495191, 927.2072378116748, 5933.6736479493175, 6088.512918966472, 6187.288461826857, 6216.316205217422, 6352.555321127569, 947.0245646983618, 1050.422267752733, 1056.6777132974516, 1061.9575216991389, 1116.659418877234, 3482.0250329907158, 3667.2298817248143, 4005.239004589119, 4045.615666511677, 4090.9181816363507, 8948.373613846969, 11047.944760276147, 11066.667999960015, 11091.611793587186, 11168.106551475863, 10518.368897866152, 10944.179186560881, 11093.667189984313, 11108.777824443467, 11173.664790056311, 8261.628226729812, 8433.662653493791, 8507.319414446953, 8513.74458766238, 8527.147285271423, 5916.556258280732, 5924.851878703067, 6029.125776762257, 6139.140520327247, 6202.472789812917, 9857.35927164732, 10247.38515689061, 10354.0, 10356.482175891326, 10412.895871041193, 10499.790004200026, 10596.523156457824, 10623.415712135908, 10625.362478251329, 10633.468326583485, 7607.543547387175, 7660.655270512952, 7704.730334438268, 7755.573443460664, 7807.960960195162, 8317.833643326978, 8361.122082181284, 8432.5783710813, 8434.536100514286, 8481.533515656698, 7393.07586551681, 7532.962335188289, 7557.244275572399, 7659.119201291923, 7724.034495688039, 7366.337029667356, 7407.481476296607, 7433.442491813027, 7534.698612055457, 7597.430192735677, 7064.399526040344, 7103.857922841617, 7143.285671432815, 7151.570905745672, 7153.642317884189, 4411.338299255138, 4807.399075115611, 4986.80052797884, 5000.049990501793, 5092.8472145835685, 4957.652964292465, 5055.241713742821, 5069.0, 5096.643234973516, 5104.2088476286235], "value": 4684.079499128154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.83888378043406, 316.5900159293715, 324.02953155299866, 328.44820701221937, 331.6418268270268, 5005.923726398823, 5424.728763561826, 5441.83674489765, 5471.6443431176995, 5495.505404513593, 5073.670211436254, 5103.897922041566, 5215.800365915841, 5268.288781014562, 5354.089804733195, 3980.034874018853, 4108.890599538138, 4110.260153172428, 4118.938215926763, 4134.917301653965, 3592.6407359264126, 4126.277901367263, 4129.648979836703, 4131.421600975866, 4150.070217348947, 3851.499305090348, 3918.94121588175, 4089.6114869087387, 4109.363048727452, 4127.669786417079, 3858.942115868236, 4042.555318914917, 4065.8780236592947, 4076.1236334188143, 4095.1604920991126, 2903.912882613525, 3177.9364412711616, 3217.7264932480766, 3434.3131373725355, 3594.3350480160993, 4359.433273674434, 4958.157113290749, 5085.491450854922, 5093.872653183663, 5098.337216161875, 4702.835400760991, 4705.6470764692785, 4707.646926480494, 4736.431628204603, 4749.501302363271, 4685.4143232095985, 5521.254630624854, 5573.27547418833, 5630.915536266959, 5655.886882262332, 3397.9830100849586, 3415.351083294191, 3977.2840888640035, 3989.122393073548, 3990.2817492851277, 5066.164082926312, 5212.29633999409, 5544.1960915667405, 5552.694601796899, 5670.518005969498, 3981.6615587675374, 4004.9599504004586, 4026.697997650188, 4045.9797701011307, 4054.5945405459806, 3775.320442320354, 3932.0, 4109.712320137622, 4125.443065186221, 4147.543770185277, 3627.2745450909924, 4156.462109019682, 4291.451061874361, 4617.653675974316, 4699.368353721927, 5520.585956053312, 5570.465438225598, 5572.359178694421, 5581.6930068846605, 5599.356074051536, 1294.6698591859015, 1325.2181213084327, 1370.712150448417, 1372.3344178073626, 1373.7939309103715, 850.434461083385, 954.7660823098386, 974.6198982396729, 981.40134517944, 983.7589790501373, 4639.489656137822, 4694.81220751173, 4871.147549178951, 5068.695878247317, 5215.973920130451, 5152.587792976553, 5381.300430943952, 5725.570582206351, 5787.247657804459, 5793.623414478046, 4359.825606975685, 4365.563443655673, 4899.559039686445, 5106.617003724736, 5115.74421278942, 2365.716114066323, 2578.046122934526, 2966.7626589872757, 3004.684508126615, 3012.6686064532882, 4492.775361231991, 4741.336212930192, 5672.432756724294, 6039.63762174271, 6099.237595300588, 5110.897782044338, 5195.220557001986, 5867.178594996704, 5940.198073260141, 5975.655477517524, 5140.768665409991, 6015.308239552507, 6024.608400453956, 6035.336113027563, 6045.8488537786025, 4524.230880750215, 5353.571714262772, 5354.598405119709, 5762.30852297727, 5808.970955145197, 5161.432242453252, 5639.887202256014, 5672.092465205547, 5706.172604972173, 5723.284589426322, 4652.0, 5427.348718153688, 5682.119271512922, 5833.737481813244, 6019.939800601938, 3899.980500097481, 3908.511436070491, 3913.8434462621176, 3934.2328245991757, 3940.389239667856, 5339.638392210117, 5345.492178243061, 5843.29880414353, 5908.675022873783, 5939.277609493199, 4756.881077973078, 5393.649412788156, 5594.9720251398485, 5629.4652008059165, 5642.407547207484, 5294.285271488454, 5508.256385388082, 5528.530074943514, 5577.916331254954, 5584.43116166568, 5381.811636592701, 5393.919091213714, 5395.56835453155, 5402.108652072364, 5405.48643381805, 4684.4378674559275, 5637.43625637433, 5637.57718171147, 5642.181883626933, 5664.122061080539, 5189.169732842727, 5496.945030549643, 5504.697241651747, 5515.393306736336, 5535.612507124462], "value": 4096.508353924182, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.7970129259928, 675.4360109308728, 726.9672864721088, 750.3397010630645, 762.8550575390672, 14905.031172973762, 15374.0, 15407.998480098786, 15410.69178616427, 15426.537203883887, 13722.31388430577, 14424.567262982102, 14473.565793026226, 14475.059121157117, 14566.126032438062, 748.4648476339416, 774.8760198368262, 921.4425272710031, 925.7824411263381, 944.3483996042709, 939.8073394954018, 943.9852158929144, 944.2304521814733, 945.9668911588096, 947.1144479911305, 1003.2275148135931, 1003.8694969653972, 1004.1715584642662, 1007.808516381885, 1008.1430783833756, 778.9805254868617, 806.4314658166008, 807.8263173417685, 810.3071873548149, 962.1917589225085, 731.1591669580025, 731.6305265840716, 732.5714457042583, 733.0250766480622, 741.8108382362517, 14990.175540345277, 15023.399064037425, 15071.0, 15100.848991510164, 15108.773368399483, 12125.636230913087, 12144.939275303655, 12167.330796806173, 12317.815232771516, 12430.192037517532, 10836.0, 10902.945485272603, 10907.945460272727, 11368.317900925971, 11481.942590286995, 4021.617946295097, 4025.2955732746946, 4030.133521292936, 4162.7502349859105, 4171.0, 79915.99999999942, 82003.17996820014, 82020.0, 85814.0, 85841.0, 9446.291528135418, 9470.715878523655, 9613.615455381707, 9697.321187516805, 9781.070798274153, 21681.891590541945, 21695.783042169693, 21715.0, 21736.239231626816, 21761.020754066107, 7493.700251989969, 7528.736494222676, 7667.654955526903, 7719.150893401721, 7721.92278077212, 4037.858674946363, 4105.568915263854, 4106.014556506417, 4106.630403263721, 4165.271077561415, 3465.9653403466145, 3556.5376501054698, 3571.2143328467696, 3574.12433953683, 3576.088097535112, 611.9938800612026, 616.8149555133442, 632.11503894548, 638.3616383616384, 640.8397900524869, 458.431544884341, 567.2087438023933, 582.266344406043, 589.6845187824493, 597.3160730963023, 7776.766696998986, 7805.882911756328, 7820.882686759704, 7981.960090199512, 8045.115037345888, 552.1744991238124, 580.8867270882138, 588.608575297431, 592.329630442149, 596.2844586496221, 3790.677792387623, 3807.2956503047026, 3820.1977584706965, 3827.9043023924623, 3879.553851307079, 77435.61282193553, 95638.0, 97038.51480742551, 98601.50699246458, 99059.50470247603, 83400.3319933598, 85376.57311713402, 85732.57133714292, 86180.1381986172, 86576.0, 74385.0, 74466.51066978739, 75288.62355688296, 75289.24710752822, 75529.0, 6291.213598300213, 6582.308857569887, 7171.175314838861, 7283.8543229136185, 7638.618069096634, 46108.53891461043, 46966.53033469622, 47036.0, 47263.52736472591, 47477.287840681725, 62467.06299405603, 64346.0, 65238.673806630664, 65542.34457655363, 65696.6715166421, 8933.553322333728, 9896.901030989598, 9997.55011024491, 10104.646337378157, 10654.62708805188, 10488.26582139243, 10664.200184986003, 10827.729306767078, 10895.291806032583, 11018.1185505161, 10012.299139060196, 10024.097831195228, 10089.243306751876, 10319.380837149794, 10446.164306855282, 10123.341982771097, 10123.64567240143, 10385.63650272237, 10444.529996150344, 10448.2163837714, 9661.275404344562, 9882.011798820347, 9921.40471571708, 10158.13655839262, 10274.33216840903, 8053.6778528858185, 8380.916190838014, 8533.487990720576, 8664.826703466022, 8930.598123084346, 7335.559866408033, 8393.118722534045, 8460.386267371734, 8991.775205619912, 9005.459672419676], "value": 6771.651871876395, "unit": "score"}], "name": "dromaeo_css", "value": 7194.044283653518, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 108.0, 111.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 140.0, 199.0, 118.0, 141.0, 140.0, 118.0, 169.0, 116.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [252.0, 250.0, 185.0, 184.0, 182.0, 185.0, 186.0, 181.0, 181.0, 185.0], "value": 197.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 126.0, 56.0, 62.0, 56.0, 58.0, 113.0, 55.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 134.0, 81.0, 79.0, 76.0, 103.0, 79.0, 79.0, 100.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 129.0, 87.0, 85.0, 86.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 94.0, 93.0, 102.0, 125.0, 94.0, 93.0, 92.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 85.0, 70.0, 130.0, 129.0, 111.0, 83.0, 86.0, 86.0, 83.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 56.0, 56.0, 55.0, 55.0, 119.0, 55.0, 54.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 74.0, 73.0, 70.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 84.0, 85.0, 84.0, 85.0, 83.0, 83.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 143.0, 139.0, 135.0, 135.0, 144.0, 136.0, 137.0, 227.0, 179.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 161.0, 161.0, 161.0, 166.0, 162.0, 160.0, 160.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 84.0, 67.0, 60.0, 57.0, 82.0, 84.0, 82.0, 65.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1501.5, "alertThreshold": 2.0}]} 18:52:37 INFO - Return code: 0 18:52:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:52:37 ERROR - # TBPL SUCCESS # 18:52:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:52:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:52:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:52:37 INFO - 2016-05-18 18:52:37.616 screenresolution[2236:34222] starting screenresolution argv=screenresolution get 18:52:37 INFO - 2016-05-18 18:52:37.619 screenresolution[2236:34222] Display 0: 1600x1200x32@60 18:52:37 INFO - 2016-05-18 18:52:37.625 screenresolution[2237:34225] starting screenresolution argv=screenresolution list 18:52:37 INFO - Available Modes on Display 0 18:52:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:52:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:52:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:52:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:52:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:52:37 INFO - Intel Iris: 18:52:37 INFO - Chipset Model: Intel Iris 18:52:37 INFO - Type: GPU 18:52:37 INFO - Bus: Built-In 18:52:37 INFO - VRAM (Dynamic, Max): 1536 MB 18:52:37 INFO - Vendor: Intel (0x8086) 18:52:37 INFO - Device ID: 0x0a2e 18:52:37 INFO - Revision ID: 0x0009 18:52:37 INFO - Displays: 18:52:37 INFO - Display: 18:52:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:52:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:52:37 INFO - Display Serial Number: 1600x1200 60 18:52:37 INFO - Main Display: Yes 18:52:37 INFO - Mirror: Off 18:52:37 INFO - Online: Yes 18:52:37 INFO - Rotation: Supported 18:52:37 INFO - Return code: 0 18:52:37 INFO - Running post-action listener: _resource_record_post_action 18:52:37 INFO - ##### 18:52:37 INFO - ##### Finished run-tests step (success) 18:52:37 INFO - ##### 18:52:37 INFO - Running post-run listener: _resource_record_post_run 18:52:37 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 647168; Write bytes: 695122432; Read time: 9; Write time: 1912 18:52:37 INFO - TinderboxPrint: CPU usage
53.7% 18:52:37 INFO - TinderboxPrint: I/O read bytes / time
647,168 / 9 18:52:37 INFO - TinderboxPrint: I/O write bytes / time
695,122,432 / 1,912 18:52:37 INFO - TinderboxPrint: CPU idle
2,243.1 (46.3%) 18:52:37 INFO - TinderboxPrint: CPU system
966.9 (20.0%) 18:52:37 INFO - TinderboxPrint: CPU user
1,635.6 (33.8%) 18:52:37 INFO - TinderboxPrint: Swap in / out
395,538,432 / 0 18:52:37 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 378771968; Write bytes: 373516288; Read time: 28149; Write time: 717 18:52:37 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 630784; Write bytes: 296628736; Read time: 9; Write time: 1141 18:52:38 INFO - Running post-run listener: _upload_blobber_files 18:52:38 INFO - Blob upload gear active. 18:52:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:52:38 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 18:52:38 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'] 18:52:38 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 18:52:39 INFO - (blobuploader) - INFO - Open directory for files ... 18:52:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:52:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:52:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:40 INFO - (blobuploader) - INFO - Done attempting. 18:52:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:52:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:52:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:42 INFO - (blobuploader) - INFO - Done attempting. 18:52:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:52:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:52:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:45 INFO - (blobuploader) - INFO - Done attempting. 18:52:45 INFO - (blobuploader) - INFO - Iteration through files over. 18:52:45 INFO - Return code: 0 18:52:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:52:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:52:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a17c5865a17f83cde8da54cead9fa59d87c43e05ea0208f5f03274e652272216e838674dd4b43aa7e271bc979a8eefbac3ce3c7ce783b62f84d80155eaf1d706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5e3f26deb8e71f068e2868b9020e6ccd95239419b283651f7b33402a4827c2028cee60bb2ce4ea6a5d803febcca69c7ef26e5740ba6c060bbbc24389d1969e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c12ff11c73879d27ddab922d83b06835ecde0e9833a8095f96427e5f6059bd54e3acaace36d4cddfe3d4ad675472bed08ee7990c3c87045708dd4b35f60dff1d"} 18:52:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:52:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:52:45 INFO - Contents: 18:52:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a17c5865a17f83cde8da54cead9fa59d87c43e05ea0208f5f03274e652272216e838674dd4b43aa7e271bc979a8eefbac3ce3c7ce783b62f84d80155eaf1d706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5e3f26deb8e71f068e2868b9020e6ccd95239419b283651f7b33402a4827c2028cee60bb2ce4ea6a5d803febcca69c7ef26e5740ba6c060bbbc24389d1969e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c12ff11c73879d27ddab922d83b06835ecde0e9833a8095f96427e5f6059bd54e3acaace36d4cddfe3d4ad675472bed08ee7990c3c87045708dd4b35f60dff1d"} 18:52:45 INFO - Running post-run listener: copy_logs_to_upload_dir 18:52:45 INFO - Copying logs to upload dir... 18:52:45 INFO - mkdir: /builds/slave/test/build/upload/logs 18:52:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.108649 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-18 18:52:45.662135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 18:52:45.665125) ========= 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.03GSEV5OvA/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.BLIGs3ifs0/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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/a17c5865a17f83cde8da54cead9fa59d87c43e05ea0208f5f03274e652272216e838674dd4b43aa7e271bc979a8eefbac3ce3c7ce783b62f84d80155eaf1d706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5e3f26deb8e71f068e2868b9020e6ccd95239419b283651f7b33402a4827c2028cee60bb2ce4ea6a5d803febcca69c7ef26e5740ba6c060bbbc24389d1969e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c12ff11c73879d27ddab922d83b06835ecde0e9833a8095f96427e5f6059bd54e3acaace36d4cddfe3d4ad675472bed08ee7990c3c87045708dd4b35f60dff1d"} build_url:https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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/a17c5865a17f83cde8da54cead9fa59d87c43e05ea0208f5f03274e652272216e838674dd4b43aa7e271bc979a8eefbac3ce3c7ce783b62f84d80155eaf1d706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5e3f26deb8e71f068e2868b9020e6ccd95239419b283651f7b33402a4827c2028cee60bb2ce4ea6a5d803febcca69c7ef26e5740ba6c060bbbc24389d1969e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c12ff11c73879d27ddab922d83b06835ecde0e9833a8095f96427e5f6059bd54e3acaace36d4cddfe3d4ad675472bed08ee7990c3c87045708dd4b35f60dff1d"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 18:52:45.777699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 18:52:45.778042) ========= 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.03GSEV5OvA/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.BLIGs3ifs0/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005351 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 18:52:45.966940) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 18:52:45.967252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 18:52:45.967550) ========= ========= Total master_lag: 0.42 =========