builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0241 starttime: 1494217096.68 results: success (0) buildid: 20170507201314 builduid: b064a1ae9e444dda810059e2233eeaa1 revision: 22aaf8bad4df8e5f8c976f1521c213cb37e2dff5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:16.684261) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:16.684726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:16.685465) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:16.713105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:16.713503) ========= 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.PHvjkCixI6/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.l0GpsIjmok/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-07 21:18:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.0M=0.001s 2017-05-07 21:18:17 (21.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.647452 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:17.403723) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:17.404089) ========= 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.PHvjkCixI6/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.l0GpsIjmok/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.030980 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:17.475780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-07 21:18:17.476108) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22aaf8bad4df8e5f8c976f1521c213cb37e2dff5 --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 22aaf8bad4df8e5f8c976f1521c213cb37e2dff5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PHvjkCixI6/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.l0GpsIjmok/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-07 21:18:17,587 truncating revision to first 12 chars 2017-05-07 21:18:17,587 Setting DEBUG logging. 2017-05-07 21:18:17,587 attempt 1/10 2017-05-07 21:18:17,587 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22aaf8bad4df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-07 21:18:18,754 unpacking tar archive at: mozilla-central-22aaf8bad4df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.464323 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-07 21:18:18.966330) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:18.966652) ========= script_repo_revision: 22aaf8bad4df8e5f8c976f1521c213cb37e2dff5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:18.967080) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:18.967343) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 21:18:18.988261) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-07 21:18:18.988646) ========= /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.PHvjkCixI6/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.l0GpsIjmok/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:18:19 INFO - MultiFileLogger online at 20170507 21:18:19 in /builds/slave/test 21:18:19 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 21:18:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:18:19 INFO - {'append_to_log': False, 21:18:19 INFO - 'base_work_dir': '/builds/slave/test', 21:18:19 INFO - 'blob_upload_branch': 'Firefox', 21:18:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:18:19 INFO - 'branch': 'Firefox', 21:18:19 INFO - 'buildbot_json_path': 'buildprops.json', 21:18:19 INFO - 'config_files': ('talos/mac_config.py',), 21:18:19 INFO - 'default_actions': ('clobber', 21:18:19 INFO - 'read-buildbot-config', 21:18:19 INFO - 'download-and-extract', 21:18:19 INFO - 'populate-webroot', 21:18:19 INFO - 'create-virtualenv', 21:18:19 INFO - 'install', 21:18:19 INFO - 'run-tests'), 21:18:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:18:19 INFO - 'download_minidump_stackwalk': True, 21:18:19 INFO - 'download_symbols': 'ondemand', 21:18:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:18:19 INFO - 'tooltool.py': '/tools/tooltool.py', 21:18:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:18:19 INFO - '/tools/misc-python/virtualenv.py')}, 21:18:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:18:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:18:19 INFO - 'gecko_profile': False, 21:18:19 INFO - 'gecko_profile_interval': 0, 21:18:19 INFO - 'installer_path': 'installer.exe', 21:18:19 INFO - 'log_level': 'info', 21:18:19 INFO - 'log_name': 'talos', 21:18:19 INFO - 'log_to_console': True, 21:18:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:18:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:18:19 INFO - 'opt_config_files': (), 21:18:19 INFO - 'pip_index': False, 21:18:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:18:19 INFO - 'cmd': ('bash', 21:18:19 INFO - '-c', 21:18:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:18:19 INFO - 'enabled': True, 21:18:19 INFO - 'halt_on_failure': False, 21:18:19 INFO - 'name': 'check_screen_resolution'},), 21:18:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:18:19 INFO - 'cmd': ('bash', 21:18:19 INFO - '-c', 21:18:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:18:19 INFO - 'enabled': True, 21:18:19 INFO - 'halt_on_failure': False, 21:18:19 INFO - 'name': 'check_screen_resolution'},), 21:18:19 INFO - 'run_cmd_checks_enabled': True, 21:18:19 INFO - 'suite': 'dromaeojs-e10s', 21:18:19 INFO - 'system_bits': '32', 21:18:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:18:19 INFO - 'title': 't-yosemite-r7-0241', 21:18:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:18:19 INFO - 'use_talos_json': True, 21:18:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:18:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:18:19 INFO - 'work_dir': 'build'} 21:18:19 INFO - [mozharness: 2017-05-08 04:18:19.205516Z] Running clobber step. 21:18:19 INFO - Running pre-action listener: _resource_record_pre_action 21:18:19 INFO - Running main action method: clobber 21:18:19 INFO - rmtree: /builds/slave/test/build 21:18:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:18:21 INFO - Running post-action listener: _resource_record_post_action 21:18:21 INFO - [mozharness: 2017-05-08 04:18:21.597701Z] Finished clobber step (success) 21:18:21 INFO - [mozharness: 2017-05-08 04:18:21.597872Z] Running read-buildbot-config step. 21:18:21 INFO - Running pre-action listener: _resource_record_pre_action 21:18:21 INFO - Running main action method: read_buildbot_config 21:18:21 INFO - Using buildbot properties: 21:18:21 INFO - { 21:18:21 INFO - "project": "", 21:18:21 INFO - "product": "firefox", 21:18:21 INFO - "script_repo_revision": "production", 21:18:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:18:21 INFO - "repository": "", 21:18:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:18:21 INFO - "buildid": "20170507201314", 21:18:21 INFO - "pgo_build": "False", 21:18:21 INFO - "basedir": "/builds/slave/test", 21:18:21 INFO - "buildnumber": 63, 21:18:21 INFO - "slavename": "t-yosemite-r7-0241", 21:18:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 21:18:21 INFO - "platform": "yosemite_r7", 21:18:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:18:21 INFO - "branch": "mozilla-central", 21:18:21 INFO - "repo_path": "mozilla-central", 21:18:21 INFO - "revision": "22aaf8bad4df8e5f8c976f1521c213cb37e2dff5", 21:18:21 INFO - "stage_platform": "macosx64", 21:18:21 INFO - "builduid": "b064a1ae9e444dda810059e2233eeaa1", 21:18:21 INFO - "slavebuilddir": "test" 21:18:21 INFO - } 21:18:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 21:18:21 INFO - Running post-action listener: _resource_record_post_action 21:18:21 INFO - [mozharness: 2017-05-08 04:18:21.601657Z] Finished read-buildbot-config step (success) 21:18:21 INFO - [mozharness: 2017-05-08 04:18:21.601809Z] Running download-and-extract step. 21:18:21 INFO - Running pre-action listener: _resource_record_pre_action 21:18:21 INFO - Running main action method: download_and_extract 21:18:21 INFO - mkdir: /builds/slave/test/build/tests 21:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:21 INFO - trying https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 21:18:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/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 21:18:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/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 21:18:22 INFO - Downloaded 1325 bytes. 21:18:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 21:18:22 INFO - Using the following test package requirements: 21:18:22 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 21:18:22 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:18:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 21:18:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 21:18:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'jsshell-mac.zip'], 21:18:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 21:18:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:18:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 21:18:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 21:18:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 21:18:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:18:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 21:18:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:18:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:18:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:18:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:18:23 INFO - Content-Length response header: 52707184 21:18:23 INFO - Bytes received: 52707184 21:18:39 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 21:18:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:18:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:18:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:18:42 INFO - Content-Length response header: 52707184 21:18:42 INFO - Bytes received: 52707184 21:18:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 21:18:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:18:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 21:18:58 INFO - Content-Length response header: 14409362 21:18:58 INFO - Bytes received: 14409362 21:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:59 INFO - trying https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:18:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 21:18:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/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 21:19:01 INFO - Downloaded 70932734 bytes. 21:19:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:19:01 INFO - mkdir: /builds/slave/test/properties 21:19:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:19:01 INFO - Writing to file /builds/slave/test/properties/build_url 21:19:01 INFO - Contents: 21:19:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:19:01 INFO - Running post-action listener: _resource_record_post_action 21:19:01 INFO - Running post-action listener: set_extra_try_arguments 21:19:01 INFO - [mozharness: 2017-05-08 04:19:01.704212Z] Finished download-and-extract step (success) 21:19:01 INFO - [mozharness: 2017-05-08 04:19:01.704312Z] Running populate-webroot step. 21:19:01 INFO - Running pre-action listener: _resource_record_pre_action 21:19:01 INFO - Running main action method: populate_webroot 21:19:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:19:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:19:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:19:01 INFO - '--tppagecycles', 21:19:01 INFO - '7'], 21:19:01 INFO - 'tests': ['tsvgm']}}, 21:19:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:19:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:19:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:19:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:19:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:19:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:19:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['damp', 'tps']}, 21:19:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'tests': ['damp', 'tps']}, 21:19:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['dromaeo_dom']}, 21:19:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:19:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 21:19:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:19:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['a11yr', 21:19:01 INFO - 'ts_paint', 21:19:01 INFO - 'tpaint', 21:19:01 INFO - 'sessionrestore', 21:19:01 INFO - 'sessionrestore_no_auto_restore', 21:19:01 INFO - 'tabpaint']}, 21:19:01 INFO - 'other-e10s': {'tests': ['a11yr', 21:19:01 INFO - 'ts_paint', 21:19:01 INFO - 'tpaint', 21:19:01 INFO - 'sessionrestore', 21:19:01 INFO - 'sessionrestore_no_auto_restore', 21:19:01 INFO - 'tabpaint']}, 21:19:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:19:01 INFO - 'ts_paint', 21:19:01 INFO - 'tpaint', 21:19:01 INFO - 'sessionrestore', 21:19:01 INFO - 'sessionrestore_no_auto_restore', 21:19:01 INFO - 'tabpaint']}, 21:19:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:19:01 INFO - 'ts_paint', 21:19:01 INFO - 'tpaint', 21:19:01 INFO - 'sessionrestore', 21:19:01 INFO - 'sessionrestore_no_auto_restore', 21:19:01 INFO - 'tabpaint']}, 21:19:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['a11yr', 21:19:01 INFO - 'ts_paint', 21:19:01 INFO - 'tpaint', 21:19:01 INFO - 'sessionrestore', 21:19:01 INFO - 'sessionrestore_no_auto_restore', 21:19:01 INFO - 'tabpaint']}, 21:19:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['a11yr', 21:19:01 INFO - 'ts_paint', 21:19:01 INFO - 'tpaint', 21:19:01 INFO - 'sessionrestore', 21:19:01 INFO - 'sessionrestore_no_auto_restore', 21:19:01 INFO - 'tabpaint']}, 21:19:01 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 21:19:01 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 21:19:01 INFO - 'bloom_basic_ref']}, 21:19:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['tsvgx', 21:19:01 INFO - 'tsvgr_opacity', 21:19:01 INFO - 'tart', 21:19:01 INFO - 'tscrollx', 21:19:01 INFO - 'cart', 21:19:01 INFO - 'tsvg_static']}, 21:19:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:19:01 INFO - 'tsvgr_opacity', 21:19:01 INFO - 'tart', 21:19:01 INFO - 'tscrollx', 21:19:01 INFO - 'cart', 21:19:01 INFO - 'tsvg_static']}, 21:19:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'talos_options': ['--disable-e10s'], 21:19:01 INFO - 'tests': ['tp5o']}, 21:19:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:19:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'talos_options': ['--disable-e10s', 21:19:01 INFO - '--xperf_path', 21:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:19:01 INFO - 'tests': ['tp5n']}, 21:19:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:19:01 INFO - 'talos_options': ['--xperf_path', 21:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:19:01 INFO - 'tests': ['tp5n']}}, 21:19:01 INFO - 'talos.zip': {'path': '', 21:19:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:19:01 INFO - Running post-action listener: _resource_record_post_action 21:19:01 INFO - [mozharness: 2017-05-08 04:19:01.723823Z] Finished populate-webroot step (success) 21:19:01 INFO - [mozharness: 2017-05-08 04:19:01.723957Z] Running create-virtualenv step. 21:19:01 INFO - Running pre-action listener: _resource_record_pre_action 21:19:01 INFO - Running main action method: create_virtualenv 21:19:01 INFO - Creating virtualenv /builds/slave/test/build/venv 21:19:01 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 21:19:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:19:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:19:01 INFO - Using real prefix '/tools/python27' 21:19:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:19:02 INFO - Installing distribute.............................................................................................................................................................................................done. 21:19:05 INFO - Installing pip.................done. 21:19:05 INFO - Return code: 0 21:19:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:19:05 INFO - Reading from file tmpfile_stdout 21:19:05 INFO - Output received: 21:19:05 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) 21:19:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:19:05 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')]} 21:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:05 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')]} 21:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:05 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:05 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 21:19:05 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 21:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:05 INFO - 'HOME': '/Users/cltbld', 21:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:05 INFO - 'LOGNAME': 'cltbld', 21:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:05 INFO - 'MOZ_NO_REMOTE': '1', 21:19:05 INFO - 'NO_EM_RESTART': '1', 21:19:05 INFO - 'PAGER': '/bin/cat', 21:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:05 INFO - 'PWD': '/builds/slave/test', 21:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:05 INFO - 'SHELL': '/bin/bash', 21:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:05 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:05 INFO - 'USER': 'cltbld', 21:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:05 INFO - 'XPC_FLAGS': '0x0', 21:19:05 INFO - 'XPC_SERVICE_NAME': '0', 21:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:06 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 21:19:06 INFO - Cleaning up... 21:19:06 INFO - Return code: 0 21:19:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:19:06 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')]} 21:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:06 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')]} 21:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:06 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:06 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 21:19:06 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 21:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:06 INFO - 'HOME': '/Users/cltbld', 21:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:06 INFO - 'LOGNAME': 'cltbld', 21:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:06 INFO - 'MOZ_NO_REMOTE': '1', 21:19:06 INFO - 'NO_EM_RESTART': '1', 21:19:06 INFO - 'PAGER': '/bin/cat', 21:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:06 INFO - 'PWD': '/builds/slave/test', 21:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:06 INFO - 'SHELL': '/bin/bash', 21:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:06 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:06 INFO - 'USER': 'cltbld', 21:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:06 INFO - 'XPC_FLAGS': '0x0', 21:19:06 INFO - 'XPC_SERVICE_NAME': '0', 21:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:06 INFO - Downloading/unpacking psutil>=3.1.1 21:19: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 21:19: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 21:19: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 21:19: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 21:19: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 21:19: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 21:19:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:19:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:19:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:19:09 INFO - Installing collected packages: psutil 21:19:09 INFO - Running setup.py install for psutil 21:19:09 INFO - building 'psutil._psutil_osx' extension 21:19:09 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 21:19:09 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 21:19:09 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 21:19:09 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 21:19:10 INFO - building 'psutil._psutil_posix' extension 21:19:10 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 21:19:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:19:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:19:10 INFO - ^ 21:19:10 INFO - 1 warning generated. 21:19:10 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 21:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:19:10 INFO - Successfully installed psutil 21:19:10 INFO - Cleaning up... 21:19:10 INFO - Return code: 0 21:19:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:19:10 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')]} 21:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:10 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')]} 21:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:10 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:10 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 21:19:10 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 21:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:10 INFO - 'HOME': '/Users/cltbld', 21:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:10 INFO - 'LOGNAME': 'cltbld', 21:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:10 INFO - 'MOZ_NO_REMOTE': '1', 21:19:10 INFO - 'NO_EM_RESTART': '1', 21:19:10 INFO - 'PAGER': '/bin/cat', 21:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:10 INFO - 'PWD': '/builds/slave/test', 21:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:10 INFO - 'SHELL': '/bin/bash', 21:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:10 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:10 INFO - 'USER': 'cltbld', 21:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:10 INFO - 'XPC_FLAGS': '0x0', 21:19:10 INFO - 'XPC_SERVICE_NAME': '0', 21:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:19:12 INFO - Installing collected packages: mozsystemmonitor 21:19:12 INFO - Running setup.py install for mozsystemmonitor 21:19:12 INFO - Successfully installed mozsystemmonitor 21:19:12 INFO - Cleaning up... 21:19:12 INFO - Return code: 0 21:19:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:12 INFO - 'HOME': '/Users/cltbld', 21:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:12 INFO - 'LOGNAME': 'cltbld', 21:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:12 INFO - 'MOZ_NO_REMOTE': '1', 21:19:12 INFO - 'NO_EM_RESTART': '1', 21:19:12 INFO - 'PAGER': '/bin/cat', 21:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:12 INFO - 'PWD': '/builds/slave/test', 21:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:12 INFO - 'SHELL': '/bin/bash', 21:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:12 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:12 INFO - 'USER': 'cltbld', 21:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:12 INFO - 'XPC_FLAGS': '0x0', 21:19:12 INFO - 'XPC_SERVICE_NAME': '0', 21:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:12 INFO - Downloading/unpacking jsonschema==2.5.1 21:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:19:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:19:13 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 21:19:13 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 21:19:13 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 21:19:13 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 21:19:13 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 21:19:13 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 21:19:14 INFO - Downloading functools32-3.2.3-2.tar.gz 21:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:19:14 INFO - warning: no files found matching '*.txt' 21:19:14 INFO - no previously-included directories found matching 'build' 21:19:14 INFO - no previously-included directories found matching 'dist' 21:19:14 INFO - no previously-included directories found matching '.git*' 21:19:14 INFO - Installing collected packages: jsonschema, functools32 21:19:14 INFO - Running setup.py install for functools32 21:19:14 INFO - warning: no files found matching '*.txt' 21:19:14 INFO - no previously-included directories found matching 'build' 21:19:14 INFO - no previously-included directories found matching 'dist' 21:19:14 INFO - no previously-included directories found matching '.git*' 21:19:14 INFO - Successfully installed jsonschema functools32 21:19:14 INFO - Cleaning up... 21:19:14 INFO - Return code: 0 21:19:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:19:14 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')]} 21:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:14 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')]} 21:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:14 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:14 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 21:19:14 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 21:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:14 INFO - 'HOME': '/Users/cltbld', 21:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:14 INFO - 'LOGNAME': 'cltbld', 21:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:14 INFO - 'MOZ_NO_REMOTE': '1', 21:19:14 INFO - 'NO_EM_RESTART': '1', 21:19:14 INFO - 'PAGER': '/bin/cat', 21:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:14 INFO - 'PWD': '/builds/slave/test', 21:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:14 INFO - 'SHELL': '/bin/bash', 21:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:14 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:14 INFO - 'USER': 'cltbld', 21:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:14 INFO - 'XPC_FLAGS': '0x0', 21:19:14 INFO - 'XPC_SERVICE_NAME': '0', 21:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:19:14 INFO - Cleaning up... 21:19:14 INFO - Return code: 0 21:19:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:19:14 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')]} 21:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:14 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')]} 21:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:14 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:14 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 21:19:14 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 21:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:14 INFO - 'HOME': '/Users/cltbld', 21:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:14 INFO - 'LOGNAME': 'cltbld', 21:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:14 INFO - 'MOZ_NO_REMOTE': '1', 21:19:14 INFO - 'NO_EM_RESTART': '1', 21:19:14 INFO - 'PAGER': '/bin/cat', 21:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:14 INFO - 'PWD': '/builds/slave/test', 21:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:14 INFO - 'SHELL': '/bin/bash', 21:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:14 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:14 INFO - 'USER': 'cltbld', 21:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:14 INFO - 'XPC_FLAGS': '0x0', 21:19:14 INFO - 'XPC_SERVICE_NAME': '0', 21:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:14 INFO - Downloading/unpacking blobuploader==1.2.4 21:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:16 INFO - Downloading blobuploader-1.2.4.tar.gz 21:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:19:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:19:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 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 21:19:16 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 21:19:17 INFO - Downloading docopt-0.6.1.tar.gz 21:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:19:17 INFO - Installing collected packages: blobuploader, requests, docopt 21:19:17 INFO - Running setup.py install for blobuploader 21:19:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:19:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:19:17 INFO - Running setup.py install for requests 21:19:17 INFO - Running setup.py install for docopt 21:19:18 INFO - Successfully installed blobuploader requests docopt 21:19:18 INFO - Cleaning up... 21:19:18 INFO - Return code: 0 21:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:18 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:18 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 21:19:18 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 21:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:18 INFO - 'HOME': '/Users/cltbld', 21:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:18 INFO - 'LOGNAME': 'cltbld', 21:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:18 INFO - 'MOZ_NO_REMOTE': '1', 21:19:18 INFO - 'NO_EM_RESTART': '1', 21:19:18 INFO - 'PAGER': '/bin/cat', 21:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:18 INFO - 'PWD': '/builds/slave/test', 21:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:18 INFO - 'SHELL': '/bin/bash', 21:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:18 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:18 INFO - 'USER': 'cltbld', 21:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:18 INFO - 'XPC_FLAGS': '0x0', 21:19:18 INFO - 'XPC_SERVICE_NAME': '0', 21:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:19:18 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-s3e34q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:19:18 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-zoJLb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:19:18 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-h1uEdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:19:18 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-eZi2Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:19:18 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Lyy45e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:19:18 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-IGC4bP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-asivhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-xWjpaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Jc1KlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-2WmQYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-GRmwc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-JDTHpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-4d0sSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-ksBdwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-QpWYTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:19:19 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-gUFCRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:19:20 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-qJ_Fdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:19:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:19:20 INFO - Running setup.py install for manifestparser 21:19:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:19:20 INFO - Running setup.py install for mozcrash 21:19:20 INFO - Running setup.py install for mozdebug 21:19:20 INFO - Running setup.py install for mozdevice 21:19:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:19:20 INFO - Running setup.py install for mozfile 21:19:20 INFO - Running setup.py install for mozhttpd 21:19:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Running setup.py install for mozinfo 21:19:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Running setup.py install for mozInstall 21:19:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Running setup.py install for mozleak 21:19:21 INFO - Running setup.py install for mozlog 21:19:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Running setup.py install for moznetwork 21:19:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:19:21 INFO - Running setup.py install for mozprocess 21:19:21 INFO - Running setup.py install for mozprofile 21:19:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:19:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:19:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:19:22 INFO - Running setup.py install for mozrunner 21:19:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:19:22 INFO - Running setup.py install for mozscreenshot 21:19:22 INFO - Running setup.py install for moztest 21:19:22 INFO - Running setup.py install for mozversion 21:19:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:19:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:19:22 INFO - Cleaning up... 21:19:22 INFO - Return code: 0 21:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:19:22 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')]} 21:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:22 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')]} 21:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:22 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:22 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 21:19:22 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 21:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:22 INFO - 'HOME': '/Users/cltbld', 21:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:22 INFO - 'LOGNAME': 'cltbld', 21:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:22 INFO - 'MOZ_NO_REMOTE': '1', 21:19:22 INFO - 'NO_EM_RESTART': '1', 21:19:22 INFO - 'PAGER': '/bin/cat', 21:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:22 INFO - 'PWD': '/builds/slave/test', 21:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:22 INFO - 'SHELL': '/bin/bash', 21:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:22 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:22 INFO - 'USER': 'cltbld', 21:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:22 INFO - 'XPC_FLAGS': '0x0', 21:19:22 INFO - 'XPC_SERVICE_NAME': '0', 21:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-BZkhne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-g1UctY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-89XV0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-jLp7Gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-pSNSEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-3WbVr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-N6Ymg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-tD2ejc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-RTFzhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:19:23 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)) 21:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:19:23 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-6GhJsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-W_HXNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-9UobFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-juKJkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-mBAKr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-1iHgLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Mb2fz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:19:24 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)) 21:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:19:24 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-OklTQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:19:26 INFO - Downloading blessings-1.6.tar.gz 21:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:19:26 INFO - Installing collected packages: blessings 21:19:26 INFO - Running setup.py install for blessings 21:19:26 INFO - Successfully installed blessings 21:19:26 INFO - Cleaning up... 21:19:26 INFO - Return code: 0 21:19:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:19:26 INFO - Reading from file tmpfile_stdout 21:19:26 INFO - Current package versions: 21:19:26 INFO - blessings == 1.6 21:19:26 INFO - blobuploader == 1.2.4 21:19:26 INFO - docopt == 0.6.1 21:19:26 INFO - functools32 == 3.2.3-2 21:19:26 INFO - jsonschema == 2.5.1 21:19:26 INFO - manifestparser == 1.1 21:19:26 INFO - mozInstall == 1.12 21:19:26 INFO - mozcrash == 1.0 21:19:26 INFO - mozdebug == 0.1 21:19:26 INFO - mozdevice == 0.50 21:19:26 INFO - mozfile == 1.2 21:19:26 INFO - mozhttpd == 0.7 21:19:26 INFO - mozinfo == 0.9 21:19:26 INFO - mozleak == 0.1 21:19:26 INFO - mozlog == 3.4 21:19:26 INFO - moznetwork == 0.27 21:19:26 INFO - mozprocess == 0.25 21:19:26 INFO - mozprofile == 0.28 21:19:26 INFO - mozrunner == 6.13 21:19:26 INFO - mozscreenshot == 0.1 21:19:26 INFO - mozsystemmonitor == 0.3 21:19:26 INFO - moztest == 0.8 21:19:26 INFO - mozversion == 1.4 21:19:26 INFO - psutil == 3.1.1 21:19:26 INFO - requests == 1.2.3 21:19:26 INFO - wsgiref == 0.1.2 21:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:26 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:26 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 21:19:26 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 21:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:26 INFO - 'HOME': '/Users/cltbld', 21:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:26 INFO - 'LOGNAME': 'cltbld', 21:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:26 INFO - 'MOZ_NO_REMOTE': '1', 21:19:26 INFO - 'NO_EM_RESTART': '1', 21:19:26 INFO - 'PAGER': '/bin/cat', 21:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:26 INFO - 'PWD': '/builds/slave/test', 21:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:26 INFO - 'SHELL': '/bin/bash', 21:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:26 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:26 INFO - 'USER': 'cltbld', 21:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:26 INFO - 'XPC_FLAGS': '0x0', 21:19:26 INFO - 'XPC_SERVICE_NAME': '0', 21:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 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)) 21:19:26 INFO - Cleaning up... 21:19:27 INFO - Return code: 0 21:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:19:27 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')]} 21:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:27 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')]} 21:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:27 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 0x1101f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d94c41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, '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.PHvjkCixI6/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.l0GpsIjmok/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 21:19:27 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 21:19:27 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 21:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:27 INFO - 'HOME': '/Users/cltbld', 21:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:27 INFO - 'LOGNAME': 'cltbld', 21:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:27 INFO - 'MOZ_NO_REMOTE': '1', 21:19:27 INFO - 'NO_EM_RESTART': '1', 21:19:27 INFO - 'PAGER': '/bin/cat', 21:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:27 INFO - 'PWD': '/builds/slave/test', 21:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:27 INFO - 'SHELL': '/bin/bash', 21:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:27 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:27 INFO - 'USER': 'cltbld', 21:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:27 INFO - 'XPC_FLAGS': '0x0', 21:19:27 INFO - 'XPC_SERVICE_NAME': '0', 21:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:19:27 INFO - Cleaning up... 21:19:27 INFO - Return code: 0 21:19:27 INFO - Running post-action listener: _resource_record_post_action 21:19:27 INFO - Running post-action listener: _start_resource_monitoring 21:19:27 INFO - Starting resource monitoring. 21:19:27 INFO - [mozharness: 2017-05-08 04:19:27.299349Z] Finished create-virtualenv step (success) 21:19:27 INFO - [mozharness: 2017-05-08 04:19:27.300328Z] Running install step. 21:19:27 INFO - Running pre-action listener: _resource_record_pre_action 21:19:27 INFO - Running main action method: install 21:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:19:27 INFO - Reading from file tmpfile_stdout 21:19:27 INFO - Detecting whether we're running mozinstall >=1.0... 21:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:19:27 INFO - Reading from file tmpfile_stdout 21:19:27 INFO - Output received: 21:19:27 INFO - Usage: mozinstall [options] installer 21:19:27 INFO - Options: 21:19:27 INFO - -h, --help show this help message and exit 21:19:27 INFO - -d DEST, --destination=DEST 21:19:27 INFO - Directory to install application into. [default: 21:19:27 INFO - "/builds/slave/test"] 21:19:27 INFO - --app=APP Application being installed. [default: firefox] 21:19:27 INFO - mkdir: /builds/slave/test/build/application 21:19:27 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'] 21:19:27 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 21:19:47 INFO - Reading from file tmpfile_stdout 21:19:47 INFO - Output received: 21:19:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:19:47 INFO - Running post-action listener: _resource_record_post_action 21:19:47 INFO - [mozharness: 2017-05-08 04:19:47.268592Z] Finished install step (success) 21:19:47 INFO - [mozharness: 2017-05-08 04:19:47.268750Z] Running run-tests step. 21:19:47 INFO - Running pre-action listener: _resource_record_pre_action 21:19:47 INFO - Running main action method: run_tests 21:19:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:19:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:19:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:19:47 INFO - 2017-05-07 21:19:47.283 screenresolution[1628:8203] starting screenresolution argv=screenresolution get 21:19:47 INFO - 2017-05-07 21:19:47.286 screenresolution[1628:8203] Display 0: 1600x1200x32@60 21:19:47 INFO - 2017-05-07 21:19:47.292 screenresolution[1629:8206] starting screenresolution argv=screenresolution list 21:19:47 INFO - Available Modes on Display 0 21:19:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:19:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:19:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:19:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:19:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:19:47 INFO - Intel Iris: 21:19:47 INFO - Chipset Model: Intel Iris 21:19:47 INFO - Type: GPU 21:19:47 INFO - Bus: Built-In 21:19:47 INFO - VRAM (Dynamic, Max): 1536 MB 21:19:47 INFO - Vendor: Intel (0x8086) 21:19:47 INFO - Device ID: 0x0a2e 21:19:47 INFO - Revision ID: 0x0009 21:19:47 INFO - Displays: 21:19:47 INFO - Display: 21:19:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:19:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:19:47 INFO - Display Serial Number: 1600x1200 60 21:19:47 INFO - Main Display: Yes 21:19:47 INFO - Mirror: Off 21:19:47 INFO - Online: Yes 21:19:47 INFO - Rotation: Supported 21:19:47 INFO - Return code: 0 21:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:19:47 INFO - Python 2.7.3 21:19:47 INFO - Return code: 0 21:19:47 INFO - grabbing minidump binary from tooltool 21:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:47 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 0x10ff0ad30>, '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 0x10febddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:19:47 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 21:19:47 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 21:19:47 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 21:19:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:19:47 INFO - Return code: 0 21:19:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:19:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:19:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:19:47 INFO - ENV: RUST_BACKTRACE is now 1 21:19:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:19:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:19:47 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/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 21:19:47 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/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0241 --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 21:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PHvjkCixI6/Render', 21:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:47 INFO - 'HOME': '/Users/cltbld', 21:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:47 INFO - 'LOGNAME': 'cltbld', 21:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:47 INFO - 'MOZ_NO_REMOTE': '1', 21:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:19:47 INFO - 'NO_EM_RESTART': '1', 21:19:47 INFO - 'PAGER': '/bin/cat', 21:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:47 INFO - 'PWD': '/builds/slave/test', 21:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:47 INFO - 'RUST_BACKTRACE': '1', 21:19:47 INFO - 'SHELL': '/bin/bash', 21:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0GpsIjmok/Listeners', 21:19:47 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 21:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:47 INFO - 'USER': 'cltbld', 21:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:47 INFO - 'XPC_FLAGS': '0x0', 21:19:47 INFO - 'XPC_SERVICE_NAME': '0', 21:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:47 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/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 21:19:47 INFO - mozversion application_buildid: 20170507201314 21:19:47 INFO - mozversion application_changeset: 22aaf8bad4df8e5f8c976f1521c213cb37e2dff5 21:19:47 INFO - mozversion application_display_name: Nightly 21:19:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:19:47 INFO - mozversion application_name: Firefox 21:19:47 INFO - mozversion application_remotingname: firefox 21:19:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:19:47 INFO - mozversion application_vendor: Mozilla 21:19:47 INFO - mozversion application_version: 55.0a1 21:19:47 INFO - mozversion platform_buildid: 20170507201314 21:19:47 INFO - mozversion platform_changeset: 22aaf8bad4df8e5f8c976f1521c213cb37e2dff5 21:19:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:19:47 INFO - mozversion platform_version: 55.0a1 21:19:47 INFO - using testdate: 1494217187 21:19:47 INFO - actual date: 1494217187 21:19:47 INFO - starting webserver on 'localhost:49241' 21:19:47 INFO - SUITE-START | Running 2 tests 21:19:47 INFO - TEST-START | dromaeo_css 21:19:47 INFO - operating with platform_type : mac_ 21:19:47 INFO - Initialising browser for dromaeo_css test... 21:19:47 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmp0nhwlx/profile http://localhost:49241/getInfo.html) 21:19:50 INFO - PID 1639 | 2017-05-07 21:19:50.588 plugin-container[1641:8396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 21:19:50 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:50 INFO - PID 1639 | 2017-05-07 21:19:50.590 plugin-container[1641:8396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 21:19:50 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:50 INFO - PID 1639 | 2017-05-07 21:19:50.590 plugin-container[1641:8396] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:19:50 INFO - PID 1639 | __metrics Screen width/height:1600/1200 21:19:50 INFO - PID 1639 | colorDepth:24 21:19:50 INFO - PID 1639 | Browser inner width/height: 1024/691 21:19:50 INFO - PID 1639 | __metrics 21:19:51 INFO - PID 1639 | 2017-05-07 21:19:51.183 plugin-container[1643:8454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x944f, name = 'com.apple.tsm.portname' 21:19:51 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:51 INFO - PID 1639 | 2017-05-07 21:19:51.183 plugin-container[1643:8454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 21:19:51 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:51 INFO - PID 1639 | 2017-05-07 21:19:51.183 plugin-container[1643:8454] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:19:51 INFO - TEST-INFO | 1639: exit 0 21:19:51 INFO - Browser initialized. 21:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 21:19:51 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmp0nhwlx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:19:54 INFO - PID 1644 | 2017-05-07 21:19:53.999 plugin-container[1645:8570] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 21:19:54 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:54 INFO - PID 1644 | 2017-05-07 21:19:54.000 plugin-container[1645:8570] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 21:19:54 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:54 INFO - PID 1644 | 2017-05-07 21:19:54.000 plugin-container[1645:8570] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:19:54 INFO - PID 1644 | 2017-05-07 21:19:54.496 plugin-container[1646:8613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 21:19:54 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:54 INFO - PID 1644 | 2017-05-07 21:19:54.496 plugin-container[1646:8613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 21:19:54 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:19:54 INFO - PID 1644 | 2017-05-07 21:19:54.496 plugin-container[1646:8613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:20:03 INFO - PID 1644 | RSS: Main: 232677376 21:20:03 INFO - PID 1644 | 21:23:07 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 21:23:09 INFO - PID 1644 | RSS: Main: 250015744 21:23:09 INFO - PID 1644 | 21:26:11 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 21:26:11 INFO - PID 1644 | RSS: Main: 255393792 21:26:11 INFO - PID 1644 | 21:29:13 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 21:29:14 INFO - PID 1644 | RSS: Main: 263835648 21:29:14 INFO - PID 1644 | 21:32:15 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 21:32:16 INFO - PID 1644 | RSS: Main: 258551808 21:32:16 INFO - PID 1644 | 21:35:19 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 21:35:19 INFO - PID 1644 | RSS: Main: 265248768 21:35:19 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 21:38:21 INFO - PID 1644 | RSS: Main: 263938048 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | __start_tp_report 21:38:21 INFO - PID 1644 | _x_x_mozilla_page_load 21:38:21 INFO - PID 1644 | _x_x_mozilla_page_load_details 21:38:21 INFO - PID 1644 | |i|pagename|runs| 21:38:21 INFO - PID 1644 | |0;dojo.html;3568.4825700273477;3594.910127246817;3596.892093237203;3618.6562276583722;3620.0587847159745;4850.563449289563;6138.48036155709;6188.319284878659;6228.781992630259;6273.717682704283;6157.753689852411;6199.070139479069;6235.875282494348;6258.217722784652;6316.74733010679;5195.68825870449;5512.641678290908;5525.198846167324;5542.778288868461;5547.472990065938;4699.647526435531;5596.384397716248;5629.60592758507;5649.745761440725;5674.829755107333;5518.862028449281;5539.41836107211;5551.583631227633;5587.720613969285;5629.268195134648;5556.083246264361;5570.526505247059;5599.804006859781;5611.3546942101775;5619.044762390404;5482.205080263379;5485.341758988947;5512.366077901012;5534.446555344473;5582.330120385539;5996.670183139926;6141.578063794062;6162.198914141178;6202.41077097675;6291.748330066792;6730;6781.609769997188;6786.4910131739825;6798.456123510156;6800.761973330909;6213.875722485525;6447.387498187664;6883.65581720912;6889.242183359826;6908.447324214101;6217.129601855744;6260.530460215502;6263.966445536526;6323.810285691437;6373.29893711696;6950.582965022115;7027.472939529555;7037.1555413350725;7038.190608080034;7084.504084714124;6432.774852880127;6501.512386571026;6504.1869766279215;6589.308122647149;6653;5827.271591051119;6752.898706519308;6846.281140480278;6852.177738671391;6858.731134740089;6476.740930362733;6581.440577550866;6746.797596072028;6756.324367563205;6759.425448836806;6583.769568065095;6638.900416493757;6697.3637504437;6738.898916516257;6765.458763298924;4538.931916021263;5374.140137577969;5393.487618676298;5395.946040539544;5406.702631355313;4766.451858036301;4805.711657300573;4807.95192048082;4817.78319975602;4881.926771098437;6135.662538560422;6274.403931626488;6296.433321001132;6300.086487459384;6341.175647165931;6833.965830170818;6852.28051054642;6854.931450685529;6869.210040845367;6873.243943166248;6244.2819075805955;6252.374762523711;6349.396807303298;6352.047192921099;6360;5857.560682948796;5914.112883067488;5977.910331345033;6053.939460605426;6061.181740464981;6913.51605387618;6916.55042422251;7008.894866577108;7039.366457018893;7060;6866.965665171642;6892.345227203407;6896.27589103137;6913.5506192097355;6918.7232510699005;6964.79105626832;7023.859522809413;7082.433405327664;7084.610346430995;7131.108611423572;6490.8052758417325;6606.537542371988;6652.90020649681;6700.497462690415;6777.254502004874;6836.794896153124;6870.553414028072;6874.759383421556;6885.173779146535;6886.586804791728;6348.301686814354;6408.9038664421005;6473.417392434703;6561.901571476336;6676.866462670817;6637.369449902251;6640.468762498892;6656.73373065072;6669.46644268467;6687;5753.712314384347;6299.433051025247;6391.552591318563;6407.743690252338;6509.7396104155305;6620.569662971891;6627.9337206627315;6635.734570617121;6687.531872769054;6711.4966377520905;6192.721327540181;6515.576487528296;6530.542861999805;6530.738770449128;6555.836104097245 21:38:21 INFO - PID 1644 | |1;ext.html;823.7281697039978;852.1776485691306;1087.6247694545384;1177.7644471105782;1276.9847970863163;37327;38112.23775524488;38150.4277435838;38177.236455270875;38240.42639360412;37755;38020.80989595048;38111.23777524448;38166.61833381665;38196;7720.922790772091;7906;8530.616122274512;8549.187827156442;8555.529445880475;8455.408121431503;8531.146885311451;8537.018242902084;8552.657893684245;8581.013183483885;7431.777046688608;8432.40973131881;8497.320214382835;8548.914510854906;8561.272291855199;8516.8722469163;8549.102344253888;8553.871691924625;8559.186877246651;8559.186877246682;8166.469179503372;8205.343572514184;8215.465994710385;8252.381071419608;8257.793555161108;36783.1608419578;36925.446118308246;37050.44424333637;37108.62891371079;37152.62847371519;3567.5362202913716;3597.3524765542193;3600.08197909534;3608.4948107265004;3615.5480564929385;30267.243318916982;30531.93138240173;30668.07995760131;30670.69329306701;30673;3162.383335249627;3184.1561986073707;3200.311932934407;3205.3749518843833;3210.1493104327374;288419.9999999979;289175;290202;290488;292731;38165.236695266496;38889.61110388916;46994;47302.763486182346;47871.521284787406;49116.75441622769;49412.01175976532;49643.76603052871;49758;49954.250686239735;14042.36809343582;14125.576232713034;14211.43154273838;14238.644033899236;14250.287485625677;11330.09359251258;11467.77064458706;11474.48364823585;11525.942370288094;11653.30080195191;12030.4586293617;12270.72991496576;13195;13233.55179291834;13320.134191277535;5864.709763851945;5880.588358814842;5894.498967587718;5906.527477801766;5913.083472061838;997.9970130019284;999.3703966501058;1001.8948010458797;1007.7279134633608;1105.0165352835982;23521.41332440192;24572.139975101145;24650.630240546405;24772.876135619208;24798.132065377624;1059.3485006720812;1163.4822503985645;1193.8806119388166;1195.7847587434346;1214.860291066521;12568.434420451102;12686.936565317113;12688.49246030167;12730.363481825872;12739.108262421541;321601;323357;324264.37867810985;324291;325125;54367.45632543703;54376.91246175134;54569.727151363986;54625;55184;1537.1852917953502;1562.3984765864996;1602.5512856400226;1605.6868910562562;1607.9919600401922;24317;24334.026638934243;24452.777361131517;24497.632535511624;24540.754592453846;38533.843984680505;38563.42154867623;38646.61353386543;38698.80650596729;38701.032474188374;271420;271681;272578;273039;273083;33186.33627327488;33731;33785.32429351448;33822;33878.98363049112;42535.361969569865;43067.56932430761;44666.55333446754;44718;44783.656490305344;25584.7207639621;28776;29896.850515747283;30201.697983019887;30948.381032379675;29484.11547653574;30054;30391;30545.08364749061;30565.930192443157;30193.69806301909;35501.82249088738;36198.27603447864;36865;37011.44482832706;29887.850560747058;29977.400451991274;30646.540301895042;30749.23126921845;30880.845595771876;19594.90202548978;22702.175222674974;23903.402414939766;24352.878235608707;24450.51098977975 21:38:21 INFO - PID 1644 | |2;jquery.html;370.9109813644726;394.9722136093688;439.7141857792433;446.49769009863905;448.67919437602114;20684.896575517138;21630.783692163095;21751.67372489414;22568.98439570219;22738.88630556845;19433.319833805785;21061.894690526526;22693;22766.31701048971;22940.426489337773;5918.437748413905;6164.352742961992;6165.352637973016;6174.475169610566;6177.227846519185;6033.637981721111;6526.738930442777;6831.316868313179;6915.757948471804;6918.2044064932425;6724.755920154763;6782.660866956657;6866.107406037208;6890.2076261230095;6934.271901450351;6887.173539175306;6903.068085808427;6907.930920690779;6910.3089691031;6928.203256625502;5727.885442291131;6506.902396464056;6782.457403407716;6796.35434633714;6796.796096117125;29489.410211795857;29743.66153523075;29923;30243;30643.080707578585;2319.026009076192;2357.4695693469002;2366.0890557135626;2368.5973384524673;2370.656254843038;18046.63906721871;18633.440996770118;19134.71297930532;19189.52026199342;19398.4180474586;2324.7210334759766;2340.0990618611786;2383.2016274548046;2384.213209640832;2395.748446413119;160202.989850507;161599;163080;163125;163164;19649.18594944248;21617.56764864694;22321.218757343417;23967.921443535084;24616.799816023802;40472.595274046886;40494.79752601218;40550.39174412386;40590.59409405868;40598.39102413466;10665.62670306551;10815.134789216843;11179.049780768726;11251;11318.434078296217;8756.824863502694;8761.25529330014;8775.254103401283;8938.374313798102;8950.14973577509;11532.769344613062;11553.826692599618;11583.6524904253;11630.139177731522;11754.177207595387;4114.300568903293;4136.234796562646;4490.236659767847;4497.730136191839;4522.8643140705835;860.7460799064166;905.8595917632778;953.1850268020743;959.2374062620233;979.9167070798919;18008.549786255186;19416.90291548533;20816.583668326548;21198.258060967793;21235.362939111852;1005.6027868991854;1013.533774463755;1025.7128004158699;1031.339942436841;1044.7858189071221;10448.477576121164;10849;10856.185786066077;10859.13126949842;10884.564617415215;536620;548667;549013.2549337228;550992;552083;90062;91577.54211228901;116230;117086.24370634447;124549.76208664819;1003.2926786615565;1011.7875246198233;1081.2485322700804;1084.8806631270554;1174.788538063157;17181.31274749046;18158.636827263115;18176.831670331983;18221.54446138857;18370.908145459187;54600.72699636476;55058.72470637781;55387.33837984867;55718;56054.719726401105;71691.64154179196;72433.6378318105;72608.27391726016;72993.27006729865;74425;12132.514699411924;12343.13598048128;12740.681482963;12757.681057973625;12807.487700491876;10727.785444291227;12974;13211.405486752927;13616;13814.65463363424;11528.308301501937;11602.07183425307;11837.467313971065;11922.523099076287;11965.923066923671;10617.522211500347;10759.569617215537;10986.340819550853;11074.058705010164;11363.602273920373;11347.659570212907;11712.472938717608;11828.23116497425;12105.81841272364;12174.269543827399;12513.43689533955;12523.74952500963;12811.231326120462;12848.486060557472;12971.416286266953;11211.215214934877;11473.13951453661;11676.824847627462;11809.173357864867;11832.940835295769 21:38:21 INFO - PID 1644 | |3;mootools.html;1272.3574594829613;1308.8494823095348;1474.9926250368746;1485.494931723214;1511.2519302945043;11238.6628401148;11568.958793708565;11750.823737643932;11787.882121178796;11886.405679716001;11687.29876207428;11886.049116070715;11887.643370698872;11888.227265227753;11915.821262681045;2500.7999360051244;2529.709083455408;2543.4404431025146;2545.4654522550286;2546.7648190627533;2295.8622482651012;2429.708434987804;2470.505898820234;2532.4428625702317;2533.5439620868237;2476.925692229227;2492.0779311654715;2502.724700282967;2524.1544082732307;2533.784628306596;2466.4943686544216;2483.3170878008514;2494.3140636324974;2495.3886297857143;2504.2862784106533;2447.278052974377;2495.201535508638;2496.3010357100043;2496.3135137837057;2538.870202759773;11420.257683250562;11513.481893314738;11623.360715160663;11648.534058637644;11655.067594592412;6451.1936007999;6473.255575608819;6704.631245281507;6732.427743641796;6755.324467553254;4024.3158663027357;4332.978335108304;4415.322177572534;4427.313766366186;4434.979954610458;1250.787366147757;1256.761215369077;1281.6539534325773;1291.231717128307;1298.156198470992;15741.212939352987;15988.76016859748;16248.75626865598;16276.75584866228;16321.183940802912;5011.248312753043;5837.211976383219;6142.846428839315;6208;6217.160683307786;13556.864431355758;13695.86304136946;13696.383662735201;13702.93148534251;13714.451421943011;5394.406615272317;5827.59206855516;6647.5346725729705;6764.966175169093;6797.694103765342;5985.521158307325;6133.785317513865;6211.751529938842;6260.561760676709;6269.185005949197;4586.334981427674;4725;4725.716457012591;4744.667873248839;4787.329773831597;1235.925844449336;1486.918219497938;1498.423107103773;1508.8943773935719;1519.156867938305;791.7505985614628;797.2545669798757;815.9026109882199;821.9835603287901;848.6944699908029;5519.116941289374;5761.279840019997;6092.116643086816;6400.295967443578;6427.614343139333;540.6915265058568;563.0793652378309;584.6696616411714;590.9822705318848;594.2482759309477;4070.531888832763;4164.37534369847;4240.6395456385935;4364.258076127066;4373.059792144704;10962;14685.77971330431;14823.036502627294;14853.108813471226;14885.404583816526;14062.507812226522;14620.634484137769;14710.0438471499;14721.190334531704;14743.631409214855;7387.445941554405;7670.156282808998;8118.35053195743;8372.958135209285;8379.371547133867;5940.910886336622;6477.157969464128;6824;6952.721891124501;6974.965125174342;10430.26988110825;12248.448819803309;12301.630951071482;12395.566155184526;12480.812787808009;11501.712457188638;12978.870211297766;13218.933905330412;13614.93192534031;13731.656708582366;7979.9202007979175;8965.058668839678;9281.814363712823;9372.437653740797;9476.431414115175;10775.299605525615;10881.292715973437;11012.779744405227;11016.1187105033;11032.834507482556;9053.049429810038;9081.863772043293;9189.172974432333;9221.723348299562;9366.438013719198;9020.458772473672;9062.682806102019;9074.727758167266;9076.31927605419;9116.772080698036;8016.799580010546;8140.918590814253;8168.632411541614;8294.58527073656;8303.086660467465;5211.583073354047;6025.337212906665;6102.176206212104;6207.037909124093;6215.96892015537;6094;6147.569670123048;6162.476189523941;6172.166757487861;6235.127082208403 21:38:21 INFO - PID 1644 | |4;prototype.html;391.4070183671736;405.9066414724613;411.65215392992945;416.543884446531;419.7859091863149;5277.706961794356;6008.819735407934;6489.480841532678;6609.4051535361805;6625.734970601176;6361.109444677737;6427.946725305489;6433.806985790434;6449.290578036413;6456.612603243809;4254.765987870666;4664.930026049612;4681.8829529261875;4690.460597031334;4697.318888761128;3889.930269175985;4615.569173556209;4637.770990687471;4660.627149828006;4670.836520721776;4198.8320467181275;4574.679772415916;4591.954080459186;4607.470140933802;4643.373144625483;3695.7619197568847;4185.22573323933;4580.473245576768;4624.352590637313;4639.932815452431;3220.4847224443974;3723.864221412468;3901.551321598024;3956.109875278068;3960.603939606045;6227.972384556543;6261.7182226799905;6267.185265915447;6289.7169627366875;6301.527385446064;4367.912641747147;5519.448055194448;5520.972395138038;5532.529734972532;5548.944510554883;5986.2517185351835;6698.229703584101;6805.149356330458;6864.519483636147;6882.931170688279;4314.352847072964;4470.776461176928;4474.753888536129;4499.775011249424;4503.437070366204;6444.227837344721;6627.73489060437;7155.212926578072;7330.5338932213235;7345.228750981123;4527.592516673515;4528.162289976365;4540.818367265339;4541.159885421208;4553.385292985405;4629.837955671535;4629.907401852011;4632.698874573142;4636.466806317317;4637.211674015425;5345.1385473028795;5421.512063914267;5475.370332411744;5476.2333273341765;5510.228568000483;6772.966135169293;6896.7586134485055;6904.378605925391;6911.6544172791155;6913;1485.6657252118403;1567.2398886540018;1583.2900467495078;1588.3249618911916;1588.9443869464744;949.9335046546813;982.9901700983041;993.8111758765956;1035.342557475993;1037.5123691864812;5839.240898683144;6173.81478555635;6487.513436492282;6560.606363618198;6575.769848055391;6758.290379510179;7189.2810718927685;7206.819829504304;7220.303228741242;7224.963875180591;5831.650100993954;5852.590318677651;6395.42441180296;6420.165378500765;6551.672416379257;3236.6277878044325;3267.1668724475107;3497.898162078937;3735.4210097434943;3763.2097259575794;6762.729490820312;7174.605396703127;7378.778636640909;7425.331720145105;7631.580263085583;5985.700714964234;7259.491835571459;7419.6661150248365;7440.925590744131;7443.88341748742;7168.211496735355;7373.820188769771;7428;7455.142658594331;7467.327940485382;6681.432078273401;6972.337627925339;7211.206767255702;7213.3868621167785;7267.672954716944;6200.844978875564;6797.626130562867;7009.7897063088185;7012.964935175291;7022.789316320519;6250.718717657625;7428.33145016951;7431.888521672071;7459.449735661351;7518.812029699301;3876.8836934891997;4449;4500.549945005473;4553.704009239389;4563.904662880818;5814.912776308274;6351.71417286214;7155;7195.6761945713615;7210.206877243394;6915.204751453647;6959.686814093479;6973.721051157897;6993.930060699531;7003.719851205895;5344.465553444625;6558;6757.290484499057;6869.313068693091;6998.580085194904;5680.857978550569;6357.332480089524;6781.220159681699;6819.556728812611;6833.3166683331265;6620.404163625297;6928.515003949675;6955.956606509064;6996.475264355296;7053.576785392687;6233.937660623335;6350.952092904619;6556.704948277435;6834.589924604539;6836.248012718696 21:38:21 INFO - PID 1644 | |5;yui.html;837.1335667584052;856.5075081827948;858.7423772868138;868.3487384461654;885.9335549833762;16061.598460038505;16665.666686666274;16725.916370418163;18776.530586735338;19281.03594820257;17966.64066718665;18432.354867579637;18466.630667386646;18481.630367392678;18555.90722046388;1017.099866618041;1044.4516628769882;1045.8274384726549;1049.3598904668183;1050.994745026274;1051.6897515232986;1054.2356791326304;1058.0001898206199;1058.6347710040025;1061.5806756331244;1085.0234788690163;1100.6808025672585;1101.531848964191;1101.6474728086976;1103.3986477369854;1088.35786885737;1091.557919042785;1097.7969075720978;1097.9011888930036;1102.9797437370441;772.7140957845577;780.1262585903768;796.8446153000167;799.60019990005;807.8545861744883;16780.328786848513;17090.572735681708;17235.65528689419;17258.3959561416;17264;16950.66098678032;16981.830181698147;17151.313947442086;17444.30222791087;17518.562035949075;13989;14079.788803167858;14081.08472949255;14134.717305653932;14172.50396236137;3167.3823604397153;3530.382183117944;3541.0793193769546;3549.432090865449;3549.6095429502734;84121;106465;126989;128148;129764.35117824351;13634.52279170244;13950.372233249529;13951.930240348936;13977.58067257984;13982.370793314325;20257;23418.882905585364;25775.87112064428;26185.607215891778;26507.33731656724;7674.731384401519;7768.41736869737;7774.689012439552;7805.921940780633;7809.219078092259;3598.8020658864016;3869.2068126034096;3879.321118804198;3885.436611691289;3886.8833934981994;4266.08279219963;4287.185434767384;4353.521112677603;4367.672424568106;4373.934390984138;933.9346245762731;964.7781010367655;966.1015255812163;967.5984466446487;967.7145242153441;650.8926027205458;665.1652176518438;670.9228438729608;672.5292295393244;694.3820000199826;11445.656630301104;11533.653990380302;11993.100517461226;12127.878721212852;12184.512619495299;652.292262894755;665.1253601513994;668.0647094068344;675.9797206083825;689.2349492063805;4896.216605343128;5293.206019097166;5543.473370029841;5605.635633683879;5667.603267771216;108946;113277.4336128314;114745;115740.84259157469;116522.50432487039;80583.59708201421;100651;100741;101682;102837;68272.6586367065;70525.64737176384;70532;70979;75606.86589701049;11469.139814513777;12621.305828179538;12990.740185196431;13012.869871301165;13013.479460821462;38385.808070959465;40266.1946761069;41066.79466602648;42572.509962151176;42694.78652606717;40223;53492.73253633707;53622;53655.46344536661;53744.731276343366;16086;16194.271257793192;16502.83497165013;16665.500034998968;16693.749593756347;17853.37513187032;17922.283108676027;18567.721484177477;18600.441986740418;18608.34870779516;16599.253033613757;16633.750493742362;16887.98672079679;16978.575535611708;17122.914385427994;16187.676246475241;16247.431339903047;16672;16723.49829505117;16760.24578893977;16057.437989670305;16483.036980473513;16570.00579965206;16660.41688540895;16771.580710482336;10960.28758130719;11606;11804.881951180378;12014.399280035788;12188.57339993096;9791.755206119904;12401.13192076582;12743.362831858556;12906.935465322613;12914.67713307175 21:38:21 INFO - PID 1644 | __end_tp_report 21:38:21 INFO - PID 1644 | __start_cc_report 21:38:21 INFO - PID 1644 | _x_x_mozilla_cycle_collect,397 21:38:21 INFO - PID 1644 | __end_cc_report 21:38:21 INFO - PID 1644 | __startTimestamp1494218301681__endTimestamp 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | ------- Summary: start ------- 21:38:21 INFO - PID 1644 | Number of tests: 6 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:6219.66 Median:6407.74 stddev:752.98 (11.8%) stddev-sans-first:726.00 21:38:21 INFO - PID 1644 | Values: 3568.5 3594.9 3596.9 3618.7 3620.1 4850.6 6138.5 6188.3 6228.8 6273.7 6157.8 6199.1 6235.9 6258.2 6316.7 5195.7 5512.6 5525.2 5542.8 5547.5 4699.6 5596.4 5629.6 5649.7 5674.8 5518.9 5539.4 5551.6 5587.7 5629.3 5556.1 5570.5 5599.8 5611.4 5619.0 5482.2 5485.3 5512.4 5534.4 5582.3 5996.7 6141.6 6162.2 6202.4 6291.7 6730.0 6781.6 6786.5 6798.5 6800.8 6213.9 6447.4 6883.7 6889.2 6908.4 6217.1 6260.5 6264.0 6323.8 6373.3 6950.6 7027.5 7037.2 7038.2 7084.5 6432.8 6501.5 6504.2 6589.3 6653.0 5827.3 6752.9 6846.3 6852.2 6858.7 6476.7 6581.4 6746.8 6756.3 6759.4 6583.8 6638.9 6697.4 6738.9 6765.5 4538.9 5374.1 5393.5 5395.9 5406.7 4766.5 4805.7 4808.0 4817.8 4881.9 6135.7 6274.4 6296.4 6300.1 6341.2 6834.0 6852.3 6854.9 6869.2 6873.2 6244.3 6252.4 6349.4 6352.0 6360.0 5857.6 5914.1 5977.9 6053.9 6061.2 6913.5 6916.6 7008.9 7039.4 7060.0 6867.0 6892.3 6896.3 6913.6 6918.7 6964.8 7023.9 7082.4 7084.6 7131.1 6490.8 6606.5 6652.9 6700.5 6777.3 6836.8 6870.6 6874.8 6885.2 6886.6 6348.3 6408.9 6473.4 6561.9 6676.9 6637.4 6640.5 6656.7 6669.5 6687.0 5753.7 6299.4 6391.6 6407.7 6509.7 6620.6 6627.9 6635.7 6687.5 6711.5 6192.7 6515.6 6530.5 6530.7 6555.8 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:44485.60 Median:324708.00 stddev:77644.74 (23.9%) stddev-sans-first:77793.35 21:38:21 INFO - PID 1644 | Values: 823.7 852.2 1087.6 1177.8 1277.0 37327.0 38112.2 38150.4 38177.2 38240.4 37755.0 38020.8 38111.2 38166.6 38196.0 7720.9 7906.0 8530.6 8549.2 8555.5 8455.4 8531.1 8537.0 8552.7 8581.0 7431.8 8432.4 8497.3 8548.9 8561.3 8516.9 8549.1 8553.9 8559.2 8559.2 8166.5 8205.3 8215.5 8252.4 8257.8 36783.2 36925.4 37050.4 37108.6 37152.6 3567.5 3597.4 3600.1 3608.5 3615.5 30267.2 30531.9 30668.1 30670.7 30673.0 3162.4 3184.2 3200.3 3205.4 3210.1 288420.0 289175.0 290202.0 290488.0 292731.0 38165.2 38889.6 46994.0 47302.8 47871.5 49116.8 49412.0 49643.8 49758.0 49954.3 14042.4 14125.6 14211.4 14238.6 14250.3 11330.1 11467.8 11474.5 11525.9 11653.3 12030.5 12270.7 13195.0 13233.6 13320.1 5864.7 5880.6 5894.5 5906.5 5913.1 998.0 999.4 1001.9 1007.7 1105.0 23521.4 24572.1 24650.6 24772.9 24798.1 1059.3 1163.5 1193.9 1195.8 1214.9 12568.4 12686.9 12688.5 12730.4 12739.1 321601.0 323357.0 324264.4 324291.0 325125.0 54367.5 54376.9 54569.7 54625.0 55184.0 1537.2 1562.4 1602.6 1605.7 1608.0 24317.0 24334.0 24452.8 24497.6 24540.8 38533.8 38563.4 38646.6 38698.8 38701.0 271420.0 271681.0 272578.0 273039.0 273083.0 33186.3 33731.0 33785.3 33822.0 33879.0 42535.4 43067.6 44666.6 44718.0 44783.7 25584.7 28776.0 29896.9 30201.7 30948.4 29484.1 30054.0 30391.0 30545.1 30565.9 30193.7 35501.8 36198.3 36865.0 37011.4 29887.9 29977.4 30646.5 30749.2 30880.8 19594.9 22702.2 23903.4 24352.9 24450.5 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:36434.26 Median:22445.10 stddev:92350.98 (411.5%) stddev-sans-first:92570.35 21:38:21 INFO - PID 1644 | Values: 370.9 395.0 439.7 446.5 448.7 20684.9 21630.8 21751.7 22569.0 22738.9 19433.3 21061.9 22693.0 22766.3 22940.4 5918.4 6164.4 6165.4 6174.5 6177.2 6033.6 6526.7 6831.3 6915.8 6918.2 6724.8 6782.7 6866.1 6890.2 6934.3 6887.2 6903.1 6907.9 6910.3 6928.2 5727.9 6506.9 6782.5 6796.4 6796.8 29489.4 29743.7 29923.0 30243.0 30643.1 2319.0 2357.5 2366.1 2368.6 2370.7 18046.6 18633.4 19134.7 19189.5 19398.4 2324.7 2340.1 2383.2 2384.2 2395.7 160203.0 161599.0 163080.0 163125.0 163164.0 19649.2 21617.6 22321.2 23967.9 24616.8 40472.6 40494.8 40550.4 40590.6 40598.4 10665.6 10815.1 11179.0 11251.0 11318.4 8756.8 8761.3 8775.3 8938.4 8950.1 11532.8 11553.8 11583.7 11630.1 11754.2 4114.3 4136.2 4490.2 4497.7 4522.9 860.7 905.9 953.2 959.2 979.9 18008.5 19416.9 20816.6 21198.3 21235.4 1005.6 1013.5 1025.7 1031.3 1044.8 10448.5 10849.0 10856.2 10859.1 10884.6 536620.0 548667.0 549013.3 550992.0 552083.0 90062.0 91577.5 116230.0 117086.2 124549.8 1003.3 1011.8 1081.2 1084.9 1174.8 17181.3 18158.6 18176.8 18221.5 18370.9 54600.7 55058.7 55387.3 55718.0 56054.7 71691.6 72433.6 72608.3 72993.3 74425.0 12132.5 12343.1 12740.7 12757.7 12807.5 10727.8 12974.0 13211.4 13616.0 13814.7 11528.3 11602.1 11837.5 11922.5 11965.9 10617.5 10759.6 10986.3 11074.1 11363.6 11347.7 11712.5 11828.2 12105.8 12174.3 12513.4 12523.7 12811.2 12848.5 12971.4 11211.2 11473.1 11676.8 11809.2 11832.9 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:7052.08 Median:4047.42 stddev:4418.54 (109.2%) stddev-sans-first:4409.59 21:38:21 INFO - PID 1644 | Values: 1272.4 1308.8 1475.0 1485.5 1511.3 11238.7 11569.0 11750.8 11787.9 11886.4 11687.3 11886.0 11887.6 11888.2 11915.8 2500.8 2529.7 2543.4 2545.5 2546.8 2295.9 2429.7 2470.5 2532.4 2533.5 2476.9 2492.1 2502.7 2524.2 2533.8 2466.5 2483.3 2494.3 2495.4 2504.3 2447.3 2495.2 2496.3 2496.3 2538.9 11420.3 11513.5 11623.4 11648.5 11655.1 6451.2 6473.3 6704.6 6732.4 6755.3 4024.3 4333.0 4415.3 4427.3 4435.0 1250.8 1256.8 1281.7 1291.2 1298.2 15741.2 15988.8 16248.8 16276.8 16321.2 5011.2 5837.2 6142.8 6208.0 6217.2 13556.9 13695.9 13696.4 13702.9 13714.5 5394.4 5827.6 6647.5 6765.0 6797.7 5985.5 6133.8 6211.8 6260.6 6269.2 4586.3 4725.0 4725.7 4744.7 4787.3 1235.9 1486.9 1498.4 1508.9 1519.2 791.8 797.3 815.9 822.0 848.7 5519.1 5761.3 6092.1 6400.3 6427.6 540.7 563.1 584.7 591.0 594.2 4070.5 4164.4 4240.6 4364.3 4373.1 10962.0 14685.8 14823.0 14853.1 14885.4 14062.5 14620.6 14710.0 14721.2 14743.6 7387.4 7670.2 8118.4 8373.0 8379.4 5940.9 6477.2 6824.0 6952.7 6975.0 10430.3 12248.4 12301.6 12395.6 12480.8 11501.7 12978.9 13218.9 13614.9 13731.7 7979.9 8965.1 9281.8 9372.4 9476.4 10775.3 10881.3 11012.8 11016.1 11032.8 9053.0 9081.9 9189.2 9221.7 9366.4 9020.5 9062.7 9074.7 9076.3 9116.8 8016.8 8140.9 8168.6 8294.6 8303.1 5211.6 6025.3 6102.2 6207.0 6216.0 6094.0 6147.6 6162.5 6172.2 6235.1 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5529.23 Median:6326.24 stddev:1803.52 (28.5%) stddev-sans-first:1766.87 21:38:21 INFO - PID 1644 | Values: 391.4 405.9 411.7 416.5 419.8 5277.7 6008.8 6489.5 6609.4 6625.7 6361.1 6427.9 6433.8 6449.3 6456.6 4254.8 4664.9 4681.9 4690.5 4697.3 3889.9 4615.6 4637.8 4660.6 4670.8 4198.8 4574.7 4592.0 4607.5 4643.4 3695.8 4185.2 4580.5 4624.4 4639.9 3220.5 3723.9 3901.6 3956.1 3960.6 6228.0 6261.7 6267.2 6289.7 6301.5 4367.9 5519.4 5521.0 5532.5 5548.9 5986.3 6698.2 6805.1 6864.5 6882.9 4314.4 4470.8 4474.8 4499.8 4503.4 6444.2 6627.7 7155.2 7330.5 7345.2 4527.6 4528.2 4540.8 4541.2 4553.4 4629.8 4629.9 4632.7 4636.5 4637.2 5345.1 5421.5 5475.4 5476.2 5510.2 6773.0 6896.8 6904.4 6911.7 6913.0 1485.7 1567.2 1583.3 1588.3 1588.9 949.9 983.0 993.8 1035.3 1037.5 5839.2 6173.8 6487.5 6560.6 6575.8 6758.3 7189.3 7206.8 7220.3 7225.0 5831.7 5852.6 6395.4 6420.2 6551.7 3236.6 3267.2 3497.9 3735.4 3763.2 6762.7 7174.6 7378.8 7425.3 7631.6 5985.7 7259.5 7419.7 7440.9 7443.9 7168.2 7373.8 7428.0 7455.1 7467.3 6681.4 6972.3 7211.2 7213.4 7267.7 6200.8 6797.6 7009.8 7013.0 7022.8 6250.7 7428.3 7431.9 7459.4 7518.8 3876.9 4449.0 4500.5 4553.7 4563.9 5814.9 6351.7 7155.0 7195.7 7210.2 6915.2 6959.7 6973.7 6993.9 7003.7 5344.5 6558.0 6757.3 6869.3 6998.6 5680.9 6357.3 6781.2 6819.6 6833.3 6620.4 6928.5 6956.0 6996.5 7053.6 6233.9 6351.0 6556.7 6834.6 6836.2 21:38:21 INFO - PID 1644 | 21:38:21 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:21611.07 Median:17247.03 stddev:30373.04 (176.1%) stddev-sans-first:30418.19 21:38:21 INFO - PID 1644 | Values: 837.1 856.5 858.7 868.3 885.9 16061.6 16665.7 16725.9 18776.5 19281.0 17966.6 18432.4 18466.6 18481.6 18555.9 1017.1 1044.5 1045.8 1049.4 1051.0 1051.7 1054.2 1058.0 1058.6 1061.6 1085.0 1100.7 1101.5 1101.6 1103.4 1088.4 1091.6 1097.8 1097.9 1103.0 772.7 780.1 796.8 799.6 807.9 16780.3 17090.6 17235.7 17258.4 17264.0 16950.7 16981.8 17151.3 17444.3 17518.6 13989.0 14079.8 14081.1 14134.7 14172.5 3167.4 3530.4 3541.1 3549.4 3549.6 84121.0 106465.0 126989.0 128148.0 129764.4 13634.5 13950.4 13951.9 13977.6 13982.4 20257.0 23418.9 25775.9 26185.6 26507.3 7674.7 7768.4 7774.7 7805.9 7809.2 3598.8 3869.2 3879.3 3885.4 3886.9 4266.1 4287.2 4353.5 4367.7 4373.9 933.9 964.8 966.1 967.6 967.7 650.9 665.2 670.9 672.5 694.4 11445.7 11533.7 11993.1 12127.9 12184.5 652.3 665.1 668.1 676.0 689.2 4896.2 5293.2 5543.5 5605.6 5667.6 108946.0 113277.4 114745.0 115740.8 116522.5 80583.6 100651.0 100741.0 101682.0 102837.0 68272.7 70525.6 70532.0 70979.0 75606.9 11469.1 12621.3 12990.7 13012.9 13013.5 38385.8 40266.2 41066.8 42572.5 42694.8 40223.0 53492.7 53622.0 53655.5 53744.7 16086.0 16194.3 16502.8 16665.5 16693.7 17853.4 17922.3 18567.7 18600.4 18608.3 16599.3 16633.8 16888.0 16978.6 17122.9 16187.7 16247.4 16672.0 16723.5 16760.2 16057.4 16483.0 16570.0 16660.4 16771.6 10960.3 11606.0 11804.9 12014.4 12188.6 9791.8 12401.1 12743.4 12906.9 12914.7 21:38:21 INFO - PID 1644 | -------- Summary: end -------- 21:38:21 INFO - PID 1644 | 21:38:22 INFO - TEST-INFO | 1644: exit 0 21:38:22 INFO - TEST-OK | dromaeo_css | took 1114442ms 21:38:22 INFO - TEST-START | kraken 21:38:22 INFO - operating with platform_type : mac_ 21:38:22 INFO - Initialising browser for kraken test... 21:38:22 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpxTZRLD/profile http://localhost:49241/getInfo.html) 21:38:25 INFO - PID 1658 | 2017-05-07 21:38:25.133 plugin-container[1659:11037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 21:38:25 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:38:25 INFO - PID 1658 | 2017-05-07 21:38:25.136 plugin-container[1659:11037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990f, name = 'com.apple.CFPasteboardClient' 21:38:25 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:38:25 INFO - PID 1658 | 2017-05-07 21:38:25.137 plugin-container[1659:11037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:38:25 INFO - PID 1658 | __metrics Screen width/height:1600/1200 21:38:25 INFO - PID 1658 | colorDepth:24 21:38:25 INFO - PID 1658 | Browser inner width/height: 1024/691 21:38:25 INFO - PID 1658 | __metrics 21:38:26 INFO - TEST-INFO | 1658: exit 0 21:38:26 INFO - Browser initialized. 21:38:26 INFO - Running cycle 1/1 for kraken test... 21:38:26 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpxTZRLD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:38:29 INFO - PID 1661 | 2017-05-07 21:38:29.161 plugin-container[1662:11193] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 21:38:29 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:38:29 INFO - PID 1661 | 2017-05-07 21:38:29.161 plugin-container[1662:11193] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.CFPasteboardClient' 21:38:29 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:38:29 INFO - PID 1661 | 2017-05-07 21:38:29.161 plugin-container[1662:11193] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:38:29 INFO - PID 1661 | 2017-05-07 21:38:29.511 plugin-container[1663:11232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913b, name = 'com.apple.tsm.portname' 21:38:29 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:38:29 INFO - PID 1661 | 2017-05-07 21:38:29.512 plugin-container[1663:11232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 21:38:29 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:38:29 INFO - PID 1661 | 2017-05-07 21:38:29.512 plugin-container[1663:11232] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:38:38 INFO - PID 1661 | RSS: Main: 233418752 21:38:38 INFO - PID 1661 | 21:38:41 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 21:38:42 INFO - PID 1661 | RSS: Main: 246513664 21:38:42 INFO - PID 1661 | 21:38:44 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 21:38:45 INFO - PID 1661 | RSS: Main: 254816256 21:38:45 INFO - PID 1661 | 21:38:48 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 21:38:49 INFO - PID 1661 | RSS: Main: 257376256 21:38:49 INFO - PID 1661 | 21:38:51 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 21:38:51 INFO - PID 1661 | RSS: Main: 258535424 21:38:51 INFO - PID 1661 | 21:38:53 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:38:54 INFO - PID 1661 | RSS: Main: 258895872 21:38:54 INFO - PID 1661 | 21:38:57 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 21:38:58 INFO - PID 1661 | RSS: Main: 264634368 21:38:58 INFO - PID 1661 | 21:39:01 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 21:39:01 INFO - PID 1661 | RSS: Main: 266547200 21:39:01 INFO - PID 1661 | 21:39:04 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 21:39:05 INFO - PID 1661 | RSS: Main: 265875456 21:39:05 INFO - PID 1661 | 21:39:06 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:39:07 INFO - PID 1661 | RSS: Main: 266772480 21:39:07 INFO - PID 1661 | 21:39:09 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:39:09 INFO - PID 1661 | RSS: Main: 266768384 21:39:09 INFO - PID 1661 | 21:39:12 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:39:12 INFO - PID 1661 | RSS: Main: 266387456 21:39:12 INFO - PID 1661 | 21:39:15 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:39:16 INFO - PID 1661 | RSS: Main: 266424320 21:39:16 INFO - PID 1661 | 21:39:19 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:39:20 INFO - PID 1661 | RSS: Main: 266776576 21:39:20 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 21:39:21 INFO - PID 1661 | RSS: Main: 268771328 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | __start_tp_report 21:39:21 INFO - PID 1661 | _x_x_mozilla_page_load 21:39:21 INFO - PID 1661 | _x_x_mozilla_page_load_details 21:39:21 INFO - PID 1661 | |i|pagename|runs| 21:39:21 INFO - PID 1661 | |0;ai-astar;102;116;115;116;116;116;116;115;114;115 21:39:21 INFO - PID 1661 | |1;audio-beat-detection;137;111;108;143;110;113;112;111;135;111 21:39:21 INFO - PID 1661 | |2;audio-dft;199;195;229;247;209;195;195;197;203;198 21:39:21 INFO - PID 1661 | |3;audio-fft;84;82;50;104;54;63;50;49;78;87 21:39:21 INFO - PID 1661 | |4;audio-oscillator;87;69;86;72;73;72;87;103;95;90 21:39:21 INFO - PID 1661 | |5;imaging-gaussian-blur;89;89;89;88;90;89;90;115;111;137 21:39:21 INFO - PID 1661 | |6;imaging-darkroom;96;104;96;96;119;97;97;97;97;96 21:39:21 INFO - PID 1661 | |7;imaging-desaturate;91;91;92;95;94;91;148;145;92;92 21:39:21 INFO - PID 1661 | |8;json-parse-financial;51;50;49;50;50;52;52;52;51;49 21:39:21 INFO - PID 1661 | |9;json-stringify-tinderbox;57;61;62;59;65;58;54;56;61;83 21:39:21 INFO - PID 1661 | |10;stanford-crypto-aes;83;84;84;84;80;83;85;86;83;81 21:39:21 INFO - PID 1661 | |11;stanford-crypto-ccm;139;142;137;142;136;135;139;136;216;161 21:39:21 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;180;185;182;185;180;187;195;184;185;185 21:39:21 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;68;67;69;66;71;70;66;68;67;71 21:39:21 INFO - PID 1661 | __end_tp_report 21:39:21 INFO - PID 1661 | __start_cc_report 21:39:21 INFO - PID 1661 | _x_x_mozilla_cycle_collect,969 21:39:21 INFO - PID 1661 | __end_cc_report 21:39:21 INFO - PID 1661 | __startTimestamp1494218361969__endTimestamp 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | ------- Summary: start ------- 21:39:21 INFO - PID 1661 | Number of tests: 14 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:114.10 Median:116.00 stddev:4.31 (3.7%) stddev-sans-first:0.73 21:39:21 INFO - PID 1661 | Values: 102.0 116.0 115.0 116.0 116.0 116.0 116.0 115.0 114.0 115.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:112.50 stddev:13.48 (12.0%) stddev-sans-first:12.64 21:39:21 INFO - PID 1661 | Values: 137.0 111.0 108.0 143.0 110.0 113.0 112.0 111.0 135.0 111.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:206.70 Median:201.00 stddev:17.58 (8.7%) stddev-sans-first:18.42 21:39:21 INFO - PID 1661 | Values: 199.0 195.0 229.0 247.0 209.0 195.0 195.0 197.0 203.0 198.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:70.10 Median:70.50 stddev:19.42 (27.5%) stddev-sans-first:19.94 21:39:21 INFO - PID 1661 | Values: 84.0 82.0 50.0 104.0 54.0 63.0 50.0 49.0 78.0 87.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:86.50 stddev:11.40 (13.2%) stddev-sans-first:12.02 21:39:21 INFO - PID 1661 | Values: 87.0 69.0 86.0 72.0 73.0 72.0 87.0 103.0 95.0 90.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:89.00 stddev:16.75 (18.8%) stddev-sans-first:17.40 21:39:21 INFO - PID 1661 | Values: 89.0 89.0 89.0 88.0 90.0 89.0 90.0 115.0 111.0 137.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:99.50 Median:96.50 stddev:7.26 (7.5%) stddev-sans-first:7.59 21:39:21 INFO - PID 1661 | Values: 96.0 104.0 96.0 96.0 119.0 97.0 97.0 97.0 97.0 96.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:92.00 stddev:22.92 (24.9%) stddev-sans-first:23.89 21:39:21 INFO - PID 1661 | Values: 91.0 91.0 92.0 95.0 94.0 91.0 148.0 145.0 92.0 92.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.17 (2.3%) stddev-sans-first:1.24 21:39:21 INFO - PID 1661 | Values: 51.0 50.0 49.0 50.0 50.0 52.0 52.0 52.0 51.0 49.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:61.00 stddev:8.17 (13.4%) stddev-sans-first:8.49 21:39:21 INFO - PID 1661 | Values: 57.0 61.0 62.0 59.0 65.0 58.0 54.0 56.0 61.0 83.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:1.77 (2.1%) stddev-sans-first:1.87 21:39:21 INFO - PID 1661 | Values: 83.0 84.0 84.0 84.0 80.0 83.0 85.0 86.0 83.0 81.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:140.50 stddev:24.95 (17.8%) stddev-sans-first:26.24 21:39:21 INFO - PID 1661 | Values: 139.0 142.0 137.0 142.0 136.0 135.0 139.0 136.0 216.0 161.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.80 Median:185.00 stddev:4.26 (2.3%) stddev-sans-first:4.15 21:39:21 INFO - PID 1661 | Values: 180.0 185.0 182.0 185.0 180.0 187.0 195.0 184.0 185.0 185.0 21:39:21 INFO - PID 1661 | 21:39:21 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.89 (2.8%) stddev-sans-first:2.00 21:39:21 INFO - PID 1661 | Values: 68.0 67.0 69.0 66.0 71.0 70.0 66.0 68.0 67.0 71.0 21:39:21 INFO - PID 1661 | -------- Summary: end -------- 21:39:21 INFO - PID 1661 | 21:39:22 INFO - TEST-INFO | 1661: exit 0 21:39:23 INFO - TEST-OK | kraken | took 60711ms 21:39:23 INFO - SUITE-END | took 1175s 21:39:23 INFO - Completed test suite (00:19:35) 21:39:23 INFO - javascript benchmark 21:39:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8082.529544018467, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3568.4825700273477, 3594.910127246817, 3596.892093237203, 3618.6562276583722, 3620.0587847159745, 4850.563449289563, 6138.48036155709, 6188.319284878659, 6228.781992630259, 6273.717682704283, 6157.753689852411, 6199.070139479069, 6235.875282494348, 6258.217722784652, 6316.74733010679, 5195.68825870449, 5512.641678290908, 5525.198846167324, 5542.778288868461, 5547.472990065938, 4699.647526435531, 5596.384397716248, 5629.60592758507, 5649.745761440725, 5674.829755107333, 5518.862028449281, 5539.41836107211, 5551.583631227633, 5587.720613969285, 5629.268195134648, 5556.083246264361, 5570.526505247059, 5599.804006859781, 5611.3546942101775, 5619.044762390404, 5482.205080263379, 5485.341758988947, 5512.366077901012, 5534.446555344473, 5582.330120385539, 5996.670183139926, 6141.578063794062, 6162.198914141178, 6202.41077097675, 6291.748330066792, 6730.0, 6781.609769997188, 6786.4910131739825, 6798.456123510156, 6800.761973330909, 6213.875722485525, 6447.387498187664, 6883.65581720912, 6889.242183359826, 6908.447324214101, 6217.129601855744, 6260.530460215502, 6263.966445536526, 6323.810285691437, 6373.29893711696, 6950.582965022115, 7027.472939529555, 7037.1555413350725, 7038.190608080034, 7084.504084714124, 6432.774852880127, 6501.512386571026, 6504.1869766279215, 6589.308122647149, 6653.0, 5827.271591051119, 6752.898706519308, 6846.281140480278, 6852.177738671391, 6858.731134740089, 6476.740930362733, 6581.440577550866, 6746.797596072028, 6756.324367563205, 6759.425448836806, 6583.769568065095, 6638.900416493757, 6697.3637504437, 6738.898916516257, 6765.458763298924, 4538.931916021263, 5374.140137577969, 5393.487618676298, 5395.946040539544, 5406.702631355313, 4766.451858036301, 4805.711657300573, 4807.95192048082, 4817.78319975602, 4881.926771098437, 6135.662538560422, 6274.403931626488, 6296.433321001132, 6300.086487459384, 6341.175647165931, 6833.965830170818, 6852.28051054642, 6854.931450685529, 6869.210040845367, 6873.243943166248, 6244.2819075805955, 6252.374762523711, 6349.396807303298, 6352.047192921099, 6360.0, 5857.560682948796, 5914.112883067488, 5977.910331345033, 6053.939460605426, 6061.181740464981, 6913.51605387618, 6916.55042422251, 7008.894866577108, 7039.366457018893, 7060.0, 6866.965665171642, 6892.345227203407, 6896.27589103137, 6913.5506192097355, 6918.7232510699005, 6964.79105626832, 7023.859522809413, 7082.433405327664, 7084.610346430995, 7131.108611423572, 6490.8052758417325, 6606.537542371988, 6652.90020649681, 6700.497462690415, 6777.254502004874, 6836.794896153124, 6870.553414028072, 6874.759383421556, 6885.173779146535, 6886.586804791728, 6348.301686814354, 6408.9038664421005, 6473.417392434703, 6561.901571476336, 6676.866462670817, 6637.369449902251, 6640.468762498892, 6656.73373065072, 6669.46644268467, 6687.0, 5753.712314384347, 6299.433051025247, 6391.552591318563, 6407.743690252338, 6509.7396104155305, 6620.569662971891, 6627.9337206627315, 6635.734570617121, 6687.531872769054, 6711.4966377520905, 6192.721327540181, 6515.576487528296, 6530.542861999805, 6530.738770449128, 6555.836104097245], "value": 6169.161872886853, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.7281697039978, 852.1776485691306, 1087.6247694545384, 1177.7644471105782, 1276.9847970863163, 37327.0, 38112.23775524488, 38150.4277435838, 38177.236455270875, 38240.42639360412, 37755.0, 38020.80989595048, 38111.23777524448, 38166.61833381665, 38196.0, 7720.922790772091, 7906.0, 8530.616122274512, 8549.187827156442, 8555.529445880475, 8455.408121431503, 8531.146885311451, 8537.018242902084, 8552.657893684245, 8581.013183483885, 7431.777046688608, 8432.40973131881, 8497.320214382835, 8548.914510854906, 8561.272291855199, 8516.8722469163, 8549.102344253888, 8553.871691924625, 8559.186877246651, 8559.186877246682, 8166.469179503372, 8205.343572514184, 8215.465994710385, 8252.381071419608, 8257.793555161108, 36783.1608419578, 36925.446118308246, 37050.44424333637, 37108.62891371079, 37152.62847371519, 3567.5362202913716, 3597.3524765542193, 3600.08197909534, 3608.4948107265004, 3615.5480564929385, 30267.243318916982, 30531.93138240173, 30668.07995760131, 30670.69329306701, 30673.0, 3162.383335249627, 3184.1561986073707, 3200.311932934407, 3205.3749518843833, 3210.1493104327374, 288419.9999999979, 289175.0, 290202.0, 290488.0, 292731.0, 38165.236695266496, 38889.61110388916, 46994.0, 47302.763486182346, 47871.521284787406, 49116.75441622769, 49412.01175976532, 49643.76603052871, 49758.0, 49954.250686239735, 14042.36809343582, 14125.576232713034, 14211.43154273838, 14238.644033899236, 14250.287485625677, 11330.09359251258, 11467.77064458706, 11474.48364823585, 11525.942370288094, 11653.30080195191, 12030.4586293617, 12270.72991496576, 13195.0, 13233.55179291834, 13320.134191277535, 5864.709763851945, 5880.588358814842, 5894.498967587718, 5906.527477801766, 5913.083472061838, 997.9970130019284, 999.3703966501058, 1001.8948010458797, 1007.7279134633608, 1105.0165352835982, 23521.41332440192, 24572.139975101145, 24650.630240546405, 24772.876135619208, 24798.132065377624, 1059.3485006720812, 1163.4822503985645, 1193.8806119388166, 1195.7847587434346, 1214.860291066521, 12568.434420451102, 12686.936565317113, 12688.49246030167, 12730.363481825872, 12739.108262421541, 321601.0, 323357.0, 324264.37867810985, 324291.0, 325125.0, 54367.45632543703, 54376.91246175134, 54569.727151363986, 54625.0, 55184.0, 1537.1852917953502, 1562.3984765864996, 1602.5512856400226, 1605.6868910562562, 1607.9919600401922, 24317.0, 24334.026638934243, 24452.777361131517, 24497.632535511624, 24540.754592453846, 38533.843984680505, 38563.42154867623, 38646.61353386543, 38698.80650596729, 38701.032474188374, 271420.0, 271681.0, 272578.0, 273039.0, 273083.0, 33186.33627327488, 33731.0, 33785.32429351448, 33822.0, 33878.98363049112, 42535.361969569865, 43067.56932430761, 44666.55333446754, 44718.0, 44783.656490305344, 25584.7207639621, 28776.0, 29896.850515747283, 30201.697983019887, 30948.381032379675, 29484.11547653574, 30054.0, 30391.0, 30545.08364749061, 30565.930192443157, 30193.69806301909, 35501.82249088738, 36198.27603447864, 36865.0, 37011.44482832706, 29887.850560747058, 29977.400451991274, 30646.540301895042, 30749.23126921845, 30880.845595771876, 19594.90202548978, 22702.175222674974, 23903.402414939766, 24352.878235608707, 24450.51098977975], "value": 17474.368081626784, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.9109813644726, 394.9722136093688, 439.7141857792433, 446.49769009863905, 448.67919437602114, 20684.896575517138, 21630.783692163095, 21751.67372489414, 22568.98439570219, 22738.88630556845, 19433.319833805785, 21061.894690526526, 22693.0, 22766.31701048971, 22940.426489337773, 5918.437748413905, 6164.352742961992, 6165.352637973016, 6174.475169610566, 6177.227846519185, 6033.637981721111, 6526.738930442777, 6831.316868313179, 6915.757948471804, 6918.2044064932425, 6724.755920154763, 6782.660866956657, 6866.107406037208, 6890.2076261230095, 6934.271901450351, 6887.173539175306, 6903.068085808427, 6907.930920690779, 6910.3089691031, 6928.203256625502, 5727.885442291131, 6506.902396464056, 6782.457403407716, 6796.35434633714, 6796.796096117125, 29489.410211795857, 29743.66153523075, 29923.0, 30243.0, 30643.080707578585, 2319.026009076192, 2357.4695693469002, 2366.0890557135626, 2368.5973384524673, 2370.656254843038, 18046.63906721871, 18633.440996770118, 19134.71297930532, 19189.52026199342, 19398.4180474586, 2324.7210334759766, 2340.0990618611786, 2383.2016274548046, 2384.213209640832, 2395.748446413119, 160202.989850507, 161599.0, 163080.0, 163125.0, 163164.0, 19649.18594944248, 21617.56764864694, 22321.218757343417, 23967.921443535084, 24616.799816023802, 40472.595274046886, 40494.79752601218, 40550.39174412386, 40590.59409405868, 40598.39102413466, 10665.62670306551, 10815.134789216843, 11179.049780768726, 11251.0, 11318.434078296217, 8756.824863502694, 8761.25529330014, 8775.254103401283, 8938.374313798102, 8950.14973577509, 11532.769344613062, 11553.826692599618, 11583.6524904253, 11630.139177731522, 11754.177207595387, 4114.300568903293, 4136.234796562646, 4490.236659767847, 4497.730136191839, 4522.8643140705835, 860.7460799064166, 905.8595917632778, 953.1850268020743, 959.2374062620233, 979.9167070798919, 18008.549786255186, 19416.90291548533, 20816.583668326548, 21198.258060967793, 21235.362939111852, 1005.6027868991854, 1013.533774463755, 1025.7128004158699, 1031.339942436841, 1044.7858189071221, 10448.477576121164, 10849.0, 10856.185786066077, 10859.13126949842, 10884.564617415215, 536620.0, 548667.0, 549013.2549337228, 550992.0, 552083.0, 90062.0, 91577.54211228901, 116230.0, 117086.24370634447, 124549.76208664819, 1003.2926786615565, 1011.7875246198233, 1081.2485322700804, 1084.8806631270554, 1174.788538063157, 17181.31274749046, 18158.636827263115, 18176.831670331983, 18221.54446138857, 18370.908145459187, 54600.72699636476, 55058.72470637781, 55387.33837984867, 55718.0, 56054.719726401105, 71691.64154179196, 72433.6378318105, 72608.27391726016, 72993.27006729865, 74425.0, 12132.514699411924, 12343.13598048128, 12740.681482963, 12757.681057973625, 12807.487700491876, 10727.785444291227, 12974.0, 13211.405486752927, 13616.0, 13814.65463363424, 11528.308301501937, 11602.07183425307, 11837.467313971065, 11922.523099076287, 11965.923066923671, 10617.522211500347, 10759.569617215537, 10986.340819550853, 11074.058705010164, 11363.602273920373, 11347.659570212907, 11712.472938717608, 11828.23116497425, 12105.81841272364, 12174.269543827399, 12513.43689533955, 12523.74952500963, 12811.231326120462, 12848.486060557472, 12971.416286266953, 11211.215214934877, 11473.13951453661, 11676.824847627462, 11809.173357864867, 11832.940835295769], "value": 11587.512838442712, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.3574594829613, 1308.8494823095348, 1474.9926250368746, 1485.494931723214, 1511.2519302945043, 11238.6628401148, 11568.958793708565, 11750.823737643932, 11787.882121178796, 11886.405679716001, 11687.29876207428, 11886.049116070715, 11887.643370698872, 11888.227265227753, 11915.821262681045, 2500.7999360051244, 2529.709083455408, 2543.4404431025146, 2545.4654522550286, 2546.7648190627533, 2295.8622482651012, 2429.708434987804, 2470.505898820234, 2532.4428625702317, 2533.5439620868237, 2476.925692229227, 2492.0779311654715, 2502.724700282967, 2524.1544082732307, 2533.784628306596, 2466.4943686544216, 2483.3170878008514, 2494.3140636324974, 2495.3886297857143, 2504.2862784106533, 2447.278052974377, 2495.201535508638, 2496.3010357100043, 2496.3135137837057, 2538.870202759773, 11420.257683250562, 11513.481893314738, 11623.360715160663, 11648.534058637644, 11655.067594592412, 6451.1936007999, 6473.255575608819, 6704.631245281507, 6732.427743641796, 6755.324467553254, 4024.3158663027357, 4332.978335108304, 4415.322177572534, 4427.313766366186, 4434.979954610458, 1250.787366147757, 1256.761215369077, 1281.6539534325773, 1291.231717128307, 1298.156198470992, 15741.212939352987, 15988.76016859748, 16248.75626865598, 16276.75584866228, 16321.183940802912, 5011.248312753043, 5837.211976383219, 6142.846428839315, 6208.0, 6217.160683307786, 13556.864431355758, 13695.86304136946, 13696.383662735201, 13702.93148534251, 13714.451421943011, 5394.406615272317, 5827.59206855516, 6647.5346725729705, 6764.966175169093, 6797.694103765342, 5985.521158307325, 6133.785317513865, 6211.751529938842, 6260.561760676709, 6269.185005949197, 4586.334981427674, 4725.0, 4725.716457012591, 4744.667873248839, 4787.329773831597, 1235.925844449336, 1486.918219497938, 1498.423107103773, 1508.8943773935719, 1519.156867938305, 791.7505985614628, 797.2545669798757, 815.9026109882199, 821.9835603287901, 848.6944699908029, 5519.116941289374, 5761.279840019997, 6092.116643086816, 6400.295967443578, 6427.614343139333, 540.6915265058568, 563.0793652378309, 584.6696616411714, 590.9822705318848, 594.2482759309477, 4070.531888832763, 4164.37534369847, 4240.6395456385935, 4364.258076127066, 4373.059792144704, 10962.0, 14685.77971330431, 14823.036502627294, 14853.108813471226, 14885.404583816526, 14062.507812226522, 14620.634484137769, 14710.0438471499, 14721.190334531704, 14743.631409214855, 7387.445941554405, 7670.156282808998, 8118.35053195743, 8372.958135209285, 8379.371547133867, 5940.910886336622, 6477.157969464128, 6824.0, 6952.721891124501, 6974.965125174342, 10430.26988110825, 12248.448819803309, 12301.630951071482, 12395.566155184526, 12480.812787808009, 11501.712457188638, 12978.870211297766, 13218.933905330412, 13614.93192534031, 13731.656708582366, 7979.9202007979175, 8965.058668839678, 9281.814363712823, 9372.437653740797, 9476.431414115175, 10775.299605525615, 10881.292715973437, 11012.779744405227, 11016.1187105033, 11032.834507482556, 9053.049429810038, 9081.863772043293, 9189.172974432333, 9221.723348299562, 9366.438013719198, 9020.458772473672, 9062.682806102019, 9074.727758167266, 9076.31927605419, 9116.772080698036, 8016.799580010546, 8140.918590814253, 8168.632411541614, 8294.58527073656, 8303.086660467465, 5211.583073354047, 6025.337212906665, 6102.176206212104, 6207.037909124093, 6215.96892015537, 6094.0, 6147.569670123048, 6162.476189523941, 6172.166757487861, 6235.127082208403], "value": 5321.737233871951, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.4070183671736, 405.9066414724613, 411.65215392992945, 416.543884446531, 419.7859091863149, 5277.706961794356, 6008.819735407934, 6489.480841532678, 6609.4051535361805, 6625.734970601176, 6361.109444677737, 6427.946725305489, 6433.806985790434, 6449.290578036413, 6456.612603243809, 4254.765987870666, 4664.930026049612, 4681.8829529261875, 4690.460597031334, 4697.318888761128, 3889.930269175985, 4615.569173556209, 4637.770990687471, 4660.627149828006, 4670.836520721776, 4198.8320467181275, 4574.679772415916, 4591.954080459186, 4607.470140933802, 4643.373144625483, 3695.7619197568847, 4185.22573323933, 4580.473245576768, 4624.352590637313, 4639.932815452431, 3220.4847224443974, 3723.864221412468, 3901.551321598024, 3956.109875278068, 3960.603939606045, 6227.972384556543, 6261.7182226799905, 6267.185265915447, 6289.7169627366875, 6301.527385446064, 4367.912641747147, 5519.448055194448, 5520.972395138038, 5532.529734972532, 5548.944510554883, 5986.2517185351835, 6698.229703584101, 6805.149356330458, 6864.519483636147, 6882.931170688279, 4314.352847072964, 4470.776461176928, 4474.753888536129, 4499.775011249424, 4503.437070366204, 6444.227837344721, 6627.73489060437, 7155.212926578072, 7330.5338932213235, 7345.228750981123, 4527.592516673515, 4528.162289976365, 4540.818367265339, 4541.159885421208, 4553.385292985405, 4629.837955671535, 4629.907401852011, 4632.698874573142, 4636.466806317317, 4637.211674015425, 5345.1385473028795, 5421.512063914267, 5475.370332411744, 5476.2333273341765, 5510.228568000483, 6772.966135169293, 6896.7586134485055, 6904.378605925391, 6911.6544172791155, 6913.0, 1485.6657252118403, 1567.2398886540018, 1583.2900467495078, 1588.3249618911916, 1588.9443869464744, 949.9335046546813, 982.9901700983041, 993.8111758765956, 1035.342557475993, 1037.5123691864812, 5839.240898683144, 6173.81478555635, 6487.513436492282, 6560.606363618198, 6575.769848055391, 6758.290379510179, 7189.2810718927685, 7206.819829504304, 7220.303228741242, 7224.963875180591, 5831.650100993954, 5852.590318677651, 6395.42441180296, 6420.165378500765, 6551.672416379257, 3236.6277878044325, 3267.1668724475107, 3497.898162078937, 3735.4210097434943, 3763.2097259575794, 6762.729490820312, 7174.605396703127, 7378.778636640909, 7425.331720145105, 7631.580263085583, 5985.700714964234, 7259.491835571459, 7419.6661150248365, 7440.925590744131, 7443.88341748742, 7168.211496735355, 7373.820188769771, 7428.0, 7455.142658594331, 7467.327940485382, 6681.432078273401, 6972.337627925339, 7211.206767255702, 7213.3868621167785, 7267.672954716944, 6200.844978875564, 6797.626130562867, 7009.7897063088185, 7012.964935175291, 7022.789316320519, 6250.718717657625, 7428.33145016951, 7431.888521672071, 7459.449735661351, 7518.812029699301, 3876.8836934891997, 4449.0, 4500.549945005473, 4553.704009239389, 4563.904662880818, 5814.912776308274, 6351.71417286214, 7155.0, 7195.6761945713615, 7210.206877243394, 6915.204751453647, 6959.686814093479, 6973.721051157897, 6993.930060699531, 7003.719851205895, 5344.465553444625, 6558.0, 6757.290484499057, 6869.313068693091, 6998.580085194904, 5680.857978550569, 6357.332480089524, 6781.220159681699, 6819.556728812611, 6833.3166683331265, 6620.404163625297, 6928.515003949675, 6955.956606509064, 6996.475264355296, 7053.576785392687, 6233.937660623335, 6350.952092904619, 6556.704948277435, 6834.589924604539, 6836.248012718696], "value": 4958.8042440212475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.1335667584052, 856.5075081827948, 858.7423772868138, 868.3487384461654, 885.9335549833762, 16061.598460038505, 16665.666686666274, 16725.916370418163, 18776.530586735338, 19281.03594820257, 17966.64066718665, 18432.354867579637, 18466.630667386646, 18481.630367392678, 18555.90722046388, 1017.099866618041, 1044.4516628769882, 1045.8274384726549, 1049.3598904668183, 1050.994745026274, 1051.6897515232986, 1054.2356791326304, 1058.0001898206199, 1058.6347710040025, 1061.5806756331244, 1085.0234788690163, 1100.6808025672585, 1101.531848964191, 1101.6474728086976, 1103.3986477369854, 1088.35786885737, 1091.557919042785, 1097.7969075720978, 1097.9011888930036, 1102.9797437370441, 772.7140957845577, 780.1262585903768, 796.8446153000167, 799.60019990005, 807.8545861744883, 16780.328786848513, 17090.572735681708, 17235.65528689419, 17258.3959561416, 17264.0, 16950.66098678032, 16981.830181698147, 17151.313947442086, 17444.30222791087, 17518.562035949075, 13989.0, 14079.788803167858, 14081.08472949255, 14134.717305653932, 14172.50396236137, 3167.3823604397153, 3530.382183117944, 3541.0793193769546, 3549.432090865449, 3549.6095429502734, 84121.0, 106465.0, 126989.0, 128148.0, 129764.35117824351, 13634.52279170244, 13950.372233249529, 13951.930240348936, 13977.58067257984, 13982.370793314325, 20257.0, 23418.882905585364, 25775.87112064428, 26185.607215891778, 26507.33731656724, 7674.731384401519, 7768.41736869737, 7774.689012439552, 7805.921940780633, 7809.219078092259, 3598.8020658864016, 3869.2068126034096, 3879.321118804198, 3885.436611691289, 3886.8833934981994, 4266.08279219963, 4287.185434767384, 4353.521112677603, 4367.672424568106, 4373.934390984138, 933.9346245762731, 964.7781010367655, 966.1015255812163, 967.5984466446487, 967.7145242153441, 650.8926027205458, 665.1652176518438, 670.9228438729608, 672.5292295393244, 694.3820000199826, 11445.656630301104, 11533.653990380302, 11993.100517461226, 12127.878721212852, 12184.512619495299, 652.292262894755, 665.1253601513994, 668.0647094068344, 675.9797206083825, 689.2349492063805, 4896.216605343128, 5293.206019097166, 5543.473370029841, 5605.635633683879, 5667.603267771216, 108946.0, 113277.4336128314, 114745.0, 115740.84259157469, 116522.50432487039, 80583.59708201421, 100651.0, 100741.0, 101682.0, 102837.0, 68272.6586367065, 70525.64737176384, 70532.0, 70979.0, 75606.86589701049, 11469.139814513777, 12621.305828179538, 12990.740185196431, 13012.869871301165, 13013.479460821462, 38385.808070959465, 40266.1946761069, 41066.79466602648, 42572.509962151176, 42694.78652606717, 40223.0, 53492.73253633707, 53622.0, 53655.46344536661, 53744.731276343366, 16086.0, 16194.271257793192, 16502.83497165013, 16665.500034998968, 16693.749593756347, 17853.37513187032, 17922.283108676027, 18567.721484177477, 18600.441986740418, 18608.34870779516, 16599.253033613757, 16633.750493742362, 16887.98672079679, 16978.575535611708, 17122.914385427994, 16187.676246475241, 16247.431339903047, 16672.0, 16723.49829505117, 16760.24578893977, 16057.437989670305, 16483.036980473513, 16570.00579965206, 16660.41688540895, 16771.580710482336, 10960.28758130719, 11606.0, 11804.881951180378, 12014.399280035788, 12188.57339993096, 9791.755206119904, 12401.13192076582, 12743.362831858556, 12906.935465322613, 12914.67713307175], "value": 8456.772918391194, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1491.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 115.0, 116.0, 116.0, 116.0, 116.0, 115.0, 114.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 108.0, 143.0, 110.0, 113.0, 112.0, 111.0, 135.0, 111.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 195.0, 229.0, 247.0, 209.0, 195.0, 195.0, 197.0, 203.0, 198.0], "value": 206.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 50.0, 104.0, 54.0, 63.0, 50.0, 49.0, 78.0, 87.0], "value": 70.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 86.0, 72.0, 73.0, 72.0, 87.0, 103.0, 95.0, 90.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 88.0, 90.0, 89.0, 90.0, 115.0, 111.0, 137.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 104.0, 96.0, 96.0, 119.0, 97.0, 97.0, 97.0, 97.0, 96.0], "value": 99.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 95.0, 94.0, 91.0, 148.0, 145.0, 92.0, 92.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 50.0, 50.0, 52.0, 52.0, 52.0, 51.0, 49.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 62.0, 59.0, 65.0, 58.0, 54.0, 56.0, 61.0, 83.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 84.0, 80.0, 83.0, 85.0, 86.0, 83.0, 81.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 137.0, 142.0, 136.0, 135.0, 139.0, 136.0, 216.0, 161.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 185.0, 182.0, 185.0, 180.0, 187.0, 195.0, 184.0, 185.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 66.0, 71.0, 70.0, 66.0, 68.0, 67.0, 71.0], "value": 68.3, "unit": "score"}]}]} 21:39:23 INFO - Return code: 0 21:39:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:39:23 INFO - # TBPL SUCCESS # 21:39:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:39:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:39:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:39:23 INFO - 2017-05-07 21:39:23.310 screenresolution[1668:11625] starting screenresolution argv=screenresolution get 21:39:23 INFO - 2017-05-07 21:39:23.313 screenresolution[1668:11625] Display 0: 1600x1200x32@60 21:39:23 INFO - 2017-05-07 21:39:23.319 screenresolution[1669:11628] starting screenresolution argv=screenresolution list 21:39:23 INFO - Available Modes on Display 0 21:39:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:39:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:39:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:39:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:39:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:39:23 INFO - Intel Iris: 21:39:23 INFO - Chipset Model: Intel Iris 21:39:23 INFO - Type: GPU 21:39:23 INFO - Bus: Built-In 21:39:23 INFO - VRAM (Dynamic, Max): 1536 MB 21:39:23 INFO - Vendor: Intel (0x8086) 21:39:23 INFO - Device ID: 0x0a2e 21:39:23 INFO - Revision ID: 0x0009 21:39:23 INFO - Displays: 21:39:23 INFO - Display: 21:39:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:39:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:39:23 INFO - Display Serial Number: 1600x1200 60 21:39:23 INFO - Main Display: Yes 21:39:23 INFO - Mirror: Off 21:39:23 INFO - Online: Yes 21:39:23 INFO - Rotation: Supported 21:39:23 INFO - Return code: 0 21:39:23 INFO - Running post-action listener: _resource_record_post_action 21:39:23 INFO - [mozharness: 2017-05-08 04:39:23.565148Z] Finished run-tests step (success) 21:39:23 INFO - Running post-run listener: _resource_record_post_run 21:39:23 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 33960960; Write bytes: 509880320; Read time: 208; Write time: 1607 21:39:23 INFO - TinderboxPrint: CPU usage
54.5% 21:39:23 INFO - TinderboxPrint: I/O read bytes / time
33,960,960 / 208 21:39:23 INFO - TinderboxPrint: I/O write bytes / time
509,880,320 / 1,607 21:39:23 INFO - TinderboxPrint: CPU idle
2,176.4 (45.5%) 21:39:23 INFO - TinderboxPrint: CPU system
965.1 (20.2%) 21:39:23 INFO - TinderboxPrint: CPU user
1,640.2 (34.3%) 21:39:23 INFO - TinderboxPrint: Swap in / out
239,251,456 / 0 21:39:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193190400; Write bytes: 194893824; Read time: 15171; Write time: 410 21:39:23 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 32986112; Write bytes: 298139648; Read time: 196; Write time: 1145 21:39:24 INFO - Running post-run listener: _upload_blobber_files 21:39:24 INFO - Blob upload gear active. 21:39:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:39:24 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 21:39:24 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'] 21:39:24 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 21:39:24 INFO - (blobuploader) - INFO - Open directory for files ... 21:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:25 INFO - (blobuploader) - INFO - Done attempting. 21:39:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:39:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:39:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:26 INFO - (blobuploader) - INFO - Done attempting. 21:39:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:39:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:39:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:27 INFO - (blobuploader) - INFO - Done attempting. 21:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:29 INFO - (blobuploader) - INFO - Done attempting. 21:39:29 INFO - (blobuploader) - INFO - Iteration through files over. 21:39:29 INFO - Return code: 0 21:39:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:39:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:39:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b114f764cb0f0e880b245b24743c9315eafac216868eda99ea3db8579846d4d50491e8aabfe792fdaff8e4a32327940e5a009aac2fffe5fdd19e386364698664", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9622e166879aa65cd50bc63504bf971518ab98d880e937e68fe6cd9283525a7e02d77dc3fed310e1d0c1fdeb5490f3e826693d22191fffd14b842916eb1543d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc524bbe2fd52fe2a9ed9383df7eeccbd695b4d1cfea5014f8ffd9959ebbd3963eae0946e52dda6570dc0fa862f99be3a0704f27ad9a17b9cc2d2eaa8d9d5ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1e9eb4bad928f63b1b5a92e55d618b7a5e15f090098d313633168544ee2bab97f09858140aee4dfce6cf8065d172b6e604194c9629c6d7a34636476748fc4c"} 21:39:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:39:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:39:29 INFO - Contents: 21:39:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b114f764cb0f0e880b245b24743c9315eafac216868eda99ea3db8579846d4d50491e8aabfe792fdaff8e4a32327940e5a009aac2fffe5fdd19e386364698664", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9622e166879aa65cd50bc63504bf971518ab98d880e937e68fe6cd9283525a7e02d77dc3fed310e1d0c1fdeb5490f3e826693d22191fffd14b842916eb1543d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc524bbe2fd52fe2a9ed9383df7eeccbd695b4d1cfea5014f8ffd9959ebbd3963eae0946e52dda6570dc0fa862f99be3a0704f27ad9a17b9cc2d2eaa8d9d5ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1e9eb4bad928f63b1b5a92e55d618b7a5e15f090098d313633168544ee2bab97f09858140aee4dfce6cf8065d172b6e604194c9629c6d7a34636476748fc4c"} 21:39:29 INFO - Running post-run listener: copy_logs_to_upload_dir 21:39:29 INFO - Copying logs to upload dir... 21:39:29 INFO - mkdir: /builds/slave/test/build/upload/logs 21:39:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.538876 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-07 21:39:29.569039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 21:39:29.572267) ========= 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.PHvjkCixI6/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.l0GpsIjmok/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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/b114f764cb0f0e880b245b24743c9315eafac216868eda99ea3db8579846d4d50491e8aabfe792fdaff8e4a32327940e5a009aac2fffe5fdd19e386364698664", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9622e166879aa65cd50bc63504bf971518ab98d880e937e68fe6cd9283525a7e02d77dc3fed310e1d0c1fdeb5490f3e826693d22191fffd14b842916eb1543d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc524bbe2fd52fe2a9ed9383df7eeccbd695b4d1cfea5014f8ffd9959ebbd3963eae0946e52dda6570dc0fa862f99be3a0704f27ad9a17b9cc2d2eaa8d9d5ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1e9eb4bad928f63b1b5a92e55d618b7a5e15f090098d313633168544ee2bab97f09858140aee4dfce6cf8065d172b6e604194c9629c6d7a34636476748fc4c"} build_url:https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011492 build_url: 'https://queue.taskcluster.net/v1/task/Q-Emtt4eS3OQDHrOcSWD7Q/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/b114f764cb0f0e880b245b24743c9315eafac216868eda99ea3db8579846d4d50491e8aabfe792fdaff8e4a32327940e5a009aac2fffe5fdd19e386364698664", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9622e166879aa65cd50bc63504bf971518ab98d880e937e68fe6cd9283525a7e02d77dc3fed310e1d0c1fdeb5490f3e826693d22191fffd14b842916eb1543d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc524bbe2fd52fe2a9ed9383df7eeccbd695b4d1cfea5014f8ffd9959ebbd3963eae0946e52dda6570dc0fa862f99be3a0704f27ad9a17b9cc2d2eaa8d9d5ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1e9eb4bad928f63b1b5a92e55d618b7a5e15f090098d313633168544ee2bab97f09858140aee4dfce6cf8065d172b6e604194c9629c6d7a34636476748fc4c"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 21:39:29.665357) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:39:29.665703) ========= 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.PHvjkCixI6/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.l0GpsIjmok/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.005223 ========= master_lag: 0.75 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:39:30.416215) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 21:39:30.416530) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 21:39:30.416917) ========= ========= Total master_lag: 0.98 =========