builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0285 starttime: 1494171941.9 results: success (0) buildid: 20170507080444 builduid: 71597242c5874d2cbc844225b6d92106 revision: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:41.902598) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:41.903043) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:41.903369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.203636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.204008) ========= 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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 --2017-05-07 08:45:42-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 52.3M=0s 2017-05-07 08:45:42 (52.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.168643 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.410631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.411423) ========= 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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.022095 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.465634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-07 08:45:42.466014) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 17d8a1e278a9c54a6fdda9d390abce4077e55b20 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 17d8a1e278a9c54a6fdda9d390abce4077e55b20 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CNggP3tMLJ/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.kaaxuWwbtE/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 2017-05-07 08:45:42,538 truncating revision to first 12 chars 2017-05-07 08:45:42,538 Setting DEBUG logging. 2017-05-07 08:45:42,538 attempt 1/10 2017-05-07 08:45:42,538 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/17d8a1e278a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-07 08:45:43,313 unpacking tar archive at: mozilla-central-17d8a1e278a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.038698 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-07 08:45:43.532980) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.533743) ========= script_repo_revision: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.534722) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.535127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.557051) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-07 08:45:43.557766) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CNggP3tMLJ/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.kaaxuWwbtE/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:45:43 INFO - MultiFileLogger online at 20170507 08:45:43 in /builds/slave/test 08:45:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:45:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:43 INFO - {'append_to_log': False, 08:45:43 INFO - 'base_work_dir': '/builds/slave/test', 08:45:43 INFO - 'blob_upload_branch': 'Firefox', 08:45:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:43 INFO - 'branch': 'Firefox', 08:45:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:43 INFO - 'config_files': ('talos/mac_config.py',), 08:45:43 INFO - 'default_actions': ('clobber', 08:45:43 INFO - 'read-buildbot-config', 08:45:43 INFO - 'download-and-extract', 08:45:43 INFO - 'populate-webroot', 08:45:43 INFO - 'create-virtualenv', 08:45:43 INFO - 'install', 08:45:43 INFO - 'run-tests'), 08:45:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:43 INFO - 'download_minidump_stackwalk': True, 08:45:43 INFO - 'download_symbols': 'ondemand', 08:45:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:43 INFO - 'gecko_profile': False, 08:45:43 INFO - 'gecko_profile_interval': 0, 08:45:43 INFO - 'installer_path': 'installer.exe', 08:45:43 INFO - 'log_level': 'info', 08:45:43 INFO - 'log_name': 'talos', 08:45:43 INFO - 'log_to_console': True, 08:45:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:45:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:45:43 INFO - 'opt_config_files': (), 08:45:43 INFO - 'pip_index': False, 08:45:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:45:43 INFO - 'cmd': ('bash', 08:45:43 INFO - '-c', 08:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:45:43 INFO - 'enabled': True, 08:45:43 INFO - 'halt_on_failure': False, 08:45:43 INFO - 'name': 'check_screen_resolution'},), 08:45:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:45:43 INFO - 'cmd': ('bash', 08:45:43 INFO - '-c', 08:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:45:43 INFO - 'enabled': True, 08:45:43 INFO - 'halt_on_failure': False, 08:45:43 INFO - 'name': 'check_screen_resolution'},), 08:45:43 INFO - 'run_cmd_checks_enabled': True, 08:45:43 INFO - 'suite': 'dromaeojs', 08:45:43 INFO - 'system_bits': '32', 08:45:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:43 INFO - 'title': 't-yosemite-r7-0285', 08:45:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:43 INFO - 'use_talos_json': True, 08:45:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:43 INFO - 'work_dir': 'build'} 08:45:43 INFO - [mozharness: 2017-05-07 15:45:43.755554Z] Running clobber step. 08:45:43 INFO - Running pre-action listener: _resource_record_pre_action 08:45:43 INFO - Running main action method: clobber 08:45:43 INFO - rmtree: /builds/slave/test/build 08:45:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:46 INFO - Running post-action listener: _resource_record_post_action 08:45:46 INFO - [mozharness: 2017-05-07 15:45:46.930801Z] Finished clobber step (success) 08:45:46 INFO - [mozharness: 2017-05-07 15:45:46.930942Z] Running read-buildbot-config step. 08:45:46 INFO - Running pre-action listener: _resource_record_pre_action 08:45:46 INFO - Running main action method: read_buildbot_config 08:45:46 INFO - Using buildbot properties: 08:45:46 INFO - { 08:45:46 INFO - "project": "", 08:45:46 INFO - "product": "firefox", 08:45:46 INFO - "script_repo_revision": "production", 08:45:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:45:46 INFO - "repository": "", 08:45:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:45:46 INFO - "buildid": "20170507080444", 08:45:46 INFO - "pgo_build": "False", 08:45:46 INFO - "basedir": "/builds/slave/test", 08:45:46 INFO - "buildnumber": 36, 08:45:46 INFO - "slavename": "t-yosemite-r7-0285", 08:45:46 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:45:46 INFO - "platform": "yosemite_r7", 08:45:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:45:46 INFO - "branch": "mozilla-central", 08:45:46 INFO - "repo_path": "mozilla-central", 08:45:46 INFO - "revision": "17d8a1e278a9c54a6fdda9d390abce4077e55b20", 08:45:46 INFO - "stage_platform": "macosx64", 08:45:46 INFO - "builduid": "71597242c5874d2cbc844225b6d92106", 08:45:46 INFO - "slavebuilddir": "test" 08:45:46 INFO - } 08:45:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:45:46 INFO - Running post-action listener: _resource_record_post_action 08:45:46 INFO - [mozharness: 2017-05-07 15:45:46.934434Z] Finished read-buildbot-config step (success) 08:45:46 INFO - [mozharness: 2017-05-07 15:45:46.934530Z] Running download-and-extract step. 08:45:46 INFO - Running pre-action listener: _resource_record_pre_action 08:45:46 INFO - Running main action method: download_and_extract 08:45:46 INFO - mkdir: /builds/slave/test/build/tests 08:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:46 INFO - trying https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:45:46 INFO - Downloading https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:45:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:45:48 INFO - Downloaded 1325 bytes. 08:45:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:45:48 INFO - Using the following test package requirements: 08:45:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:45:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:45:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:45:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:45:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'jsshell-mac.zip'], 08:45:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:45:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:45:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:45:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:45:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:45:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:45:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:45:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:45:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:45:48 INFO - Fetch https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:45:49 INFO - Content-Length response header: 52703730 08:45:49 INFO - Bytes received: 52703730 08:46:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:46:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:46:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:46:05 INFO - Fetch https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:46:08 INFO - Content-Length response header: 52703730 08:46:08 INFO - Bytes received: 52703730 08:46:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:46:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:46:23 INFO - Fetch https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:46:24 INFO - Content-Length response header: 14408697 08:46:24 INFO - Bytes received: 14408697 08:46:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:24 INFO - trying https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:46:24 INFO - Downloading https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:46:26 INFO - Downloaded 70884591 bytes. 08:46:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:46:26 INFO - mkdir: /builds/slave/test/properties 08:46:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:26 INFO - Contents: 08:46:26 INFO - build_url:https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:46:27 INFO - Running post-action listener: _resource_record_post_action 08:46:27 INFO - Running post-action listener: set_extra_try_arguments 08:46:27 INFO - [mozharness: 2017-05-07 15:46:27.557531Z] Finished download-and-extract step (success) 08:46:27 INFO - [mozharness: 2017-05-07 15:46:27.557652Z] Running populate-webroot step. 08:46:27 INFO - Running pre-action listener: _resource_record_pre_action 08:46:27 INFO - Running main action method: populate_webroot 08:46:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:27 INFO - '--tppagecycles', 08:46:27 INFO - '7'], 08:46:27 INFO - 'tests': ['tsvgm']}}, 08:46:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['damp', 'tps']}, 08:46:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'tests': ['damp', 'tps']}, 08:46:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['dromaeo_dom']}, 08:46:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:46:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:46:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:46:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:46:27 INFO - 'bloom_basic_ref']}, 08:46:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tsvgx', 08:46:27 INFO - 'tsvgr_opacity', 08:46:27 INFO - 'tart', 08:46:27 INFO - 'tscrollx', 08:46:27 INFO - 'cart', 08:46:27 INFO - 'tsvg_static']}, 08:46:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:27 INFO - 'tsvgr_opacity', 08:46:27 INFO - 'tart', 08:46:27 INFO - 'tscrollx', 08:46:27 INFO - 'cart', 08:46:27 INFO - 'tsvg_static']}, 08:46:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tp5o']}, 08:46:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:46:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s', 08:46:27 INFO - '--xperf_path', 08:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:27 INFO - 'tests': ['tp5n']}, 08:46:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:46:27 INFO - 'talos_options': ['--xperf_path', 08:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:27 INFO - 'tests': ['tp5n']}}, 08:46:27 INFO - 'talos.zip': {'path': '', 08:46:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:27 INFO - Running post-action listener: _resource_record_post_action 08:46:27 INFO - [mozharness: 2017-05-07 15:46:27.576691Z] Finished populate-webroot step (success) 08:46:27 INFO - [mozharness: 2017-05-07 15:46:27.576798Z] Running create-virtualenv step. 08:46:27 INFO - Running pre-action listener: _resource_record_pre_action 08:46:27 INFO - Running main action method: create_virtualenv 08:46:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:27 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 08:46:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:27 INFO - Using real prefix '/tools/python27' 08:46:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:31 INFO - Installing pip.................done. 08:46:31 INFO - Return code: 0 08:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:31 INFO - Reading from file tmpfile_stdout 08:46:31 INFO - Output received: 08:46:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:46:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:31 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:31 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 08:46:31 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 08:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:31 INFO - 'HOME': '/Users/cltbld', 08:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:31 INFO - 'LOGNAME': 'cltbld', 08:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:31 INFO - 'MOZ_NO_REMOTE': '1', 08:46:31 INFO - 'NO_EM_RESTART': '1', 08:46:31 INFO - 'PAGER': '/bin/cat', 08:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:31 INFO - 'PWD': '/builds/slave/test', 08:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:31 INFO - 'SHELL': '/bin/bash', 08:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:31 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:31 INFO - 'USER': 'cltbld', 08:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:31 INFO - 'XPC_FLAGS': '0x0', 08:46:31 INFO - 'XPC_SERVICE_NAME': '0', 08:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:31 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 08:46:31 INFO - Cleaning up... 08:46:31 INFO - Return code: 0 08:46:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:31 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:31 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 08:46:31 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 08:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:31 INFO - 'HOME': '/Users/cltbld', 08:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:31 INFO - 'LOGNAME': 'cltbld', 08:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:31 INFO - 'MOZ_NO_REMOTE': '1', 08:46:31 INFO - 'NO_EM_RESTART': '1', 08:46:31 INFO - 'PAGER': '/bin/cat', 08:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:31 INFO - 'PWD': '/builds/slave/test', 08:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:31 INFO - 'SHELL': '/bin/bash', 08:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:31 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:31 INFO - 'USER': 'cltbld', 08:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:31 INFO - 'XPC_FLAGS': '0x0', 08:46:31 INFO - 'XPC_SERVICE_NAME': '0', 08:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:32 INFO - Downloading/unpacking psutil>=3.1.1 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:34 INFO - Installing collected packages: psutil 08:46:34 INFO - Running setup.py install for psutil 08:46:34 INFO - building 'psutil._psutil_osx' extension 08:46:34 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 08:46:34 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 08:46:35 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 08:46:35 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 08:46:35 INFO - building 'psutil._psutil_posix' extension 08:46:35 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 08:46:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:46:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:46:35 INFO - ^ 08:46:35 INFO - 1 warning generated. 08:46:35 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 08:46:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:35 INFO - Successfully installed psutil 08:46:35 INFO - Cleaning up... 08:46:35 INFO - Return code: 0 08:46:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:35 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:35 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 08:46:35 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 08:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:35 INFO - 'HOME': '/Users/cltbld', 08:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:35 INFO - 'LOGNAME': 'cltbld', 08:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:35 INFO - 'MOZ_NO_REMOTE': '1', 08:46:35 INFO - 'NO_EM_RESTART': '1', 08:46:35 INFO - 'PAGER': '/bin/cat', 08:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:35 INFO - 'PWD': '/builds/slave/test', 08:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:35 INFO - 'SHELL': '/bin/bash', 08:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:35 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:35 INFO - 'USER': 'cltbld', 08:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:35 INFO - 'XPC_FLAGS': '0x0', 08:46:35 INFO - 'XPC_SERVICE_NAME': '0', 08:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:37 INFO - Installing collected packages: mozsystemmonitor 08:46:37 INFO - Running setup.py install for mozsystemmonitor 08:46:37 INFO - Successfully installed mozsystemmonitor 08:46:37 INFO - Cleaning up... 08:46:37 INFO - Return code: 0 08:46:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:37 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:37 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==2.5.1'] in /builds/slave/test/build 08:46:37 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==2.5.1 08:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:37 INFO - 'HOME': '/Users/cltbld', 08:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:37 INFO - 'LOGNAME': 'cltbld', 08:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:37 INFO - 'MOZ_NO_REMOTE': '1', 08:46:37 INFO - 'NO_EM_RESTART': '1', 08:46:37 INFO - 'PAGER': '/bin/cat', 08:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:37 INFO - 'PWD': '/builds/slave/test', 08:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:37 INFO - 'SHELL': '/bin/bash', 08:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:37 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:37 INFO - 'USER': 'cltbld', 08:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:37 INFO - 'XPC_FLAGS': '0x0', 08:46:37 INFO - 'XPC_SERVICE_NAME': '0', 08:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:39 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 08:46:39 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 08:46:39 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 08:46:39 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 08:46:39 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 08:46:39 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 08:46:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:39 INFO - warning: no files found matching '*.txt' 08:46:39 INFO - no previously-included directories found matching 'build' 08:46:39 INFO - no previously-included directories found matching 'dist' 08:46:39 INFO - no previously-included directories found matching '.git*' 08:46:39 INFO - Installing collected packages: jsonschema, functools32 08:46:39 INFO - Running setup.py install for functools32 08:46:39 INFO - warning: no files found matching '*.txt' 08:46:39 INFO - no previously-included directories found matching 'build' 08:46:39 INFO - no previously-included directories found matching 'dist' 08:46:39 INFO - no previously-included directories found matching '.git*' 08:46:39 INFO - Successfully installed jsonschema functools32 08:46:39 INFO - Cleaning up... 08:46:39 INFO - Return code: 0 08:46:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:39 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:39 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:46:39 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 functools32==3.2.3-2 08:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:39 INFO - 'HOME': '/Users/cltbld', 08:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:39 INFO - 'LOGNAME': 'cltbld', 08:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:39 INFO - 'MOZ_NO_REMOTE': '1', 08:46:39 INFO - 'NO_EM_RESTART': '1', 08:46:39 INFO - 'PAGER': '/bin/cat', 08:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:39 INFO - 'PWD': '/builds/slave/test', 08:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:39 INFO - 'SHELL': '/bin/bash', 08:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:39 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:39 INFO - 'USER': 'cltbld', 08:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:39 INFO - 'XPC_FLAGS': '0x0', 08:46:39 INFO - 'XPC_SERVICE_NAME': '0', 08:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:39 INFO - Cleaning up... 08:46:39 INFO - Return code: 0 08:46:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:39 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:39 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 08:46:39 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 08:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:39 INFO - 'HOME': '/Users/cltbld', 08:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:39 INFO - 'LOGNAME': 'cltbld', 08:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:39 INFO - 'MOZ_NO_REMOTE': '1', 08:46:39 INFO - 'NO_EM_RESTART': '1', 08:46:39 INFO - 'PAGER': '/bin/cat', 08:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:39 INFO - 'PWD': '/builds/slave/test', 08:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:39 INFO - 'SHELL': '/bin/bash', 08:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:39 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:39 INFO - 'USER': 'cltbld', 08:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:39 INFO - 'XPC_FLAGS': '0x0', 08:46:39 INFO - 'XPC_SERVICE_NAME': '0', 08:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:41 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 INFO - Downloading docopt-0.6.1.tar.gz 08:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:42 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:42 INFO - Running setup.py install for blobuploader 08:46:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:42 INFO - Running setup.py install for requests 08:46:43 INFO - Running setup.py install for docopt 08:46:43 INFO - Successfully installed blobuploader requests docopt 08:46:43 INFO - Cleaning up... 08:46:43 INFO - Return code: 0 08:46:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:43 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:43 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 08:46:43 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 08:46:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:43 INFO - 'HOME': '/Users/cltbld', 08:46:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:43 INFO - 'LOGNAME': 'cltbld', 08:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:43 INFO - 'MOZ_NO_REMOTE': '1', 08:46:43 INFO - 'NO_EM_RESTART': '1', 08:46:43 INFO - 'PAGER': '/bin/cat', 08:46:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:43 INFO - 'PWD': '/builds/slave/test', 08:46:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:43 INFO - 'SHELL': '/bin/bash', 08:46:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:43 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:43 INFO - 'USER': 'cltbld', 08:46:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:43 INFO - 'XPC_FLAGS': '0x0', 08:46:43 INFO - 'XPC_SERVICE_NAME': '0', 08:46:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:43 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-_UhUqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:43 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-6yx_Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:43 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-khW8XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:43 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-ZKymxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:43 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-ZYBhp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-x7X6en-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-dyFYRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-SC3qt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-LEG08r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-scKooE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-0KiRQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-vVw5wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-PuyF5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:44 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-bw1PGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:45 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-EDqRnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:45 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-RcvLYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:45 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Gd5w2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:45 INFO - Running setup.py install for manifestparser 08:46:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:45 INFO - Running setup.py install for mozcrash 08:46:45 INFO - Running setup.py install for mozdebug 08:46:45 INFO - Running setup.py install for mozdevice 08:46:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:45 INFO - Running setup.py install for mozfile 08:46:46 INFO - Running setup.py install for mozhttpd 08:46:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Running setup.py install for mozinfo 08:46:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Running setup.py install for mozInstall 08:46:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Running setup.py install for mozleak 08:46:46 INFO - Running setup.py install for mozlog 08:46:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Running setup.py install for moznetwork 08:46:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Running setup.py install for mozprocess 08:46:47 INFO - Running setup.py install for mozprofile 08:46:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Running setup.py install for mozrunner 08:46:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Running setup.py install for mozscreenshot 08:46:47 INFO - Running setup.py install for moztest 08:46:47 INFO - Running setup.py install for mozversion 08:46:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:47 INFO - Cleaning up... 08:46:47 INFO - Return code: 0 08:46:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:47 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:47 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 08:46:47 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 08:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:47 INFO - 'HOME': '/Users/cltbld', 08:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:47 INFO - 'LOGNAME': 'cltbld', 08:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:47 INFO - 'MOZ_NO_REMOTE': '1', 08:46:47 INFO - 'NO_EM_RESTART': '1', 08:46:47 INFO - 'PAGER': '/bin/cat', 08:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:47 INFO - 'PWD': '/builds/slave/test', 08:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:47 INFO - 'SHELL': '/bin/bash', 08:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:47 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:47 INFO - 'USER': 'cltbld', 08:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:47 INFO - 'XPC_FLAGS': '0x0', 08:46:47 INFO - 'XPC_SERVICE_NAME': '0', 08:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-kTA4iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:48 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-MPsaQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-L8_d2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:48 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-u37aCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-gQsJOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:48 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-s5_9p5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:48 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-UkZmOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:48 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)) 08:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:48 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-8ThUFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Kg_lU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-mfQdeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-im64xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-jpPBxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-a79211-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-SiToD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Ia39Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-_RPCvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:49 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-A3Qid2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:49 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)) 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:49 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)) 08:46:49 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:46:49 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:46:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:51 INFO - Downloading blessings-1.6.tar.gz 08:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:51 INFO - Installing collected packages: blessings 08:46:51 INFO - Running setup.py install for blessings 08:46:51 INFO - Successfully installed blessings 08:46:51 INFO - Cleaning up... 08:46:51 INFO - Return code: 0 08:46:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:51 INFO - Reading from file tmpfile_stdout 08:46:51 INFO - Current package versions: 08:46:51 INFO - blessings == 1.6 08:46:51 INFO - blobuploader == 1.2.4 08:46:51 INFO - docopt == 0.6.1 08:46:51 INFO - functools32 == 3.2.3-2 08:46:51 INFO - jsonschema == 2.5.1 08:46:51 INFO - manifestparser == 1.1 08:46:51 INFO - mozInstall == 1.12 08:46:51 INFO - mozcrash == 1.0 08:46:51 INFO - mozdebug == 0.1 08:46:51 INFO - mozdevice == 0.50 08:46:51 INFO - mozfile == 1.2 08:46:51 INFO - mozhttpd == 0.7 08:46:51 INFO - mozinfo == 0.9 08:46:51 INFO - mozleak == 0.1 08:46:51 INFO - mozlog == 3.4 08:46:51 INFO - moznetwork == 0.27 08:46:51 INFO - mozprocess == 0.25 08:46:51 INFO - mozprofile == 0.28 08:46:51 INFO - mozrunner == 6.13 08:46:51 INFO - mozscreenshot == 0.1 08:46:51 INFO - mozsystemmonitor == 0.3 08:46:51 INFO - moztest == 0.8 08:46:51 INFO - mozversion == 1.4 08:46:51 INFO - psutil == 3.1.1 08:46:51 INFO - requests == 1.2.3 08:46:51 INFO - wsgiref == 0.1.2 08:46:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:51 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:51 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 08:46:51 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 08:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:51 INFO - 'HOME': '/Users/cltbld', 08:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:51 INFO - 'LOGNAME': 'cltbld', 08:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:51 INFO - 'MOZ_NO_REMOTE': '1', 08:46:51 INFO - 'NO_EM_RESTART': '1', 08:46:51 INFO - 'PAGER': '/bin/cat', 08:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:51 INFO - 'PWD': '/builds/slave/test', 08:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:51 INFO - 'SHELL': '/bin/bash', 08:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:51 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:51 INFO - 'USER': 'cltbld', 08:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:51 INFO - 'XPC_FLAGS': '0x0', 08:46:51 INFO - 'XPC_SERVICE_NAME': '0', 08:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 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)) 08:46:52 INFO - Cleaning up... 08:46:52 INFO - Return code: 0 08:46:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:52 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:52 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:52 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 0x1044aeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4795e5480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, '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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:46:52 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 08:46:52 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 08:46:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:46:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:52 INFO - 'HOME': '/Users/cltbld', 08:46:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:52 INFO - 'LOGNAME': 'cltbld', 08:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:52 INFO - 'MOZ_NO_REMOTE': '1', 08:46:52 INFO - 'NO_EM_RESTART': '1', 08:46:52 INFO - 'PAGER': '/bin/cat', 08:46:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:52 INFO - 'PWD': '/builds/slave/test', 08:46:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:52 INFO - 'SHELL': '/bin/bash', 08:46:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:46:52 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:46:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:52 INFO - 'USER': 'cltbld', 08:46:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:52 INFO - 'XPC_FLAGS': '0x0', 08:46:52 INFO - 'XPC_SERVICE_NAME': '0', 08:46:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:52 INFO - Cleaning up... 08:46:52 INFO - Return code: 0 08:46:52 INFO - Running post-action listener: _resource_record_post_action 08:46:52 INFO - Running post-action listener: _start_resource_monitoring 08:46:52 INFO - Starting resource monitoring. 08:46:52 INFO - [mozharness: 2017-05-07 15:46:52.471015Z] Finished create-virtualenv step (success) 08:46:52 INFO - [mozharness: 2017-05-07 15:46:52.472071Z] Running install step. 08:46:52 INFO - Running pre-action listener: _resource_record_pre_action 08:46:52 INFO - Running main action method: install 08:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:52 INFO - Reading from file tmpfile_stdout 08:46:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:52 INFO - Reading from file tmpfile_stdout 08:46:52 INFO - Output received: 08:46:52 INFO - Usage: mozinstall [options] installer 08:46:52 INFO - Options: 08:46:52 INFO - -h, --help show this help message and exit 08:46:52 INFO - -d DEST, --destination=DEST 08:46:52 INFO - Directory to install application into. [default: 08:46:52 INFO - "/builds/slave/test"] 08:46:52 INFO - --app=APP Application being installed. [default: firefox] 08:46:52 INFO - mkdir: /builds/slave/test/build/application 08:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:47:13 INFO - Reading from file tmpfile_stdout 08:47:13 INFO - Output received: 08:47:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:47:13 INFO - Running post-action listener: _resource_record_post_action 08:47:13 INFO - [mozharness: 2017-05-07 15:47:13.093177Z] Finished install step (success) 08:47:13 INFO - [mozharness: 2017-05-07 15:47:13.093295Z] Running run-tests step. 08:47:13 INFO - Running pre-action listener: _resource_record_pre_action 08:47:13 INFO - Running main action method: run_tests 08:47:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:13 INFO - 2017-05-07 08:47:13.107 screenresolution[1944:33287] starting screenresolution argv=screenresolution get 08:47:13 INFO - 2017-05-07 08:47:13.110 screenresolution[1944:33287] Display 0: 1600x1200x32@60 08:47:13 INFO - 2017-05-07 08:47:13.116 screenresolution[1945:33290] starting screenresolution argv=screenresolution list 08:47:13 INFO - Available Modes on Display 0 08:47:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:13 INFO - Intel Iris: 08:47:13 INFO - Chipset Model: Intel Iris 08:47:13 INFO - Type: GPU 08:47:13 INFO - Bus: Built-In 08:47:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:13 INFO - Vendor: Intel (0x8086) 08:47:13 INFO - Device ID: 0x0a2e 08:47:13 INFO - Revision ID: 0x0009 08:47:13 INFO - Displays: 08:47:13 INFO - Display: 08:47:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:13 INFO - Display Serial Number: 1600x1200 60 08:47:13 INFO - Main Display: Yes 08:47:13 INFO - Mirror: Off 08:47:13 INFO - Online: Yes 08:47:13 INFO - Rotation: Supported 08:47:13 INFO - Return code: 0 08:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:13 INFO - Python 2.7.3 08:47:13 INFO - Return code: 0 08:47:13 INFO - grabbing minidump binary from tooltool 08:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:13 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 0x10428cd30>, '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 0x1041b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104281be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:13 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 08:47:13 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 08:47:13 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 08:47:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:13 INFO - Return code: 0 08:47:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:47:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:13 INFO - ENV: RUST_BACKTRACE is now 1 08:47:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:47:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--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 08:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0285 --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 08:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CNggP3tMLJ/Render', 08:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:13 INFO - 'HOME': '/Users/cltbld', 08:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:13 INFO - 'LOGNAME': 'cltbld', 08:47:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:13 INFO - 'MOZ_NO_REMOTE': '1', 08:47:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:13 INFO - 'NO_EM_RESTART': '1', 08:47:13 INFO - 'PAGER': '/bin/cat', 08:47:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:13 INFO - 'PWD': '/builds/slave/test', 08:47:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:13 INFO - 'RUST_BACKTRACE': '1', 08:47:13 INFO - 'SHELL': '/bin/bash', 08:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaaxuWwbtE/Listeners', 08:47:13 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:13 INFO - 'USER': 'cltbld', 08:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:13 INFO - 'XPC_FLAGS': '0x0', 08:47:13 INFO - 'XPC_SERVICE_NAME': '0', 08:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--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 08:47:13 INFO - mozversion application_buildid: 20170507080444 08:47:13 INFO - mozversion application_changeset: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 08:47:13 INFO - mozversion application_display_name: Nightly 08:47:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:13 INFO - mozversion application_name: Firefox 08:47:13 INFO - mozversion application_remotingname: firefox 08:47:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:47:13 INFO - mozversion application_vendor: Mozilla 08:47:13 INFO - mozversion application_version: 55.0a1 08:47:13 INFO - mozversion platform_buildid: 20170507080444 08:47:13 INFO - mozversion platform_changeset: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 08:47:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:47:13 INFO - mozversion platform_version: 55.0a1 08:47:13 INFO - using testdate: 1494172033 08:47:13 INFO - actual date: 1494172033 08:47:13 INFO - starting webserver on 'localhost:56894' 08:47:13 INFO - SUITE-START | Running 2 tests 08:47:13 INFO - TEST-START | dromaeo_css 08:47:13 INFO - operating with platform_type : mac_ 08:47:13 INFO - Initialising browser for dromaeo_css test... 08:47:13 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmph3yb4c/profile http://localhost:56894/getInfo.html) 08:47:16 INFO - PID 1955 | __metrics Screen width/height:1600/1200 08:47:16 INFO - PID 1955 | colorDepth:24 08:47:16 INFO - PID 1955 | Browser inner width/height: 1024/691 08:47:16 INFO - PID 1955 | __metrics 08:47:16 INFO - PID 1955 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:47:16 INFO - PID 1955 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:47:17 INFO - TEST-INFO | 1955: exit 0 08:47:17 INFO - Browser initialized. 08:47:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:17 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmph3yb4c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:29 INFO - PID 1957 | RSS: Main: 239341568 08:47:29 INFO - PID 1957 | 08:50:33 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56894/tests/dromaeo/cssquery-ext.html) 08:50:36 INFO - PID 1957 | RSS: Main: 433725440 08:50:36 INFO - PID 1957 | 08:53:38 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-ext.html (next: http://localhost:56894/tests/dromaeo/cssquery-jquery.html) 08:53:39 INFO - PID 1957 | RSS: Main: 364466176 08:53:39 INFO - PID 1957 | 08:56:41 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56894/tests/dromaeo/cssquery-mootools.html) 08:56:42 INFO - PID 1957 | RSS: Main: 322306048 08:56:42 INFO - PID 1957 | 08:59:44 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56894/tests/dromaeo/cssquery-prototype.html) 08:59:45 INFO - PID 1957 | RSS: Main: 329662464 08:59:45 INFO - PID 1957 | 09:02:48 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56894/tests/dromaeo/cssquery-yui.html) 09:02:49 INFO - PID 1957 | RSS: Main: 327163904 09:02:49 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-yui.html (next: http://localhost:56894/tests/dromaeo/cssquery-dojo.html) 09:05:51 INFO - PID 1957 | RSS: Main: 371597312 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | __start_tp_report 09:05:51 INFO - PID 1957 | _x_x_mozilla_page_load 09:05:51 INFO - PID 1957 | _x_x_mozilla_page_load_details 09:05:51 INFO - PID 1957 | |i|pagename|runs| 09:05:51 INFO - PID 1957 | |0;dojo.html;3522;3545.237773878617;3555.857765689372;3573.731970102242;3592.38929382005;6080.300765411979;6162.722677479513;6172.969135154323;6191.071339299107;6212.161358216645;6112.625067133446;6176.876462470759;6198.845028874281;6200.255969283692;6202.193714817067;4493.438320209973;4868.520050283439;5170.121079416508;5348.625596208267;5397.78408863645;5402.216678581603;5414.810481633143;5479;5487.176923461473;5503.559715222793;5276.630635855472;5357.678539287655;5360.946390536123;5401.135818269058;5487.533559647395;5380.112281473552;5422.566194704454;5456;5488.396276409591;5505.642133261325;5382.4886635769935;5383.596230282745;5391.676499410048;5396.298481197458;5412.134058550652;6137.079438084278;6165;6193.5354848386105;6205.720742566596;6220.906686399708;5422.701751403672;5526;6586.703598338038;6601.900971485432;6623.973284140918;6713.362230588086;6718.798436046926;6720;6720.260771315151;6724.731010759564;6154.446099851035;6233.189685340877;6241.594296370721;6254.781082662085;6261.311255761862;6637.800865974028;6727.360900714424;6747;6758.560693555001;6759.2226893906845;6284.874302514016;6444.709988050549;6462.612243265325;6463.515236357292;6470.0618410330235;6689.096971908827;6717.294684058202;6723.226828914738;6737.629430381376;6746.123004009545;6617.900731489031;6622.337766223339;6626.834329141713;6645.534812563171;6646.036324732887;6326.49388048955;6443.001334793021;6487.351264873475;6617.007448882609;6651.368120028589;5298.231756395378;5301.575873930076;5311.362636483569;5328.813491527855;5336.2529245904825;4569.680122391467;4587.77061146948;4691.601213896857;4753.786079626425;4771.284307353924;6189.195404597373;6209.161763162006;6239.126522286883;6305.779297724557;6315.747370105144;6754.358335958076;6754.5947243165565;6764.966175169093;6787.762428314985;6797.728090876409;6278.748850045947;6287.49700023997;6289.842753931097;6291.937080629226;6304.338044505353;5611.242482264842;5915.852103697442;5934.406559344031;5939.792107276225;5954.642721436728;6730.730770769114;6734.898976515258;6781.321867813179;6972.477064220305;6981.162260528769;6189.319174890848;6383.042543618494;6399.360063993563;6756.898646520404;6758.391744742997;6737.258901520927;6764.323567643196;6783.3555812197765;6954.443644508528;6967.163940327193;6580.341965803382;6581.835454113685;6582.8354291143105;6590.308017658077;6597.505187111082;6407.10300557913;6502.772402965874;6504.349565043458;6510.641914694548;6724.8991265132045;6268.968655156695;6290.2451705793765;6297.527685423704;6302.810915672537;6419.775307864203;6283.937160628335;6409.2949775525585;6455.903161452488;6456.192975878015;6461.838454038499;6291.1192433058495;6339.904901426575;6342.207224096988;6343.080253363329;6344;6480.25477070143;6482.157319548429;6483.935160648333;6485.870282594227;6494;6167.5374346925055;6232.906506402498;6248.187735594343;6251.437370636664;6254 09:05:51 INFO - PID 1957 | |1;ext.html;792.3581898662084;808.7169490678261;1117.2961034548239;1126.4142645824177;1131.0386171754005;32665;35804.2839143217;36062.098447538825;36063.09842253945;36072.737454189075;37573;37823.62176378236;37839.054023649354;37876;37901.81049094757;5922.555808314372;5985.970070149643;8228.835423291546;8385.6226469809;8387.245147936685;8209.179082091772;8307.169283071706;8348.787571293613;8349.33205343571;8351.37364697647;8371.246587807096;8428.578571071452;8440.451370660918;8442;8474.830503389929;5968.820935371902;6030.909536356959;8334.70828521002;8400;8416.705415310435;8077.798055048612;8208.507489550646;8227.465214761081;8233.46482478637;8243.010838699334;30302;34928;35251;35541;35704.46443303353;3454.7236221102253;3484.250886059484;3489.0928358626684;3490.9825450872836;3497.1257185703575;29071.70928290711;29215.707842921725;29228.976985805606;29260.707392926222;29278.560821587693;3096.2878537936176;3144.984275078633;3160.1783536280504;3194.4409728297455;3195.121341631047;217809;286142;286238.5688071567;286407;287524;37992.62007379946;38012.80993595014;38422.80788596095;47535.286970695466;47575.762121189175;46719.29921051187;46826.53173468222;46861.297080543816;46889.76555117202;47922.60386980637;13516.594502164951;13628.114172578751;13631.591052268448;13632.591022269347;13636;11129.109671226444;11135.665930022113;11137.331760094421;11139.164562657834;11145.554177832959;11412.828807567894;11426.25729327591;11465.885341146482;11475.02462290698;11544.422778861024;4590.839320623762;5272.657277276965;5338.706371149624;5344.198370244494;5352.785888564492;975.1272611013104;982.2142286171035;984.1437948984429;1021.4177918586483;1039.4231201682987;23157.073717051466;23390.883045584662;23420.76579234186;23471.178508752455;23481.41296467602;1075.1291453922192;1075.4622688655672;1109.8945600167972;1123.0790751583786;1125.6285425809463;8663.826723465496;10330.535125919352;11969.521219151136;12055.336956467478;12067.094967877269;276033;277212;279230;279458;280454;50951.74524127356;51111;51260.718482037504;51297.97404051898;51354.22968655473;1451.7314296854904;1490.7614781634886;1501.5870635575304;1507.1409296700995;1526.3436722209556;27136.592951105355;27811.304717382227;27830.447308868996;28006.87972481078;28058.71941280561;30784.53823192609;34873;34874;34895.82552087223;34899.4765078519;243103;243185.7840710856;243414;243664;244289;27194.640267986128;27208;30769.384612308077;30928.381432371676;30992.22519437032;33239.16902077467;40035.59964400319;40044;40060;40226.99432514211;24758.628620571068;26245.21264362072;26531;28194.85902570474;28928;27273;27441.451170976867;28096.01663941752;28309.867605295556;28834.134975950754;34322.313553729284;34346.48480272848;34385.82807085948;34421.139471513416;34424.82787586146;23459.648105278095;26898.789554470488;27665.72334276712;28357.007504737234;28695.713042869305;22127.92723533218;22662.433439164153;22834.429139270986;22857.31428057161;22929.541409172056 09:05:51 INFO - PID 1957 | |2;jquery.html;380.28506407953046;417.4906613931003;427.1648926348988;431.732325957906;450.407713856279;19542.7068593971;21338.89330553347;21804.672929906024;22097.33707988761;22102.557948841055;21892.79589622574;23000.309990700305;23537.411564710892;23604.527909441844;23814.523709525798;5452.40023597404;6055.9697201514045;6271.937280627204;6281.466075383598;6293.811185664437;5975.0439929611275;6196.318404975449;6259.029850373199;6264.436200741955;6267.122602835607;5976.5517586180995;6199.69001549923;6215.782447614334;6225.564210505267;6226.3462336454695;5998.670073145975;6128.417800308964;6129.908051379233;6137.32489426165;6138;5300.754322734155;5479.232907392929;5489.835304940858;5498.1752737089355;5574.60977731559;26845.194644160707;27291.863540682367;27454.862725686246;27693.86153069222;27842.860785696146;2036.0939381975027;2114.7990940860745;2148.9032993515175;2220.4781876259067;2224.9888750556306;17408.47774566765;17666.293348266056;17691.823081769147;18043.007634580223;18293.817061829344;2121.4060063182333;2150.849440539163;2165.2638103044883;2171.6742488626674;2173.119886445992;131962;155786;156539;158361;161827.19086404494;11897.821532677017;12494.875051249554;12502.562410315595;12522.123451358317;12550.74898502025;16810.915945420194;16818.915905420396;16819.747703784455;16822.747658785127;16832.915835420743;8643.35174861888;8839.425437346552;8854.645814167361;8947.642094316154;9002.729918102468;6842.3499767523;8371.414001019992;8390.37072219586;8543.444676096035;8557.27263182624;7580.204078571781;7687.846243075219;7710.884336734954;7731.845363092707;7756.146823849372;2102.6005059038735;2122.6073176462405;2125.585510825405;2130.371540395587;2302.689136966488;914.9542522873829;922.3405265235255;947.1522986926662;953.2659851913987;967.8772623756408;20689.27587534429;21185.093341599022;21294.467638309165;21322.466938326357;21388.893055534933;939.3283802081545;972.1348000279756;978.8972157923458;980.4803454169302;984;10348.913364096812;10393.84409233862;10705.357678539312;10793.460326983619;10804.513796879159;483968;555869;559856;574787;574849;93619;103123;123096;126931;127075;1088.6733979806027;1105.8009558279587;1158.79141754485;1159.095905193967;1166.7958107331353;22101.447463813533;22454.98952547172;22463.550728985003;22495.662565061866;22760.20339288183;56557.62702543252;56604.64139661818;56947;57061.14408283962;58634;57262.56843578777;70547;74685.25314747001;76218.6189069051;77913.61043194748;7475;8883.955580222317;9090.272778177598;9148.725538233864;9211.374758193882;11779.941100294787;14215.218163001135;14260.643483912985;14410.711785764435;14616.196109214095;11489.770204596029;11916.225445346025;12212.57255996036;12252.326122063372;12257.26456412618;10360.481975901326;10690.946545267223;11286.830697539379;11549.133814963743;11872.228305160137;10571.418571978615;11957.820632690691;12027.819582706092;12196.51213951432;12411.007119430244;8818.867716984121;8867.157620026088;9027.277817774431;9166.908330916605;9210.447373157633;10135.442550659785;11590.594329198437;11693.766124677288;12107;12127.272363658209 09:05:51 INFO - PID 1957 | |3;mootools.html;1420.9644758881027;1454.345544504973;1468.8017117689112;1476.9409223631055;1477.0989696285271;11455.541778328867;11942.940285298582;11957.880421195785;11983.101267404947;11988;9738.415695058302;11011;11691;11691.29852208868;11729.882701172986;2211.9557608847813;2491.813114016447;2552.910648127316;2554.821162518615;2557.2200478853943;2473.75262473753;2495.625656151583;2532.632768248612;2539.2255362114547;2540.618907163922;2449.7795198432136;2542.2881593153948;2546.694396672402;2549.3499157714755;2551.8086143539217;2127.9148834046696;2470.061376676851;2508.5610018246734;2520.0171932946164;2524.406764410362;2469.074097213545;2494.1270555305678;2496.4133428644436;2503.3616427810975;2503.7746602805837;11230.831537526861;11504.769904601944;11507.539698412054;11508.021818145467;11526.962573368437;6479.449246814026;6748.662566871637;6751.898721519181;6753.358430949052;6758.864822703518;3945.782981935992;4266.06146647737;4350.499692535367;4355.956440435587;4356.629686476654;1236.7279198576298;1239.975200495985;1282.4229096906429;1297.1503665099376;1301.6095171448528;14855.2572371381;15134.091954482656;15152.621184470476;16021.359145634277;16335.59161020984;5747.885042299131;5882.705864706748;6047.516198704093;6065.696715164224;6073.848153796102;11285.60500382499;13897.305134743425;13925.164490130624;13930.930345348208;13940;6852.965735171392;6857.485688573377;6859.176898772179;6862.828429289208;6862.828429289308;5993.101034844721;6062.242219722535;6135.44780969706;6205.968970155211;6213.502919766408;4530.433695788027;4655.674102812771;4684.765761711969;4738.099761045391;4758.595519380822;1328.1499840102342;1402.172718096328;1440.4310297432455;1452.7021960498073;1479.8383269133678;783.9804004899809;792.7542461836849;795.3637090327715;798.441091236137;803.3533005930283;6518.315576864456;6752.797416077526;6905.033473840814;6922.826929326807;6937.583744975318;798.8815658078736;916.9317744827334;939.7039932421402;947.2658689515542;948.6347756113807;3806.2958352704836;3826.100866296418;3856.305864944281;3912.39357899526;3915.6671682906704;14295.428182872569;14774.039687420283;14778.704425911421;14829.925850370895;14843.183624900517;14652.974291799472;14663.633409164642;14673.413063477341;14681.853181468263;14693.412263509554;7527.661255243636;8139.511629302142;8369.539675317794;8425.662973481114;8650.221480066824;5850.976079186211;6245.469135123565;6727.663616819237;7029.683664235224;7074.080369551925;12354.505819767108;12866.169442722587;13286.468541258242;13293.268870211845;13382.464701411835;11076.612318568812;13329.66675833112;13473.461061557427;13540.72918541604;13580.25308608036;9177.724668259963;9228.538573071186;9370.437773733598;9371.34400591952;9375;9836.754081148028;10492.160627149962;10622.20333474977;10642.787144257227;10660;8938.50838203905;9021.503817290111;9108.453492790453;9257.120573545502;9316.34785565004;8838.911610883808;8924.910750892408;8998.820023599623;9010.909890901006;9038.683646072355;7836.138024817379;8022.518648881086;8093.312068474245;8109.310708589836;8129.065157506962;5938.317093534299;5981.700914954322;6029.125776762433;6039.396060394101;6172.839506172946;5952.136940143569;6086.1175129606845;6122.081687746695;6130.2030736004035;6149.877002460015 09:05:51 INFO - PID 1957 | |4;prototype.html;314.69946201377684;341.87521554632565;388.970827187961;407.9857204997822;408.14289991018865;5617.5225105866;5687.573431992601;6092.7867524636595;6170.691465426722;6184.443400093991;5827.329857066438;5967.641941483514;6052.822268309129;6096.841600095979;6101.267847858263;3946.605339466052;4538.02432477019;4612.607928326096;4627.858067030006;4669.463011753658;4013.9598604014022;4165.56261592533;4364.236258654739;4477.440319960005;4606.055758569502;4162.812673429703;4436.112777444508;4478.149151661174;4560.97719511402;4593.954060459403;4062.9187416251807;4560.589546940775;4577.450705915311;4582.954170458286;4601.37561750442;4045.4538637283886;4061.5938406159444;4071.3078776607754;4071.389291606253;4083.203775263825;5735.770569177228;5850.239468869062;5961.672108034057;5963.0459126539545;5973.402659734035;4587.518310577375;5425.26758887553;5432.859099589052;5439.700816455093;5465.59008074392;5662.433756624346;6413.615183089029;6466.741330346827;6477.287498375175;6495.434303450158;3564.536610240678;3723.845607861572;4148.211839750437;4291.334843099294;4657.6273898088075;6490.221173459215;6659.6670166491485;6698.224970382852;6780;6870.3473170048765;4011.656095208138;4239.872803815829;4305.397244385788;4312.5903039211225;4316.935245971313;3666.3400587894434;4037.9394309085387;4197.300093462152;4350.564943505688;4385.232584297735;4329.393884856123;4369;5055.443901170868;5094;5197.428282888879;5886.970565147147;6391.680415979183;6445.903311450332;6470.514711396664;6475.061116138134;1381.2334154544324;1473.9631509212356;1484.8366679665228;1496.8922996982155;1508.0876069977505;840.9789755256045;935.1723724503863;963.9132478076866;975.8438649815995;983.6081944049233;5201.713905735145;5865.384134665884;6111.205543279345;6120.846978825476;6122.448979591858;6523.412892839668;6680.392588002424;6680.432163266177;6682.5322227444585;6839.443489385491;5359.3032905722;5885.9405307045145;6058.1518587397795;6085.665288409092;6100.298465676416;2835.8940013394795;3425.989333146728;3451.2234747182183;3480.564929383827;3486.5356550248957;5469.741959349372;6658.167729033871;6668.633225172565;6747.672053141659;6880.724771009103;5817.476427121579;6356;6885.552439091443;6940.930590694129;6957.478189135835;5643.971780141073;6809.863802724017;6866.210385805696;6976.720931162697;6994.755183568653;5625.915611265917;6636.668166591555;6722.495812814156;6730.46156307504;6743;5967.6121052131475;6267.937320626917;6374.298827129105;6581.045748366556;6613.503987200883;5172.5603323717905;6028.6985650716415;6585.934140658724;6739.0902228198565;6933.410660093747;4208.75841626727;4306.612404883575;4337.544557821509;4338;4339.804708788175;6598.175228096488;6660.600363978177;6683.799486015428;6701.731930722912;6727.831804204739;5141.562967147835;6239.594426362272;6440.25937017249;6446.871062578816;6449.06528041595;5359.758810853599;5732.59871808986;6045.486133678511;6116.706235161814;6307.779227727007;5534.280543529316;6297.685115744102;6314.778982735582;6333.493320534439;6344.429001389897;6225.879341718534;6352.110704501466;6403.903941440975;6434.268124487692;6469.547131700736;5091.344713986165;6188.504919606331;6279.905801412891;6282.434580887742;6297.937020629918 09:05:51 INFO - PID 1957 | |5;yui.html;799.964001619927;820.4748960665175;829.796699808547;833.762377722349;845.8858054162686;10793.730156746085;14034.789478157836;15358.69282614347;15738.449154279602;16160.999999999985;11455.445640861035;12565.246085234892;16607.169641517936;16666.916665416655;16669.749953750703;840.9285210757063;983.3018556824663;987.5161171026217;993.7962717642865;994.6966175316526;1004.507791182319;1004.7036124343336;1006.2855372685402;1009.6718566465892;1014.9644762433317;678.3928384096231;696.1194089476816;1001.2740762946878;1015.096564057989;1019.867417235762;989.4706332112357;992.0525897767686;992.4789485520088;993.0169132558786;1027.6146445083093;729.4383324839844;729.9744508942215;730.6018220070075;744.1256523584766;769.0579040675128;15430.691386172213;15548.144852033134;15573.922130389275;15652.608684782857;15898.443554475654;15125.546233612899;15150.54548363551;15382.923085384502;15436.614084647861;15475.613109672237;12800.80798788019;12877.613671589866;13007.284599347033;13043.282619455926;13361;3444.4488881778793;3458.3774920514297;3460.3425349183576;3462.913427164341;3471.5660542432197;129131;129194.06208906874;129344.353278233;131685;131995;12229.938850305869;12324.198927069712;12417.813732794015;12482.563110291096;12533.43599538023;23851.642225366635;24021;24113.91487383072;24442.87778561096;24481.02075916979;7276.454265930077;7355.485116041933;7451.515651482637;7480.812979675443;7500.662470188855;3797.867074652374;3878.553966293856;3881.2237552489614;3882.2623701496627;3882.7476214045996;4035.6973227008093;4078.2047500737663;4097.221527909687;4148.087420767426;4169.457970463881;863.1627321498137;878.2227728460231;878.345632503776;879.4898958603994;880.6961598248594;638.2851206648584;645.5739212120072;645.6513482719373;656.8226578823786;682.5529278322707;10576.259661823598;10587.735306617242;10739.83890241647;11265.15511336653;11279.548818047351;650.2911826109469;661.3584822721951;673.1282988529865;678.915135608049;686.9519133660696;4875.414950205927;5065.746712664352;5140.971805638813;5162.432132465426;5220.477952204825;73847.63076184585;90872.5456372714;93791.53104234434;94452.52773736087;95673.5648965266;79410;80988.19011809924;82257.58871205605;82465;83000.58499707463;65666.3583410409;75434;76648.61675691586;76718.6164069176;77415;8508.232493552769;10401.791964160826;10583.047525722723;10791.946040269748;10852.348859068481;39035.02412439712;39676.60323396729;39689.789656667;39847.40228896511;39934.201315974096;52034.739826300625;52787;71281;71605;72936;15464.14947177871;15747;15788.21058947025;15796.36814527452;15840.920795395949;14175.858241417454;15539.989900656421;15903;16542.255598497857;17373.47879563615;13557.525486607921;15053.623659408164;15372.769408458658;15954.521364359089;16002;14852.034617749812;15363.923180384027;15798.36806527726;15940.123293218985;15990.200489975223;13067.934660326637;15034.548963531111;15040.247987600795;15691.372345106069;15775.211239438211;11129.998300152702;11252.887471125185;11333.26333788301;11356.148288878532;11394.601188958346;12041.698957526132;12122.818157727817;12135.514579416724;12214.46889723875;12260 09:05:51 INFO - PID 1957 | __end_tp_report 09:05:51 INFO - PID 1957 | __start_cc_report 09:05:51 INFO - PID 1957 | _x_x_mozilla_cycle_collect,2320 09:05:51 INFO - PID 1957 | __end_cc_report 09:05:51 INFO - PID 1957 | __startTimestamp1494173151787__endTimestamp 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | ------- Summary: start ------- 09:05:51 INFO - PID 1957 | Number of tests: 6 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | [#0] dojo.html Cycles:165 Average:6096.29 Median:6290.25 stddev:719.74 (11.4%) stddev-sans-first:693.03 09:05:51 INFO - PID 1957 | Values: 3522.0 3545.2 3555.9 3573.7 3592.4 6080.3 6162.7 6173.0 6191.1 6212.2 6112.6 6176.9 6198.8 6200.3 6202.2 4493.4 4868.5 5170.1 5348.6 5397.8 5402.2 5414.8 5479.0 5487.2 5503.6 5276.6 5357.7 5360.9 5401.1 5487.5 5380.1 5422.6 5456.0 5488.4 5505.6 5382.5 5383.6 5391.7 5396.3 5412.1 6137.1 6165.0 6193.5 6205.7 6220.9 5422.7 5526.0 6586.7 6601.9 6624.0 6713.4 6718.8 6720.0 6720.3 6724.7 6154.4 6233.2 6241.6 6254.8 6261.3 6637.8 6727.4 6747.0 6758.6 6759.2 6284.9 6444.7 6462.6 6463.5 6470.1 6689.1 6717.3 6723.2 6737.6 6746.1 6617.9 6622.3 6626.8 6645.5 6646.0 6326.5 6443.0 6487.4 6617.0 6651.4 5298.2 5301.6 5311.4 5328.8 5336.3 4569.7 4587.8 4691.6 4753.8 4771.3 6189.2 6209.2 6239.1 6305.8 6315.7 6754.4 6754.6 6765.0 6787.8 6797.7 6278.7 6287.5 6289.8 6291.9 6304.3 5611.2 5915.9 5934.4 5939.8 5954.6 6730.7 6734.9 6781.3 6972.5 6981.2 6189.3 6383.0 6399.4 6756.9 6758.4 6737.3 6764.3 6783.4 6954.4 6967.2 6580.3 6581.8 6582.8 6590.3 6597.5 6407.1 6502.8 6504.3 6510.6 6724.9 6269.0 6290.2 6297.5 6302.8 6419.8 6283.9 6409.3 6455.9 6456.2 6461.8 6291.1 6339.9 6342.2 6343.1 6344.0 6480.3 6482.2 6483.9 6485.9 6494.0 6167.5 6232.9 6248.2 6251.4 6254.0 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | [#1] ext.html Cycles:180 Average:40761.89 Median:17044.26 stddev:69575.55 (408.2%) stddev-sans-first:69706.00 09:05:51 INFO - PID 1957 | Values: 792.4 808.7 1117.3 1126.4 1131.0 32665.0 35804.3 36062.1 36063.1 36072.7 37573.0 37823.6 37839.1 37876.0 37901.8 5922.6 5986.0 8228.8 8385.6 8387.2 8209.2 8307.2 8348.8 8349.3 8351.4 8371.2 8428.6 8440.5 8442.0 8474.8 5968.8 6030.9 8334.7 8400.0 8416.7 8077.8 8208.5 8227.5 8233.5 8243.0 30302.0 34928.0 35251.0 35541.0 35704.5 3454.7 3484.3 3489.1 3491.0 3497.1 29071.7 29215.7 29229.0 29260.7 29278.6 3096.3 3145.0 3160.2 3194.4 3195.1 217809.0 286142.0 286238.6 286407.0 287524.0 37992.6 38012.8 38422.8 47535.3 47575.8 46719.3 46826.5 46861.3 46889.8 47922.6 13516.6 13628.1 13631.6 13632.6 13636.0 11129.1 11135.7 11137.3 11139.2 11145.6 11412.8 11426.3 11465.9 11475.0 11544.4 4590.8 5272.7 5338.7 5344.2 5352.8 975.1 982.2 984.1 1021.4 1039.4 23157.1 23390.9 23420.8 23471.2 23481.4 1075.1 1075.5 1109.9 1123.1 1125.6 8663.8 10330.5 11969.5 12055.3 12067.1 276033.0 277212.0 279230.0 279458.0 280454.0 50951.7 51111.0 51260.7 51298.0 51354.2 1451.7 1490.8 1501.6 1507.1 1526.3 27136.6 27811.3 27830.4 28006.9 28058.7 30784.5 34873.0 34874.0 34895.8 34899.5 243103.0 243185.8 243414.0 243664.0 244289.0 27194.6 27208.0 30769.4 30928.4 30992.2 33239.2 40035.6 40044.0 40060.0 40227.0 24758.6 26245.2 26531.0 28194.9 28928.0 27273.0 27441.5 28096.0 28309.9 28834.1 34322.3 34346.5 34385.8 34421.1 34424.8 23459.6 26898.8 27665.7 28357.0 28695.7 22127.9 22662.4 22834.4 22857.3 22929.5 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | [#2] jquery.html Cycles:180 Average:35057.59 Median:12920.05 stddev:92961.79 (719.5%) stddev-sans-first:93186.11 09:05:51 INFO - PID 1957 | Values: 380.3 417.5 427.2 431.7 450.4 19542.7 21338.9 21804.7 22097.3 22102.6 21892.8 23000.3 23537.4 23604.5 23814.5 5452.4 6056.0 6271.9 6281.5 6293.8 5975.0 6196.3 6259.0 6264.4 6267.1 5976.6 6199.7 6215.8 6225.6 6226.3 5998.7 6128.4 6129.9 6137.3 6138.0 5300.8 5479.2 5489.8 5498.2 5574.6 26845.2 27291.9 27454.9 27693.9 27842.9 2036.1 2114.8 2148.9 2220.5 2225.0 17408.5 17666.3 17691.8 18043.0 18293.8 2121.4 2150.8 2165.3 2171.7 2173.1 131962.0 155786.0 156539.0 158361.0 161827.2 11897.8 12494.9 12502.6 12522.1 12550.7 16810.9 16818.9 16819.7 16822.7 16832.9 8643.4 8839.4 8854.6 8947.6 9002.7 6842.3 8371.4 8390.4 8543.4 8557.3 7580.2 7687.8 7710.9 7731.8 7756.1 2102.6 2122.6 2125.6 2130.4 2302.7 915.0 922.3 947.2 953.3 967.9 20689.3 21185.1 21294.5 21322.5 21388.9 939.3 972.1 978.9 980.5 984.0 10348.9 10393.8 10705.4 10793.5 10804.5 483968.0 555869.0 559856.0 574787.0 574849.0 93619.0 103123.0 123096.0 126931.0 127075.0 1088.7 1105.8 1158.8 1159.1 1166.8 22101.4 22455.0 22463.6 22495.7 22760.2 56557.6 56604.6 56947.0 57061.1 58634.0 57262.6 70547.0 74685.3 76218.6 77913.6 7475.0 8884.0 9090.3 9148.7 9211.4 11779.9 14215.2 14260.6 14410.7 14616.2 11489.8 11916.2 12212.6 12252.3 12257.3 10360.5 10690.9 11286.8 11549.1 11872.2 10571.4 11957.8 12027.8 12196.5 12411.0 8818.9 8867.2 9027.3 9166.9 9210.4 10135.4 11590.6 11693.8 12107.0 12127.3 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | [#3] mootools.html Cycles:180 Average:7087.68 Median:3884.35 stddev:4406.89 (113.5%) stddev-sans-first:4398.67 09:05:51 INFO - PID 1957 | Values: 1421.0 1454.3 1468.8 1476.9 1477.1 11455.5 11942.9 11957.9 11983.1 11988.0 9738.4 11011.0 11691.0 11691.3 11729.9 2212.0 2491.8 2552.9 2554.8 2557.2 2473.8 2495.6 2532.6 2539.2 2540.6 2449.8 2542.3 2546.7 2549.3 2551.8 2127.9 2470.1 2508.6 2520.0 2524.4 2469.1 2494.1 2496.4 2503.4 2503.8 11230.8 11504.8 11507.5 11508.0 11527.0 6479.4 6748.7 6751.9 6753.4 6758.9 3945.8 4266.1 4350.5 4356.0 4356.6 1236.7 1240.0 1282.4 1297.2 1301.6 14855.3 15134.1 15152.6 16021.4 16335.6 5747.9 5882.7 6047.5 6065.7 6073.8 11285.6 13897.3 13925.2 13930.9 13940.0 6853.0 6857.5 6859.2 6862.8 6862.8 5993.1 6062.2 6135.4 6206.0 6213.5 4530.4 4655.7 4684.8 4738.1 4758.6 1328.1 1402.2 1440.4 1452.7 1479.8 784.0 792.8 795.4 798.4 803.4 6518.3 6752.8 6905.0 6922.8 6937.6 798.9 916.9 939.7 947.3 948.6 3806.3 3826.1 3856.3 3912.4 3915.7 14295.4 14774.0 14778.7 14829.9 14843.2 14653.0 14663.6 14673.4 14681.9 14693.4 7527.7 8139.5 8369.5 8425.7 8650.2 5851.0 6245.5 6727.7 7029.7 7074.1 12354.5 12866.2 13286.5 13293.3 13382.5 11076.6 13329.7 13473.5 13540.7 13580.3 9177.7 9228.5 9370.4 9371.3 9375.0 9836.8 10492.2 10622.2 10642.8 10660.0 8938.5 9021.5 9108.5 9257.1 9316.3 8838.9 8924.9 8998.8 9010.9 9038.7 7836.1 8022.5 8093.3 8109.3 8129.1 5938.3 5981.7 6029.1 6039.4 6172.8 5952.1 6086.1 6122.1 6130.2 6149.9 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | [#4] prototype.html Cycles:180 Average:5182.66 Median:5924.32 stddev:1639.11 (27.7%) stddev-sans-first:1602.47 09:05:51 INFO - PID 1957 | Values: 314.7 341.9 389.0 408.0 408.1 5617.5 5687.6 6092.8 6170.7 6184.4 5827.3 5967.6 6052.8 6096.8 6101.3 3946.6 4538.0 4612.6 4627.9 4669.5 4014.0 4165.6 4364.2 4477.4 4606.1 4162.8 4436.1 4478.1 4561.0 4594.0 4062.9 4560.6 4577.5 4583.0 4601.4 4045.5 4061.6 4071.3 4071.4 4083.2 5735.8 5850.2 5961.7 5963.0 5973.4 4587.5 5425.3 5432.9 5439.7 5465.6 5662.4 6413.6 6466.7 6477.3 6495.4 3564.5 3723.8 4148.2 4291.3 4657.6 6490.2 6659.7 6698.2 6780.0 6870.3 4011.7 4239.9 4305.4 4312.6 4316.9 3666.3 4037.9 4197.3 4350.6 4385.2 4329.4 4369.0 5055.4 5094.0 5197.4 5887.0 6391.7 6445.9 6470.5 6475.1 1381.2 1474.0 1484.8 1496.9 1508.1 841.0 935.2 963.9 975.8 983.6 5201.7 5865.4 6111.2 6120.8 6122.4 6523.4 6680.4 6680.4 6682.5 6839.4 5359.3 5885.9 6058.2 6085.7 6100.3 2835.9 3426.0 3451.2 3480.6 3486.5 5469.7 6658.2 6668.6 6747.7 6880.7 5817.5 6356.0 6885.6 6940.9 6957.5 5644.0 6809.9 6866.2 6976.7 6994.8 5625.9 6636.7 6722.5 6730.5 6743.0 5967.6 6267.9 6374.3 6581.0 6613.5 5172.6 6028.7 6585.9 6739.1 6933.4 4208.8 4306.6 4337.5 4338.0 4339.8 6598.2 6660.6 6683.8 6701.7 6727.8 5141.6 6239.6 6440.3 6446.9 6449.1 5359.8 5732.6 6045.5 6116.7 6307.8 5534.3 6297.7 6314.8 6333.5 6344.4 6225.9 6352.1 6403.9 6434.3 6469.5 5091.3 6188.5 6279.9 6282.4 6297.9 09:05:51 INFO - PID 1957 | 09:05:51 INFO - PID 1957 | [#5] yui.html Cycles:180 Average:20637.68 Median:24278.40 stddev:29615.51 (122.0%) stddev-sans-first:29661.13 09:05:51 INFO - PID 1957 | Values: 800.0 820.5 829.8 833.8 845.9 10793.7 14034.8 15358.7 15738.4 16161.0 11455.4 12565.2 16607.2 16666.9 16669.7 840.9 983.3 987.5 993.8 994.7 1004.5 1004.7 1006.3 1009.7 1015.0 678.4 696.1 1001.3 1015.1 1019.9 989.5 992.1 992.5 993.0 1027.6 729.4 730.0 730.6 744.1 769.1 15430.7 15548.1 15573.9 15652.6 15898.4 15125.5 15150.5 15382.9 15436.6 15475.6 12800.8 12877.6 13007.3 13043.3 13361.0 3444.4 3458.4 3460.3 3462.9 3471.6 129131.0 129194.1 129344.4 131685.0 131995.0 12229.9 12324.2 12417.8 12482.6 12533.4 23851.6 24021.0 24113.9 24442.9 24481.0 7276.5 7355.5 7451.5 7480.8 7500.7 3797.9 3878.6 3881.2 3882.3 3882.7 4035.7 4078.2 4097.2 4148.1 4169.5 863.2 878.2 878.3 879.5 880.7 638.3 645.6 645.7 656.8 682.6 10576.3 10587.7 10739.8 11265.2 11279.5 650.3 661.4 673.1 678.9 687.0 4875.4 5065.7 5141.0 5162.4 5220.5 73847.6 90872.5 93791.5 94452.5 95673.6 79410.0 80988.2 82257.6 82465.0 83000.6 65666.4 75434.0 76648.6 76718.6 77415.0 8508.2 10401.8 10583.0 10791.9 10852.3 39035.0 39676.6 39689.8 39847.4 39934.2 52034.7 52787.0 71281.0 71605.0 72936.0 15464.1 15747.0 15788.2 15796.4 15840.9 14175.9 15540.0 15903.0 16542.3 17373.5 13557.5 15053.6 15372.8 15954.5 16002.0 14852.0 15363.9 15798.4 15940.1 15990.2 13067.9 15034.5 15040.2 15691.4 15775.2 11130.0 11252.9 11333.3 11356.1 11394.6 12041.7 12122.8 12135.5 12214.5 12260.0 09:05:51 INFO - PID 1957 | -------- Summary: end -------- 09:05:51 INFO - PID 1957 | 09:05:52 INFO - TEST-INFO | 1957: exit 0 09:05:52 INFO - TEST-OK | dromaeo_css | took 1118764ms 09:05:52 INFO - TEST-START | kraken 09:05:52 INFO - operating with platform_type : mac_ 09:05:52 INFO - Initialising browser for kraken test... 09:05:52 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpnlj617/profile http://localhost:56894/getInfo.html) 09:05:54 INFO - PID 1961 | __metrics Screen width/height:1600/1200 09:05:54 INFO - PID 1961 | colorDepth:24 09:05:54 INFO - PID 1961 | Browser inner width/height: 1024/691 09:05:54 INFO - PID 1961 | __metrics 09:05:54 INFO - PID 1961 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:05:54 INFO - PID 1961 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:05:55 INFO - TEST-INFO | 1961: exit 0 09:05:55 INFO - Browser initialized. 09:05:55 INFO - Running cycle 1/1 for kraken test... 09:05:55 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpnlj617/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:07 INFO - PID 1962 | RSS: Main: 239235072 09:06:07 INFO - PID 1962 | 09:06:10 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:11 INFO - PID 1962 | RSS: Main: 308244480 09:06:11 INFO - PID 1962 | 09:06:14 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-dft) 09:06:14 INFO - PID 1962 | RSS: Main: 291016704 09:06:14 INFO - PID 1962 | 09:06:18 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-fft) 09:06:18 INFO - PID 1962 | RSS: Main: 382595072 09:06:18 INFO - PID 1962 | 09:06:20 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-oscillator) 09:06:21 INFO - PID 1962 | RSS: Main: 283422720 09:06:21 INFO - PID 1962 | 09:06:23 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56894/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:23 INFO - PID 1962 | RSS: Main: 287498240 09:06:23 INFO - PID 1962 | 09:06:26 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56894/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:27 INFO - PID 1962 | RSS: Main: 349790208 09:06:27 INFO - PID 1962 | 09:06:30 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56894/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:31 INFO - PID 1962 | RSS: Main: 368902144 09:06:31 INFO - PID 1962 | 09:06:33 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56894/tests/kraken/driver.html?testName=json-parse-financial) 09:06:34 INFO - PID 1962 | RSS: Main: 379260928 09:06:34 INFO - PID 1962 | 09:06:36 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56894/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:36 INFO - PID 1962 | RSS: Main: 326971392 09:06:36 INFO - PID 1962 | 09:06:38 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:39 INFO - PID 1962 | RSS: Main: 330842112 09:06:39 INFO - PID 1962 | 09:06:41 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:41 INFO - PID 1962 | RSS: Main: 332468224 09:06:41 INFO - PID 1962 | 09:06:44 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:45 INFO - PID 1962 | RSS: Main: 349679616 09:06:45 INFO - PID 1962 | 09:06:48 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:06:49 INFO - PID 1962 | RSS: Main: 342396928 09:06:49 INFO - PID 1962 | 09:06:50 INFO - PID 1962 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56894/tests/kraken/driver.html?testName=ai-astar) 09:06:51 INFO - PID 1962 | RSS: Main: 385314816 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | __start_tp_report 09:06:51 INFO - PID 1962 | _x_x_mozilla_page_load 09:06:51 INFO - PID 1962 | _x_x_mozilla_page_load_details 09:06:51 INFO - PID 1962 | |i|pagename|runs| 09:06:51 INFO - PID 1962 | |0;ai-astar;111;110;111;110;110;112;111;111;110;109 09:06:51 INFO - PID 1962 | |1;audio-beat-detection;141;133;133;154;111;130;139;120;135;108 09:06:51 INFO - PID 1962 | |2;audio-dft;184;183;185;183;186;184;184;183;180;184 09:06:51 INFO - PID 1962 | |3;audio-fft;80;57;58;87;60;75;83;81;86;59 09:06:51 INFO - PID 1962 | |4;audio-oscillator;90;69;84;68;68;67;89;69;69;87 09:06:51 INFO - PID 1962 | |5;imaging-gaussian-blur;91;91;88;90;111;92;91;133;93;90 09:06:51 INFO - PID 1962 | |6;imaging-darkroom;95;95;100;95;94;94;95;95;93;94 09:06:51 INFO - PID 1962 | |7;imaging-desaturate;85;86;84;84;85;87;84;84;84;102 09:06:51 INFO - PID 1962 | |8;json-parse-financial;49;48;48;47;48;49;50;49;48;49 09:06:51 INFO - PID 1962 | |9;json-stringify-tinderbox;56;55;59;71;65;62;57;57;58;58 09:06:51 INFO - PID 1962 | |10;stanford-crypto-aes;82;81;82;84;81;81;80;82;81;81 09:06:51 INFO - PID 1962 | |11;stanford-crypto-ccm;135;135;135;134;135;135;133;134;193;147 09:06:51 INFO - PID 1962 | |12;stanford-crypto-pbkdf2;215;204;189;182;187;184;182;185;182;211 09:06:51 INFO - PID 1962 | |13;stanford-crypto-sha256-iterative;68;67;66;68;66;67;70;68;67;66 09:06:51 INFO - PID 1962 | __end_tp_report 09:06:51 INFO - PID 1962 | __start_cc_report 09:06:51 INFO - PID 1962 | _x_x_mozilla_cycle_collect,2094 09:06:51 INFO - PID 1962 | __end_cc_report 09:06:51 INFO - PID 1962 | __startTimestamp1494173211050__endTimestamp 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | ------- Summary: start ------- 09:06:51 INFO - PID 1962 | Number of tests: 14 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:0.85 (0.8%) stddev-sans-first:0.88 09:06:51 INFO - PID 1962 | Values: 111.0 110.0 111.0 110.0 110.0 112.0 111.0 111.0 110.0 109.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:134.00 stddev:14.00 (10.4%) stddev-sans-first:14.32 09:06:51 INFO - PID 1962 | Values: 141.0 133.0 133.0 154.0 111.0 130.0 139.0 120.0 135.0 108.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.58 (0.9%) stddev-sans-first:1.67 09:06:51 INFO - PID 1962 | Values: 184.0 183.0 185.0 183.0 186.0 184.0 184.0 183.0 180.0 184.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#3] audio-fft Cycles:10 Average:72.60 Median:80.50 stddev:12.59 (15.6%) stddev-sans-first:13.07 09:06:51 INFO - PID 1962 | Values: 80.0 57.0 58.0 87.0 60.0 75.0 83.0 81.0 86.0 59.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:76.50 stddev:10.03 (13.1%) stddev-sans-first:9.28 09:06:51 INFO - PID 1962 | Values: 90.0 69.0 84.0 68.0 68.0 67.0 89.0 69.0 69.0 87.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:91.00 stddev:14.22 (15.6%) stddev-sans-first:14.92 09:06:51 INFO - PID 1962 | Values: 91.0 91.0 88.0 90.0 111.0 92.0 91.0 133.0 93.0 90.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.89 (2.0%) stddev-sans-first:2.00 09:06:51 INFO - PID 1962 | Values: 95.0 95.0 100.0 95.0 94.0 94.0 95.0 95.0 93.0 94.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:84.50 stddev:5.54 (6.6%) stddev-sans-first:5.85 09:06:51 INFO - PID 1962 | Values: 85.0 86.0 84.0 84.0 85.0 87.0 84.0 84.0 84.0 102.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#8] json-parse-financial Cycles:10 Average:48.50 Median:49.00 stddev:0.85 (1.7%) stddev-sans-first:0.88 09:06:51 INFO - PID 1962 | Values: 49.0 48.0 48.0 47.0 48.0 49.0 50.0 49.0 48.0 49.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:58.50 stddev:4.92 (8.4%) stddev-sans-first:5.02 09:06:51 INFO - PID 1962 | Values: 56.0 55.0 59.0 71.0 65.0 62.0 57.0 57.0 58.0 58.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.50 stddev:1.08 (1.3%) stddev-sans-first:1.13 09:06:51 INFO - PID 1962 | Values: 82.0 81.0 82.0 84.0 81.0 81.0 80.0 82.0 81.0 81.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:135.00 stddev:18.49 (13.7%) stddev-sans-first:19.46 09:06:51 INFO - PID 1962 | Values: 135.0 135.0 135.0 134.0 135.0 135.0 133.0 134.0 193.0 147.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:188.00 stddev:12.83 (6.8%) stddev-sans-first:10.60 09:06:51 INFO - PID 1962 | Values: 215.0 204.0 189.0 182.0 187.0 184.0 182.0 185.0 182.0 211.0 09:06:51 INFO - PID 1962 | 09:06:51 INFO - PID 1962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.25 (1.9%) stddev-sans-first:1.30 09:06:51 INFO - PID 1962 | Values: 68.0 67.0 66.0 68.0 66.0 67.0 70.0 68.0 67.0 66.0 09:06:51 INFO - PID 1962 | -------- Summary: end -------- 09:06:51 INFO - PID 1962 | 09:06:52 INFO - TEST-INFO | 1962: exit 0 09:06:52 INFO - TEST-OK | kraken | took 59858ms 09:06:52 INFO - SUITE-END | took 1178s 09:06:52 INFO - Completed test suite (00:19:39) 09:06:52 INFO - javascript benchmark 09:06:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7678.227874941285, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3522.0, 3545.237773878617, 3555.857765689372, 3573.731970102242, 3592.38929382005, 6080.300765411979, 6162.722677479513, 6172.969135154323, 6191.071339299107, 6212.161358216645, 6112.625067133446, 6176.876462470759, 6198.845028874281, 6200.255969283692, 6202.193714817067, 4493.438320209973, 4868.520050283439, 5170.121079416508, 5348.625596208267, 5397.78408863645, 5402.216678581603, 5414.810481633143, 5479.0, 5487.176923461473, 5503.559715222793, 5276.630635855472, 5357.678539287655, 5360.946390536123, 5401.135818269058, 5487.533559647395, 5380.112281473552, 5422.566194704454, 5456.0, 5488.396276409591, 5505.642133261325, 5382.4886635769935, 5383.596230282745, 5391.676499410048, 5396.298481197458, 5412.134058550652, 6137.079438084278, 6165.0, 6193.5354848386105, 6205.720742566596, 6220.906686399708, 5422.701751403672, 5526.0, 6586.703598338038, 6601.900971485432, 6623.973284140918, 6713.362230588086, 6718.798436046926, 6720.0, 6720.260771315151, 6724.731010759564, 6154.446099851035, 6233.189685340877, 6241.594296370721, 6254.781082662085, 6261.311255761862, 6637.800865974028, 6727.360900714424, 6747.0, 6758.560693555001, 6759.2226893906845, 6284.874302514016, 6444.709988050549, 6462.612243265325, 6463.515236357292, 6470.0618410330235, 6689.096971908827, 6717.294684058202, 6723.226828914738, 6737.629430381376, 6746.123004009545, 6617.900731489031, 6622.337766223339, 6626.834329141713, 6645.534812563171, 6646.036324732887, 6326.49388048955, 6443.001334793021, 6487.351264873475, 6617.007448882609, 6651.368120028589, 5298.231756395378, 5301.575873930076, 5311.362636483569, 5328.813491527855, 5336.2529245904825, 4569.680122391467, 4587.77061146948, 4691.601213896857, 4753.786079626425, 4771.284307353924, 6189.195404597373, 6209.161763162006, 6239.126522286883, 6305.779297724557, 6315.747370105144, 6754.358335958076, 6754.5947243165565, 6764.966175169093, 6787.762428314985, 6797.728090876409, 6278.748850045947, 6287.49700023997, 6289.842753931097, 6291.937080629226, 6304.338044505353, 5611.242482264842, 5915.852103697442, 5934.406559344031, 5939.792107276225, 5954.642721436728, 6730.730770769114, 6734.898976515258, 6781.321867813179, 6972.477064220305, 6981.162260528769, 6189.319174890848, 6383.042543618494, 6399.360063993563, 6756.898646520404, 6758.391744742997, 6737.258901520927, 6764.323567643196, 6783.3555812197765, 6954.443644508528, 6967.163940327193, 6580.341965803382, 6581.835454113685, 6582.8354291143105, 6590.308017658077, 6597.505187111082, 6407.10300557913, 6502.772402965874, 6504.349565043458, 6510.641914694548, 6724.8991265132045, 6268.968655156695, 6290.2451705793765, 6297.527685423704, 6302.810915672537, 6419.775307864203, 6283.937160628335, 6409.2949775525585, 6455.903161452488, 6456.192975878015, 6461.838454038499, 6291.1192433058495, 6339.904901426575, 6342.207224096988, 6343.080253363329, 6344.0, 6480.25477070143, 6482.157319548429, 6483.935160648333, 6485.870282594227, 6494.0, 6167.5374346925055, 6232.906506402498, 6248.187735594343, 6251.437370636664, 6254.0], "value": 6047.966451582466, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.3581898662084, 808.7169490678261, 1117.2961034548239, 1126.4142645824177, 1131.0386171754005, 32665.0, 35804.2839143217, 36062.098447538825, 36063.09842253945, 36072.737454189075, 37573.0, 37823.62176378236, 37839.054023649354, 37876.0, 37901.81049094757, 5922.555808314372, 5985.970070149643, 8228.835423291546, 8385.6226469809, 8387.245147936685, 8209.179082091772, 8307.169283071706, 8348.787571293613, 8349.33205343571, 8351.37364697647, 8371.246587807096, 8428.578571071452, 8440.451370660918, 8442.0, 8474.830503389929, 5968.820935371902, 6030.909536356959, 8334.70828521002, 8400.0, 8416.705415310435, 8077.798055048612, 8208.507489550646, 8227.465214761081, 8233.46482478637, 8243.010838699334, 30302.0, 34928.0, 35251.0, 35541.0, 35704.46443303353, 3454.7236221102253, 3484.250886059484, 3489.0928358626684, 3490.9825450872836, 3497.1257185703575, 29071.70928290711, 29215.707842921725, 29228.976985805606, 29260.707392926222, 29278.560821587693, 3096.2878537936176, 3144.984275078633, 3160.1783536280504, 3194.4409728297455, 3195.121341631047, 217809.0, 286142.0, 286238.5688071567, 286407.0, 287524.0, 37992.62007379946, 38012.80993595014, 38422.80788596095, 47535.286970695466, 47575.762121189175, 46719.29921051187, 46826.53173468222, 46861.297080543816, 46889.76555117202, 47922.60386980637, 13516.594502164951, 13628.114172578751, 13631.591052268448, 13632.591022269347, 13636.0, 11129.109671226444, 11135.665930022113, 11137.331760094421, 11139.164562657834, 11145.554177832959, 11412.828807567894, 11426.25729327591, 11465.885341146482, 11475.02462290698, 11544.422778861024, 4590.839320623762, 5272.657277276965, 5338.706371149624, 5344.198370244494, 5352.785888564492, 975.1272611013104, 982.2142286171035, 984.1437948984429, 1021.4177918586483, 1039.4231201682987, 23157.073717051466, 23390.883045584662, 23420.76579234186, 23471.178508752455, 23481.41296467602, 1075.1291453922192, 1075.4622688655672, 1109.8945600167972, 1123.0790751583786, 1125.6285425809463, 8663.826723465496, 10330.535125919352, 11969.521219151136, 12055.336956467478, 12067.094967877269, 276033.0, 277212.0, 279230.0, 279458.0, 280454.0, 50951.74524127356, 51111.0, 51260.718482037504, 51297.97404051898, 51354.22968655473, 1451.7314296854904, 1490.7614781634886, 1501.5870635575304, 1507.1409296700995, 1526.3436722209556, 27136.592951105355, 27811.304717382227, 27830.447308868996, 28006.87972481078, 28058.71941280561, 30784.53823192609, 34873.0, 34874.0, 34895.82552087223, 34899.4765078519, 243103.0, 243185.7840710856, 243414.0, 243664.0, 244289.0, 27194.640267986128, 27208.0, 30769.384612308077, 30928.381432371676, 30992.22519437032, 33239.16902077467, 40035.59964400319, 40044.0, 40060.0, 40226.99432514211, 24758.628620571068, 26245.21264362072, 26531.0, 28194.85902570474, 28928.0, 27273.0, 27441.451170976867, 28096.01663941752, 28309.867605295556, 28834.134975950754, 34322.313553729284, 34346.48480272848, 34385.82807085948, 34421.139471513416, 34424.82787586146, 23459.648105278095, 26898.789554470488, 27665.72334276712, 28357.007504737234, 28695.713042869305, 22127.92723533218, 22662.433439164153, 22834.429139270986, 22857.31428057161, 22929.541409172056], "value": 16420.159508650402, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.28506407953046, 417.4906613931003, 427.1648926348988, 431.732325957906, 450.407713856279, 19542.7068593971, 21338.89330553347, 21804.672929906024, 22097.33707988761, 22102.557948841055, 21892.79589622574, 23000.309990700305, 23537.411564710892, 23604.527909441844, 23814.523709525798, 5452.40023597404, 6055.9697201514045, 6271.937280627204, 6281.466075383598, 6293.811185664437, 5975.0439929611275, 6196.318404975449, 6259.029850373199, 6264.436200741955, 6267.122602835607, 5976.5517586180995, 6199.69001549923, 6215.782447614334, 6225.564210505267, 6226.3462336454695, 5998.670073145975, 6128.417800308964, 6129.908051379233, 6137.32489426165, 6138.0, 5300.754322734155, 5479.232907392929, 5489.835304940858, 5498.1752737089355, 5574.60977731559, 26845.194644160707, 27291.863540682367, 27454.862725686246, 27693.86153069222, 27842.860785696146, 2036.0939381975027, 2114.7990940860745, 2148.9032993515175, 2220.4781876259067, 2224.9888750556306, 17408.47774566765, 17666.293348266056, 17691.823081769147, 18043.007634580223, 18293.817061829344, 2121.4060063182333, 2150.849440539163, 2165.2638103044883, 2171.6742488626674, 2173.119886445992, 131962.0, 155786.0, 156539.0, 158361.0, 161827.19086404494, 11897.821532677017, 12494.875051249554, 12502.562410315595, 12522.123451358317, 12550.74898502025, 16810.915945420194, 16818.915905420396, 16819.747703784455, 16822.747658785127, 16832.915835420743, 8643.35174861888, 8839.425437346552, 8854.645814167361, 8947.642094316154, 9002.729918102468, 6842.3499767523, 8371.414001019992, 8390.37072219586, 8543.444676096035, 8557.27263182624, 7580.204078571781, 7687.846243075219, 7710.884336734954, 7731.845363092707, 7756.146823849372, 2102.6005059038735, 2122.6073176462405, 2125.585510825405, 2130.371540395587, 2302.689136966488, 914.9542522873829, 922.3405265235255, 947.1522986926662, 953.2659851913987, 967.8772623756408, 20689.27587534429, 21185.093341599022, 21294.467638309165, 21322.466938326357, 21388.893055534933, 939.3283802081545, 972.1348000279756, 978.8972157923458, 980.4803454169302, 984.0, 10348.913364096812, 10393.84409233862, 10705.357678539312, 10793.460326983619, 10804.513796879159, 483968.0, 555869.0, 559856.0, 574787.0, 574849.0, 93619.0, 103123.0, 123096.0, 126931.0, 127075.0, 1088.6733979806027, 1105.8009558279587, 1158.79141754485, 1159.095905193967, 1166.7958107331353, 22101.447463813533, 22454.98952547172, 22463.550728985003, 22495.662565061866, 22760.20339288183, 56557.62702543252, 56604.64139661818, 56947.0, 57061.14408283962, 58634.0, 57262.56843578777, 70547.0, 74685.25314747001, 76218.6189069051, 77913.61043194748, 7475.0, 8883.955580222317, 9090.272778177598, 9148.725538233864, 9211.374758193882, 11779.941100294787, 14215.218163001135, 14260.643483912985, 14410.711785764435, 14616.196109214095, 11489.770204596029, 11916.225445346025, 12212.57255996036, 12252.326122063372, 12257.26456412618, 10360.481975901326, 10690.946545267223, 11286.830697539379, 11549.133814963743, 11872.228305160137, 10571.418571978615, 11957.820632690691, 12027.819582706092, 12196.51213951432, 12411.007119430244, 8818.867716984121, 8867.157620026088, 9027.277817774431, 9166.908330916605, 9210.447373157633, 10135.442550659785, 11590.594329198437, 11693.766124677288, 12107.0, 12127.272363658209], "value": 10377.056250782032, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.9644758881027, 1454.345544504973, 1468.8017117689112, 1476.9409223631055, 1477.0989696285271, 11455.541778328867, 11942.940285298582, 11957.880421195785, 11983.101267404947, 11988.0, 9738.415695058302, 11011.0, 11691.0, 11691.29852208868, 11729.882701172986, 2211.9557608847813, 2491.813114016447, 2552.910648127316, 2554.821162518615, 2557.2200478853943, 2473.75262473753, 2495.625656151583, 2532.632768248612, 2539.2255362114547, 2540.618907163922, 2449.7795198432136, 2542.2881593153948, 2546.694396672402, 2549.3499157714755, 2551.8086143539217, 2127.9148834046696, 2470.061376676851, 2508.5610018246734, 2520.0171932946164, 2524.406764410362, 2469.074097213545, 2494.1270555305678, 2496.4133428644436, 2503.3616427810975, 2503.7746602805837, 11230.831537526861, 11504.769904601944, 11507.539698412054, 11508.021818145467, 11526.962573368437, 6479.449246814026, 6748.662566871637, 6751.898721519181, 6753.358430949052, 6758.864822703518, 3945.782981935992, 4266.06146647737, 4350.499692535367, 4355.956440435587, 4356.629686476654, 1236.7279198576298, 1239.975200495985, 1282.4229096906429, 1297.1503665099376, 1301.6095171448528, 14855.2572371381, 15134.091954482656, 15152.621184470476, 16021.359145634277, 16335.59161020984, 5747.885042299131, 5882.705864706748, 6047.516198704093, 6065.696715164224, 6073.848153796102, 11285.60500382499, 13897.305134743425, 13925.164490130624, 13930.930345348208, 13940.0, 6852.965735171392, 6857.485688573377, 6859.176898772179, 6862.828429289208, 6862.828429289308, 5993.101034844721, 6062.242219722535, 6135.44780969706, 6205.968970155211, 6213.502919766408, 4530.433695788027, 4655.674102812771, 4684.765761711969, 4738.099761045391, 4758.595519380822, 1328.1499840102342, 1402.172718096328, 1440.4310297432455, 1452.7021960498073, 1479.8383269133678, 783.9804004899809, 792.7542461836849, 795.3637090327715, 798.441091236137, 803.3533005930283, 6518.315576864456, 6752.797416077526, 6905.033473840814, 6922.826929326807, 6937.583744975318, 798.8815658078736, 916.9317744827334, 939.7039932421402, 947.2658689515542, 948.6347756113807, 3806.2958352704836, 3826.100866296418, 3856.305864944281, 3912.39357899526, 3915.6671682906704, 14295.428182872569, 14774.039687420283, 14778.704425911421, 14829.925850370895, 14843.183624900517, 14652.974291799472, 14663.633409164642, 14673.413063477341, 14681.853181468263, 14693.412263509554, 7527.661255243636, 8139.511629302142, 8369.539675317794, 8425.662973481114, 8650.221480066824, 5850.976079186211, 6245.469135123565, 6727.663616819237, 7029.683664235224, 7074.080369551925, 12354.505819767108, 12866.169442722587, 13286.468541258242, 13293.268870211845, 13382.464701411835, 11076.612318568812, 13329.66675833112, 13473.461061557427, 13540.72918541604, 13580.25308608036, 9177.724668259963, 9228.538573071186, 9370.437773733598, 9371.34400591952, 9375.0, 9836.754081148028, 10492.160627149962, 10622.20333474977, 10642.787144257227, 10660.0, 8938.50838203905, 9021.503817290111, 9108.453492790453, 9257.120573545502, 9316.34785565004, 8838.911610883808, 8924.910750892408, 8998.820023599623, 9010.909890901006, 9038.683646072355, 7836.138024817379, 8022.518648881086, 8093.312068474245, 8109.310708589836, 8129.065157506962, 5938.317093534299, 5981.700914954322, 6029.125776762433, 6039.396060394101, 6172.839506172946, 5952.136940143569, 6086.1175129606845, 6122.081687746695, 6130.2030736004035, 6149.877002460015], "value": 5401.505199388114, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.69946201377684, 341.87521554632565, 388.970827187961, 407.9857204997822, 408.14289991018865, 5617.5225105866, 5687.573431992601, 6092.7867524636595, 6170.691465426722, 6184.443400093991, 5827.329857066438, 5967.641941483514, 6052.822268309129, 6096.841600095979, 6101.267847858263, 3946.605339466052, 4538.02432477019, 4612.607928326096, 4627.858067030006, 4669.463011753658, 4013.9598604014022, 4165.56261592533, 4364.236258654739, 4477.440319960005, 4606.055758569502, 4162.812673429703, 4436.112777444508, 4478.149151661174, 4560.97719511402, 4593.954060459403, 4062.9187416251807, 4560.589546940775, 4577.450705915311, 4582.954170458286, 4601.37561750442, 4045.4538637283886, 4061.5938406159444, 4071.3078776607754, 4071.389291606253, 4083.203775263825, 5735.770569177228, 5850.239468869062, 5961.672108034057, 5963.0459126539545, 5973.402659734035, 4587.518310577375, 5425.26758887553, 5432.859099589052, 5439.700816455093, 5465.59008074392, 5662.433756624346, 6413.615183089029, 6466.741330346827, 6477.287498375175, 6495.434303450158, 3564.536610240678, 3723.845607861572, 4148.211839750437, 4291.334843099294, 4657.6273898088075, 6490.221173459215, 6659.6670166491485, 6698.224970382852, 6780.0, 6870.3473170048765, 4011.656095208138, 4239.872803815829, 4305.397244385788, 4312.5903039211225, 4316.935245971313, 3666.3400587894434, 4037.9394309085387, 4197.300093462152, 4350.564943505688, 4385.232584297735, 4329.393884856123, 4369.0, 5055.443901170868, 5094.0, 5197.428282888879, 5886.970565147147, 6391.680415979183, 6445.903311450332, 6470.514711396664, 6475.061116138134, 1381.2334154544324, 1473.9631509212356, 1484.8366679665228, 1496.8922996982155, 1508.0876069977505, 840.9789755256045, 935.1723724503863, 963.9132478076866, 975.8438649815995, 983.6081944049233, 5201.713905735145, 5865.384134665884, 6111.205543279345, 6120.846978825476, 6122.448979591858, 6523.412892839668, 6680.392588002424, 6680.432163266177, 6682.5322227444585, 6839.443489385491, 5359.3032905722, 5885.9405307045145, 6058.1518587397795, 6085.665288409092, 6100.298465676416, 2835.8940013394795, 3425.989333146728, 3451.2234747182183, 3480.564929383827, 3486.5356550248957, 5469.741959349372, 6658.167729033871, 6668.633225172565, 6747.672053141659, 6880.724771009103, 5817.476427121579, 6356.0, 6885.552439091443, 6940.930590694129, 6957.478189135835, 5643.971780141073, 6809.863802724017, 6866.210385805696, 6976.720931162697, 6994.755183568653, 5625.915611265917, 6636.668166591555, 6722.495812814156, 6730.46156307504, 6743.0, 5967.6121052131475, 6267.937320626917, 6374.298827129105, 6581.045748366556, 6613.503987200883, 5172.5603323717905, 6028.6985650716415, 6585.934140658724, 6739.0902228198565, 6933.410660093747, 4208.75841626727, 4306.612404883575, 4337.544557821509, 4338.0, 4339.804708788175, 6598.175228096488, 6660.600363978177, 6683.799486015428, 6701.731930722912, 6727.831804204739, 5141.562967147835, 6239.594426362272, 6440.25937017249, 6446.871062578816, 6449.06528041595, 5359.758810853599, 5732.59871808986, 6045.486133678511, 6116.706235161814, 6307.779227727007, 5534.280543529316, 6297.685115744102, 6314.778982735582, 6333.493320534439, 6344.429001389897, 6225.879341718534, 6352.110704501466, 6403.903941440975, 6434.268124487692, 6469.547131700736, 5091.344713986165, 6188.504919606331, 6279.905801412891, 6282.434580887742, 6297.937020629918], "value": 4661.4694096141575, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.964001619927, 820.4748960665175, 829.796699808547, 833.762377722349, 845.8858054162686, 10793.730156746085, 14034.789478157836, 15358.69282614347, 15738.449154279602, 16160.999999999985, 11455.445640861035, 12565.246085234892, 16607.169641517936, 16666.916665416655, 16669.749953750703, 840.9285210757063, 983.3018556824663, 987.5161171026217, 993.7962717642865, 994.6966175316526, 1004.507791182319, 1004.7036124343336, 1006.2855372685402, 1009.6718566465892, 1014.9644762433317, 678.3928384096231, 696.1194089476816, 1001.2740762946878, 1015.096564057989, 1019.867417235762, 989.4706332112357, 992.0525897767686, 992.4789485520088, 993.0169132558786, 1027.6146445083093, 729.4383324839844, 729.9744508942215, 730.6018220070075, 744.1256523584766, 769.0579040675128, 15430.691386172213, 15548.144852033134, 15573.922130389275, 15652.608684782857, 15898.443554475654, 15125.546233612899, 15150.54548363551, 15382.923085384502, 15436.614084647861, 15475.613109672237, 12800.80798788019, 12877.613671589866, 13007.284599347033, 13043.282619455926, 13361.0, 3444.4488881778793, 3458.3774920514297, 3460.3425349183576, 3462.913427164341, 3471.5660542432197, 129131.0, 129194.06208906874, 129344.353278233, 131685.0, 131995.0, 12229.938850305869, 12324.198927069712, 12417.813732794015, 12482.563110291096, 12533.43599538023, 23851.642225366635, 24021.0, 24113.91487383072, 24442.87778561096, 24481.02075916979, 7276.454265930077, 7355.485116041933, 7451.515651482637, 7480.812979675443, 7500.662470188855, 3797.867074652374, 3878.553966293856, 3881.2237552489614, 3882.2623701496627, 3882.7476214045996, 4035.6973227008093, 4078.2047500737663, 4097.221527909687, 4148.087420767426, 4169.457970463881, 863.1627321498137, 878.2227728460231, 878.345632503776, 879.4898958603994, 880.6961598248594, 638.2851206648584, 645.5739212120072, 645.6513482719373, 656.8226578823786, 682.5529278322707, 10576.259661823598, 10587.735306617242, 10739.83890241647, 11265.15511336653, 11279.548818047351, 650.2911826109469, 661.3584822721951, 673.1282988529865, 678.915135608049, 686.9519133660696, 4875.414950205927, 5065.746712664352, 5140.971805638813, 5162.432132465426, 5220.477952204825, 73847.63076184585, 90872.5456372714, 93791.53104234434, 94452.52773736087, 95673.5648965266, 79410.0, 80988.19011809924, 82257.58871205605, 82465.0, 83000.58499707463, 65666.3583410409, 75434.0, 76648.61675691586, 76718.6164069176, 77415.0, 8508.232493552769, 10401.791964160826, 10583.047525722723, 10791.946040269748, 10852.348859068481, 39035.02412439712, 39676.60323396729, 39689.789656667, 39847.40228896511, 39934.201315974096, 52034.739826300625, 52787.0, 71281.0, 71605.0, 72936.0, 15464.14947177871, 15747.0, 15788.21058947025, 15796.36814527452, 15840.920795395949, 14175.858241417454, 15539.989900656421, 15903.0, 16542.255598497857, 17373.47879563615, 13557.525486607921, 15053.623659408164, 15372.769408458658, 15954.521364359089, 16002.0, 14852.034617749812, 15363.923180384027, 15798.36806527726, 15940.123293218985, 15990.200489975223, 13067.934660326637, 15034.548963531111, 15040.247987600795, 15691.372345106069, 15775.211239438211, 11129.998300152702, 11252.887471125185, 11333.26333788301, 11356.148288878532, 11394.601188958346, 12041.698957526132, 12122.818157727817, 12135.514579416724, 12214.46889723875, 12260.0], "value": 7896.563489354948, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 111.0, 110.0, 110.0, 112.0, 111.0, 111.0, 110.0, 109.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 133.0, 154.0, 111.0, 130.0, 139.0, 120.0, 135.0, 108.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 183.0, 186.0, 184.0, 184.0, 183.0, 180.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 58.0, 87.0, 60.0, 75.0, 83.0, 81.0, 86.0, 59.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 84.0, 68.0, 68.0, 67.0, 89.0, 69.0, 69.0, 87.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 88.0, 90.0, 111.0, 92.0, 91.0, 133.0, 93.0, 90.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 100.0, 95.0, 94.0, 94.0, 95.0, 95.0, 93.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 84.0, 85.0, 87.0, 84.0, 84.0, 84.0, 102.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 48.0, 47.0, 48.0, 49.0, 50.0, 49.0, 48.0, 49.0], "value": 48.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 59.0, 71.0, 65.0, 62.0, 57.0, 57.0, 58.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 84.0, 81.0, 81.0, 80.0, 82.0, 81.0, 81.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 135.0, 135.0, 134.0, 135.0, 135.0, 133.0, 134.0, 193.0, 147.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 204.0, 189.0, 182.0, 187.0, 184.0, 182.0, 185.0, 182.0, 211.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 68.0, 66.0, 67.0, 70.0, 68.0, 67.0, 66.0], "value": 67.3, "unit": "score"}]}]} 09:06:52 INFO - Return code: 0 09:06:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:06:52 INFO - # TBPL SUCCESS # 09:06:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:52 INFO - 2017-05-07 09:06:52.862 screenresolution[1967:35477] starting screenresolution argv=screenresolution get 09:06:52 INFO - 2017-05-07 09:06:52.864 screenresolution[1967:35477] Display 0: 1600x1200x32@60 09:06:52 INFO - 2017-05-07 09:06:52.870 screenresolution[1968:35480] starting screenresolution argv=screenresolution list 09:06:52 INFO - Available Modes on Display 0 09:06:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:53 INFO - Intel Iris: 09:06:53 INFO - Chipset Model: Intel Iris 09:06:53 INFO - Type: GPU 09:06:53 INFO - Bus: Built-In 09:06:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:53 INFO - Vendor: Intel (0x8086) 09:06:53 INFO - Device ID: 0x0a2e 09:06:53 INFO - Revision ID: 0x0009 09:06:53 INFO - Displays: 09:06:53 INFO - Display: 09:06:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:53 INFO - Display Serial Number: 1600x1200 60 09:06:53 INFO - Main Display: Yes 09:06:53 INFO - Mirror: Off 09:06:53 INFO - Online: Yes 09:06:53 INFO - Rotation: Supported 09:06:53 INFO - Return code: 0 09:06:53 INFO - Running post-action listener: _resource_record_post_action 09:06:53 INFO - [mozharness: 2017-05-07 16:06:53.114652Z] Finished run-tests step (success) 09:06:53 INFO - Running post-run listener: _resource_record_post_run 09:06:53 INFO - Total resource usage - Wall time: 1200s; CPU: 53.0%; Read bytes: 16384; Write bytes: 512120832; Read time: 2; Write time: 1569 09:06:53 INFO - TinderboxPrint: CPU usage
53.3% 09:06:53 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 2 09:06:53 INFO - TinderboxPrint: I/O write bytes / time
512,120,832 / 1,569 09:06:53 INFO - TinderboxPrint: CPU idle
2,241.0 (46.7%) 09:06:53 INFO - TinderboxPrint: CPU system
952.1 (19.8%) 09:06:53 INFO - TinderboxPrint: CPU user
1,608.6 (33.5%) 09:06:53 INFO - TinderboxPrint: Swap in / out
207,720,448 / 0 09:06:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203016704; Write bytes: 199030784; Read time: 16230; Write time: 412 09:06:53 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 16384; Write bytes: 298524672; Read time: 2; Write time: 1109 09:06:53 INFO - Running post-run listener: _upload_blobber_files 09:06:53 INFO - Blob upload gear active. 09:06:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:06:53 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 09:06:53 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:06:53 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:06:54 INFO - (blobuploader) - INFO - Open directory for files ... 09:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:55 INFO - (blobuploader) - INFO - Done attempting. 09:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:06:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:56 INFO - (blobuploader) - INFO - Done attempting. 09:06:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:06:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:06:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:57 INFO - (blobuploader) - INFO - Done attempting. 09:06:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:06:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:06:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:59 INFO - (blobuploader) - INFO - Done attempting. 09:06:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:06:59 INFO - Return code: 0 09:06:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:06:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:06:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d7c2bd0f27bff28bb1721d51f0407b9e377c3506fb4d2b753b0f34cb1791534056ed04256d90754e8c7b62c45c04d8b48229332a452719cf725c61a76852a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172857b7f62c01990fc4c5f8b815a3f6116f0f77abaf59903830ea15fcabb2bc2c7fe3b34daf07b1f26761c6fc6d60e85a2936625be8f97763835656e5f20c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e778c39352799c3cb50eee37c2cdd759443fe33e730085fabda6d2489db75bbca32c7949602d31180f8816c9e0d8236217783eaf54643a84131601bbe5a32f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a704c8eeb3bb6d4922243d21e78c46c56e0466b2f0b8826df8caa583cd048b5fdd4b978c077a84e009c6005ec81422196f408ef341d7e8b5a5be60c235b7b250"} 09:06:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:06:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:06:59 INFO - Contents: 09:06:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d7c2bd0f27bff28bb1721d51f0407b9e377c3506fb4d2b753b0f34cb1791534056ed04256d90754e8c7b62c45c04d8b48229332a452719cf725c61a76852a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172857b7f62c01990fc4c5f8b815a3f6116f0f77abaf59903830ea15fcabb2bc2c7fe3b34daf07b1f26761c6fc6d60e85a2936625be8f97763835656e5f20c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e778c39352799c3cb50eee37c2cdd759443fe33e730085fabda6d2489db75bbca32c7949602d31180f8816c9e0d8236217783eaf54643a84131601bbe5a32f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a704c8eeb3bb6d4922243d21e78c46c56e0466b2f0b8826df8caa583cd048b5fdd4b978c077a84e009c6005ec81422196f408ef341d7e8b5a5be60c235b7b250"} 09:06:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:06:59 INFO - Copying logs to upload dir... 09:06:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:06:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.087088 ========= master_lag: 1.21 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-07 09:07:00.855338) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 09:07:00.858441) ========= 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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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/Firefox/sha512/612d7c2bd0f27bff28bb1721d51f0407b9e377c3506fb4d2b753b0f34cb1791534056ed04256d90754e8c7b62c45c04d8b48229332a452719cf725c61a76852a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172857b7f62c01990fc4c5f8b815a3f6116f0f77abaf59903830ea15fcabb2bc2c7fe3b34daf07b1f26761c6fc6d60e85a2936625be8f97763835656e5f20c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e778c39352799c3cb50eee37c2cdd759443fe33e730085fabda6d2489db75bbca32c7949602d31180f8816c9e0d8236217783eaf54643a84131601bbe5a32f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a704c8eeb3bb6d4922243d21e78c46c56e0466b2f0b8826df8caa583cd048b5fdd4b978c077a84e009c6005ec81422196f408ef341d7e8b5a5be60c235b7b250"} build_url:https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d7c2bd0f27bff28bb1721d51f0407b9e377c3506fb4d2b753b0f34cb1791534056ed04256d90754e8c7b62c45c04d8b48229332a452719cf725c61a76852a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172857b7f62c01990fc4c5f8b815a3f6116f0f77abaf59903830ea15fcabb2bc2c7fe3b34daf07b1f26761c6fc6d60e85a2936625be8f97763835656e5f20c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e778c39352799c3cb50eee37c2cdd759443fe33e730085fabda6d2489db75bbca32c7949602d31180f8816c9e0d8236217783eaf54643a84131601bbe5a32f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a704c8eeb3bb6d4922243d21e78c46c56e0466b2f0b8826df8caa583cd048b5fdd4b978c077a84e009c6005ec81422196f408ef341d7e8b5a5be60c235b7b250"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 09:07:00.894142) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 09:07:00.894494) ========= 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.CNggP3tMLJ/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.kaaxuWwbtE/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.005190 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 09:07:00.945318) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 09:07:00.945620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 09:07:00.945933) ========= ========= Total master_lag: 1.38 =========