builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0169 starttime: 1493850307.53 results: success (0) buildid: 20170503134320 builduid: 11a86fe266a34a05b973d68ce41d8710 revision: b25ad0674afd563e888dc07981baa626e8d794db ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:07.528286) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:07.528741) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:07.529020) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:07.556512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-03 15:25:07.556787) ========= 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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-03 15:25:07-- 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% 8.00M=0.001s 2017-05-03 15:25:07 (8.00 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137214 ========= master_lag: 1.94 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-03 15:25:09.637952) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:09.638290) ========= 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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.026303 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:09.690519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:09.690882) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b25ad0674afd563e888dc07981baa626e8d794db --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 b25ad0674afd563e888dc07981baa626e8d794db --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-03 15:25:09,777 truncating revision to first 12 chars 2017-05-03 15:25:09,777 Setting DEBUG logging. 2017-05-03 15:25:09,777 attempt 1/10 2017-05-03 15:25:09,777 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b25ad0674afd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 15:25:10,423 unpacking tar archive at: mozilla-central-b25ad0674afd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.911654 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:10.631879) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:10.632191) ========= script_repo_revision: b25ad0674afd563e888dc07981baa626e8d794db ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:10.632780) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:10.633086) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 15:25:10.653756) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-03 15:25:10.654210) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:25:10 INFO - MultiFileLogger online at 20170503 15:25:10 in /builds/slave/test 15:25:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:25:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:25:10 INFO - {'append_to_log': False, 15:25:10 INFO - 'base_work_dir': '/builds/slave/test', 15:25:10 INFO - 'blob_upload_branch': 'Firefox', 15:25:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:25:10 INFO - 'branch': 'Firefox', 15:25:10 INFO - 'buildbot_json_path': 'buildprops.json', 15:25:10 INFO - 'config_files': ('talos/mac_config.py',), 15:25:10 INFO - 'default_actions': ('clobber', 15:25:10 INFO - 'read-buildbot-config', 15:25:10 INFO - 'download-and-extract', 15:25:10 INFO - 'populate-webroot', 15:25:10 INFO - 'create-virtualenv', 15:25:10 INFO - 'install', 15:25:10 INFO - 'run-tests'), 15:25:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:25:10 INFO - 'download_minidump_stackwalk': True, 15:25:10 INFO - 'download_symbols': 'ondemand', 15:25:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:25:10 INFO - 'tooltool.py': '/tools/tooltool.py', 15:25:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:25:10 INFO - '/tools/misc-python/virtualenv.py')}, 15:25:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:25:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:25:10 INFO - 'gecko_profile': False, 15:25:10 INFO - 'gecko_profile_interval': 0, 15:25:10 INFO - 'installer_path': 'installer.exe', 15:25:10 INFO - 'log_level': 'info', 15:25:10 INFO - 'log_name': 'talos', 15:25:10 INFO - 'log_to_console': True, 15:25:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:25:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:25:10 INFO - 'opt_config_files': (), 15:25:10 INFO - 'pip_index': False, 15:25:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:25:10 INFO - 'cmd': ('bash', 15:25:10 INFO - '-c', 15:25:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:25:10 INFO - 'enabled': True, 15:25:10 INFO - 'halt_on_failure': False, 15:25:10 INFO - 'name': 'check_screen_resolution'},), 15:25:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:25:10 INFO - 'cmd': ('bash', 15:25:10 INFO - '-c', 15:25:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:25:10 INFO - 'enabled': True, 15:25:10 INFO - 'halt_on_failure': False, 15:25:10 INFO - 'name': 'check_screen_resolution'},), 15:25:10 INFO - 'run_cmd_checks_enabled': True, 15:25:10 INFO - 'suite': 'dromaeojs-e10s', 15:25:10 INFO - 'system_bits': '32', 15:25:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:25:10 INFO - 'title': 't-yosemite-r7-0169', 15:25:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:25:10 INFO - 'use_talos_json': True, 15:25:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:25:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:25:10 INFO - 'work_dir': 'build'} 15:25:10 INFO - [mozharness: 2017-05-03 22:25:10.859537Z] Running clobber step. 15:25:10 INFO - Running pre-action listener: _resource_record_pre_action 15:25:10 INFO - Running main action method: clobber 15:25:10 INFO - rmtree: /builds/slave/test/build 15:25:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:25:12 INFO - Running post-action listener: _resource_record_post_action 15:25:12 INFO - [mozharness: 2017-05-03 22:25:12.722500Z] Finished clobber step (success) 15:25:12 INFO - [mozharness: 2017-05-03 22:25:12.722723Z] Running read-buildbot-config step. 15:25:12 INFO - Running pre-action listener: _resource_record_pre_action 15:25:12 INFO - Running main action method: read_buildbot_config 15:25:12 INFO - Using buildbot properties: 15:25:12 INFO - { 15:25:12 INFO - "project": "", 15:25:12 INFO - "product": "firefox", 15:25:12 INFO - "script_repo_revision": "production", 15:25:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:25:12 INFO - "repository": "", 15:25:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:25:12 INFO - "buildid": "20170503134320", 15:25:12 INFO - "pgo_build": "False", 15:25:12 INFO - "basedir": "/builds/slave/test", 15:25:12 INFO - "buildnumber": 63, 15:25:12 INFO - "slavename": "t-yosemite-r7-0169", 15:25:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:25:12 INFO - "platform": "yosemite_r7", 15:25:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:25:12 INFO - "branch": "mozilla-central", 15:25:12 INFO - "repo_path": "mozilla-central", 15:25:12 INFO - "revision": "b25ad0674afd563e888dc07981baa626e8d794db", 15:25:12 INFO - "stage_platform": "macosx64", 15:25:12 INFO - "builduid": "11a86fe266a34a05b973d68ce41d8710", 15:25:12 INFO - "slavebuilddir": "test" 15:25:12 INFO - } 15:25:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:25:12 INFO - Running post-action listener: _resource_record_post_action 15:25:12 INFO - [mozharness: 2017-05-03 22:25:12.728289Z] Finished read-buildbot-config step (success) 15:25:12 INFO - [mozharness: 2017-05-03 22:25:12.728447Z] Running download-and-extract step. 15:25:12 INFO - Running pre-action listener: _resource_record_pre_action 15:25:12 INFO - Running main action method: download_and_extract 15:25:12 INFO - mkdir: /builds/slave/test/build/tests 15:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:12 INFO - trying https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:25:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/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 15:25:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/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 15:25:14 INFO - Downloaded 1325 bytes. 15:25:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:25:14 INFO - Using the following test package requirements: 15:25:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:25:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:25:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:25:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:25:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'jsshell-mac.zip'], 15:25:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:25:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:25:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:25:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:25:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:25:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:25:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:25:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:25:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:25:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:25:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:25:15 INFO - Content-Length response header: 52700713 15:25:15 INFO - Bytes received: 52700713 15:25:32 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 15:25:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:25:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:25:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:25:34 INFO - Content-Length response header: 52700713 15:25:34 INFO - Bytes received: 52700713 15:25:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:25:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:25:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:25:51 INFO - Content-Length response header: 14408809 15:25:51 INFO - Bytes received: 14408809 15:25: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')]} 15:25:51 INFO - trying https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:25:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:25:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/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 15:25:53 INFO - Downloaded 70882149 bytes. 15:25:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:25:53 INFO - mkdir: /builds/slave/test/properties 15:25:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:25:53 INFO - Writing to file /builds/slave/test/properties/build_url 15:25:53 INFO - Contents: 15:25:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:25:54 INFO - Running post-action listener: _resource_record_post_action 15:25:54 INFO - Running post-action listener: set_extra_try_arguments 15:25:54 INFO - [mozharness: 2017-05-03 22:25:54.555894Z] Finished download-and-extract step (success) 15:25:54 INFO - [mozharness: 2017-05-03 22:25:54.556030Z] Running populate-webroot step. 15:25:54 INFO - Running pre-action listener: _resource_record_pre_action 15:25:54 INFO - Running main action method: populate_webroot 15:25:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:25:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:25:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:25:54 INFO - '--tppagecycles', 15:25:54 INFO - '7'], 15:25:54 INFO - 'tests': ['tsvgm']}}, 15:25:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:25:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:25:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:25:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:25:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['damp', 'tps']}, 15:25:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'tests': ['damp', 'tps']}, 15:25:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['dromaeo_dom']}, 15:25:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:25:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:25:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:25:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['a11yr', 15:25:54 INFO - 'ts_paint', 15:25:54 INFO - 'tpaint', 15:25:54 INFO - 'sessionrestore', 15:25:54 INFO - 'sessionrestore_no_auto_restore', 15:25:54 INFO - 'tabpaint']}, 15:25:54 INFO - 'other-e10s': {'tests': ['a11yr', 15:25:54 INFO - 'ts_paint', 15:25:54 INFO - 'tpaint', 15:25:54 INFO - 'sessionrestore', 15:25:54 INFO - 'sessionrestore_no_auto_restore', 15:25:54 INFO - 'tabpaint']}, 15:25:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:25:54 INFO - 'ts_paint', 15:25:54 INFO - 'tpaint', 15:25:54 INFO - 'sessionrestore', 15:25:54 INFO - 'sessionrestore_no_auto_restore', 15:25:54 INFO - 'tabpaint']}, 15:25:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:25:54 INFO - 'ts_paint', 15:25:54 INFO - 'tpaint', 15:25:54 INFO - 'sessionrestore', 15:25:54 INFO - 'sessionrestore_no_auto_restore', 15:25:54 INFO - 'tabpaint']}, 15:25:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['a11yr', 15:25:54 INFO - 'ts_paint', 15:25:54 INFO - 'tpaint', 15:25:54 INFO - 'sessionrestore', 15:25:54 INFO - 'sessionrestore_no_auto_restore', 15:25:54 INFO - 'tabpaint']}, 15:25:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['a11yr', 15:25:54 INFO - 'ts_paint', 15:25:54 INFO - 'tpaint', 15:25:54 INFO - 'sessionrestore', 15:25:54 INFO - 'sessionrestore_no_auto_restore', 15:25:54 INFO - 'tabpaint']}, 15:25:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:25:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:25:54 INFO - 'bloom_basic_ref']}, 15:25:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['tsvgx', 15:25:54 INFO - 'tsvgr_opacity', 15:25:54 INFO - 'tart', 15:25:54 INFO - 'tscrollx', 15:25:54 INFO - 'cart', 15:25:54 INFO - 'tsvg_static']}, 15:25:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:25:54 INFO - 'tsvgr_opacity', 15:25:54 INFO - 'tart', 15:25:54 INFO - 'tscrollx', 15:25:54 INFO - 'cart', 15:25:54 INFO - 'tsvg_static']}, 15:25:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'talos_options': ['--disable-e10s'], 15:25:54 INFO - 'tests': ['tp5o']}, 15:25:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:25:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'talos_options': ['--disable-e10s', 15:25:54 INFO - '--xperf_path', 15:25:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:54 INFO - 'tests': ['tp5n']}, 15:25:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:25:54 INFO - 'talos_options': ['--xperf_path', 15:25:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:54 INFO - 'tests': ['tp5n']}}, 15:25:54 INFO - 'talos.zip': {'path': '', 15:25:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:25:54 INFO - Running post-action listener: _resource_record_post_action 15:25:54 INFO - [mozharness: 2017-05-03 22:25:54.575436Z] Finished populate-webroot step (success) 15:25:54 INFO - [mozharness: 2017-05-03 22:25:54.575566Z] Running create-virtualenv step. 15:25:54 INFO - Running pre-action listener: _resource_record_pre_action 15:25:54 INFO - Running main action method: create_virtualenv 15:25:54 INFO - Creating virtualenv /builds/slave/test/build/venv 15:25:54 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 15:25:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:25:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:25:54 INFO - Using real prefix '/tools/python27' 15:25:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:25:55 INFO - Installing distribute.............................................................................................................................................................................................done. 15:25:58 INFO - Installing pip.................done. 15:25:58 INFO - Return code: 0 15:25:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:25:58 INFO - Reading from file tmpfile_stdout 15:25:58 INFO - Output received: 15:25:58 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) 15:25:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:58 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:25:58 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 15:25:58 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 15:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:58 INFO - 'HOME': '/Users/cltbld', 15:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:58 INFO - 'LOGNAME': 'cltbld', 15:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:58 INFO - 'MOZ_NO_REMOTE': '1', 15:25:58 INFO - 'NO_EM_RESTART': '1', 15:25:58 INFO - 'PAGER': '/bin/cat', 15:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:58 INFO - 'PWD': '/builds/slave/test', 15:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:58 INFO - 'SHELL': '/bin/bash', 15:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:25:58 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:58 INFO - 'USER': 'cltbld', 15:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:58 INFO - 'XPC_FLAGS': '0x0', 15:25:58 INFO - 'XPC_SERVICE_NAME': '0', 15:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:58 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 15:25:58 INFO - Cleaning up... 15:25:58 INFO - Return code: 0 15:25:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:58 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:25:58 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 15:25:58 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 15:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:58 INFO - 'HOME': '/Users/cltbld', 15:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:58 INFO - 'LOGNAME': 'cltbld', 15:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:58 INFO - 'MOZ_NO_REMOTE': '1', 15:25:58 INFO - 'NO_EM_RESTART': '1', 15:25:58 INFO - 'PAGER': '/bin/cat', 15:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:58 INFO - 'PWD': '/builds/slave/test', 15:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:58 INFO - 'SHELL': '/bin/bash', 15:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:25:58 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:58 INFO - 'USER': 'cltbld', 15:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:58 INFO - 'XPC_FLAGS': '0x0', 15:25:58 INFO - 'XPC_SERVICE_NAME': '0', 15:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:59 INFO - Downloading/unpacking psutil>=3.1.1 15:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:26:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:01 INFO - Installing collected packages: psutil 15:26:01 INFO - Running setup.py install for psutil 15:26:01 INFO - building 'psutil._psutil_osx' extension 15:26:01 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 15:26:01 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 15:26:01 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 15:26:01 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 15:26:01 INFO - building 'psutil._psutil_posix' extension 15:26:01 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 15:26:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:26:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:26:01 INFO - ^ 15:26:01 INFO - 1 warning generated. 15:26:01 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 15:26:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:02 INFO - Successfully installed psutil 15:26:02 INFO - Cleaning up... 15:26:02 INFO - Return code: 0 15:26:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:26:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:02 INFO - 'HOME': '/Users/cltbld', 15:26:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:02 INFO - 'LOGNAME': 'cltbld', 15:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:02 INFO - 'MOZ_NO_REMOTE': '1', 15:26:02 INFO - 'NO_EM_RESTART': '1', 15:26:02 INFO - 'PAGER': '/bin/cat', 15:26:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:02 INFO - 'PWD': '/builds/slave/test', 15:26:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:02 INFO - 'SHELL': '/bin/bash', 15:26:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:02 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:02 INFO - 'USER': 'cltbld', 15:26:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:02 INFO - 'XPC_FLAGS': '0x0', 15:26:02 INFO - 'XPC_SERVICE_NAME': '0', 15:26:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:26:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:26:03 INFO - Installing collected packages: mozsystemmonitor 15:26:03 INFO - Running setup.py install for mozsystemmonitor 15:26:03 INFO - Successfully installed mozsystemmonitor 15:26:03 INFO - Cleaning up... 15:26:04 INFO - Return code: 0 15:26:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:26:04 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')]} 15:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:04 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')]} 15:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:04 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:04 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 15:26:04 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 15:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:04 INFO - 'HOME': '/Users/cltbld', 15:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:04 INFO - 'LOGNAME': 'cltbld', 15:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:04 INFO - 'MOZ_NO_REMOTE': '1', 15:26:04 INFO - 'NO_EM_RESTART': '1', 15:26:04 INFO - 'PAGER': '/bin/cat', 15:26:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:04 INFO - 'PWD': '/builds/slave/test', 15:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:04 INFO - 'SHELL': '/bin/bash', 15:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:04 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:04 INFO - 'USER': 'cltbld', 15:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:04 INFO - 'XPC_FLAGS': '0x0', 15:26:04 INFO - 'XPC_SERVICE_NAME': '0', 15:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:05 INFO - Downloading/unpacking jsonschema==2.5.1 15:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:26:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:07 INFO - Downloading functools32-3.2.3-2.tar.gz 15:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:26:07 INFO - warning: no files found matching '*.txt' 15:26:07 INFO - no previously-included directories found matching 'build' 15:26:07 INFO - no previously-included directories found matching 'dist' 15:26:07 INFO - no previously-included directories found matching '.git*' 15:26:07 INFO - Installing collected packages: jsonschema, functools32 15:26:07 INFO - Running setup.py install for functools32 15:26:07 INFO - warning: no files found matching '*.txt' 15:26:07 INFO - no previously-included directories found matching 'build' 15:26:07 INFO - no previously-included directories found matching 'dist' 15:26:07 INFO - no previously-included directories found matching '.git*' 15:26:07 INFO - Successfully installed jsonschema functools32 15:26:07 INFO - Cleaning up... 15:26:07 INFO - Return code: 0 15:26:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:07 INFO - 'HOME': '/Users/cltbld', 15:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:07 INFO - 'LOGNAME': 'cltbld', 15:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:07 INFO - 'MOZ_NO_REMOTE': '1', 15:26:07 INFO - 'NO_EM_RESTART': '1', 15:26:07 INFO - 'PAGER': '/bin/cat', 15:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:07 INFO - 'PWD': '/builds/slave/test', 15:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:07 INFO - 'SHELL': '/bin/bash', 15:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:07 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:07 INFO - 'USER': 'cltbld', 15:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:07 INFO - 'XPC_FLAGS': '0x0', 15:26:07 INFO - 'XPC_SERVICE_NAME': '0', 15:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:26:07 INFO - Cleaning up... 15:26:07 INFO - Return code: 0 15:26:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:07 INFO - 'HOME': '/Users/cltbld', 15:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:07 INFO - 'LOGNAME': 'cltbld', 15:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:07 INFO - 'MOZ_NO_REMOTE': '1', 15:26:07 INFO - 'NO_EM_RESTART': '1', 15:26:07 INFO - 'PAGER': '/bin/cat', 15:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:07 INFO - 'PWD': '/builds/slave/test', 15:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:07 INFO - 'SHELL': '/bin/bash', 15:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:07 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:07 INFO - 'USER': 'cltbld', 15:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:07 INFO - 'XPC_FLAGS': '0x0', 15:26:07 INFO - 'XPC_SERVICE_NAME': '0', 15:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:07 INFO - Downloading/unpacking blobuploader==1.2.4 15:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:09 INFO - Downloading blobuploader-1.2.4.tar.gz 15:26:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:26:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:26:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:10 INFO - Downloading docopt-0.6.1.tar.gz 15:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:26:10 INFO - Installing collected packages: blobuploader, requests, docopt 15:26:10 INFO - Running setup.py install for blobuploader 15:26:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:26:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:26:10 INFO - Running setup.py install for requests 15:26:10 INFO - Running setup.py install for docopt 15:26:11 INFO - Successfully installed blobuploader requests docopt 15:26:11 INFO - Cleaning up... 15:26:11 INFO - Return code: 0 15:26:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:11 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')]} 15:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:11 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')]} 15:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:11 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:11 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 15:26:11 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 15:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:11 INFO - 'HOME': '/Users/cltbld', 15:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:11 INFO - 'LOGNAME': 'cltbld', 15:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:11 INFO - 'MOZ_NO_REMOTE': '1', 15:26:11 INFO - 'NO_EM_RESTART': '1', 15:26:11 INFO - 'PAGER': '/bin/cat', 15:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:11 INFO - 'PWD': '/builds/slave/test', 15:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:11 INFO - 'SHELL': '/bin/bash', 15:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:11 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:11 INFO - 'USER': 'cltbld', 15:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:11 INFO - 'XPC_FLAGS': '0x0', 15:26:11 INFO - 'XPC_SERVICE_NAME': '0', 15:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-FlIZPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-JyLm5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Sye3O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-aY1rsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-kl14QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:11 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-WRuSnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pXtoH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-5CEWRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-11HIad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-f6gebC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-LjB_tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ZxU3Mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7HU5vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-oLZQZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-SgnYqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:26:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-733zsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:26:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ULWby2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:26:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:26:13 INFO - Running setup.py install for manifestparser 15:26:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:26:13 INFO - Running setup.py install for mozcrash 15:26:13 INFO - Running setup.py install for mozdebug 15:26:13 INFO - Running setup.py install for mozdevice 15:26:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:26:13 INFO - Running setup.py install for mozfile 15:26:13 INFO - Running setup.py install for mozhttpd 15:26:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Running setup.py install for mozinfo 15:26:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Running setup.py install for mozInstall 15:26:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Running setup.py install for mozleak 15:26:14 INFO - Running setup.py install for mozlog 15:26:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Running setup.py install for moznetwork 15:26:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:26:14 INFO - Running setup.py install for mozprocess 15:26:15 INFO - Running setup.py install for mozprofile 15:26:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:26:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:26:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:26:15 INFO - Running setup.py install for mozrunner 15:26:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:26:15 INFO - Running setup.py install for mozscreenshot 15:26:15 INFO - Running setup.py install for moztest 15:26:15 INFO - Running setup.py install for mozversion 15:26:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:26:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:26:15 INFO - Cleaning up... 15:26:15 INFO - Return code: 0 15:26:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:15 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')]} 15:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:15 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')]} 15:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:15 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:15 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 15:26:15 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 15:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:15 INFO - 'HOME': '/Users/cltbld', 15:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:15 INFO - 'LOGNAME': 'cltbld', 15:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:15 INFO - 'MOZ_NO_REMOTE': '1', 15:26:15 INFO - 'NO_EM_RESTART': '1', 15:26:15 INFO - 'PAGER': '/bin/cat', 15:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:15 INFO - 'PWD': '/builds/slave/test', 15:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:15 INFO - 'SHELL': '/bin/bash', 15:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:15 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:15 INFO - 'USER': 'cltbld', 15:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:15 INFO - 'XPC_FLAGS': '0x0', 15:26:15 INFO - 'XPC_SERVICE_NAME': '0', 15:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pyaLgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-FXEhSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-2M4Pgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-I_pF8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-H6rRAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-qTnxF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-al798P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-K5jR6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:16 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)) 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:16 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-avHM_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-jsauDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-4GAyZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-jL_xrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-uCrN1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-aOWVGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-4xMfks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-NCR0HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:26:17 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)) 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:26:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-eNgubl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:26:17 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)) 15:26:17 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)) 15:26:17 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)) 15:26:17 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)) 15:26:17 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)) 15:26:17 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)) 15:26:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:19 INFO - Downloading blessings-1.6.tar.gz 15:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:26:19 INFO - Installing collected packages: blessings 15:26:19 INFO - Running setup.py install for blessings 15:26:19 INFO - Successfully installed blessings 15:26:19 INFO - Cleaning up... 15:26:19 INFO - Return code: 0 15:26:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:26:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:19 INFO - Reading from file tmpfile_stdout 15:26:19 INFO - Current package versions: 15:26:19 INFO - blessings == 1.6 15:26:19 INFO - blobuploader == 1.2.4 15:26:19 INFO - docopt == 0.6.1 15:26:19 INFO - functools32 == 3.2.3-2 15:26:19 INFO - jsonschema == 2.5.1 15:26:19 INFO - manifestparser == 1.1 15:26:19 INFO - mozInstall == 1.12 15:26:19 INFO - mozcrash == 1.0 15:26:19 INFO - mozdebug == 0.1 15:26:19 INFO - mozdevice == 0.50 15:26:19 INFO - mozfile == 1.2 15:26:19 INFO - mozhttpd == 0.7 15:26:19 INFO - mozinfo == 0.9 15:26:19 INFO - mozleak == 0.1 15:26:19 INFO - mozlog == 3.4 15:26:19 INFO - moznetwork == 0.27 15:26:19 INFO - mozprocess == 0.25 15:26:19 INFO - mozprofile == 0.28 15:26:19 INFO - mozrunner == 6.13 15:26:19 INFO - mozscreenshot == 0.1 15:26:19 INFO - mozsystemmonitor == 0.3 15:26:19 INFO - moztest == 0.8 15:26:19 INFO - mozversion == 1.4 15:26:19 INFO - psutil == 3.1.1 15:26:19 INFO - requests == 1.2.3 15:26:19 INFO - wsgiref == 0.1.2 15:26:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:19 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')]} 15:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:19 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')]} 15:26:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:19 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:19 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 15:26:19 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 15:26:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:19 INFO - 'HOME': '/Users/cltbld', 15:26:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:19 INFO - 'LOGNAME': 'cltbld', 15:26:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:19 INFO - 'MOZ_NO_REMOTE': '1', 15:26:19 INFO - 'NO_EM_RESTART': '1', 15:26:19 INFO - 'PAGER': '/bin/cat', 15:26:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:19 INFO - 'PWD': '/builds/slave/test', 15:26:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:19 INFO - 'SHELL': '/bin/bash', 15:26:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:19 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:19 INFO - 'USER': 'cltbld', 15:26:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:19 INFO - 'XPC_FLAGS': '0x0', 15:26:19 INFO - 'XPC_SERVICE_NAME': '0', 15:26:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 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)) 15:26:20 INFO - Cleaning up... 15:26:20 INFO - Return code: 0 15:26:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:20 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 0x103ecab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ecd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a0d9ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, '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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 15:26:20 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 15:26:20 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 15:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:20 INFO - 'HOME': '/Users/cltbld', 15:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:20 INFO - 'LOGNAME': 'cltbld', 15:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:20 INFO - 'MOZ_NO_REMOTE': '1', 15:26:20 INFO - 'NO_EM_RESTART': '1', 15:26:20 INFO - 'PAGER': '/bin/cat', 15:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:20 INFO - 'PWD': '/builds/slave/test', 15:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:20 INFO - 'SHELL': '/bin/bash', 15:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:20 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:20 INFO - 'USER': 'cltbld', 15:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:20 INFO - 'XPC_FLAGS': '0x0', 15:26:20 INFO - 'XPC_SERVICE_NAME': '0', 15:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:26:20 INFO - Cleaning up... 15:26:20 INFO - Return code: 0 15:26:20 INFO - Running post-action listener: _resource_record_post_action 15:26:20 INFO - Running post-action listener: _start_resource_monitoring 15:26:20 INFO - Starting resource monitoring. 15:26:20 INFO - [mozharness: 2017-05-03 22:26:20.479562Z] Finished create-virtualenv step (success) 15:26:20 INFO - [mozharness: 2017-05-03 22:26:20.480482Z] Running install step. 15:26:20 INFO - Running pre-action listener: _resource_record_pre_action 15:26:20 INFO - Running main action method: install 15:26:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:20 INFO - Reading from file tmpfile_stdout 15:26:20 INFO - Detecting whether we're running mozinstall >=1.0... 15:26:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:26:20 INFO - Reading from file tmpfile_stdout 15:26:20 INFO - Output received: 15:26:20 INFO - Usage: mozinstall [options] installer 15:26:20 INFO - Options: 15:26:20 INFO - -h, --help show this help message and exit 15:26:20 INFO - -d DEST, --destination=DEST 15:26:20 INFO - Directory to install application into. [default: 15:26:20 INFO - "/builds/slave/test"] 15:26:20 INFO - --app=APP Application being installed. [default: firefox] 15:26:20 INFO - mkdir: /builds/slave/test/build/application 15:26:20 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'] 15:26:20 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 15:26:40 INFO - Reading from file tmpfile_stdout 15:26:40 INFO - Output received: 15:26:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:26:40 INFO - Running post-action listener: _resource_record_post_action 15:26:40 INFO - [mozharness: 2017-05-03 22:26:40.656752Z] Finished install step (success) 15:26:40 INFO - [mozharness: 2017-05-03 22:26:40.656900Z] Running run-tests step. 15:26:40 INFO - Running pre-action listener: _resource_record_pre_action 15:26:40 INFO - Running main action method: run_tests 15:26:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:26:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:26:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:26:40 INFO - 2017-05-03 15:26:40.671 screenresolution[3486:60671] starting screenresolution argv=screenresolution get 15:26:40 INFO - 2017-05-03 15:26:40.674 screenresolution[3486:60671] Display 0: 1600x1200x32@60 15:26:40 INFO - 2017-05-03 15:26:40.680 screenresolution[3487:60674] starting screenresolution argv=screenresolution list 15:26:40 INFO - Available Modes on Display 0 15:26:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:26:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:26:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:26:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:26:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:26:40 INFO - Intel Iris: 15:26:40 INFO - Chipset Model: Intel Iris 15:26:40 INFO - Type: GPU 15:26:40 INFO - Bus: Built-In 15:26:40 INFO - VRAM (Dynamic, Max): 1536 MB 15:26:40 INFO - Vendor: Intel (0x8086) 15:26:40 INFO - Device ID: 0x0a2e 15:26:40 INFO - Revision ID: 0x0009 15:26:40 INFO - Displays: 15:26:40 INFO - Display: 15:26:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:26:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:26:40 INFO - Display Serial Number: 1600x1200 60 15:26:40 INFO - Main Display: Yes 15:26:40 INFO - Mirror: Off 15:26:40 INFO - Online: Yes 15:26:40 INFO - Rotation: Supported 15:26:40 INFO - Return code: 0 15:26:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:26:40 INFO - Python 2.7.3 15:26:40 INFO - Return code: 0 15:26:40 INFO - grabbing minidump binary from tooltool 15:26:40 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')]} 15:26:40 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 0x103ca8d30>, '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 0x103bcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c9dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:26:40 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 15:26:40 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 15:26:40 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 15:26:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:26:41 INFO - Return code: 0 15:26:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:26:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:26:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:26:41 INFO - ENV: RUST_BACKTRACE is now 1 15:26:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:26:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:26:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:26:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xq1XvyIgCo/Render', 15:26:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:41 INFO - 'HOME': '/Users/cltbld', 15:26:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:41 INFO - 'LOGNAME': 'cltbld', 15:26:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:26:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:41 INFO - 'MOZ_NO_REMOTE': '1', 15:26:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:26:41 INFO - 'NO_EM_RESTART': '1', 15:26:41 INFO - 'PAGER': '/bin/cat', 15:26:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:41 INFO - 'PWD': '/builds/slave/test', 15:26:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:26:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:41 INFO - 'RUST_BACKTRACE': '1', 15:26:41 INFO - 'SHELL': '/bin/bash', 15:26:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sD7HYG9cSB/Listeners', 15:26:41 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 15:26:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:41 INFO - 'USER': 'cltbld', 15:26:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:41 INFO - 'XPC_FLAGS': '0x0', 15:26:41 INFO - 'XPC_SERVICE_NAME': '0', 15:26:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:26:41 INFO - mozversion application_buildid: 20170503134320 15:26:41 INFO - mozversion application_changeset: b25ad0674afd563e888dc07981baa626e8d794db 15:26:41 INFO - mozversion application_display_name: Nightly 15:26:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:26:41 INFO - mozversion application_name: Firefox 15:26:41 INFO - mozversion application_remotingname: firefox 15:26:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:26:41 INFO - mozversion application_vendor: Mozilla 15:26:41 INFO - mozversion application_version: 55.0a1 15:26:41 INFO - mozversion platform_buildid: 20170503134320 15:26:41 INFO - mozversion platform_changeset: b25ad0674afd563e888dc07981baa626e8d794db 15:26:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:26:41 INFO - mozversion platform_version: 55.0a1 15:26:41 INFO - using testdate: 1493850401 15:26:41 INFO - actual date: 1493850401 15:26:41 INFO - starting webserver on 'localhost:52555' 15:26:41 INFO - SUITE-START | Running 2 tests 15:26:41 INFO - TEST-START | dromaeo_css 15:26:41 INFO - operating with platform_type : mac_ 15:26:41 INFO - Initialising browser for dromaeo_css test... 15:26:41 INFO - TEST-INFO | started process 3497 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpePnGVM/profile http://localhost:52555/getInfo.html) 15:26:44 INFO - PID 3497 | 2017-05-03 15:26:44.137 plugin-container[3499:60840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 15:26:44 INFO - PID 3497 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:44 INFO - PID 3497 | 2017-05-03 15:26:44.139 plugin-container[3499:60840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 15:26:44 INFO - PID 3497 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:44 INFO - PID 3497 | 2017-05-03 15:26:44.139 plugin-container[3499:60840] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:44 INFO - PID 3497 | __metrics Screen width/height:1600/1200 15:26:44 INFO - PID 3497 | colorDepth:24 15:26:44 INFO - PID 3497 | Browser inner width/height: 1024/691 15:26:44 INFO - PID 3497 | __metrics 15:26:44 INFO - PID 3497 | 2017-05-03 15:26:44.789 plugin-container[3500:60891] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 15:26:44 INFO - PID 3497 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:44 INFO - PID 3497 | 2017-05-03 15:26:44.790 plugin-container[3500:60891] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970f, name = 'com.apple.CFPasteboardClient' 15:26:44 INFO - PID 3497 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:44 INFO - PID 3497 | 2017-05-03 15:26:44.790 plugin-container[3500:60891] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:45 INFO - TEST-INFO | 3497: exit 0 15:26:45 INFO - Browser initialized. 15:26:45 INFO - Running cycle 1/1 for dromaeo_css test... 15:26:45 INFO - TEST-INFO | started process 3501 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpePnGVM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:47 INFO - PID 3501 | 2017-05-03 15:26:47.766 plugin-container[3502:61005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 15:26:47 INFO - PID 3501 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:47 INFO - PID 3501 | 2017-05-03 15:26:47.766 plugin-container[3502:61005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9527, name = 'com.apple.CFPasteboardClient' 15:26:47 INFO - PID 3501 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:47 INFO - PID 3501 | 2017-05-03 15:26:47.767 plugin-container[3502:61005] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:48 INFO - PID 3501 | 2017-05-03 15:26:48.298 plugin-container[3503:61046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 15:26:48 INFO - PID 3501 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:48 INFO - PID 3501 | 2017-05-03 15:26:48.299 plugin-container[3503:61046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 15:26:48 INFO - PID 3501 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:26:48 INFO - PID 3501 | 2017-05-03 15:26:48.299 plugin-container[3503:61046] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:57 INFO - PID 3501 | RSS: Main: 233836544 15:26:57 INFO - PID 3501 | 15:30:00 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:52555/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52555/tests/dromaeo/cssquery-ext.html) 15:30:02 INFO - PID 3501 | RSS: Main: 249671680 15:30:02 INFO - PID 3501 | 15:33:04 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:52555/tests/dromaeo/cssquery-ext.html (next: http://localhost:52555/tests/dromaeo/cssquery-jquery.html) 15:33:05 INFO - PID 3501 | RSS: Main: 252809216 15:33:05 INFO - PID 3501 | 15:36:06 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:52555/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52555/tests/dromaeo/cssquery-mootools.html) 15:36:07 INFO - PID 3501 | RSS: Main: 263880704 15:36:07 INFO - PID 3501 | 15:39:09 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:52555/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52555/tests/dromaeo/cssquery-prototype.html) 15:39:09 INFO - PID 3501 | RSS: Main: 258514944 15:39:09 INFO - PID 3501 | 15:42:12 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:52555/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52555/tests/dromaeo/cssquery-yui.html) 15:42:13 INFO - PID 3501 | RSS: Main: 262619136 15:42:13 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:52555/tests/dromaeo/cssquery-yui.html (next: http://localhost:52555/tests/dromaeo/cssquery-dojo.html) 15:45:15 INFO - PID 3501 | RSS: Main: 261246976 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | __start_tp_report 15:45:15 INFO - PID 3501 | _x_x_mozilla_page_load 15:45:15 INFO - PID 3501 | _x_x_mozilla_page_load_details 15:45:15 INFO - PID 3501 | |i|pagename|runs| 15:45:15 INFO - PID 3501 | |0;dojo.html;2967.95548066779;3548.627394123617;3574.392353299939;3586.802725850079;3599.8740044098463;5013.874653133669;6105.429421494168;6184.701212745324;6188.102725104863;6247.750089996394;4793.592544633701;6107.2976607690125;6163.876722465548;6224.533160012995;6224.595401298912;5395.001924643953;5439.292891924044;5446.2375267462485;5467.972660136694;5540.307461567304;5340.145576707728;5455.454454554532;5498.230247765316;5501.174823776446;5571.8607034824045;5449.455054494559;5502.642328248651;5508.421615730351;5555.416681248471;5558.610897237195;4374.606285434326;5100.826809833759;5343.145096784535;5356.91964620531;5410.890767392675;4749.643776716725;5394.622376433651;5436.755346009439;5445.53712934401;5487.039768040617;4755.001449695568;5355.7857685692525;6092.02527595587;6235.158253635746;6238.937610623926;6663.900041499382;6700.698468568878;6714.127163468718;6726.966365168192;6742.089817874573;6561.606303621798;6616.867662646769;6738.258791532928;6842.692078856414;6850.6574671266235;6285.8742825143245;6331.96834015827;6342.904856427203;6344.524160687967;6346.524010699216;6849.109615750021;6868.244493105754;6926.168859736864;6933.479989000845;6934.375906168469;6323.1779868617705;6400.167978162809;6435.324290949477;6527.477801775846;6599.53803233769;6727.394534491919;6766.28953959837;6775.898361524581;6785.694643741043;6791.219009813836;6622.801315960529;6645.60126392418;6674;6676.46588272937;6701.028350889093;6425.068365087036;6540.803775886731;6593.86812263752;6608.3391660834495;6713.56361836479;5321.281626980385;5343.5190832824455;5349.331333583302;5379.1393377059485;5390.622656414092;4216.6928252241905;4535.682502224879;4640.605548528413;4735.147673418817;4741.596964258008;4954.801807927643;6136.427173609184;6300.747970081237;6316.178896743486;6346.555741098079;5622.943770562242;6627.569208001464;6761.729530818712;6832.350926662059;6847.828804279833;6292.150559674477;6313.747450102036;6325.082862984841;6356.968215158987;6361.395667411589;6009.248843894513;6018.969905150446;6042.1843051187525;6043.909341359883;6044.24446944132;6743.629100399427;6823.078884350549;6917.066196063668;6947.513674042769;6981.371676549135;6777.830554236183;6795.45636349081;6809.353111454404;6851.212110607344;6862.210845752603;6751.86496270062;6822.897656535057;6852.486063545154;7014.789556313319;7039.648017598997;6732.427743641845;6740.9325906740305;6745.5952642841585;6748.257691654012;6763.391294783493;6531.608103513804;6624.205095388585;6663.900041499478;6888.690008949509;6892.896606550806;6474.546781725234;6510.056041873996;6577.835554111186;6592.67036648156;6616.636085015371;6427.357264273535;6584.604923704402;6585.670716464253;6593.472522198309;6642.900356494753;6423.4218920295525;6450.354964503512;6500;6512.446442052478;6528.053432252202;6309.305976342507;6413.42279194856;6477.902831457626;6524.934750652433;6545.934540654533;6367.204099487564;6380.776672816429;6394.488440924808;6403.711832967434;6409.583377080475 15:45:15 INFO - PID 3501 | |1;ext.html;747.985040299194;813.515958004987;823.0206054794794;1216.738401243733;1276.9680757981055;37225.25549489012;37888.62111378885;37902.43146352807;37956.62043379565;38057.99999999996;37929.43105853407;38189.61810381895;38238;38247.23505529888;38302.61697383032;7822.921770782291;7948.602569871512;8319.500829950222;8463.4075614707;8494.66021359148;8434.451760635538;8476.957615211946;8542.871856922151;8544.572771361438;8559.101294364096;8488.78778030548;8506.36202284831;8570.74287771368;8576;8583.012953510364;8460.66157353705;8509.446885952424;8525.147485251488;8530.616122274512;8531.445456045369;7125.966734823422;7207.675654595556;7397.556146631166;8382.832343353099;8387;37601.435978460344;37671.24657506862;37810.43284350737;37833.6758213464;37868.86393408202;3659.048647351681;3693.8522459101605;3694.353488139565;3694.4458331250526;3708.9443658345144;31027;31250.06249812509;31263.84368078168;31281.37437251265;31308.843455782804;3163.3198862244726;3201.439748044083;3203.08712017074;3210.486322188439;3222.146131275214;219874;239585;249656;308059;311701;48585.514144858804;48689.02621947541;48720.02559948781;48772.26841597379;48935.77660558443;50604.74697626488;50713;50796;50900.74549627302;51059.489405106215;14371.353289102015;14470.059446135967;14491.78262326066;14509.492167774288;14550.054246473945;11578.42107894602;11672.824907626393;11823.645290641294;11824.704382390337;11845.407729613486;11814.173007889538;13189.934050329686;13625.228720266301;13700.177989320473;13702.45190192381;5882.794027224409;5919.970400147971;5942;5952.7023648817385;5984.940150598438;997.6607953295769;1014.4420568687193;1023.0894503891542;1058.327961744298;1066.5893630951985;20850.89574552148;23403.648945265835;24599.508009839705;24716.13493527718;24746;1044.6709286574858;1115.8493603363443;1116.625930313338;1149.5689116581282;1151.1078913841839;11911.3448760319;12502.624921252376;12522.311272880079;12543.811842822364;12563.93718031404;303111.48444257636;303601;303780;306359;307150;54220.45779542154;54506.727466363205;54567.90864182694;54614.45385546173;54650.453495464535;1514.9848501515064;1525.8321584625683;1570.46604154588;1572.2217502336416;1573.465021892562;24294;24330.90510926977;24489;24534;24584.508309834062;39568.80215598903;39667;39744.602553975245;39833.80083099566;39911;278329;280255;280262;280460;281483;34553.65446345605;34777.65222347744;35061.64938350686;35100.297994040484;35166.82416587901;42661.78669106634;43380.34929476123;44718.55281447144;44761;44905.428310008996;29860.850695746383;30356.544651829798;31013.689863101983;31136.84431577918;31535.211619708774;30496.237594060327;31219.84390078035;31299;31339.84330078426;31347.216319592193;37052;37428.2514349706;37609.247815044095;37633.81183094067;37656.81171594124;25961.221163365128;29384.26539336534;30152.94464693813;31241.062768116994;31420.371592568477;19503;24114.63828042616;24291.39271518226;24378.878105609358;24541.263762087165 15:45:15 INFO - PID 3501 | |2;jquery.html;377.7035027503409;417.3760228458455;423.74078363795576;427.2437785120337;431.5425648812258;19882.90058549709;21079.789202107975;21781.455463613416;21942.89028554857;21969.340919772392;21965.780342196573;22916.885415572942;23720.051197952103;23818.928148233328;23929.16247931323;6121.143039974419;6302.180716506848;6323.114763933053;6327.272363678167;6327.746890124389;5356.571474282067;5964.582479226456;6240.875182496347;6314.526410519206;6362.618242905417;5392.811251606195;6233.690924905775;6250.312465628777;6264.185655864731;6289.842753931165;6023.156758053876;6193.4425901669065;6199.163112979735;6226.470749986257;6241.531885108635;5330.413654498002;5494.917576236359;5503.642263252876;5530.861728456781;5537.529310008654;25059.872305746285;27372.45255094887;27644.723552764415;28236;28355.71644283572;2157.3851452336166;2230.074519074582;2231.832612554065;2235.117128734141;2236.2173239366252;15466.458673946358;17860.374886878897;17968.281268749233;18162.45512634623;18248.543786405313;2109.430453777487;2141.0686351437175;2165.7942495462908;2167.306461932182;2169.1540299283292;169576.12967092308;170889;173070.13464932595;173648;175119;10777.946110269399;12577.371131443391;12679.688905772082;12809.103362764703;12816.295103769382;16284.837151628331;16536.6692666146;16539.421120260733;16560.254788534545;16568.66862662764;8007.599620019092;8909.599068041955;9023.77440563978;9114.225290850352;9279.72160835176;6464.321246269074;7069.399101076466;8680;8691.565421728888;8756.737297881074;7876.645550950221;7996.800079998046;8031.718889838828;8032.718854840169;8067.556284404414;2138.3478039198194;2142.068200332829;2144.249512670584;2147.9248226312316;2327.7788610082016;910.3263584947091;931.8857245262092;944.7401964459829;961.8364877970762;988.5304480371766;20730;21245.89377053105;21334;21385.78614213838;21451;998.2812375089924;1026.2098184397971;1041.068243921686;1041.8697662792151;1042.2183362478142;9426.397512422904;9490.810183796424;11028.338299701883;11037.295862513736;11056.557737690402;535845;536841;543075;549226;550752;124736.75263247252;130733.03900441501;133504.332478337;140026;140287;1190.119311709329;1244.4026867103842;1252.1360261419773;1258.5091814192472;1259.2822091407809;19885.80685158895;22109.77890221077;22416;22664.320070397913;22708.545829083658;57037.71481142568;57448.1382779267;57739.711301443225;58019;58710;80083;80234.19765802268;81705.59147204463;81969.59015204886;82036;8841.381103322705;8857.734267971971;8918.420302680304;9135.634574617206;9350.53247337644;14134.575962720723;14296.57110286693;14345.784813227601;14734.115953042852;14894.85105148978;11421;11890.643280701594;12084;12234.143609947574;12298.631041068782;9783.315167938175;10412.21908356861;11243.60647377338;11525.596604118815;11823.113266504875;12154.939225303817;12184.20802647825;12312.322822244863;12534.874651253736;12538.93730531378;8992.59533320989;9021.142991416067;9047;9088.227500662253;9165.266778657558;10247.282690211912;11972.401379930794;12002.279863208236;12153.210041347284;12207.267563946192 15:45:15 INFO - PID 3501 | |3;mootools.html;1312.9737405251901;1400.9649758756034;1416.9716605667886;1465.5237047959415;1471.9632009199768;11521.654350369492;11837;12037.398130093494;12077.335746533938;12088.879111208886;11888.702782430444;11936.16446848721;12082;12131.332776697278;12160.27038377698;2168.8698678079368;2531.7468253174675;2579.9097031603897;2583.7803786678155;2583.9483210335875;2488.1042824583137;2536.9238922832346;2548.362909272682;2552.081250749738;2558.7952963762855;2098.90554925028;2527.0397249045336;2567.627693984371;2568.3065572295563;2576.742325767427;2482.8013758899333;2505.1607711416696;2544.211294498711;2553.616957456378;2553.8467691938545;2116.2169997101173;2457.1154384421593;2545.26187405654;2575.562154433732;2578.1749840051193;11949.880501194964;12049.397530123459;12052.337121458317;12056.758864822743;12093.939530302292;6678.065070890079;6834.350736680008;6851.931480685179;6925.757598484029;6928;3957.3668213086057;4308.4399028126145;4371.9781401093105;4488.371627972087;4498.280275155959;1183.4142099660723;1254.8870601645804;1263.5577547858268;1284.621036794148;1324.5165514587202;15075.623109422242;15591.22043897812;16715.582110447336;16803.495895123167;16864.662706745796;5015.598752099823;5602.803901863415;5832.037713777179;5853.1220316952795;5865.794697185578;10353.171746260281;10712.143028557697;11913.642590722293;11928;11953.282803031845;6476.838079048061;6567.474602031826;6605.042268871083;6624.337566243434;6628.801135965929;6124.724387402578;6153.538484613672;6189;6247.812565623039;6313.684315784192;3626.8005259710967;3672.576225563735;3898.8830335089992;4488.159854459721;4548.6133678637025;1103.7957977774138;1282.410091357967;1283.4096315694885;1292.8299888600832;1297.5393735224047;779.2986312318958;788.3502655104214;800.1078797141146;808.9797755056171;819.4714409206148;5867.589268751147;6119.84700382494;6182.258129024546;6335.9228931081825;6428.164338636041;587.1926101610286;593.5607650338804;607.781198768443;615.9753609855645;619.8295468746131;3949.782761948063;4268.210381079511;4330.133973205371;4353.32523458858;4483.775811209426;14428.783568246485;15033.924830375778;15041.796656267472;15063.47277845292;15075;14618.415263389557;14792.186429746253;14972.700545989237;14973.251337433085;14977.251137443085;8670.523121228267;8763.56182190888;8770.210681038738;8796.780080498038;8804.075572064841;4740.241561350167;6296.4814074370615;6433.935660643521;6790.796276111724;6995.230524642184;13179.934100329821;13613.52352667652;13641.86358136406;13642.52251171244;13655.590332290047;13487.865121349054;13785;14111;14197.503087391891;14221.217833018869;8023.759287221393;9278.489683067495;9552.522373881138;9554.713358599252;9563.952180239054;10793.08258798011;10793.56825726962;10805.675829725122;10934.945325273322;10981.61564345244;9207.953960230156;9290.442573445345;9334.626614935327;9370.76573085651;9494.952525237606;9167.816643667224;9199.310051746012;9204.631814727603;9217.723468295962;9260.583273752563;6874.828129296808;8370.037445693823;8397.412181147261;8410.453320534145;8417.494950303002;6007.068904319837;6032.54755893301;6111.022236442148;6217.844553886189;6243.063540468966;6142.232220972379;6183.690815459299;6275.246970363585;6298.370162983665;6339.5562310637815 15:45:15 INFO - PID 3501 | |4;prototype.html;324.50026958483926;400.6174103730937;402.1996227507261;408.1612286750728;410.9547949725531;5056.544910958013;5535.418781027995;6169.16716243307;6265.624062556244;6269.467095296894;5671.744771485282;6106.938930610692;6244.406781355775;6258.906116408258;6308.5899416537895;3879.941800872975;4450.998525331806;4490.046829629106;4501.1672840524525;4514.61625761809;4259.51015633203;4438.1567502174485;4510.729356238619;4513.864584062483;4519.796609152596;4474.194644963905;4474.731516109044;4489.349044388562;4491.8096704373365;4492.842750503733;3710.684591809699;4392.6266267367155;4418.5581441855875;4448.221561226773;4472.396226509412;3318.7179089777396;3438.570178727659;3613.765105268149;3806.543214814212;3818.9045273868096;5267.919171927106;5321.494458026481;5483.287172667568;6114.633122012693;6126.509879209652;4484.910301793946;5199.922001169985;5351.297594714683;5399.919001214946;5405.459454054602;6224.813255602339;6292.574761781097;6678.933210667879;6702.525444402224;6704.46314843029;3938.7833669148185;4084.1831633673382;4329.2423825830665;4360.89097772555;4364.759938203398;6181.81454556364;6320.557560970734;6764.163623009572;6830.726770929157;6891.793246202622;3905.6875449963936;4225.795648240237;4290.2921018031675;4390.363397307437;4420.602145806893;3944.7830369329963;4505.256632655641;4507.684462087623;4511.436070491189;4516;4032.213718324957;5006.249062640561;5081.83117882889;5261.110043982143;5361.035013697494;6487.643179625072;6683.799486015428;6705.530612857151;6716.899246511306;6727.553575981187;1235.8022716365338;1510.4335874047233;1533.8082739657543;1535.96322482324;1541.922903854825;947.6683160893632;977.9047466837188;989.3915242126185;1000.0349662575504;1002.1131298800614;5329.54698850602;6324.209473815773;6377.266614339411;6412.487001039905;6418.1656384669695;5546.362168350611;6869.381755642016;7039.507234493536;7074.434045276365;7090.4327653788605;5582.0789569721355;6221.817854607701;6254.6247225166635;6358.395952384516;6368.203974503187;3415.5389022481645;3632.564092308887;3666.5233519642275;3670.22925185714;3677.632236776301;5901.203337549376;6462.935370646327;6880.965595172092;7012.123484564429;7110.217876033632;6199.56603037783;6327.525435592349;7093.467989900778;7103.396211322096;7115.228928667814;5726.742296596663;6681.1314529110905;7025.437964962791;7105.147382314155;7126.394256488256;6930.514863959676;6933.861322773617;6972.4073453757;6995.564081045349;7032.296770323132;5847.912281315698;5882.794102206402;6731.48541578141;6775.220849602359;6775.525713200226;6216.906746398898;6913.274106218774;6951.930480695129;7200.855982880418;7276.417886569167;3795.525559305087;4400.4279443673395;4422.982713975739;4439.023414848728;4444.599986001275;6916.239213686591;6956.469666498813;7025.894611580932;7028.718851245892;7087.787366379017;6737.157855268091;6757.898631520433;6758.222804377363;6798.252192258748;6811.705775902563;6440.516961227833;6571.244306904767;6664.5334826561675;6757.459403247827;6817.829554261183;6051;6127.142200091902;6469.126667899774;6649.434798042221;6671.6997735103005;5733.799317024051;5899.938011158033;6476.384743449365;6699.866002679822;6877.277885821916;6558.737235653692;6588.637624930675;6593.604383736991;6623.900641490478;6679.998000299994 15:45:15 INFO - PID 3501 | |5;yui.html;834.1658341658342;843.2242337049913;853.1340689200463;873.6592728835756;878.3719640457072;13838.792418113737;15219.239038048108;15451.07293562387;16596.33614655414;16748.58128546787;16801.07594082325;17501.124943752795;17516.912415437906;17597.3840915568;18300.359487417943;978.3982850546898;1007.5516395204137;1011.4386515483903;1019.0930072235716;1022.1209759606733;1022.8465730140501;1027.7790275090854;1032.6179313653959;1037.1080870451406;1040.9375437473775;878.3412440669498;1060.7560261139904;1082.215393839464;1084.1110289562562;1087.836824476327;899.7885496908222;1056.96300629478;1073;1073.613499140309;1074.7044562745268;768.7847402727245;781.0315209140681;790.9683612655487;792.9682812687486;793.678560183121;16965.491035268962;17421.825781742275;17599.208035638298;17614.2073606688;17614.647707045788;16685.91657041707;16989.745153822703;17206.967581945122;17389.82610173895;17395.826041739674;12463.12758106933;13877.653058673613;13943.442262309496;14179.503717369842;14285.357158927873;3375.8987230383127;3505.772124811879;3532.6290739472497;3553.8578456861696;3554.751167418282;119053;121266;121929.39035304855;122773.38613306877;124457;13523.256220907944;13538.120022198525;13654.97587680928;13659.931700341434;13715.382807773674;25083.996640134555;25702.614460783105;25831.095911643377;25894.611580826302;26190.083347083142;7818.374530037583;7959.801004974806;7986.920130798618;7995.1605081465605;7997.600119994094;3520;3739.6447337502896;3866.5940076292145;4062.6343629073526;4090.6318431341324;4649.116667833101;4843.685160464559;4873;4875.829345972945;4886.65793394459;948.4593781544592;978.7944531648291;989.3272574649163;993.7515621094726;996.4021587047713;569.262804667956;653.9934600654028;660.1714847865993;662.159057996342;689.3761146162731;9739.56171972263;10232.846507302396;11455.59905403307;12090.730473300353;12260.632181034583;640.9038644203407;659.2451642868984;661.8841702702105;678.5453745990199;689.975850845218;5461.617686761968;5735.7418916148035;5744.396838331915;5819.621724587888;5840.795572154954;109252.4537377308;109491;110972.5610975552;112243.87756122334;115884;97221.51389243008;97553;97565;98112.99999999857;99842;66412.00381994274;76782.84825727619;77333;78531;79163.60418197872;12608.432620532281;12750.234985900875;12946;12993.545225917047;13224.801627975781;27826;38034.42948355832;38827.02932426712;39177.216455671296;40175.59824401836;72285.55428891498;72317.6384118097;72547.63726181335;73027;73209;16539.586510337336;16591.585210369838;16667.166641667627;17482.650346993243;17496.650066998332;18403.355882544045;18665.6266874659;18779;18872.622547549247;18918.905405472884;15831.366745330519;16682.74975875387;16734.414295500086;16914.915425422794;17976;16932.984020958782;17071.06109164008;17366.913165434093;17523.474295771146;17647.558811029827;16080.115593641995;16523.752143717615;17061.061641609333;17350.525205357684;17384.217710202818;11689.649310520697;11922.761544768882;12008.819867701817;12248.693782655504;12257.754844902873;13362.331883405985;13517.188968661912;13717.45130194821;13909.791353129509;14232.359543820347 15:45:15 INFO - PID 3501 | __end_tp_report 15:45:15 INFO - PID 3501 | __start_cc_report 15:45:15 INFO - PID 3501 | _x_x_mozilla_cycle_collect,410 15:45:15 INFO - PID 3501 | __end_cc_report 15:45:15 INFO - PID 3501 | __startTimestamp1493851515522__endTimestamp 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | ------- Summary: start ------- 15:45:15 INFO - PID 3501 | Number of tests: 6 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | [#0] dojo.html Cycles:165 Average:6133.16 Median:6403.71 stddev:795.75 (12.4%) stddev-sans-first:758.47 15:45:15 INFO - PID 3501 | Values: 2968.0 3548.6 3574.4 3586.8 3599.9 5013.9 6105.4 6184.7 6188.1 6247.8 4793.6 6107.3 6163.9 6224.5 6224.6 5395.0 5439.3 5446.2 5468.0 5540.3 5340.1 5455.5 5498.2 5501.2 5571.9 5449.5 5502.6 5508.4 5555.4 5558.6 4374.6 5100.8 5343.1 5356.9 5410.9 4749.6 5394.6 5436.8 5445.5 5487.0 4755.0 5355.8 6092.0 6235.2 6238.9 6663.9 6700.7 6714.1 6727.0 6742.1 6561.6 6616.9 6738.3 6842.7 6850.7 6285.9 6332.0 6342.9 6344.5 6346.5 6849.1 6868.2 6926.2 6933.5 6934.4 6323.2 6400.2 6435.3 6527.5 6599.5 6727.4 6766.3 6775.9 6785.7 6791.2 6622.8 6645.6 6674.0 6676.5 6701.0 6425.1 6540.8 6593.9 6608.3 6713.6 5321.3 5343.5 5349.3 5379.1 5390.6 4216.7 4535.7 4640.6 4735.1 4741.6 4954.8 6136.4 6300.7 6316.2 6346.6 5622.9 6627.6 6761.7 6832.4 6847.8 6292.2 6313.7 6325.1 6357.0 6361.4 6009.2 6019.0 6042.2 6043.9 6044.2 6743.6 6823.1 6917.1 6947.5 6981.4 6777.8 6795.5 6809.4 6851.2 6862.2 6751.9 6822.9 6852.5 7014.8 7039.6 6732.4 6740.9 6745.6 6748.3 6763.4 6531.6 6624.2 6663.9 6888.7 6892.9 6474.5 6510.1 6577.8 6592.7 6616.6 6427.4 6584.6 6585.7 6593.5 6642.9 6423.4 6450.4 6500.0 6512.4 6528.1 6309.3 6413.4 6477.9 6524.9 6545.9 6367.2 6380.8 6394.5 6403.7 6409.6 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | [#1] ext.html Cycles:180 Average:43885.02 Median:18887.90 stddev:74566.00 (394.8%) stddev-sans-first:74704.84 15:45:15 INFO - PID 3501 | Values: 748.0 813.5 823.0 1216.7 1277.0 37225.3 37888.6 37902.4 37956.6 38058.0 37929.4 38189.6 38238.0 38247.2 38302.6 7822.9 7948.6 8319.5 8463.4 8494.7 8434.5 8477.0 8542.9 8544.6 8559.1 8488.8 8506.4 8570.7 8576.0 8583.0 8460.7 8509.4 8525.1 8530.6 8531.4 7126.0 7207.7 7397.6 8382.8 8387.0 37601.4 37671.2 37810.4 37833.7 37868.9 3659.0 3693.9 3694.4 3694.4 3708.9 31027.0 31250.1 31263.8 31281.4 31308.8 3163.3 3201.4 3203.1 3210.5 3222.1 219874.0 239585.0 249656.0 308059.0 311701.0 48585.5 48689.0 48720.0 48772.3 48935.8 50604.7 50713.0 50796.0 50900.7 51059.5 14371.4 14470.1 14491.8 14509.5 14550.1 11578.4 11672.8 11823.6 11824.7 11845.4 11814.2 13189.9 13625.2 13700.2 13702.5 5882.8 5920.0 5942.0 5952.7 5984.9 997.7 1014.4 1023.1 1058.3 1066.6 20850.9 23403.6 24599.5 24716.1 24746.0 1044.7 1115.8 1116.6 1149.6 1151.1 11911.3 12502.6 12522.3 12543.8 12563.9 303111.5 303601.0 303780.0 306359.0 307150.0 54220.5 54506.7 54567.9 54614.5 54650.5 1515.0 1525.8 1570.5 1572.2 1573.5 24294.0 24330.9 24489.0 24534.0 24584.5 39568.8 39667.0 39744.6 39833.8 39911.0 278329.0 280255.0 280262.0 280460.0 281483.0 34553.7 34777.7 35061.6 35100.3 35166.8 42661.8 43380.3 44718.6 44761.0 44905.4 29860.9 30356.5 31013.7 31136.8 31535.2 30496.2 31219.8 31299.0 31339.8 31347.2 37052.0 37428.3 37609.2 37633.8 37656.8 25961.2 29384.3 30152.9 31241.1 31420.4 19503.0 24114.6 24291.4 24378.9 24541.3 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | [#2] jquery.html Cycles:180 Average:36249.61 Median:22812.72 stddev:93089.88 (408.1%) stddev-sans-first:93312.05 15:45:15 INFO - PID 3501 | Values: 377.7 417.4 423.7 427.2 431.5 19882.9 21079.8 21781.5 21942.9 21969.3 21965.8 22916.9 23720.1 23818.9 23929.2 6121.1 6302.2 6323.1 6327.3 6327.7 5356.6 5964.6 6240.9 6314.5 6362.6 5392.8 6233.7 6250.3 6264.2 6289.8 6023.2 6193.4 6199.2 6226.5 6241.5 5330.4 5494.9 5503.6 5530.9 5537.5 25059.9 27372.5 27644.7 28236.0 28355.7 2157.4 2230.1 2231.8 2235.1 2236.2 15466.5 17860.4 17968.3 18162.5 18248.5 2109.4 2141.1 2165.8 2167.3 2169.2 169576.1 170889.0 173070.1 173648.0 175119.0 10777.9 12577.4 12679.7 12809.1 12816.3 16284.8 16536.7 16539.4 16560.3 16568.7 8007.6 8909.6 9023.8 9114.2 9279.7 6464.3 7069.4 8680.0 8691.6 8756.7 7876.6 7996.8 8031.7 8032.7 8067.6 2138.3 2142.1 2144.2 2147.9 2327.8 910.3 931.9 944.7 961.8 988.5 20730.0 21245.9 21334.0 21385.8 21451.0 998.3 1026.2 1041.1 1041.9 1042.2 9426.4 9490.8 11028.3 11037.3 11056.6 535845.0 536841.0 543075.0 549226.0 550752.0 124736.8 130733.0 133504.3 140026.0 140287.0 1190.1 1244.4 1252.1 1258.5 1259.3 19885.8 22109.8 22416.0 22664.3 22708.5 57037.7 57448.1 57739.7 58019.0 58710.0 80083.0 80234.2 81705.6 81969.6 82036.0 8841.4 8857.7 8918.4 9135.6 9350.5 14134.6 14296.6 14345.8 14734.1 14894.9 11421.0 11890.6 12084.0 12234.1 12298.6 9783.3 10412.2 11243.6 11525.6 11823.1 12154.9 12184.2 12312.3 12534.9 12538.9 8992.6 9021.1 9047.0 9088.2 9165.3 10247.3 11972.4 12002.3 12153.2 12207.3 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | [#3] mootools.html Cycles:180 Average:7135.23 Median:3649.69 stddev:4541.41 (124.4%) stddev-sans-first:4533.08 15:45:15 INFO - PID 3501 | Values: 1313.0 1401.0 1417.0 1465.5 1472.0 11521.7 11837.0 12037.4 12077.3 12088.9 11888.7 11936.2 12082.0 12131.3 12160.3 2168.9 2531.7 2579.9 2583.8 2583.9 2488.1 2536.9 2548.4 2552.1 2558.8 2098.9 2527.0 2567.6 2568.3 2576.7 2482.8 2505.2 2544.2 2553.6 2553.8 2116.2 2457.1 2545.3 2575.6 2578.2 11949.9 12049.4 12052.3 12056.8 12093.9 6678.1 6834.4 6851.9 6925.8 6928.0 3957.4 4308.4 4372.0 4488.4 4498.3 1183.4 1254.9 1263.6 1284.6 1324.5 15075.6 15591.2 16715.6 16803.5 16864.7 5015.6 5602.8 5832.0 5853.1 5865.8 10353.2 10712.1 11913.6 11928.0 11953.3 6476.8 6567.5 6605.0 6624.3 6628.8 6124.7 6153.5 6189.0 6247.8 6313.7 3626.8 3672.6 3898.9 4488.2 4548.6 1103.8 1282.4 1283.4 1292.8 1297.5 779.3 788.4 800.1 809.0 819.5 5867.6 6119.8 6182.3 6335.9 6428.2 587.2 593.6 607.8 616.0 619.8 3949.8 4268.2 4330.1 4353.3 4483.8 14428.8 15033.9 15041.8 15063.5 15075.0 14618.4 14792.2 14972.7 14973.3 14977.3 8670.5 8763.6 8770.2 8796.8 8804.1 4740.2 6296.5 6433.9 6790.8 6995.2 13179.9 13613.5 13641.9 13642.5 13655.6 13487.9 13785.0 14111.0 14197.5 14221.2 8023.8 9278.5 9552.5 9554.7 9564.0 10793.1 10793.6 10805.7 10934.9 10981.6 9208.0 9290.4 9334.6 9370.8 9495.0 9167.8 9199.3 9204.6 9217.7 9260.6 6874.8 8370.0 8397.4 8410.5 8417.5 6007.1 6032.5 6111.0 6217.8 6243.1 6142.2 6183.7 6275.2 6298.4 6339.6 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | [#4] prototype.html Cycles:180 Average:5322.93 Median:6126.83 stddev:1729.77 (28.2%) stddev-sans-first:1693.44 15:45:15 INFO - PID 3501 | Values: 324.5 400.6 402.2 408.2 411.0 5056.5 5535.4 6169.2 6265.6 6269.5 5671.7 6106.9 6244.4 6258.9 6308.6 3879.9 4451.0 4490.0 4501.2 4514.6 4259.5 4438.2 4510.7 4513.9 4519.8 4474.2 4474.7 4489.3 4491.8 4492.8 3710.7 4392.6 4418.6 4448.2 4472.4 3318.7 3438.6 3613.8 3806.5 3818.9 5267.9 5321.5 5483.3 6114.6 6126.5 4484.9 5199.9 5351.3 5399.9 5405.5 6224.8 6292.6 6678.9 6702.5 6704.5 3938.8 4084.2 4329.2 4360.9 4364.8 6181.8 6320.6 6764.2 6830.7 6891.8 3905.7 4225.8 4290.3 4390.4 4420.6 3944.8 4505.3 4507.7 4511.4 4516.0 4032.2 5006.2 5081.8 5261.1 5361.0 6487.6 6683.8 6705.5 6716.9 6727.6 1235.8 1510.4 1533.8 1536.0 1541.9 947.7 977.9 989.4 1000.0 1002.1 5329.5 6324.2 6377.3 6412.5 6418.2 5546.4 6869.4 7039.5 7074.4 7090.4 5582.1 6221.8 6254.6 6358.4 6368.2 3415.5 3632.6 3666.5 3670.2 3677.6 5901.2 6462.9 6881.0 7012.1 7110.2 6199.6 6327.5 7093.5 7103.4 7115.2 5726.7 6681.1 7025.4 7105.1 7126.4 6930.5 6933.9 6972.4 6995.6 7032.3 5847.9 5882.8 6731.5 6775.2 6775.5 6216.9 6913.3 6951.9 7200.9 7276.4 3795.5 4400.4 4423.0 4439.0 4444.6 6916.2 6956.5 7025.9 7028.7 7087.8 6737.2 6757.9 6758.2 6798.3 6811.7 6440.5 6571.2 6664.5 6757.5 6817.8 6051.0 6127.1 6469.1 6649.4 6671.7 5733.8 5899.9 6476.4 6699.9 6877.3 6558.7 6588.6 6593.6 6623.9 6680.0 15:45:15 INFO - PID 3501 | 15:45:15 INFO - PID 3501 | [#5] yui.html Cycles:180 Average:22441.53 Median:17606.71 stddev:31568.27 (179.3%) stddev-sans-first:31615.14 15:45:15 INFO - PID 3501 | Values: 834.2 843.2 853.1 873.7 878.4 13838.8 15219.2 15451.1 16596.3 16748.6 16801.1 17501.1 17516.9 17597.4 18300.4 978.4 1007.6 1011.4 1019.1 1022.1 1022.8 1027.8 1032.6 1037.1 1040.9 878.3 1060.8 1082.2 1084.1 1087.8 899.8 1057.0 1073.0 1073.6 1074.7 768.8 781.0 791.0 793.0 793.7 16965.5 17421.8 17599.2 17614.2 17614.6 16685.9 16989.7 17207.0 17389.8 17395.8 12463.1 13877.7 13943.4 14179.5 14285.4 3375.9 3505.8 3532.6 3553.9 3554.8 119053.0 121266.0 121929.4 122773.4 124457.0 13523.3 13538.1 13655.0 13659.9 13715.4 25084.0 25702.6 25831.1 25894.6 26190.1 7818.4 7959.8 7986.9 7995.2 7997.6 3520.0 3739.6 3866.6 4062.6 4090.6 4649.1 4843.7 4873.0 4875.8 4886.7 948.5 978.8 989.3 993.8 996.4 569.3 654.0 660.2 662.2 689.4 9739.6 10232.8 11455.6 12090.7 12260.6 640.9 659.2 661.9 678.5 690.0 5461.6 5735.7 5744.4 5819.6 5840.8 109252.5 109491.0 110972.6 112243.9 115884.0 97221.5 97553.0 97565.0 98113.0 99842.0 66412.0 76782.8 77333.0 78531.0 79163.6 12608.4 12750.2 12946.0 12993.5 13224.8 27826.0 38034.4 38827.0 39177.2 40175.6 72285.6 72317.6 72547.6 73027.0 73209.0 16539.6 16591.6 16667.2 17482.7 17496.7 18403.4 18665.6 18779.0 18872.6 18918.9 15831.4 16682.7 16734.4 16914.9 17976.0 16933.0 17071.1 17366.9 17523.5 17647.6 16080.1 16523.8 17061.1 17350.5 17384.2 11689.6 11922.8 12008.8 12248.7 12257.8 13362.3 13517.2 13717.5 13909.8 14232.4 15:45:15 INFO - PID 3501 | -------- Summary: end -------- 15:45:15 INFO - PID 3501 | 15:45:16 INFO - TEST-INFO | 3501: exit 0 15:45:16 INFO - TEST-OK | dromaeo_css | took 1115046ms 15:45:16 INFO - TEST-START | kraken 15:45:16 INFO - operating with platform_type : mac_ 15:45:16 INFO - Initialising browser for kraken test... 15:45:16 INFO - TEST-INFO | started process 3509 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpQzYjNE/profile http://localhost:52555/getInfo.html) 15:45:19 INFO - PID 3509 | 2017-05-03 15:45:19.016 plugin-container[3510:63089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a47, name = 'com.apple.tsm.portname' 15:45:19 INFO - PID 3509 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:19 INFO - PID 3509 | 2017-05-03 15:45:19.019 plugin-container[3510:63089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 15:45:19 INFO - PID 3509 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:19 INFO - PID 3509 | 2017-05-03 15:45:19.019 plugin-container[3510:63089] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:19 INFO - PID 3509 | __metrics Screen width/height:1600/1200 15:45:19 INFO - PID 3509 | colorDepth:24 15:45:19 INFO - PID 3509 | Browser inner width/height: 1024/691 15:45:19 INFO - PID 3509 | __metrics 15:45:20 INFO - TEST-INFO | 3509: exit 0 15:45:20 INFO - Browser initialized. 15:45:20 INFO - Running cycle 1/1 for kraken test... 15:45:20 INFO - TEST-INFO | started process 3512 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpQzYjNE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:22 INFO - PID 3512 | 2017-05-03 15:45:22.547 plugin-container[3513:63253] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 15:45:22 INFO - PID 3512 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:22 INFO - PID 3512 | 2017-05-03 15:45:22.547 plugin-container[3513:63253] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 15:45:22 INFO - PID 3512 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:22 INFO - PID 3512 | 2017-05-03 15:45:22.547 plugin-container[3513:63253] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:23 INFO - PID 3512 | 2017-05-03 15:45:23.067 plugin-container[3514:63294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913b, name = 'com.apple.tsm.portname' 15:45:23 INFO - PID 3512 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:23 INFO - PID 3512 | 2017-05-03 15:45:23.067 plugin-container[3514:63294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 15:45:23 INFO - PID 3512 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:23 INFO - PID 3512 | 2017-05-03 15:45:23.067 plugin-container[3514:63294] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:32 INFO - PID 3512 | RSS: Main: 233844736 15:45:32 INFO - PID 3512 | 15:45:35 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52555/tests/kraken/driver.html?testName=audio-beat-detection) 15:45:36 INFO - PID 3512 | RSS: Main: 246599680 15:45:36 INFO - PID 3512 | 15:45:38 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52555/tests/kraken/driver.html?testName=audio-dft) 15:45:39 INFO - PID 3512 | RSS: Main: 254857216 15:45:39 INFO - PID 3512 | 15:45:42 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52555/tests/kraken/driver.html?testName=audio-fft) 15:45:43 INFO - PID 3512 | RSS: Main: 257208320 15:45:43 INFO - PID 3512 | 15:45:45 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52555/tests/kraken/driver.html?testName=audio-oscillator) 15:45:45 INFO - PID 3512 | RSS: Main: 259555328 15:45:45 INFO - PID 3512 | 15:45:47 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52555/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:45:48 INFO - PID 3512 | RSS: Main: 260104192 15:45:48 INFO - PID 3512 | 15:45:51 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52555/tests/kraken/driver.html?testName=imaging-darkroom) 15:45:51 INFO - PID 3512 | RSS: Main: 265666560 15:45:51 INFO - PID 3512 | 15:45:54 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52555/tests/kraken/driver.html?testName=imaging-desaturate) 15:45:55 INFO - PID 3512 | RSS: Main: 267268096 15:45:55 INFO - PID 3512 | 15:45:58 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52555/tests/kraken/driver.html?testName=json-parse-financial) 15:45:58 INFO - PID 3512 | RSS: Main: 266698752 15:45:58 INFO - PID 3512 | 15:46:00 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52555/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:46:01 INFO - PID 3512 | RSS: Main: 266522624 15:46:01 INFO - PID 3512 | 15:46:02 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:46:03 INFO - PID 3512 | RSS: Main: 266412032 15:46:03 INFO - PID 3512 | 15:46:05 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:46:06 INFO - PID 3512 | RSS: Main: 266104832 15:46:06 INFO - PID 3512 | 15:46:08 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:46:09 INFO - PID 3512 | RSS: Main: 266162176 15:46:09 INFO - PID 3512 | 15:46:12 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:46:13 INFO - PID 3512 | RSS: Main: 266481664 15:46:13 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:52555/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52555/tests/kraken/driver.html?testName=ai-astar) 15:46:15 INFO - PID 3512 | RSS: Main: 268365824 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | __start_tp_report 15:46:15 INFO - PID 3512 | _x_x_mozilla_page_load 15:46:15 INFO - PID 3512 | _x_x_mozilla_page_load_details 15:46:15 INFO - PID 3512 | |i|pagename|runs| 15:46:15 INFO - PID 3512 | |0;ai-astar;106;116;114;115;115;116;115;115;114;116 15:46:15 INFO - PID 3512 | |1;audio-beat-detection;160;112;110;150;112;112;118;142;153;110 15:46:15 INFO - PID 3512 | |2;audio-dft;196;197;202;204;208;206;213;198;227;284 15:46:15 INFO - PID 3512 | |3;audio-fft;87;67;85;95;59;60;59;57;79;56 15:46:15 INFO - PID 3512 | |4;audio-oscillator;91;70;85;70;70;68;85;70;72;90 15:46:15 INFO - PID 3512 | |5;imaging-gaussian-blur;89;90;90;88;91;89;94;90;89;85 15:46:15 INFO - PID 3512 | |6;imaging-darkroom;145;97;96;96;96;96;98;96;97;97 15:46:15 INFO - PID 3512 | |7;imaging-desaturate;91;97;92;93;92;93;92;93;91;92 15:46:15 INFO - PID 3512 | |8;json-parse-financial;49;50;49;50;52;52;51;51;50;48 15:46:15 INFO - PID 3512 | |9;json-stringify-tinderbox;58;63;61;66;67;65;57;59;57;68 15:46:15 INFO - PID 3512 | |10;stanford-crypto-aes;81;84;81;83;83;83;82;87;81;84 15:46:15 INFO - PID 3512 | |11;stanford-crypto-ccm;137;138;135;137;138;139;149;150;187;140 15:46:15 INFO - PID 3512 | |12;stanford-crypto-pbkdf2;186;211;230;190;183;185;194;184;183;182 15:46:15 INFO - PID 3512 | |13;stanford-crypto-sha256-iterative;68;67;71;66;70;67;68;68;69;66 15:46:15 INFO - PID 3512 | __end_tp_report 15:46:15 INFO - PID 3512 | __start_cc_report 15:46:15 INFO - PID 3512 | _x_x_mozilla_cycle_collect,967 15:46:15 INFO - PID 3512 | __end_cc_report 15:46:15 INFO - PID 3512 | __startTimestamp1493851575390__endTimestamp 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | ------- Summary: start ------- 15:46:15 INFO - PID 3512 | Number of tests: 14 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.00 stddev:2.97 (2.6%) stddev-sans-first:0.78 15:46:15 INFO - PID 3512 | Values: 106.0 116.0 114.0 115.0 115.0 116.0 115.0 115.0 114.0 116.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:130.00 stddev:20.67 (15.9%) stddev-sans-first:18.37 15:46:15 INFO - PID 3512 | Values: 160.0 112.0 110.0 150.0 112.0 112.0 118.0 142.0 153.0 110.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#2] audio-dft Cycles:10 Average:213.50 Median:207.00 stddev:26.42 (12.8%) stddev-sans-first:27.25 15:46:15 INFO - PID 3512 | Values: 196.0 197.0 202.0 204.0 208.0 206.0 213.0 198.0 227.0 284.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#3] audio-fft Cycles:10 Average:70.40 Median:73.00 stddev:14.66 (20.1%) stddev-sans-first:14.27 15:46:15 INFO - PID 3512 | Values: 87.0 67.0 85.0 95.0 59.0 60.0 59.0 57.0 79.0 56.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.50 stddev:9.40 (12.0%) stddev-sans-first:8.52 15:46:15 INFO - PID 3512 | Values: 91.0 70.0 85.0 70.0 70.0 68.0 85.0 70.0 72.0 90.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:2.27 (2.5%) stddev-sans-first:2.40 15:46:15 INFO - PID 3512 | Values: 89.0 90.0 90.0 88.0 91.0 89.0 94.0 90.0 89.0 85.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:96.50 stddev:15.33 (15.9%) stddev-sans-first:0.73 15:46:15 INFO - PID 3512 | Values: 145.0 97.0 96.0 96.0 96.0 96.0 98.0 96.0 97.0 97.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:92.50 stddev:1.71 (1.9%) stddev-sans-first:1.72 15:46:15 INFO - PID 3512 | Values: 91.0 97.0 92.0 93.0 92.0 93.0 92.0 93.0 91.0 92.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.50 stddev:1.32 (2.6%) stddev-sans-first:1.32 15:46:15 INFO - PID 3512 | Values: 49.0 50.0 49.0 50.0 52.0 52.0 51.0 51.0 50.0 48.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:64.00 stddev:4.25 (6.6%) stddev-sans-first:4.25 15:46:15 INFO - PID 3512 | Values: 58.0 63.0 61.0 66.0 67.0 65.0 57.0 59.0 57.0 68.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:1.85 (2.2%) stddev-sans-first:1.83 15:46:15 INFO - PID 3512 | Values: 81.0 84.0 81.0 83.0 83.0 83.0 82.0 87.0 81.0 84.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:139.50 stddev:15.61 (11.2%) stddev-sans-first:16.28 15:46:15 INFO - PID 3512 | Values: 137.0 138.0 135.0 137.0 138.0 139.0 149.0 150.0 187.0 140.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:188.00 stddev:15.70 (8.3%) stddev-sans-first:16.46 15:46:15 INFO - PID 3512 | Values: 186.0 211.0 230.0 190.0 183.0 185.0 194.0 184.0 183.0 182.0 15:46:15 INFO - PID 3512 | 15:46:15 INFO - PID 3512 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.63 (2.4%) stddev-sans-first:1.73 15:46:15 INFO - PID 3512 | Values: 68.0 67.0 71.0 66.0 70.0 67.0 68.0 68.0 69.0 66.0 15:46:15 INFO - PID 3512 | -------- Summary: end -------- 15:46:15 INFO - PID 3512 | 15:46:16 INFO - TEST-INFO | 3512: exit 0 15:46:16 INFO - TEST-OK | kraken | took 60133ms 15:46:16 INFO - SUITE-END | took 1175s 15:46:16 INFO - Completed test suite (00:19:35) 15:46:16 INFO - javascript benchmark 15:46:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7914.186363230719, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2967.95548066779, 3548.627394123617, 3574.392353299939, 3586.802725850079, 3599.8740044098463, 5013.874653133669, 6105.429421494168, 6184.701212745324, 6188.102725104863, 6247.750089996394, 4793.592544633701, 6107.2976607690125, 6163.876722465548, 6224.533160012995, 6224.595401298912, 5395.001924643953, 5439.292891924044, 5446.2375267462485, 5467.972660136694, 5540.307461567304, 5340.145576707728, 5455.454454554532, 5498.230247765316, 5501.174823776446, 5571.8607034824045, 5449.455054494559, 5502.642328248651, 5508.421615730351, 5555.416681248471, 5558.610897237195, 4374.606285434326, 5100.826809833759, 5343.145096784535, 5356.91964620531, 5410.890767392675, 4749.643776716725, 5394.622376433651, 5436.755346009439, 5445.53712934401, 5487.039768040617, 4755.001449695568, 5355.7857685692525, 6092.02527595587, 6235.158253635746, 6238.937610623926, 6663.900041499382, 6700.698468568878, 6714.127163468718, 6726.966365168192, 6742.089817874573, 6561.606303621798, 6616.867662646769, 6738.258791532928, 6842.692078856414, 6850.6574671266235, 6285.8742825143245, 6331.96834015827, 6342.904856427203, 6344.524160687967, 6346.524010699216, 6849.109615750021, 6868.244493105754, 6926.168859736864, 6933.479989000845, 6934.375906168469, 6323.1779868617705, 6400.167978162809, 6435.324290949477, 6527.477801775846, 6599.53803233769, 6727.394534491919, 6766.28953959837, 6775.898361524581, 6785.694643741043, 6791.219009813836, 6622.801315960529, 6645.60126392418, 6674.0, 6676.46588272937, 6701.028350889093, 6425.068365087036, 6540.803775886731, 6593.86812263752, 6608.3391660834495, 6713.56361836479, 5321.281626980385, 5343.5190832824455, 5349.331333583302, 5379.1393377059485, 5390.622656414092, 4216.6928252241905, 4535.682502224879, 4640.605548528413, 4735.147673418817, 4741.596964258008, 4954.801807927643, 6136.427173609184, 6300.747970081237, 6316.178896743486, 6346.555741098079, 5622.943770562242, 6627.569208001464, 6761.729530818712, 6832.350926662059, 6847.828804279833, 6292.150559674477, 6313.747450102036, 6325.082862984841, 6356.968215158987, 6361.395667411589, 6009.248843894513, 6018.969905150446, 6042.1843051187525, 6043.909341359883, 6044.24446944132, 6743.629100399427, 6823.078884350549, 6917.066196063668, 6947.513674042769, 6981.371676549135, 6777.830554236183, 6795.45636349081, 6809.353111454404, 6851.212110607344, 6862.210845752603, 6751.86496270062, 6822.897656535057, 6852.486063545154, 7014.789556313319, 7039.648017598997, 6732.427743641845, 6740.9325906740305, 6745.5952642841585, 6748.257691654012, 6763.391294783493, 6531.608103513804, 6624.205095388585, 6663.900041499478, 6888.690008949509, 6892.896606550806, 6474.546781725234, 6510.056041873996, 6577.835554111186, 6592.67036648156, 6616.636085015371, 6427.357264273535, 6584.604923704402, 6585.670716464253, 6593.472522198309, 6642.900356494753, 6423.4218920295525, 6450.354964503512, 6500.0, 6512.446442052478, 6528.053432252202, 6309.305976342507, 6413.42279194856, 6477.902831457626, 6524.934750652433, 6545.934540654533, 6367.204099487564, 6380.776672816429, 6394.488440924808, 6403.711832967434, 6409.583377080475], "value": 6077.472207376726, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.985040299194, 813.515958004987, 823.0206054794794, 1216.738401243733, 1276.9680757981055, 37225.25549489012, 37888.62111378885, 37902.43146352807, 37956.62043379565, 38057.99999999996, 37929.43105853407, 38189.61810381895, 38238.0, 38247.23505529888, 38302.61697383032, 7822.921770782291, 7948.602569871512, 8319.500829950222, 8463.4075614707, 8494.66021359148, 8434.451760635538, 8476.957615211946, 8542.871856922151, 8544.572771361438, 8559.101294364096, 8488.78778030548, 8506.36202284831, 8570.74287771368, 8576.0, 8583.012953510364, 8460.66157353705, 8509.446885952424, 8525.147485251488, 8530.616122274512, 8531.445456045369, 7125.966734823422, 7207.675654595556, 7397.556146631166, 8382.832343353099, 8387.0, 37601.435978460344, 37671.24657506862, 37810.43284350737, 37833.6758213464, 37868.86393408202, 3659.048647351681, 3693.8522459101605, 3694.353488139565, 3694.4458331250526, 3708.9443658345144, 31027.0, 31250.06249812509, 31263.84368078168, 31281.37437251265, 31308.843455782804, 3163.3198862244726, 3201.439748044083, 3203.08712017074, 3210.486322188439, 3222.146131275214, 219874.0, 239585.0, 249656.0, 308059.0, 311701.0, 48585.514144858804, 48689.02621947541, 48720.02559948781, 48772.26841597379, 48935.77660558443, 50604.74697626488, 50713.0, 50796.0, 50900.74549627302, 51059.489405106215, 14371.353289102015, 14470.059446135967, 14491.78262326066, 14509.492167774288, 14550.054246473945, 11578.42107894602, 11672.824907626393, 11823.645290641294, 11824.704382390337, 11845.407729613486, 11814.173007889538, 13189.934050329686, 13625.228720266301, 13700.177989320473, 13702.45190192381, 5882.794027224409, 5919.970400147971, 5942.0, 5952.7023648817385, 5984.940150598438, 997.6607953295769, 1014.4420568687193, 1023.0894503891542, 1058.327961744298, 1066.5893630951985, 20850.89574552148, 23403.648945265835, 24599.508009839705, 24716.13493527718, 24746.0, 1044.6709286574858, 1115.8493603363443, 1116.625930313338, 1149.5689116581282, 1151.1078913841839, 11911.3448760319, 12502.624921252376, 12522.311272880079, 12543.811842822364, 12563.93718031404, 303111.48444257636, 303601.0, 303780.0, 306359.0, 307150.0, 54220.45779542154, 54506.727466363205, 54567.90864182694, 54614.45385546173, 54650.453495464535, 1514.9848501515064, 1525.8321584625683, 1570.46604154588, 1572.2217502336416, 1573.465021892562, 24294.0, 24330.90510926977, 24489.0, 24534.0, 24584.508309834062, 39568.80215598903, 39667.0, 39744.602553975245, 39833.80083099566, 39911.0, 278329.0, 280255.0, 280262.0, 280460.0, 281483.0, 34553.65446345605, 34777.65222347744, 35061.64938350686, 35100.297994040484, 35166.82416587901, 42661.78669106634, 43380.34929476123, 44718.55281447144, 44761.0, 44905.428310008996, 29860.850695746383, 30356.544651829798, 31013.689863101983, 31136.84431577918, 31535.211619708774, 30496.237594060327, 31219.84390078035, 31299.0, 31339.84330078426, 31347.216319592193, 37052.0, 37428.2514349706, 37609.247815044095, 37633.81183094067, 37656.81171594124, 25961.221163365128, 29384.26539336534, 30152.94464693813, 31241.062768116994, 31420.371592568477, 19503.0, 24114.63828042616, 24291.39271518226, 24378.878105609358, 24541.263762087165], "value": 17543.683606912724, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.7035027503409, 417.3760228458455, 423.74078363795576, 427.2437785120337, 431.5425648812258, 19882.90058549709, 21079.789202107975, 21781.455463613416, 21942.89028554857, 21969.340919772392, 21965.780342196573, 22916.885415572942, 23720.051197952103, 23818.928148233328, 23929.16247931323, 6121.143039974419, 6302.180716506848, 6323.114763933053, 6327.272363678167, 6327.746890124389, 5356.571474282067, 5964.582479226456, 6240.875182496347, 6314.526410519206, 6362.618242905417, 5392.811251606195, 6233.690924905775, 6250.312465628777, 6264.185655864731, 6289.842753931165, 6023.156758053876, 6193.4425901669065, 6199.163112979735, 6226.470749986257, 6241.531885108635, 5330.413654498002, 5494.917576236359, 5503.642263252876, 5530.861728456781, 5537.529310008654, 25059.872305746285, 27372.45255094887, 27644.723552764415, 28236.0, 28355.71644283572, 2157.3851452336166, 2230.074519074582, 2231.832612554065, 2235.117128734141, 2236.2173239366252, 15466.458673946358, 17860.374886878897, 17968.281268749233, 18162.45512634623, 18248.543786405313, 2109.430453777487, 2141.0686351437175, 2165.7942495462908, 2167.306461932182, 2169.1540299283292, 169576.12967092308, 170889.0, 173070.13464932595, 173648.0, 175119.0, 10777.946110269399, 12577.371131443391, 12679.688905772082, 12809.103362764703, 12816.295103769382, 16284.837151628331, 16536.6692666146, 16539.421120260733, 16560.254788534545, 16568.66862662764, 8007.599620019092, 8909.599068041955, 9023.77440563978, 9114.225290850352, 9279.72160835176, 6464.321246269074, 7069.399101076466, 8680.0, 8691.565421728888, 8756.737297881074, 7876.645550950221, 7996.800079998046, 8031.718889838828, 8032.718854840169, 8067.556284404414, 2138.3478039198194, 2142.068200332829, 2144.249512670584, 2147.9248226312316, 2327.7788610082016, 910.3263584947091, 931.8857245262092, 944.7401964459829, 961.8364877970762, 988.5304480371766, 20730.0, 21245.89377053105, 21334.0, 21385.78614213838, 21451.0, 998.2812375089924, 1026.2098184397971, 1041.068243921686, 1041.8697662792151, 1042.2183362478142, 9426.397512422904, 9490.810183796424, 11028.338299701883, 11037.295862513736, 11056.557737690402, 535845.0, 536841.0, 543075.0, 549226.0, 550752.0, 124736.75263247252, 130733.03900441501, 133504.332478337, 140026.0, 140287.0, 1190.119311709329, 1244.4026867103842, 1252.1360261419773, 1258.5091814192472, 1259.2822091407809, 19885.80685158895, 22109.77890221077, 22416.0, 22664.320070397913, 22708.545829083658, 57037.71481142568, 57448.1382779267, 57739.711301443225, 58019.0, 58710.0, 80083.0, 80234.19765802268, 81705.59147204463, 81969.59015204886, 82036.0, 8841.381103322705, 8857.734267971971, 8918.420302680304, 9135.634574617206, 9350.53247337644, 14134.575962720723, 14296.57110286693, 14345.784813227601, 14734.115953042852, 14894.85105148978, 11421.0, 11890.643280701594, 12084.0, 12234.143609947574, 12298.631041068782, 9783.315167938175, 10412.21908356861, 11243.60647377338, 11525.596604118815, 11823.113266504875, 12154.939225303817, 12184.20802647825, 12312.322822244863, 12534.874651253736, 12538.93730531378, 8992.59533320989, 9021.142991416067, 9047.0, 9088.227500662253, 9165.266778657558, 10247.282690211912, 11972.401379930794, 12002.279863208236, 12153.210041347284, 12207.267563946192], "value": 10563.761497098643, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.9737405251901, 1400.9649758756034, 1416.9716605667886, 1465.5237047959415, 1471.9632009199768, 11521.654350369492, 11837.0, 12037.398130093494, 12077.335746533938, 12088.879111208886, 11888.702782430444, 11936.16446848721, 12082.0, 12131.332776697278, 12160.27038377698, 2168.8698678079368, 2531.7468253174675, 2579.9097031603897, 2583.7803786678155, 2583.9483210335875, 2488.1042824583137, 2536.9238922832346, 2548.362909272682, 2552.081250749738, 2558.7952963762855, 2098.90554925028, 2527.0397249045336, 2567.627693984371, 2568.3065572295563, 2576.742325767427, 2482.8013758899333, 2505.1607711416696, 2544.211294498711, 2553.616957456378, 2553.8467691938545, 2116.2169997101173, 2457.1154384421593, 2545.26187405654, 2575.562154433732, 2578.1749840051193, 11949.880501194964, 12049.397530123459, 12052.337121458317, 12056.758864822743, 12093.939530302292, 6678.065070890079, 6834.350736680008, 6851.931480685179, 6925.757598484029, 6928.0, 3957.3668213086057, 4308.4399028126145, 4371.9781401093105, 4488.371627972087, 4498.280275155959, 1183.4142099660723, 1254.8870601645804, 1263.5577547858268, 1284.621036794148, 1324.5165514587202, 15075.623109422242, 15591.22043897812, 16715.582110447336, 16803.495895123167, 16864.662706745796, 5015.598752099823, 5602.803901863415, 5832.037713777179, 5853.1220316952795, 5865.794697185578, 10353.171746260281, 10712.143028557697, 11913.642590722293, 11928.0, 11953.282803031845, 6476.838079048061, 6567.474602031826, 6605.042268871083, 6624.337566243434, 6628.801135965929, 6124.724387402578, 6153.538484613672, 6189.0, 6247.812565623039, 6313.684315784192, 3626.8005259710967, 3672.576225563735, 3898.8830335089992, 4488.159854459721, 4548.6133678637025, 1103.7957977774138, 1282.410091357967, 1283.4096315694885, 1292.8299888600832, 1297.5393735224047, 779.2986312318958, 788.3502655104214, 800.1078797141146, 808.9797755056171, 819.4714409206148, 5867.589268751147, 6119.84700382494, 6182.258129024546, 6335.9228931081825, 6428.164338636041, 587.1926101610286, 593.5607650338804, 607.781198768443, 615.9753609855645, 619.8295468746131, 3949.782761948063, 4268.210381079511, 4330.133973205371, 4353.32523458858, 4483.775811209426, 14428.783568246485, 15033.924830375778, 15041.796656267472, 15063.47277845292, 15075.0, 14618.415263389557, 14792.186429746253, 14972.700545989237, 14973.251337433085, 14977.251137443085, 8670.523121228267, 8763.56182190888, 8770.210681038738, 8796.780080498038, 8804.075572064841, 4740.241561350167, 6296.4814074370615, 6433.935660643521, 6790.796276111724, 6995.230524642184, 13179.934100329821, 13613.52352667652, 13641.86358136406, 13642.52251171244, 13655.590332290047, 13487.865121349054, 13785.0, 14111.0, 14197.503087391891, 14221.217833018869, 8023.759287221393, 9278.489683067495, 9552.522373881138, 9554.713358599252, 9563.952180239054, 10793.08258798011, 10793.56825726962, 10805.675829725122, 10934.945325273322, 10981.61564345244, 9207.953960230156, 9290.442573445345, 9334.626614935327, 9370.76573085651, 9494.952525237606, 9167.816643667224, 9199.310051746012, 9204.631814727603, 9217.723468295962, 9260.583273752563, 6874.828129296808, 8370.037445693823, 8397.412181147261, 8410.453320534145, 8417.494950303002, 6007.068904319837, 6032.54755893301, 6111.022236442148, 6217.844553886189, 6243.063540468966, 6142.232220972379, 6183.690815459299, 6275.246970363585, 6298.370162983665, 6339.5562310637815], "value": 5333.554418651594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.50026958483926, 400.6174103730937, 402.1996227507261, 408.1612286750728, 410.9547949725531, 5056.544910958013, 5535.418781027995, 6169.16716243307, 6265.624062556244, 6269.467095296894, 5671.744771485282, 6106.938930610692, 6244.406781355775, 6258.906116408258, 6308.5899416537895, 3879.941800872975, 4450.998525331806, 4490.046829629106, 4501.1672840524525, 4514.61625761809, 4259.51015633203, 4438.1567502174485, 4510.729356238619, 4513.864584062483, 4519.796609152596, 4474.194644963905, 4474.731516109044, 4489.349044388562, 4491.8096704373365, 4492.842750503733, 3710.684591809699, 4392.6266267367155, 4418.5581441855875, 4448.221561226773, 4472.396226509412, 3318.7179089777396, 3438.570178727659, 3613.765105268149, 3806.543214814212, 3818.9045273868096, 5267.919171927106, 5321.494458026481, 5483.287172667568, 6114.633122012693, 6126.509879209652, 4484.910301793946, 5199.922001169985, 5351.297594714683, 5399.919001214946, 5405.459454054602, 6224.813255602339, 6292.574761781097, 6678.933210667879, 6702.525444402224, 6704.46314843029, 3938.7833669148185, 4084.1831633673382, 4329.2423825830665, 4360.89097772555, 4364.759938203398, 6181.81454556364, 6320.557560970734, 6764.163623009572, 6830.726770929157, 6891.793246202622, 3905.6875449963936, 4225.795648240237, 4290.2921018031675, 4390.363397307437, 4420.602145806893, 3944.7830369329963, 4505.256632655641, 4507.684462087623, 4511.436070491189, 4516.0, 4032.213718324957, 5006.249062640561, 5081.83117882889, 5261.110043982143, 5361.035013697494, 6487.643179625072, 6683.799486015428, 6705.530612857151, 6716.899246511306, 6727.553575981187, 1235.8022716365338, 1510.4335874047233, 1533.8082739657543, 1535.96322482324, 1541.922903854825, 947.6683160893632, 977.9047466837188, 989.3915242126185, 1000.0349662575504, 1002.1131298800614, 5329.54698850602, 6324.209473815773, 6377.266614339411, 6412.487001039905, 6418.1656384669695, 5546.362168350611, 6869.381755642016, 7039.507234493536, 7074.434045276365, 7090.4327653788605, 5582.0789569721355, 6221.817854607701, 6254.6247225166635, 6358.395952384516, 6368.203974503187, 3415.5389022481645, 3632.564092308887, 3666.5233519642275, 3670.22925185714, 3677.632236776301, 5901.203337549376, 6462.935370646327, 6880.965595172092, 7012.123484564429, 7110.217876033632, 6199.56603037783, 6327.525435592349, 7093.467989900778, 7103.396211322096, 7115.228928667814, 5726.742296596663, 6681.1314529110905, 7025.437964962791, 7105.147382314155, 7126.394256488256, 6930.514863959676, 6933.861322773617, 6972.4073453757, 6995.564081045349, 7032.296770323132, 5847.912281315698, 5882.794102206402, 6731.48541578141, 6775.220849602359, 6775.525713200226, 6216.906746398898, 6913.274106218774, 6951.930480695129, 7200.855982880418, 7276.417886569167, 3795.525559305087, 4400.4279443673395, 4422.982713975739, 4439.023414848728, 4444.599986001275, 6916.239213686591, 6956.469666498813, 7025.894611580932, 7028.718851245892, 7087.787366379017, 6737.157855268091, 6757.898631520433, 6758.222804377363, 6798.252192258748, 6811.705775902563, 6440.516961227833, 6571.244306904767, 6664.5334826561675, 6757.459403247827, 6817.829554261183, 6051.0, 6127.142200091902, 6469.126667899774, 6649.434798042221, 6671.6997735103005, 5733.799317024051, 5899.938011158033, 6476.384743449365, 6699.866002679822, 6877.277885821916, 6558.737235653692, 6588.637624930675, 6593.604383736991, 6623.900641490478, 6679.998000299994], "value": 4776.302410784925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.1658341658342, 843.2242337049913, 853.1340689200463, 873.6592728835756, 878.3719640457072, 13838.792418113737, 15219.239038048108, 15451.07293562387, 16596.33614655414, 16748.58128546787, 16801.07594082325, 17501.124943752795, 17516.912415437906, 17597.3840915568, 18300.359487417943, 978.3982850546898, 1007.5516395204137, 1011.4386515483903, 1019.0930072235716, 1022.1209759606733, 1022.8465730140501, 1027.7790275090854, 1032.6179313653959, 1037.1080870451406, 1040.9375437473775, 878.3412440669498, 1060.7560261139904, 1082.215393839464, 1084.1110289562562, 1087.836824476327, 899.7885496908222, 1056.96300629478, 1073.0, 1073.613499140309, 1074.7044562745268, 768.7847402727245, 781.0315209140681, 790.9683612655487, 792.9682812687486, 793.678560183121, 16965.491035268962, 17421.825781742275, 17599.208035638298, 17614.2073606688, 17614.647707045788, 16685.91657041707, 16989.745153822703, 17206.967581945122, 17389.82610173895, 17395.826041739674, 12463.12758106933, 13877.653058673613, 13943.442262309496, 14179.503717369842, 14285.357158927873, 3375.8987230383127, 3505.772124811879, 3532.6290739472497, 3553.8578456861696, 3554.751167418282, 119053.0, 121266.0, 121929.39035304855, 122773.38613306877, 124457.0, 13523.256220907944, 13538.120022198525, 13654.97587680928, 13659.931700341434, 13715.382807773674, 25083.996640134555, 25702.614460783105, 25831.095911643377, 25894.611580826302, 26190.083347083142, 7818.374530037583, 7959.801004974806, 7986.920130798618, 7995.1605081465605, 7997.600119994094, 3520.0, 3739.6447337502896, 3866.5940076292145, 4062.6343629073526, 4090.6318431341324, 4649.116667833101, 4843.685160464559, 4873.0, 4875.829345972945, 4886.65793394459, 948.4593781544592, 978.7944531648291, 989.3272574649163, 993.7515621094726, 996.4021587047713, 569.262804667956, 653.9934600654028, 660.1714847865993, 662.159057996342, 689.3761146162731, 9739.56171972263, 10232.846507302396, 11455.59905403307, 12090.730473300353, 12260.632181034583, 640.9038644203407, 659.2451642868984, 661.8841702702105, 678.5453745990199, 689.975850845218, 5461.617686761968, 5735.7418916148035, 5744.396838331915, 5819.621724587888, 5840.795572154954, 109252.4537377308, 109491.0, 110972.5610975552, 112243.87756122334, 115884.0, 97221.51389243008, 97553.0, 97565.0, 98112.99999999857, 99842.0, 66412.00381994274, 76782.84825727619, 77333.0, 78531.0, 79163.60418197872, 12608.432620532281, 12750.234985900875, 12946.0, 12993.545225917047, 13224.801627975781, 27826.0, 38034.42948355832, 38827.02932426712, 39177.216455671296, 40175.59824401836, 72285.55428891498, 72317.6384118097, 72547.63726181335, 73027.0, 73209.0, 16539.586510337336, 16591.585210369838, 16667.166641667627, 17482.650346993243, 17496.650066998332, 18403.355882544045, 18665.6266874659, 18779.0, 18872.622547549247, 18918.905405472884, 15831.366745330519, 16682.74975875387, 16734.414295500086, 16914.915425422794, 17976.0, 16932.984020958782, 17071.06109164008, 17366.913165434093, 17523.474295771146, 17647.558811029827, 16080.115593641995, 16523.752143717615, 17061.061641609333, 17350.525205357684, 17384.217710202818, 11689.649310520697, 11922.761544768882, 12008.819867701817, 12248.693782655504, 12257.754844902873, 13362.331883405985, 13517.188968661912, 13717.45130194821, 13909.791353129509, 14232.359543820347], "value": 8563.190361411462, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 114.0, 115.0, 115.0, 116.0, 115.0, 115.0, 114.0, 116.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 112.0, 110.0, 150.0, 112.0, 112.0, 118.0, 142.0, 153.0, 110.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 197.0, 202.0, 204.0, 208.0, 206.0, 213.0, 198.0, 227.0, 284.0], "value": 213.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 67.0, 85.0, 95.0, 59.0, 60.0, 59.0, 57.0, 79.0, 56.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 85.0, 70.0, 70.0, 68.0, 85.0, 70.0, 72.0, 90.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 88.0, 91.0, 89.0, 94.0, 90.0, 89.0, 85.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 97.0, 96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 97.0, 97.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 92.0, 93.0, 92.0, 93.0, 92.0, 93.0, 91.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 52.0, 52.0, 51.0, 51.0, 50.0, 48.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 61.0, 66.0, 67.0, 65.0, 57.0, 59.0, 57.0, 68.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 81.0, 83.0, 83.0, 83.0, 82.0, 87.0, 81.0, 84.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 135.0, 137.0, 138.0, 139.0, 149.0, 150.0, 187.0, 140.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 211.0, 230.0, 190.0, 183.0, 185.0, 194.0, 184.0, 183.0, 182.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 71.0, 66.0, 70.0, 67.0, 68.0, 68.0, 69.0, 66.0], "value": 68.0, "unit": "score"}]}]} 15:46:16 INFO - Return code: 0 15:46:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:46:16 INFO - # TBPL SUCCESS # 15:46:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:46:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:46:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:46:16 INFO - 2017-05-03 15:46:16.752 screenresolution[3519:63678] starting screenresolution argv=screenresolution get 15:46:16 INFO - 2017-05-03 15:46:16.755 screenresolution[3519:63678] Display 0: 1600x1200x32@60 15:46:16 INFO - 2017-05-03 15:46:16.761 screenresolution[3520:63681] starting screenresolution argv=screenresolution list 15:46:16 INFO - Available Modes on Display 0 15:46:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:46:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:46:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:46:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:46:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:46:16 INFO - Intel Iris: 15:46:16 INFO - Chipset Model: Intel Iris 15:46:16 INFO - Type: GPU 15:46:16 INFO - Bus: Built-In 15:46:16 INFO - VRAM (Dynamic, Max): 1536 MB 15:46:16 INFO - Vendor: Intel (0x8086) 15:46:16 INFO - Device ID: 0x0a2e 15:46:16 INFO - Revision ID: 0x0009 15:46:16 INFO - Displays: 15:46:16 INFO - Display: 15:46:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:46:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:46:16 INFO - Display Serial Number: 1600x1200 60 15:46:16 INFO - Main Display: Yes 15:46:16 INFO - Mirror: Off 15:46:16 INFO - Online: Yes 15:46:16 INFO - Rotation: Supported 15:46:17 INFO - Return code: 0 15:46:17 INFO - Running post-action listener: _resource_record_post_action 15:46:17 INFO - [mozharness: 2017-05-03 22:46:17.000657Z] Finished run-tests step (success) 15:46:17 INFO - Running post-run listener: _resource_record_post_run 15:46:17 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 0; Write bytes: 509307392; Read time: 0; Write time: 1671 15:46:17 INFO - TinderboxPrint: CPU usage
54.3% 15:46:17 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:46:17 INFO - TinderboxPrint: I/O write bytes / time
509,307,392 / 1,671 15:46:17 INFO - TinderboxPrint: CPU idle
2,189.1 (45.7%) 15:46:17 INFO - TinderboxPrint: CPU system
967.7 (20.2%) 15:46:17 INFO - TinderboxPrint: CPU user
1,628.9 (34.0%) 15:46:17 INFO - TinderboxPrint: Swap in / out
207,609,856 / 0 15:46:17 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207624704; Write bytes: 205121024; Read time: 16029; Write time: 436 15:46:17 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 0; Write bytes: 297014272; Read time: 0; Write time: 1208 15:46:17 INFO - Running post-run listener: _upload_blobber_files 15:46:17 INFO - Blob upload gear active. 15:46:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:46:17 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 15:46:17 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'] 15:46:17 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 15:46:17 INFO - (blobuploader) - INFO - Open directory for files ... 15:46:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:46:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:46:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:18 INFO - (blobuploader) - INFO - Done attempting. 15:46:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:46:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:46:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:19 INFO - (blobuploader) - INFO - Done attempting. 15:46:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:46:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:46:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:20 INFO - (blobuploader) - INFO - Done attempting. 15:46:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:46:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:46:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:22 INFO - (blobuploader) - INFO - Done attempting. 15:46:22 INFO - (blobuploader) - INFO - Iteration through files over. 15:46:22 INFO - Return code: 0 15:46:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:46:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:46:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f93da0f283e26a48824fd6f7d2b92b0eb6e6e0f82fc035407ee4d2a0dea53d705da1876aa5f3d369df4506be429e16509c51d8aa7bcbf103f7ddfe8bb6ea31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb1d196b175df11dd836ba7d7626f2b07fa628bbddb77ac203629598ab38b45c6b5e654dc7038df3ff5548eb5bb4e56738bc8b0eb4b8ce7819a4ef929ac80c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f153a88cb241fb858fb288b164bcfd51120970ed7eb3d500b81e86b593f1b2ec2c8a61b75d3d5a572843e11b589a2a036abb80770daafb02b9d8d7ad0b642c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368a0bb69e88752c03aa442223fcc1c7fdc049755cb9c1ecebbff71d6af4e0e4f586ad0846186e6819a88d4f20a966dceafc356c359daf31e67b31ebec499280"} 15:46:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:46:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:46:22 INFO - Contents: 15:46:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f93da0f283e26a48824fd6f7d2b92b0eb6e6e0f82fc035407ee4d2a0dea53d705da1876aa5f3d369df4506be429e16509c51d8aa7bcbf103f7ddfe8bb6ea31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb1d196b175df11dd836ba7d7626f2b07fa628bbddb77ac203629598ab38b45c6b5e654dc7038df3ff5548eb5bb4e56738bc8b0eb4b8ce7819a4ef929ac80c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f153a88cb241fb858fb288b164bcfd51120970ed7eb3d500b81e86b593f1b2ec2c8a61b75d3d5a572843e11b589a2a036abb80770daafb02b9d8d7ad0b642c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368a0bb69e88752c03aa442223fcc1c7fdc049755cb9c1ecebbff71d6af4e0e4f586ad0846186e6819a88d4f20a966dceafc356c359daf31e67b31ebec499280"} 15:46:22 INFO - Running post-run listener: copy_logs_to_upload_dir 15:46:22 INFO - Copying logs to upload dir... 15:46:22 INFO - mkdir: /builds/slave/test/build/upload/logs 15:46:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.257696 ========= master_lag: 1.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-03 15:46:23.930746) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 15:46:23.933784) ========= 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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f93da0f283e26a48824fd6f7d2b92b0eb6e6e0f82fc035407ee4d2a0dea53d705da1876aa5f3d369df4506be429e16509c51d8aa7bcbf103f7ddfe8bb6ea31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb1d196b175df11dd836ba7d7626f2b07fa628bbddb77ac203629598ab38b45c6b5e654dc7038df3ff5548eb5bb4e56738bc8b0eb4b8ce7819a4ef929ac80c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f153a88cb241fb858fb288b164bcfd51120970ed7eb3d500b81e86b593f1b2ec2c8a61b75d3d5a572843e11b589a2a036abb80770daafb02b9d8d7ad0b642c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368a0bb69e88752c03aa442223fcc1c7fdc049755cb9c1ecebbff71d6af4e0e4f586ad0846186e6819a88d4f20a966dceafc356c359daf31e67b31ebec499280"} build_url:https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/Z9jURjVYT_etjeaffsO2bQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f93da0f283e26a48824fd6f7d2b92b0eb6e6e0f82fc035407ee4d2a0dea53d705da1876aa5f3d369df4506be429e16509c51d8aa7bcbf103f7ddfe8bb6ea31", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb1d196b175df11dd836ba7d7626f2b07fa628bbddb77ac203629598ab38b45c6b5e654dc7038df3ff5548eb5bb4e56738bc8b0eb4b8ce7819a4ef929ac80c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f153a88cb241fb858fb288b164bcfd51120970ed7eb3d500b81e86b593f1b2ec2c8a61b75d3d5a572843e11b589a2a036abb80770daafb02b9d8d7ad0b642c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368a0bb69e88752c03aa442223fcc1c7fdc049755cb9c1ecebbff71d6af4e0e4f586ad0846186e6819a88d4f20a966dceafc356c359daf31e67b31ebec499280"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 15:46:23.970187) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:46:23.970540) ========= 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.Xq1XvyIgCo/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.sD7HYG9cSB/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:46:24.019109) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 15:46:24.019425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 15:46:24.019732) ========= ========= Total master_lag: 3.09 =========