builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0121 starttime: 1494514877.54 results: success (0) revision: 838652a84b76c273e084d0705f3f4f3be89520a8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.540611) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.541078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.541361) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.566965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.567347) ========= 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.fsAuMCXGip/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.GrcLZc6aPP/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-11 08:01:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2017-05-11 08:01:17 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154458 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.745243) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.745592) ========= 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.fsAuMCXGip/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.GrcLZc6aPP/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.040321 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:17.807346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-11 08:01:17.807848) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 838652a84b76c273e084d0705f3f4f3be89520a8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 838652a84b76c273e084d0705f3f4f3be89520a8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fsAuMCXGip/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.GrcLZc6aPP/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-11 08:01:17,890 truncating revision to first 12 chars 2017-05-11 08:01:17,891 Setting DEBUG logging. 2017-05-11 08:01:17,891 attempt 1/10 2017-05-11 08:01:17,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/838652a84b76?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 08:01:19,528 unpacking tar archive at: mozilla-central-838652a84b76/testing/mozharness/ program finished with exit code 0 elapsedTime=2.801604 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-11 08:01:20.633708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:20.634334) ========= script_repo_revision: 838652a84b76c273e084d0705f3f4f3be89520a8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:20.634798) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:20.635106) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 08:01:20.655701) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 25 secs) (at 2017-05-11 08:01:20.656374) ========= /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.fsAuMCXGip/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.GrcLZc6aPP/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:01:20 INFO - MultiFileLogger online at 20170511 08:01:20 in /builds/slave/test 08:01:20 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 08:01:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:01:20 INFO - {'append_to_log': False, 08:01:20 INFO - 'base_work_dir': '/builds/slave/test', 08:01:20 INFO - 'blob_upload_branch': 'Firefox', 08:01:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:01:20 INFO - 'branch': 'Firefox', 08:01:20 INFO - 'buildbot_json_path': 'buildprops.json', 08:01:20 INFO - 'config_files': ('talos/mac_config.py',), 08:01:20 INFO - 'default_actions': ('clobber', 08:01:20 INFO - 'read-buildbot-config', 08:01:20 INFO - 'download-and-extract', 08:01:20 INFO - 'populate-webroot', 08:01:20 INFO - 'create-virtualenv', 08:01:20 INFO - 'install', 08:01:20 INFO - 'run-tests'), 08:01:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:01:20 INFO - 'download_minidump_stackwalk': True, 08:01:20 INFO - 'download_symbols': 'ondemand', 08:01:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:01:20 INFO - 'tooltool.py': '/tools/tooltool.py', 08:01:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:01:20 INFO - '/tools/misc-python/virtualenv.py')}, 08:01:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:01:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:01:20 INFO - 'gecko_profile': False, 08:01:20 INFO - 'gecko_profile_interval': 0, 08:01:20 INFO - 'installer_path': 'installer.exe', 08:01:20 INFO - 'log_level': 'info', 08:01:20 INFO - 'log_name': 'talos', 08:01:20 INFO - 'log_to_console': True, 08:01:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:01:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:01:20 INFO - 'opt_config_files': (), 08:01:20 INFO - 'pip_index': False, 08:01:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:20 INFO - 'cmd': ('bash', 08:01:20 INFO - '-c', 08:01:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:20 INFO - 'enabled': True, 08:01:20 INFO - 'halt_on_failure': False, 08:01:20 INFO - 'name': 'check_screen_resolution'},), 08:01:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:20 INFO - 'cmd': ('bash', 08:01:20 INFO - '-c', 08:01:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:20 INFO - 'enabled': True, 08:01:20 INFO - 'halt_on_failure': False, 08:01:20 INFO - 'name': 'check_screen_resolution'},), 08:01:20 INFO - 'run_cmd_checks_enabled': True, 08:01:20 INFO - 'suite': 'dromaeojs-e10s', 08:01:20 INFO - 'system_bits': '32', 08:01:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:01:20 INFO - 'title': 't-yosemite-r7-0121', 08:01:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:01:20 INFO - 'use_talos_json': True, 08:01:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:01:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:01:20 INFO - 'work_dir': 'build'} 08:01:20 INFO - [mozharness: 2017-05-11 15:01:20.855904Z] Running clobber step. 08:01:20 INFO - Running pre-action listener: _resource_record_pre_action 08:01:20 INFO - Running main action method: clobber 08:01:20 INFO - rmtree: /builds/slave/test/build 08:01:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:01:23 INFO - Running post-action listener: _resource_record_post_action 08:01:23 INFO - [mozharness: 2017-05-11 15:01:23.554541Z] Finished clobber step (success) 08:01:23 INFO - [mozharness: 2017-05-11 15:01:23.554675Z] Running read-buildbot-config step. 08:01:23 INFO - Running pre-action listener: _resource_record_pre_action 08:01:23 INFO - Running main action method: read_buildbot_config 08:01:23 INFO - Using buildbot properties: 08:01:23 INFO - { 08:01:23 INFO - "project": "", 08:01:23 INFO - "product": "firefox", 08:01:23 INFO - "who": "ryanvm@gmail.com", 08:01:23 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:01:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:01:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:01:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:01:23 INFO - "stage_platform": "macosx64", 08:01:23 INFO - "basedir": "/builds/slave/test", 08:01:23 INFO - "buildnumber": 56, 08:01:23 INFO - "platform": "yosemite_r7", 08:01:23 INFO - "parent_task_id": "XirYgAHeR1Gti4CPSijIMQ", 08:01:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:01:23 INFO - "slavebuilddir": "test", 08:01:23 INFO - "taskId": "Z2r2l0KiQ3CePG8A8dxPKQ", 08:01:23 INFO - "branch": "mozilla-central", 08:01:23 INFO - "script_repo_revision": "production", 08:01:23 INFO - "revision": "838652a84b76c273e084d0705f3f4f3be89520a8", 08:01:23 INFO - "slavename": "t-yosemite-r7-0121", 08:01:23 INFO - "repo_path": "mozilla-central" 08:01:23 INFO - } 08:01:23 INFO - Finding installer, test and symbols from parent task. 08:01:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2r2l0KiQ3CePG8A8dxPKQ'}, attempt #1 08:01:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2r2l0KiQ3CePG8A8dxPKQ'}, attempt #1 08:01:24 INFO - Task dependencies: YgNmcywBS7GhIlyYb60QuA 08:01:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ'}, attempt #1 08:01:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:01:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/test_packages.json 08:01:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/target.crashreporter-symbols.zip 08:01:25 INFO - Running post-action listener: _resource_record_post_action 08:01:25 INFO - [mozharness: 2017-05-11 15:01:25.292445Z] Finished read-buildbot-config step (success) 08:01:25 INFO - [mozharness: 2017-05-11 15:01:25.292590Z] Running download-and-extract step. 08:01:25 INFO - Running pre-action listener: _resource_record_pre_action 08:01:25 INFO - Running main action method: download_and_extract 08:01:25 INFO - mkdir: /builds/slave/test/build/tests 08:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:25 INFO - trying https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:01:25 INFO - Downloading https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:01:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:01:25 INFO - Downloaded 1325 bytes. 08:01:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:01:25 INFO - Using the following test package requirements: 08:01:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:01:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:01:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:01:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:01:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'jsshell-mac.zip'], 08:01:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:01:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:01:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:01:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:01:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:01:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:01:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:01:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:01:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:01:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:01:25 INFO - Fetch https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:01:28 INFO - Content-Length response header: 52707870 08:01:28 INFO - Bytes received: 52707870 08:01:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:01:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:01:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:01:44 INFO - Fetch https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:01:46 INFO - Content-Length response header: 52707870 08:01:46 INFO - Bytes received: 52707870 08:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:02:03 INFO - Content-Length response header: 14409352 08:02:03 INFO - Bytes received: 14409352 08:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:03 INFO - trying https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:02:03 INFO - Downloading https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:02:06 INFO - Downloaded 75538977 bytes. 08:02:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:02:06 INFO - mkdir: /builds/slave/test/properties 08:02:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:06 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:06 INFO - Contents: 08:02:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:02:06 INFO - Running post-action listener: _resource_record_post_action 08:02:06 INFO - Running post-action listener: set_extra_try_arguments 08:02:06 INFO - [mozharness: 2017-05-11 15:02:06.079677Z] Finished download-and-extract step (success) 08:02:06 INFO - [mozharness: 2017-05-11 15:02:06.079815Z] Running populate-webroot step. 08:02:06 INFO - Running pre-action listener: _resource_record_pre_action 08:02:06 INFO - Running main action method: populate_webroot 08:02:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:06 INFO - '--tppagecycles', 08:02:06 INFO - '7'], 08:02:06 INFO - 'tests': ['tsvgm']}}, 08:02:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:02:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:02:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['damp', 'tps']}, 08:02:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'tests': ['damp', 'tps']}, 08:02:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['dromaeo_dom']}, 08:02:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:02:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:02:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['a11yr', 08:02:06 INFO - 'ts_paint', 08:02:06 INFO - 'tpaint', 08:02:06 INFO - 'sessionrestore', 08:02:06 INFO - 'sessionrestore_no_auto_restore', 08:02:06 INFO - 'tabpaint']}, 08:02:06 INFO - 'other-e10s': {'tests': ['a11yr', 08:02:06 INFO - 'ts_paint', 08:02:06 INFO - 'tpaint', 08:02:06 INFO - 'sessionrestore', 08:02:06 INFO - 'sessionrestore_no_auto_restore', 08:02:06 INFO - 'tabpaint']}, 08:02:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:02:06 INFO - 'ts_paint', 08:02:06 INFO - 'tpaint', 08:02:06 INFO - 'sessionrestore', 08:02:06 INFO - 'sessionrestore_no_auto_restore', 08:02:06 INFO - 'tabpaint']}, 08:02:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:02:06 INFO - 'ts_paint', 08:02:06 INFO - 'tpaint', 08:02:06 INFO - 'sessionrestore', 08:02:06 INFO - 'sessionrestore_no_auto_restore', 08:02:06 INFO - 'tabpaint']}, 08:02:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['a11yr', 08:02:06 INFO - 'ts_paint', 08:02:06 INFO - 'tpaint', 08:02:06 INFO - 'sessionrestore', 08:02:06 INFO - 'sessionrestore_no_auto_restore', 08:02:06 INFO - 'tabpaint']}, 08:02:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['a11yr', 08:02:06 INFO - 'ts_paint', 08:02:06 INFO - 'tpaint', 08:02:06 INFO - 'sessionrestore', 08:02:06 INFO - 'sessionrestore_no_auto_restore', 08:02:06 INFO - 'tabpaint']}, 08:02:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:02:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:02:06 INFO - 'bloom_basic_ref']}, 08:02:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['tsvgx', 08:02:06 INFO - 'tsvgr_opacity', 08:02:06 INFO - 'tart', 08:02:06 INFO - 'tscrollx', 08:02:06 INFO - 'cart', 08:02:06 INFO - 'tsvg_static']}, 08:02:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:02:06 INFO - 'tsvgr_opacity', 08:02:06 INFO - 'tart', 08:02:06 INFO - 'tscrollx', 08:02:06 INFO - 'cart', 08:02:06 INFO - 'tsvg_static']}, 08:02:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'talos_options': ['--disable-e10s'], 08:02:06 INFO - 'tests': ['tp5o']}, 08:02:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:02:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'talos_options': ['--disable-e10s', 08:02:06 INFO - '--xperf_path', 08:02:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:06 INFO - 'tests': ['tp5n']}, 08:02:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:02:06 INFO - 'talos_options': ['--xperf_path', 08:02:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:06 INFO - 'tests': ['tp5n']}}, 08:02:06 INFO - 'talos.zip': {'path': '', 08:02:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:06 INFO - Running post-action listener: _resource_record_post_action 08:02:06 INFO - [mozharness: 2017-05-11 15:02:06.101386Z] Finished populate-webroot step (success) 08:02:06 INFO - [mozharness: 2017-05-11 15:02:06.101521Z] Running create-virtualenv step. 08:02:06 INFO - Running pre-action listener: _resource_record_pre_action 08:02:06 INFO - Running main action method: create_virtualenv 08:02:06 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:02:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:06 INFO - Using real prefix '/tools/python27' 08:02:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:06 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:09 INFO - Installing pip.................done. 08:02:09 INFO - Return code: 0 08:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:02:10 INFO - Reading from file tmpfile_stdout 08:02:10 INFO - Output received: 08:02:10 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:02:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:02: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', 'pip>=1.5'] in /builds/slave/test/build 08:02: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 pip>=1.5 08:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:10 INFO - 'HOME': '/Users/cltbld', 08:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:10 INFO - 'LOGNAME': 'cltbld', 08:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:10 INFO - 'MOZ_NO_REMOTE': '1', 08:02:10 INFO - 'NO_EM_RESTART': '1', 08:02:10 INFO - 'PAGER': '/bin/cat', 08:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:10 INFO - 'PWD': '/builds/slave/test', 08:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:10 INFO - 'SHELL': '/bin/bash', 08:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:02:10 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:10 INFO - 'USER': 'cltbld', 08:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:10 INFO - 'XPC_FLAGS': '0x0', 08:02:10 INFO - 'XPC_SERVICE_NAME': '0', 08:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:02:10 INFO - Cleaning up... 08:02:10 INFO - Return code: 0 08:02:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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', '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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:02: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 psutil>=3.1.1 08:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:10 INFO - 'HOME': '/Users/cltbld', 08:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:10 INFO - 'LOGNAME': 'cltbld', 08:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:10 INFO - 'MOZ_NO_REMOTE': '1', 08:02:10 INFO - 'NO_EM_RESTART': '1', 08:02:10 INFO - 'PAGER': '/bin/cat', 08:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:10 INFO - 'PWD': '/builds/slave/test', 08:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:10 INFO - 'SHELL': '/bin/bash', 08:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:02:10 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:10 INFO - 'USER': 'cltbld', 08:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:10 INFO - 'XPC_FLAGS': '0x0', 08:02:10 INFO - 'XPC_SERVICE_NAME': '0', 08:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:10 INFO - Downloading/unpacking psutil>=3.1.1 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:04:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:43 INFO - Installing collected packages: psutil 08:04:43 INFO - Running setup.py install for psutil 08:04:43 INFO - building 'psutil._psutil_osx' extension 08:04:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:04:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:04:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:04:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:04:43 INFO - building 'psutil._psutil_posix' extension 08:04:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:04:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:04:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:04:43 INFO - ^ 08:04:44 INFO - 1 warning generated. 08:04:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:04:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:44 INFO - Successfully installed psutil 08:04:44 INFO - Cleaning up... 08:04:44 INFO - Return code: 0 08:04:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:44 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:04:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:44 INFO - 'HOME': '/Users/cltbld', 08:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:44 INFO - 'LOGNAME': 'cltbld', 08:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:44 INFO - 'MOZ_NO_REMOTE': '1', 08:04:44 INFO - 'NO_EM_RESTART': '1', 08:04:44 INFO - 'PAGER': '/bin/cat', 08:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:44 INFO - 'PWD': '/builds/slave/test', 08:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:44 INFO - 'SHELL': '/bin/bash', 08:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:04:44 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:44 INFO - 'USER': 'cltbld', 08:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:44 INFO - 'XPC_FLAGS': '0x0', 08:04:44 INFO - 'XPC_SERVICE_NAME': '0', 08:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:07:17 INFO - Installing collected packages: mozsystemmonitor 08:07:17 INFO - Running setup.py install for mozsystemmonitor 08:07:17 INFO - Successfully installed mozsystemmonitor 08:07:17 INFO - Cleaning up... 08:07:17 INFO - Return code: 0 08:07:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:19 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:07:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:19 INFO - 'HOME': '/Users/cltbld', 08:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:19 INFO - 'LOGNAME': 'cltbld', 08:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:19 INFO - 'MOZ_NO_REMOTE': '1', 08:07:19 INFO - 'NO_EM_RESTART': '1', 08:07:19 INFO - 'PAGER': '/bin/cat', 08:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:19 INFO - 'PWD': '/builds/slave/test', 08:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:19 INFO - 'SHELL': '/bin/bash', 08:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:07:19 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:19 INFO - 'USER': 'cltbld', 08:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:19 INFO - 'XPC_FLAGS': '0x0', 08:07:19 INFO - 'XPC_SERVICE_NAME': '0', 08:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:19 INFO - Downloading/unpacking jsonschema==2.5.1 08:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:12:23 INFO - warning: no files found matching '*.txt' 08:12:23 INFO - no previously-included directories found matching 'build' 08:12:23 INFO - no previously-included directories found matching 'dist' 08:12:23 INFO - no previously-included directories found matching '.git*' 08:12:23 INFO - Installing collected packages: jsonschema, functools32 08:12:23 INFO - Running setup.py install for functools32 08:12:23 INFO - warning: no files found matching '*.txt' 08:12:23 INFO - no previously-included directories found matching 'build' 08:12:23 INFO - no previously-included directories found matching 'dist' 08:12:23 INFO - no previously-included directories found matching '.git*' 08:12:23 INFO - Successfully installed jsonschema functools32 08:12:23 INFO - Cleaning up... 08:12:23 INFO - Return code: 0 08:12:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:25 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:25 INFO - 'HOME': '/Users/cltbld', 08:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:25 INFO - 'LOGNAME': 'cltbld', 08:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:25 INFO - 'MOZ_NO_REMOTE': '1', 08:12:25 INFO - 'NO_EM_RESTART': '1', 08:12:25 INFO - 'PAGER': '/bin/cat', 08:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:25 INFO - 'PWD': '/builds/slave/test', 08:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:25 INFO - 'SHELL': '/bin/bash', 08:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:12:25 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:25 INFO - 'USER': 'cltbld', 08:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:25 INFO - 'XPC_FLAGS': '0x0', 08:12:25 INFO - 'XPC_SERVICE_NAME': '0', 08:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:12:25 INFO - Cleaning up... 08:12:25 INFO - Return code: 0 08:12:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:25 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:25 INFO - 'HOME': '/Users/cltbld', 08:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:25 INFO - 'LOGNAME': 'cltbld', 08:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:25 INFO - 'MOZ_NO_REMOTE': '1', 08:12:25 INFO - 'NO_EM_RESTART': '1', 08:12:25 INFO - 'PAGER': '/bin/cat', 08:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:25 INFO - 'PWD': '/builds/slave/test', 08:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:25 INFO - 'SHELL': '/bin/bash', 08:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:12:25 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:25 INFO - 'USER': 'cltbld', 08:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:25 INFO - 'XPC_FLAGS': '0x0', 08:12:25 INFO - 'XPC_SERVICE_NAME': '0', 08:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:25 INFO - Downloading/unpacking blobuploader==1.2.4 08:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:57 INFO - Downloading blobuploader-1.2.4.tar.gz 08:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:14:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:17:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:59 INFO - Downloading docopt-0.6.1.tar.gz 08:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:19:59 INFO - Installing collected packages: blobuploader, requests, docopt 08:19:59 INFO - Running setup.py install for blobuploader 08:19:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:19:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:19:59 INFO - Running setup.py install for requests 08:19:59 INFO - Running setup.py install for docopt 08:20:00 INFO - Successfully installed blobuploader requests docopt 08:20:00 INFO - Cleaning up... 08:20:00 INFO - Return code: 0 08:20:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:00 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:00 INFO - 'HOME': '/Users/cltbld', 08:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:00 INFO - 'LOGNAME': 'cltbld', 08:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:00 INFO - 'MOZ_NO_REMOTE': '1', 08:20:00 INFO - 'NO_EM_RESTART': '1', 08:20:00 INFO - 'PAGER': '/bin/cat', 08:20:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:00 INFO - 'PWD': '/builds/slave/test', 08:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:00 INFO - 'SHELL': '/bin/bash', 08:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:20:00 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:00 INFO - 'USER': 'cltbld', 08:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:00 INFO - 'XPC_FLAGS': '0x0', 08:20:00 INFO - 'XPC_SERVICE_NAME': '0', 08:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:00 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ZlCiOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-59im44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-NUQtuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Rb2XMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-RRF1Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-BMZOnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-P6jJLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ngx9G_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-NhV6kX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:01 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-5uWv08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-SzV8MS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-nQGbkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-JId1fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-TGAJmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-myxukk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Qn5vix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-SPeXhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:20:02 INFO - Running setup.py install for manifestparser 08:20:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:20:02 INFO - Running setup.py install for mozcrash 08:20:03 INFO - Running setup.py install for mozdebug 08:20:03 INFO - Running setup.py install for mozdevice 08:20:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Running setup.py install for mozfile 08:20:03 INFO - Running setup.py install for mozhttpd 08:20:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Running setup.py install for mozinfo 08:20:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Running setup.py install for mozInstall 08:20:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:20:03 INFO - Running setup.py install for mozleak 08:20:04 INFO - Running setup.py install for mozlog 08:20:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:20:04 INFO - Running setup.py install for moznetwork 08:20:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:20:04 INFO - Running setup.py install for mozprocess 08:20:04 INFO - Running setup.py install for mozprofile 08:20:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:20:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:20:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:20:04 INFO - Running setup.py install for mozrunner 08:20:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:20:04 INFO - Running setup.py install for mozscreenshot 08:20:05 INFO - Running setup.py install for moztest 08:20:05 INFO - Running setup.py install for mozversion 08:20:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:20:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:20:05 INFO - Cleaning up... 08:20:05 INFO - Return code: 0 08:20:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:05 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:20:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:20:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:20:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:05 INFO - 'HOME': '/Users/cltbld', 08:20:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:05 INFO - 'LOGNAME': 'cltbld', 08:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:05 INFO - 'MOZ_NO_REMOTE': '1', 08:20:05 INFO - 'NO_EM_RESTART': '1', 08:20:05 INFO - 'PAGER': '/bin/cat', 08:20:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:05 INFO - 'PWD': '/builds/slave/test', 08:20:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:05 INFO - 'SHELL': '/bin/bash', 08:20:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:20:05 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:20:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:05 INFO - 'USER': 'cltbld', 08:20:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:05 INFO - 'XPC_FLAGS': '0x0', 08:20:05 INFO - 'XPC_SERVICE_NAME': '0', 08:20:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:05 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-YK65Dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:05 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-2F7Dos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:05 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-R3_C1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:05 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-PpAVN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-oTj4xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-9_ZL4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Zdp4JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-jm2Aek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-iKBDtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-CE_96b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-DE76hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-OIXWTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-XPyQZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Ruetu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-_36JRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-BV2PdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-dTGxoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:39 INFO - Downloading blessings-1.6.tar.gz 08:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:22:40 INFO - Installing collected packages: blessings 08:22:40 INFO - Running setup.py install for blessings 08:22:40 INFO - Successfully installed blessings 08:22:40 INFO - Cleaning up... 08:22:40 INFO - Return code: 0 08:22:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:40 INFO - Reading from file tmpfile_stdout 08:22:40 INFO - Current package versions: 08:22:40 INFO - blessings == 1.6 08:22:40 INFO - blobuploader == 1.2.4 08:22:40 INFO - docopt == 0.6.1 08:22:40 INFO - functools32 == 3.2.3-2 08:22:40 INFO - jsonschema == 2.5.1 08:22:40 INFO - manifestparser == 1.1 08:22:40 INFO - mozInstall == 1.12 08:22:40 INFO - mozcrash == 1.0 08:22:40 INFO - mozdebug == 0.1 08:22:40 INFO - mozdevice == 0.50 08:22:40 INFO - mozfile == 1.2 08:22:40 INFO - mozhttpd == 0.7 08:22:40 INFO - mozinfo == 0.9 08:22:40 INFO - mozleak == 0.1 08:22:40 INFO - mozlog == 3.4 08:22:40 INFO - moznetwork == 0.27 08:22:40 INFO - mozprocess == 0.25 08:22:40 INFO - mozprofile == 0.28 08:22:40 INFO - mozrunner == 6.13 08:22:40 INFO - mozscreenshot == 0.1 08:22:40 INFO - mozsystemmonitor == 0.3 08:22:40 INFO - moztest == 0.8 08:22:40 INFO - mozversion == 1.4 08:22:40 INFO - psutil == 3.1.1 08:22:40 INFO - requests == 1.2.3 08:22:40 INFO - wsgiref == 0.1.2 08:22:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:41 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:41 INFO - 'HOME': '/Users/cltbld', 08:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:41 INFO - 'LOGNAME': 'cltbld', 08:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:41 INFO - 'MOZ_NO_REMOTE': '1', 08:22:41 INFO - 'NO_EM_RESTART': '1', 08:22:41 INFO - 'PAGER': '/bin/cat', 08:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:41 INFO - 'PWD': '/builds/slave/test', 08:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:41 INFO - 'SHELL': '/bin/bash', 08:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:22:41 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:41 INFO - 'USER': 'cltbld', 08:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:41 INFO - 'XPC_FLAGS': '0x0', 08:22:41 INFO - 'XPC_SERVICE_NAME': '0', 08:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:22:41 INFO - Cleaning up... 08:22:41 INFO - Return code: 0 08:22:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:42 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 0x10c1d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecbbca9260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, '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.fsAuMCXGip/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.GrcLZc6aPP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:42 INFO - 'HOME': '/Users/cltbld', 08:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:42 INFO - 'LOGNAME': 'cltbld', 08:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:42 INFO - 'MOZ_NO_REMOTE': '1', 08:22:42 INFO - 'NO_EM_RESTART': '1', 08:22:42 INFO - 'PAGER': '/bin/cat', 08:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:42 INFO - 'PWD': '/builds/slave/test', 08:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:42 INFO - 'SHELL': '/bin/bash', 08:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:22:42 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:42 INFO - 'USER': 'cltbld', 08:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:42 INFO - 'XPC_FLAGS': '0x0', 08:22:42 INFO - 'XPC_SERVICE_NAME': '0', 08:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:22:42 INFO - Cleaning up... 08:22:42 INFO - Return code: 0 08:22:42 INFO - Running post-action listener: _resource_record_post_action 08:22:42 INFO - Running post-action listener: _start_resource_monitoring 08:22:42 INFO - Starting resource monitoring. 08:22:42 INFO - [mozharness: 2017-05-11 15:22:42.290595Z] Finished create-virtualenv step (success) 08:22:42 INFO - [mozharness: 2017-05-11 15:22:42.291550Z] Running install step. 08:22:42 INFO - Running pre-action listener: _resource_record_pre_action 08:22:42 INFO - Running main action method: install 08:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:42 INFO - Reading from file tmpfile_stdout 08:22:42 INFO - Detecting whether we're running mozinstall >=1.0... 08:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:22:42 INFO - Reading from file tmpfile_stdout 08:22:42 INFO - Output received: 08:22:42 INFO - Usage: mozinstall [options] installer 08:22:42 INFO - Options: 08:22:42 INFO - -h, --help show this help message and exit 08:22:42 INFO - -d DEST, --destination=DEST 08:22:42 INFO - Directory to install application into. [default: 08:22:42 INFO - "/builds/slave/test"] 08:22:42 INFO - --app=APP Application being installed. [default: firefox] 08:22:42 INFO - mkdir: /builds/slave/test/build/application 08:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:23:03 INFO - Reading from file tmpfile_stdout 08:23:03 INFO - Output received: 08:23:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:23:03 INFO - Running post-action listener: _resource_record_post_action 08:23:03 INFO - [mozharness: 2017-05-11 15:23:03.301386Z] Finished install step (success) 08:23:03 INFO - [mozharness: 2017-05-11 15:23:03.301502Z] Running run-tests step. 08:23:03 INFO - Running pre-action listener: _resource_record_pre_action 08:23:03 INFO - Running main action method: run_tests 08:23:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:03 INFO - 2017-05-11 08:23:03.316 screenresolution[1636:8643] starting screenresolution argv=screenresolution get 08:23:03 INFO - 2017-05-11 08:23:03.318 screenresolution[1636:8643] Display 0: 1600x1200x32@60 08:23:03 INFO - 2017-05-11 08:23:03.324 screenresolution[1637:8645] starting screenresolution argv=screenresolution list 08:23:03 INFO - Available Modes on Display 0 08:23:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:03 INFO - Intel Iris: 08:23:03 INFO - Chipset Model: Intel Iris 08:23:03 INFO - Type: GPU 08:23:03 INFO - Bus: Built-In 08:23:03 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:03 INFO - Vendor: Intel (0x8086) 08:23:03 INFO - Device ID: 0x0a2e 08:23:03 INFO - Revision ID: 0x0009 08:23:03 INFO - Displays: 08:23:03 INFO - Display: 08:23:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:03 INFO - Display Serial Number: 1600x1200 60 08:23:03 INFO - Main Display: Yes 08:23:03 INFO - Mirror: Off 08:23:03 INFO - Online: Yes 08:23:03 INFO - Rotation: Supported 08:23:03 INFO - Return code: 0 08:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:23:03 INFO - Python 2.7.3 08:23:03 INFO - Return code: 0 08:23:03 INFO - grabbing minidump binary from tooltool 08:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:03 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 0x10bee5d30>, '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 0x10be98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bed9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:23:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:23:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:23:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:23:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:23:03 INFO - Return code: 0 08:23:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:23:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:23:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:23:03 INFO - ENV: RUST_BACKTRACE is now 1 08:23:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:23:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:23:03 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/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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 08:23:03 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/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0121 --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 08:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsAuMCXGip/Render', 08:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:03 INFO - 'HOME': '/Users/cltbld', 08:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:03 INFO - 'LOGNAME': 'cltbld', 08:23:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:23:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:03 INFO - 'MOZ_NO_REMOTE': '1', 08:23:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:23:03 INFO - 'NO_EM_RESTART': '1', 08:23:03 INFO - 'PAGER': '/bin/cat', 08:23:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:03 INFO - 'PWD': '/builds/slave/test', 08:23:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:03 INFO - 'RUST_BACKTRACE': '1', 08:23:03 INFO - 'SHELL': '/bin/bash', 08:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrcLZc6aPP/Listeners', 08:23:03 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 08:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:03 INFO - 'USER': 'cltbld', 08:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:03 INFO - 'XPC_FLAGS': '0x0', 08:23:03 INFO - 'XPC_SERVICE_NAME': '0', 08:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:03 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/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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 08:23:03 INFO - mozversion application_buildid: 20170511063639 08:23:03 INFO - mozversion application_changeset: 838652a84b76c273e084d0705f3f4f3be89520a8 08:23:03 INFO - mozversion application_display_name: Nightly 08:23:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:23:03 INFO - mozversion application_name: Firefox 08:23:03 INFO - mozversion application_remotingname: firefox 08:23:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:23:03 INFO - mozversion application_vendor: Mozilla 08:23:03 INFO - mozversion application_version: 55.0a1 08:23:03 INFO - mozversion platform_buildid: 20170511063639 08:23:03 INFO - mozversion platform_changeset: 838652a84b76c273e084d0705f3f4f3be89520a8 08:23:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:23:03 INFO - mozversion platform_version: 55.0a1 08:23:03 INFO - using testdate: 1494516183 08:23:03 INFO - actual date: 1494516183 08:23:03 INFO - starting webserver on 'localhost:49251' 08:23:03 INFO - SUITE-START | Running 2 tests 08:23:03 INFO - TEST-START | dromaeo_css 08:23:03 INFO - operating with platform_type : mac_ 08:23:03 INFO - Initialising browser for dromaeo_css test... 08:23:03 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpXUNiqQ/profile http://localhost:49251/getInfo.html) 08:23:06 INFO - PID 1647 | 2017-05-11 08:23:06.540 plugin-container[1649:8828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 08:23:06 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:06 INFO - PID 1647 | 2017-05-11 08:23:06.543 plugin-container[1649:8828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a27, name = 'com.apple.CFPasteboardClient' 08:23:06 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:06 INFO - PID 1647 | 2017-05-11 08:23:06.543 plugin-container[1649:8828] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:06 INFO - PID 1647 | __metrics Screen width/height:1600/1200 08:23:06 INFO - PID 1647 | colorDepth:24 08:23:06 INFO - PID 1647 | Browser inner width/height: 1024/691 08:23:06 INFO - PID 1647 | __metrics 08:23:07 INFO - PID 1647 | 08:23:07 INFO - PID 1647 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 08:23:07 INFO - PID 1647 | 08:23:07 INFO - TEST-INFO | 1647: exit 0 08:23:07 INFO - Browser initialized. 08:23:07 INFO - Running cycle 1/1 for dromaeo_css test... 08:23:07 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpXUNiqQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:23:09 INFO - PID 1652 | 2017-05-11 08:23:09.666 plugin-container[1653:8995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:23:09 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:09 INFO - PID 1652 | 2017-05-11 08:23:09.669 plugin-container[1653:8995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:23:09 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:09 INFO - PID 1652 | 2017-05-11 08:23:09.669 plugin-container[1653:8995] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:10 INFO - PID 1652 | 2017-05-11 08:23:10.389 plugin-container[1654:9040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:23:10 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:10 INFO - PID 1652 | 2017-05-11 08:23:10.390 plugin-container[1654:9040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 08:23:10 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:10 INFO - PID 1652 | 2017-05-11 08:23:10.390 plugin-container[1654:9040] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:19 INFO - PID 1652 | RSS: Main: 227450880 08:23:19 INFO - PID 1652 | 08:26:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 08:26:25 INFO - PID 1652 | RSS: Main: 253825024 08:26:25 INFO - PID 1652 | 08:29:27 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 08:29:27 INFO - PID 1652 | RSS: Main: 258990080 08:29:27 INFO - PID 1652 | 08:32:29 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 08:32:30 INFO - PID 1652 | RSS: Main: 267767808 08:32:30 INFO - PID 1652 | 08:35:31 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 08:35:32 INFO - PID 1652 | RSS: Main: 260259840 08:35:32 INFO - PID 1652 | 08:38:35 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 08:38:36 INFO - PID 1652 | RSS: Main: 265383936 08:38:36 INFO - PID 1652 | 08:41:37 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 08:41:38 INFO - PID 1652 | RSS: Main: 264163328 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | __start_tp_report 08:41:38 INFO - PID 1652 | _x_x_mozilla_page_load 08:41:38 INFO - PID 1652 | _x_x_mozilla_page_load_details 08:41:38 INFO - PID 1652 | |i|pagename|runs| 08:41:38 INFO - PID 1652 | |0;dojo.html;3621.7826930384163;3631.9092022699438;3641.5448068991377;3664.5052917856083;3674.2835147146307;4820.301056346836;6237.53218508612;6249.875002499947;6315.160567587752;6328.114064031033;6262.780802671908;6289.937100628992;6308.936910630892;6316.905246421307;6324.778632747854;5341.385740639837;5441.455854414567;5517.475839795223;5593.3847276799515;5661.122526008455;5551.8889622207535;5594.9720251398685;5633.40849210831;5643.887122257553;5645.491905728483;5419.918701219485;5509.724513774336;5561.805336813192;5592.133219351007;5599.7480113394995;5506.91739623906;5514.310711161105;5547.33431988164;5569.498745112959;5575.8606034849045;5534.806281780158;5536.529395001389;5544.390117087117;5549.834534747708;5553.583481238883;5535.197396377503;5652.265205523297;6256.124142620037;6283.717232724538;6287.402696743802;6751.122354093936;6756.628385438799;6779.084823548857;6787.830304242384;6794.728210871559;6890.379865812101;6899.689513971884;6914.274001229899;6926.445884329241;6938.757143500004;6251.78118765841;6298.68506574674;6304.275008374096;6339.175907132043;6347.428731414195;5926.644401335933;6971.372576468142;7004.15950085993;7037.859242815115;7079.1858936223;6380.425761681471;6501.382368674968;6532.902006469907;6648.069270302161;6656.467482601477;6701.497387695942;6771.255161932184;6837.145356830396;6846.760363387258;6855.965720171367;6452.774152904626;6530.771423000172;6655.600663960178;6658.334166583303;6661.966690166518;5746.4253574643035;6445.097686323918;6623.9006414903815;6732.192136943598;6742.898856517156;4513.616342610849;5291.417944026154;5292.814751483679;5297.470252974749;5335.253064570963;4734.55021772931;4749.572538471554;4750.049990002013;4751.691140075884;4764.761761911891;6293.622382657055;6338.556301058881;6344.968275158687;6367.840803979845;6391.712372943229;5646.350669672958;6772.322767723188;6850.6574671266235;6865.691043903036;6878.037074809531;6281.748730050838;6346.11154438379;6361;6363.681815909186;6382.074599183093;5810.854728631818;5878.265216847894;5889;5939.524838012949;6008.579399441997;7126.073610430611;7126.180489243803;7151.356377926116;7168.641567921479;7175.03137076488;6897.965510172417;6912;6934.826629334307;6944.652767361713;6948.374646281859;6759.459243260429;6854.286428392902;7072.787816365517;7110.1112360954885;7170.605616691132;6590.637514936533;6671.799846004434;6708.262091170065;6739.66301684904;6784.525083244125;5687.715614219355;6839.247682754993;6919.550229235084;6921.550099243533;6925.411340036153;5393.568514518907;6384.489240860627;6443.871122577616;6458.160439142881;6500.772472963424;6214.751409943552;6244.500439964882;6294.307626161027;6655.700493477708;6669.7665581704405;6030.879382412415;6294.590851594632;6359.300476947625;6382.585131966407;6469.902951455826;6540.117084193573;6577.177852768404;6588.374104460068;6618.2719900811835;6636.402723754885;6470.191226096738;6486.481081513373;6487.675616219264;6491.156149700509;6515.218173819172 08:41:38 INFO - PID 1652 | |1;ext.html;722.9855402891942;824.7360844529751;1185.2473679213701;1282.7947528395448;1284.1524593768108;37420.06449838755;37985.62014379855;38000.049998750044;38016.61983380169;38085.61914380855;38108;38440.61559384405;38494.4602215911;38501.03747406316;38602.42096368557;7777.338926191266;8332.958335208315;8534.74395768128;8556.486610803371;8590.828183436328;8452.78868028298;8565.657373705044;8572.442791218582;8577.571121443934;8589.742307730778;7187.425005999507;8558.70044548441;8620.741377758677;8623.913760862406;8652.351073669468;8473.661053557851;8563.529005904673;8579.184977427134;8592.570371481432;8595.226429621302;8362.121977192368;8385;8393.160683931557;8397.286230670401;8444.957775211145;37407.81296093529;37517.62482375196;37614.05964850901;37676.05809854748;37727.81136094302;3559.804210768407;3580.785152890835;3599.190182209008;3608.585012723544;3617.4935509028755;29960.95136670228;30380.69619303801;30444.238894027603;30449.847750761324;30545.541816872767;2873.218604465224;2930.956035659467;3204.7275981541598;3208.582884225059;3210.341879914612;251604;298254.0174598248;299227;300998;302856;37080;39154.45496042748;39373;48090.55728328156;48659;49754.751226243636;50154.49845501571;50295;50428;50517.73705657315;14095.295235238196;14104.365303561364;14231.003829731819;14254.144751314954;14354.49759258421;11428.94285528567;11522.193446458836;11551.306921584732;11561.537538498535;11667.533298667959;12845.357732113356;13288.867111328764;13451;13484.932575337061;13894.81894039018;5237.214417837278;5992.940070599326;6008.158857759917;6029.57792954489;6050.939490605126;1027.8972102789662;1039.5633833789827;1042.4839704346398;1069.1553672598716;1077.0468135699805;19484.610307793766;22793;24628.138015169385;24895.377615559395;24964;962.8988956159642;1105.3367979212408;1107.479484642217;1147.1167201254987;1163.6567212672282;11475.368854712904;11990.520379184734;12110.212836165621;12682.429290681941;12697.55558555446;251538;299727;301582;301906;302360;54691.72654136783;55500.44499555034;55531;55796.44203557912;55890;1488.0773920169563;1514.4623658601038;1554.727922613538;1559.376249500209;1570.2698245315983;24238.515229694956;24241.878790605933;24266.87866560656;24312.87843560771;24368;39007;39161.608383915795;39264.803675981435;39271.214575708895;39309.82070537889;251858;253341;253355;254091;254393;30255.394892101594;30658.540121897742;34452.82773586216;34862.82568587141;35408;44299;45087.32369014533;45165.32252016288;46504;46660.766696166305;30139;31191.844040780557;31633.20916977094;31648.367032659677;31789;30805.22986925345;31201.687983120788;31463.68536314699;31518.36963260768;31524.527132093495;37402.625973739916;37775.62224377721;37822.62177378191;37905.67330143432;37913.43129852999;29563.704362956094;31076.844615776776;31193.688063119986;31771.682283177797;32215;24575.262742117764;24602.138925137533;24718.382040449134;24892.75107248977;25018.874905625355 08:41:38 INFO - PID 1652 | |2;jquery.html;385.85144719283073;434.70874514075564;440.56824312174086;443.9378487011817;466.7666166916542;21063.36809895704;21348.35954921351;21764;21920.23279185229;21951.0122044508;21468.46328841776;22336.441588960242;22458.55082898341;22619.208327708493;22664.09343626257;5844.211031510756;6022.066579680157;6072.453479186872;6098.4511393974535;6102.694865256742;5693.259876216086;5962.403759624024;5968.224130863004;6017.06735456005;6021.548383871227;5864.2962844458725;5932.2584676915385;5950.226470558821;5985.4912332451795;6001.129836173752;4757.858114052637;4868.488808675109;5379.843333683258;5558.972205138969;5752.28096487939;4992.750362481861;5189.377274727057;5272;5284.154535274338;5307.3896501902;26230.34424139412;26495.072672456372;26577.468450631073;26690.733092669212;26856.374389457367;2223.4774827915426;2263.0495192019393;2280.2817112609473;2285.3486756274383;2314.351981445198;17608.471745847644;17647.558811029827;18021.369252076114;18162.45512634623;18227.54431139219;1909.9713504297447;2128.9254876079262;2174.5759576882515;2195.3633446300555;2201.559688062394;125402;144972;145307;147638;160489;12149.757004859854;12492.562760303346;12573.485302939449;12669.683257918627;12712.110152289251;16072.437464688679;16201;16203.351865925466;16221.756673649905;16287.267072981744;8807.119288071142;8870.911290887138;9002.819943601222;9103.772405689911;9153.95423022894;8433.241008309158;8490.27832634233;8495.405321627426;8497.320214382835;8575.614097365633;7843.960780196063;7932.563708996061;7993.240642138988;7993.240642138988;8023.277904988579;2235.418791114322;2249.5950728868634;2260.7400148982747;2262.6719125726677;2436.8781560921884;834.6577903059716;941.7928055827707;950.273041123541;1005.7083445800782;1007.7581380468635;20515.692264616042;20640.58718825615;20694.48263793387;20872.58254834895;21104.577908442054;980.67147505585;983.7294743945472;1003.6085926488676;1003.9046290602381;1013.3160116921049;10392.42841643717;10569.894301057046;10588.947055264674;10597.311174773615;10721.624743133953;504703;506299;514396;516659;518777;113135;117685;117847;120073;120867.18699219519;1195.3784032303154;1233.210745123122;1235.018160562337;1262.2931158551241;1262.5013119817804;21307.78692213058;22070.779292206873;22107.115715371205;22171;22215.666764998216;44091.77954110209;53323.733381332844;54019.459805403014;55122.72438637781;55263.723681381336;50454.747726261136;73405;73841;77115;81265.18734812812;7416.25837416254;8664.826703466022;8815.779605509913;8893.377463577488;9066.592003359996;13820.792688109888;13975.231362275172;14015.159090454605;14314.355853986805;14608.634784130481;9648.324617276723;9853.753656158653;11870.762584748083;12031.097667674785;12081;9611.423314601147;10401.53193106297;10622.910179581071;10810;10959.890401095887;11867.287962722265;11897.940510297392;12133.635990920286;12184.695382615506;12241.938790305992;8779.736607901774;8804.603792829466;8808.383413161017;9123.498207598646;9180.862287065833;10231.48842557884;11692.298462092302;11736.823947640964;11855.466504007169;12034.217775844892 08:41:38 INFO - PID 1652 | |3;mootools.html;1251.2117366059383;1333.9733205335895;1381.7443772902009;1460.664047269128;1460.9269536523184;11396.487158077885;11546.595869144581;11636.941815290922;11735.47190376433;11900.285982841036;11441.313521188735;11690.24013439128;11741.354225517593;11752;11767.470463829126;2489.1785710715512;2537.1627362970175;2544.033267358401;2544.567423537994;2545.6054311581734;2423.933469273514;2469.3579669286023;2487.3035550046015;2490.091116742385;2492.950140997179;2500.499900019994;2501.8373805702663;2543.1480454047824;2544.9872750636314;2548.6686730724978;2459.4712136890566;2480.268320845346;2499.450120973392;2512.560301947152;2516.3331717094984;2395.8921848516857;2474.8886300116537;2486.5151295497385;2488.850668959868;2502.5245203411478;11712;11846.466908989116;11858.762824743457;11871.940640296829;11882.643520694306;6587.2095348558005;6811.352921472452;6837.453003759687;6843.62360070196;6865.794026179223;3959.8614048508452;4335;4338.2191205582985;4402.933955990663;4406.074724307867;1252.3425201769053;1261.438659796391;1267.7781388257017;1301.7982212757038;1306.3664122900386;16102.355905763756;16415.83584164155;16558;16568.420105296256;16730.74903876431;5541.972290138524;5629.831105066854;5707.429257074259;5762.827115186551;5816.243888294579;11646.475908584134;11701.2979221247;11725.472353744102;11735.647930562096;11841.348725819991;6472.546921715435;6545.37818907203;6549.476041916635;6550.8034758956355;6562.2453417856605;6023.126646636213;6093.512518998469;6150.108234306001;6242.843928901813;6269.0909818076125;4202.957970420257;4370.300751879684;4375.16871794358;4408.074304396048;4411.117776444724;1075.9946200268948;1119.5018216893568;1269.1940617707637;1290.3354772292278;1298.8506321772927;797.1191833024425;808.6159074439594;824.4517395931758;829.8630725930275;856.9914300857037;6151.72317245725;6184.752609895644;6304.5271604628915;6436.195475565554;6467.223933128054;592.5911121326358;592.9021711417654;599.2688919518181;614.640435345319;616.3528295289927;3475.4091804393315;3867.9226415471535;3968.920621587552;4003.5195776507007;4320.351947207944;13968.650783730285;14101.435942562388;14658.120512769055;14660.486882959045;14671.853281467262;13703.383347749375;13706.314684265746;14246;14934.327955241823;15009.699806003819;7234.131904171533;7891.763247102596;8246.628901699438;8281.29608983243;8290.668373265122;5784.797532086357;6238.157848690369;6642.767503137367;7046;7101.3307560777375;11344.546218151512;12907.096503244682;13404;13544.525941591997;13572.592822215349;12251.264924104582;13303.334833258492;13700.588982330546;13897;13955.511557095859;9132.680356187502;9266.536673166449;9267.582958767007;9270.72187834366;9306.06939306064;10231.7442063949;10267.024632659886;10533.736656583647;10614.469276536296;10621.522031508446;8719.084496127814;8780.824383512167;8973.102689730975;9015.594298256725;9093.681721139728;7614.314711675976;8778.648854045767;8934.553272336227;8998.685046023358;9065.954670226607;8128.309093727099;8228.711995080144;8272.255497005299;8273.503589784392;8276.83446331082;6027.789027384196;6040.335563088145;6158.507319414526;6187.504999600112;6224.439800417803;5476.658218736435;5852.239208902815;6105.481034111973;6205.5345849060595;6290.182276304051 08:41:38 INFO - PID 1652 | |4;prototype.html;386.92841824262496;398.82651046794626;406.4756464161232;406.90437747129425;409.92211479818843;4818.180909245428;6102.481289090421;6283.968580157099;6427.2929977702415;6430.421262086411;5745.683987380703;6044.637321760686;6280.4661603763625;6305.558610897239;6380.840478988028;4237.38557909103;4505.6170225530705;4821.577634597793;4842;4846.4911184325665;4730.989329534951;4840.877633926116;4842.443119041302;4855.902881942359;4871.902561948759;3910.042039700277;4359.542248063955;4399.05420334628;4694.366260554815;4771.18889788735;3913.491246137984;4777.570018698317;4787.976060119712;4799.928001079987;4811.518848115161;3378.763486555942;4250.766207858566;4287.314029755255;4296.677749168824;4315.913681726379;4282.571742825724;4423.867283981485;6000.7698421823425;6028.336882942873;6152.938470615281;4353.956460435386;5392.448175466827;5416.756245968941;5422.267993820823;5442.394493624351;5631.5776316776;5827.796027139072;5896.027155519377;6388;6716.3283671632935;4411.933820992687;4413.227685155085;4415.448068991376;4443.644508439317;4447.599716025573;6235.594686345418;6886.662836301856;6995.650217489105;7159.212486626467;7276.090488688914;4420.668449866273;4444.31113177458;4448.666350023761;4460.397846290774;4470;4551.065797036246;4564;4567.200739870509;4569.81720731167;4578.862634120982;5057.367829021372;5289.709066001406;5420.728963551806;5427;5459.153831156177;6050.515958723292;6237.347103017704;6256.874862502725;6744.224414192333;6757.918733002696;1380.496118916588;1480.208088672555;1542.4113163441673;1563.2262030295076;1567.6237702951437;885.4775682347317;982.3123813330554;1007.6473234367912;1012.1953047327245;1024.4570377699831;5841.977653910633;6333.22669652498;6374.055912946503;6385.840353991188;6416.839579010562;6597.043428702811;6653.534252602368;6776.7628133015105;6799.3200679931615;7044.471664625071;5377.569794416438;5600.5799565032785;6480.157579514633;6498.577592456475;6507.219133703891;2980.687027862087;3180.030090822321;3453.723702103876;3718.2935242303874;3725.2549490102087;6405.29541750407;6429.7749578764515;6568.638724870082;7076.115485564304;7276.235995220426;5907.6959985605645;6422.293547709748;7353.70585176587;7358.77923662291;7369.336759691546;6187.855246779451;6594.845901967136;7040.295970402919;7286.635668216568;7458.589777562178;7194.352508274281;7195.424366050808;7197.964010180125;7198.424126069797;7208.783736487913;5451.563874890078;6094.75620975156;6672.566283191577;6728.259891411843;6978.232394436506;6480.870382592416;6583.275839657538;7200.811866042044;7378.372838308804;7456.925430745623;4490.236659767782;4494;4505.504394516666;4518.118966801574;4529.275315949432;6386.361363863576;6511.283758786435;6990.860182796418;7063.646817659199;7096.409810419263;5135.820246291362;5589.552835773047;6012.5791194617705;6452.35476452351;6499.4800415965665;5502.394736578893;5583.74873130702;5874.559408044499;6559.639219842955;6809.727610895707;5805.738741756547;6131.540134489842;6623.456450798993;6690.364415380531;6691.46468282546;5911.467967882912;6755.7973260802255;6788.796336109925;6807.115075040213;6832.829179270558;5239.554637855673;6584.4732421405215;6611.404973552403;6639.269680335063;6654.73381064752 08:41:38 INFO - PID 1652 | |5;yui.html;839.8530257204987;861.9999999999997;862.5988915154452;865.9307255419567;883.9292856571475;15927;16392.26234819433;16887.32450701972;16902.239399227034;19516.51208719783;15478.845211547909;17858.374956876512;18376.724349134773;18537.721934170997;18653.81346186538;995.2336700740426;1001.0040010189871;1009.6516701737888;1016.9644062457816;1017.3539802225603;1011.9595216191343;1012.8784545854471;1014.2595904989362;1015.8019186258682;1019.3119644240145;1019.5463018956567;1070.347088276151;1080.4543705428748;1083.8320060390652;1085.696005118568;1056.487603512296;1071.539238127605;1077.8221593437074;1080.3193987787683;1083.0685610375072;732.6300218389679;767.7044337929881;781.1212386065677;781.2500000000007;783;16707.91646041762;16963.49109526704;16969.151542422955;17140.485785426456;17170.57073573158;15290.388384464608;17130.828691713174;17451.04019278952;17594.47216583492;17633.677474189488;13834.031617786759;13894.791578126336;14133.929330353385;14135.081219720685;14782.408703651841;3539.7345199109914;3596.9820150899077;3605.675489205958;3608.566971963381;3610.332088563624;119474.80525194724;139011;139078;139602.30198849042;140423.1915361687;11891.28652280866;13553;13556.59330220095;13853.168809871238;13907.374168162458;22096.88951555232;26014.34964125874;26419;26500.867495662784;26923.461530769277;7199.784006479814;7377.852442951218;7430.331270185709;7440.813979650444;7462.925370746331;3682.1899182179877;3756.7745935243975;3756.9812150939615;3757.680597149273;3762.811859407019;4335.739855608674;4371.69398142127;4440.245158323029;4441.444819397575;4441.822327106879;953.3517208298424;969.8545218217324;970.6214576315101;974.9951250243703;975.6292608808608;556.7271193589268;562.7917670461874;563.5266376243975;563.6646195513663;575.6057100885906;10039.598416063422;10814.891851081546;11163.497642606102;11923;12111.152219344562;630.1021045010841;649.6426965169233;654.5320096131289;669.4477056428466;684.1892357556312;4995.475475075064;5453.23654688344;5576.191452239403;5623.268975033219;5664.915026274609;105801;105976;106618;106721.4663926691;108125;72691.27308726845;96466;96624;96798.51600741952;96918;68930;76975.61512192515;82256;87547.12452875389;89048;11703.414829258674;12074.335911524951;12397.75204495923;12478.875211248134;12771.169873958162;38301.808490957366;39413;39479.60520394874;40076.7996160029;40498.59501405066;51353;66164.66917665381;69003.96494052492;70377;72421.27578724145;16176.191190440666;16287.51137465878;16668.166591670124;17025.829741702426;17078.573035674206;17657.64684706273;18703.719444208076;18712.25150993945;18801.05994700287;19092.949887755753;12953;15236.552527509868;16160.838391615933;17690.734638980168;17723.20245588926;13367.663233676554;16169.514914552583;17355.2190151446;17463.738043929097;17463.912680436515;13439.46242150303;16671.666566668842;16701.49895503137;16755.796763535956;17037.318507260068;10290.845637315297;10634.41510716887;10854.403007834644;10987.011168994828;11464.770704585695;13949.930250348683;14150.504732334319;14184.36170372315;14265.286735663383;14390.136591804107 08:41:38 INFO - PID 1652 | __end_tp_report 08:41:38 INFO - PID 1652 | __start_cc_report 08:41:38 INFO - PID 1652 | _x_x_mozilla_cycle_collect,408 08:41:38 INFO - PID 1652 | __end_cc_report 08:41:38 INFO - PID 1652 | __startTimestamp1494517298056__endTimestamp 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | ------- Summary: start ------- 08:41:38 INFO - PID 1652 | Number of tests: 6 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6195.55 Median:6363.68 stddev:754.05 (11.8%) stddev-sans-first:728.83 08:41:38 INFO - PID 1652 | Values: 3621.8 3631.9 3641.5 3664.5 3674.3 4820.3 6237.5 6249.9 6315.2 6328.1 6262.8 6289.9 6308.9 6316.9 6324.8 5341.4 5441.5 5517.5 5593.4 5661.1 5551.9 5595.0 5633.4 5643.9 5645.5 5419.9 5509.7 5561.8 5592.1 5599.7 5506.9 5514.3 5547.3 5569.5 5575.9 5534.8 5536.5 5544.4 5549.8 5553.6 5535.2 5652.3 6256.1 6283.7 6287.4 6751.1 6756.6 6779.1 6787.8 6794.7 6890.4 6899.7 6914.3 6926.4 6938.8 6251.8 6298.7 6304.3 6339.2 6347.4 5926.6 6971.4 7004.2 7037.9 7079.2 6380.4 6501.4 6532.9 6648.1 6656.5 6701.5 6771.3 6837.1 6846.8 6856.0 6452.8 6530.8 6655.6 6658.3 6662.0 5746.4 6445.1 6623.9 6732.2 6742.9 4513.6 5291.4 5292.8 5297.5 5335.3 4734.6 4749.6 4750.0 4751.7 4764.8 6293.6 6338.6 6345.0 6367.8 6391.7 5646.4 6772.3 6850.7 6865.7 6878.0 6281.7 6346.1 6361.0 6363.7 6382.1 5810.9 5878.3 5889.0 5939.5 6008.6 7126.1 7126.2 7151.4 7168.6 7175.0 6898.0 6912.0 6934.8 6944.7 6948.4 6759.5 6854.3 7072.8 7110.1 7170.6 6590.6 6671.8 6708.3 6739.7 6784.5 5687.7 6839.2 6919.6 6921.6 6925.4 5393.6 6384.5 6443.9 6458.2 6500.8 6214.8 6244.5 6294.3 6655.7 6669.8 6030.9 6294.6 6359.3 6382.6 6469.9 6540.1 6577.2 6588.4 6618.3 6636.4 6470.2 6486.5 6487.7 6491.2 6515.2 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:43346.81 Median:3206.66 stddev:73101.16 (2279.7%) stddev-sans-first:73236.17 08:41:38 INFO - PID 1652 | Values: 723.0 824.7 1185.2 1282.8 1284.2 37420.1 37985.6 38000.0 38016.6 38085.6 38108.0 38440.6 38494.5 38501.0 38602.4 7777.3 8333.0 8534.7 8556.5 8590.8 8452.8 8565.7 8572.4 8577.6 8589.7 7187.4 8558.7 8620.7 8623.9 8652.4 8473.7 8563.5 8579.2 8592.6 8595.2 8362.1 8385.0 8393.2 8397.3 8445.0 37407.8 37517.6 37614.1 37676.1 37727.8 3559.8 3580.8 3599.2 3608.6 3617.5 29961.0 30380.7 30444.2 30449.8 30545.5 2873.2 2931.0 3204.7 3208.6 3210.3 251604.0 298254.0 299227.0 300998.0 302856.0 37080.0 39154.5 39373.0 48090.6 48659.0 49754.8 50154.5 50295.0 50428.0 50517.7 14095.3 14104.4 14231.0 14254.1 14354.5 11428.9 11522.2 11551.3 11561.5 11667.5 12845.4 13288.9 13451.0 13484.9 13894.8 5237.2 5992.9 6008.2 6029.6 6050.9 1027.9 1039.6 1042.5 1069.2 1077.0 19484.6 22793.0 24628.1 24895.4 24964.0 962.9 1105.3 1107.5 1147.1 1163.7 11475.4 11990.5 12110.2 12682.4 12697.6 251538.0 299727.0 301582.0 301906.0 302360.0 54691.7 55500.4 55531.0 55796.4 55890.0 1488.1 1514.5 1554.7 1559.4 1570.3 24238.5 24241.9 24266.9 24312.9 24368.0 39007.0 39161.6 39264.8 39271.2 39309.8 251858.0 253341.0 253355.0 254091.0 254393.0 30255.4 30658.5 34452.8 34862.8 35408.0 44299.0 45087.3 45165.3 46504.0 46660.8 30139.0 31191.8 31633.2 31648.4 31789.0 30805.2 31201.7 31463.7 31518.4 31524.5 37402.6 37775.6 37822.6 37905.7 37913.4 29563.7 31076.8 31193.7 31771.7 32215.0 24575.3 24602.1 24718.4 24892.8 25018.9 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:33606.08 Median:2262.86 stddev:86736.75 (3833.1%) stddev-sans-first:86944.20 08:41:38 INFO - PID 1652 | Values: 385.9 434.7 440.6 443.9 466.8 21063.4 21348.4 21764.0 21920.2 21951.0 21468.5 22336.4 22458.6 22619.2 22664.1 5844.2 6022.1 6072.5 6098.5 6102.7 5693.3 5962.4 5968.2 6017.1 6021.5 5864.3 5932.3 5950.2 5985.5 6001.1 4757.9 4868.5 5379.8 5559.0 5752.3 4992.8 5189.4 5272.0 5284.2 5307.4 26230.3 26495.1 26577.5 26690.7 26856.4 2223.5 2263.0 2280.3 2285.3 2314.4 17608.5 17647.6 18021.4 18162.5 18227.5 1910.0 2128.9 2174.6 2195.4 2201.6 125402.0 144972.0 145307.0 147638.0 160489.0 12149.8 12492.6 12573.5 12669.7 12712.1 16072.4 16201.0 16203.4 16221.8 16287.3 8807.1 8870.9 9002.8 9103.8 9154.0 8433.2 8490.3 8495.4 8497.3 8575.6 7844.0 7932.6 7993.2 7993.2 8023.3 2235.4 2249.6 2260.7 2262.7 2436.9 834.7 941.8 950.3 1005.7 1007.8 20515.7 20640.6 20694.5 20872.6 21104.6 980.7 983.7 1003.6 1003.9 1013.3 10392.4 10569.9 10588.9 10597.3 10721.6 504703.0 506299.0 514396.0 516659.0 518777.0 113135.0 117685.0 117847.0 120073.0 120867.2 1195.4 1233.2 1235.0 1262.3 1262.5 21307.8 22070.8 22107.1 22171.0 22215.7 44091.8 53323.7 54019.5 55122.7 55263.7 50454.7 73405.0 73841.0 77115.0 81265.2 7416.3 8664.8 8815.8 8893.4 9066.6 13820.8 13975.2 14015.2 14314.4 14608.6 9648.3 9853.8 11870.8 12031.1 12081.0 9611.4 10401.5 10622.9 10810.0 10959.9 11867.3 11897.9 12133.6 12184.7 12241.9 8779.7 8804.6 8808.4 9123.5 9180.9 10231.5 11692.3 11736.8 11855.5 12034.2 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7014.09 Median:3671.67 stddev:4421.77 (120.4%) stddev-sans-first:4412.97 08:41:38 INFO - PID 1652 | Values: 1251.2 1334.0 1381.7 1460.7 1460.9 11396.5 11546.6 11636.9 11735.5 11900.3 11441.3 11690.2 11741.4 11752.0 11767.5 2489.2 2537.2 2544.0 2544.6 2545.6 2423.9 2469.4 2487.3 2490.1 2493.0 2500.5 2501.8 2543.1 2545.0 2548.7 2459.5 2480.3 2499.5 2512.6 2516.3 2395.9 2474.9 2486.5 2488.9 2502.5 11712.0 11846.5 11858.8 11871.9 11882.6 6587.2 6811.4 6837.5 6843.6 6865.8 3959.9 4335.0 4338.2 4402.9 4406.1 1252.3 1261.4 1267.8 1301.8 1306.4 16102.4 16415.8 16558.0 16568.4 16730.7 5542.0 5629.8 5707.4 5762.8 5816.2 11646.5 11701.3 11725.5 11735.6 11841.3 6472.5 6545.4 6549.5 6550.8 6562.2 6023.1 6093.5 6150.1 6242.8 6269.1 4203.0 4370.3 4375.2 4408.1 4411.1 1076.0 1119.5 1269.2 1290.3 1298.9 797.1 808.6 824.5 829.9 857.0 6151.7 6184.8 6304.5 6436.2 6467.2 592.6 592.9 599.3 614.6 616.4 3475.4 3867.9 3968.9 4003.5 4320.4 13968.7 14101.4 14658.1 14660.5 14671.9 13703.4 13706.3 14246.0 14934.3 15009.7 7234.1 7891.8 8246.6 8281.3 8290.7 5784.8 6238.2 6642.8 7046.0 7101.3 11344.5 12907.1 13404.0 13544.5 13572.6 12251.3 13303.3 13700.6 13897.0 13955.5 9132.7 9266.5 9267.6 9270.7 9306.1 10231.7 10267.0 10533.7 10614.5 10621.5 8719.1 8780.8 8973.1 9015.6 9093.7 7614.3 8778.6 8934.6 8998.7 9066.0 8128.3 8228.7 8272.3 8273.5 8276.8 6027.8 6040.3 6158.5 6187.5 6224.4 5476.7 5852.2 6105.5 6205.5 6290.2 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5356.31 Median:5901.86 stddev:1696.44 (28.7%) stddev-sans-first:1659.69 08:41:38 INFO - PID 1652 | Values: 386.9 398.8 406.5 406.9 409.9 4818.2 6102.5 6284.0 6427.3 6430.4 5745.7 6044.6 6280.5 6305.6 6380.8 4237.4 4505.6 4821.6 4842.0 4846.5 4731.0 4840.9 4842.4 4855.9 4871.9 3910.0 4359.5 4399.1 4694.4 4771.2 3913.5 4777.6 4788.0 4799.9 4811.5 3378.8 4250.8 4287.3 4296.7 4315.9 4282.6 4423.9 6000.8 6028.3 6152.9 4354.0 5392.4 5416.8 5422.3 5442.4 5631.6 5827.8 5896.0 6388.0 6716.3 4411.9 4413.2 4415.4 4443.6 4447.6 6235.6 6886.7 6995.7 7159.2 7276.1 4420.7 4444.3 4448.7 4460.4 4470.0 4551.1 4564.0 4567.2 4569.8 4578.9 5057.4 5289.7 5420.7 5427.0 5459.2 6050.5 6237.3 6256.9 6744.2 6757.9 1380.5 1480.2 1542.4 1563.2 1567.6 885.5 982.3 1007.6 1012.2 1024.5 5842.0 6333.2 6374.1 6385.8 6416.8 6597.0 6653.5 6776.8 6799.3 7044.5 5377.6 5600.6 6480.2 6498.6 6507.2 2980.7 3180.0 3453.7 3718.3 3725.3 6405.3 6429.8 6568.6 7076.1 7276.2 5907.7 6422.3 7353.7 7358.8 7369.3 6187.9 6594.8 7040.3 7286.6 7458.6 7194.4 7195.4 7198.0 7198.4 7208.8 5451.6 6094.8 6672.6 6728.3 6978.2 6480.9 6583.3 7200.8 7378.4 7456.9 4490.2 4494.0 4505.5 4518.1 4529.3 6386.4 6511.3 6990.9 7063.6 7096.4 5135.8 5589.6 6012.6 6452.4 6499.5 5502.4 5583.7 5874.6 6559.6 6809.7 5805.7 6131.5 6623.5 6690.4 6691.5 5911.5 6755.8 6788.8 6807.1 6832.8 5239.6 6584.5 6611.4 6639.3 6654.7 08:41:38 INFO - PID 1652 | 08:41:38 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:22423.16 Median:17614.07 stddev:32228.59 (183.0%) stddev-sans-first:32278.25 08:41:38 INFO - PID 1652 | Values: 839.9 862.0 862.6 865.9 883.9 15927.0 16392.3 16887.3 16902.2 19516.5 15478.8 17858.4 18376.7 18537.7 18653.8 995.2 1001.0 1009.7 1017.0 1017.4 1012.0 1012.9 1014.3 1015.8 1019.3 1019.5 1070.3 1080.5 1083.8 1085.7 1056.5 1071.5 1077.8 1080.3 1083.1 732.6 767.7 781.1 781.3 783.0 16707.9 16963.5 16969.2 17140.5 17170.6 15290.4 17130.8 17451.0 17594.5 17633.7 13834.0 13894.8 14133.9 14135.1 14782.4 3539.7 3597.0 3605.7 3608.6 3610.3 119474.8 139011.0 139078.0 139602.3 140423.2 11891.3 13553.0 13556.6 13853.2 13907.4 22096.9 26014.3 26419.0 26500.9 26923.5 7199.8 7377.9 7430.3 7440.8 7462.9 3682.2 3756.8 3757.0 3757.7 3762.8 4335.7 4371.7 4440.2 4441.4 4441.8 953.4 969.9 970.6 975.0 975.6 556.7 562.8 563.5 563.7 575.6 10039.6 10814.9 11163.5 11923.0 12111.2 630.1 649.6 654.5 669.4 684.2 4995.5 5453.2 5576.2 5623.3 5664.9 105801.0 105976.0 106618.0 106721.5 108125.0 72691.3 96466.0 96624.0 96798.5 96918.0 68930.0 76975.6 82256.0 87547.1 89048.0 11703.4 12074.3 12397.8 12478.9 12771.2 38301.8 39413.0 39479.6 40076.8 40498.6 51353.0 66164.7 69004.0 70377.0 72421.3 16176.2 16287.5 16668.2 17025.8 17078.6 17657.6 18703.7 18712.3 18801.1 19092.9 12953.0 15236.6 16160.8 17690.7 17723.2 13367.7 16169.5 17355.2 17463.7 17463.9 13439.5 16671.7 16701.5 16755.8 17037.3 10290.8 10634.4 10854.4 10987.0 11464.8 13949.9 14150.5 14184.4 14265.3 14390.1 08:41:38 INFO - PID 1652 | -------- Summary: end -------- 08:41:38 INFO - PID 1652 | 08:41:38 INFO - TEST-INFO | 1652: exit 0 08:41:38 INFO - TEST-OK | dromaeo_css | took 1114907ms 08:41:38 INFO - TEST-START | kraken 08:41:38 INFO - operating with platform_type : mac_ 08:41:38 INFO - Initialising browser for kraken test... 08:41:38 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpRfiL56/profile http://localhost:49251/getInfo.html) 08:41:41 INFO - PID 1663 | 2017-05-11 08:41:41.158 plugin-container[1664:11277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:41:41 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:41 INFO - PID 1663 | 2017-05-11 08:41:41.160 plugin-container[1664:11277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:41:41 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:41 INFO - PID 1663 | 2017-05-11 08:41:41.160 plugin-container[1664:11277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:41 INFO - PID 1663 | __metrics Screen width/height:1600/1200 08:41:41 INFO - PID 1663 | colorDepth:24 08:41:41 INFO - PID 1663 | Browser inner width/height: 1024/691 08:41:41 INFO - PID 1663 | __metrics 08:41:42 INFO - TEST-INFO | 1663: exit 0 08:41:42 INFO - Browser initialized. 08:41:42 INFO - Running cycle 1/1 for kraken test... 08:41:42 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpRfiL56/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:44 INFO - PID 1665 | 2017-05-11 08:41:44.126 plugin-container[1666:11401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 08:41:44 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:44 INFO - PID 1665 | 2017-05-11 08:41:44.126 plugin-container[1666:11401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:41:44 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:44 INFO - PID 1665 | 2017-05-11 08:41:44.126 plugin-container[1666:11401] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:44 INFO - PID 1665 | 2017-05-11 08:41:44.678 plugin-container[1667:11446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:41:44 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:44 INFO - PID 1665 | 2017-05-11 08:41:44.679 plugin-container[1667:11446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 08:41:44 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:44 INFO - PID 1665 | 2017-05-11 08:41:44.679 plugin-container[1667:11446] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:54 INFO - PID 1665 | RSS: Main: 228069376 08:41:54 INFO - PID 1665 | 08:41:56 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 08:41:57 INFO - PID 1665 | RSS: Main: 241672192 08:41:57 INFO - PID 1665 | 08:42:00 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 08:42:00 INFO - PID 1665 | RSS: Main: 249905152 08:42:00 INFO - PID 1665 | 08:42:04 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 08:42:04 INFO - PID 1665 | RSS: Main: 252104704 08:42:04 INFO - PID 1665 | 08:42:06 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 08:42:07 INFO - PID 1665 | RSS: Main: 253202432 08:42:07 INFO - PID 1665 | 08:42:09 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:42:10 INFO - PID 1665 | RSS: Main: 253710336 08:42:10 INFO - PID 1665 | 08:42:13 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 08:42:13 INFO - PID 1665 | RSS: Main: 259350528 08:42:13 INFO - PID 1665 | 08:42:17 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 08:42:17 INFO - PID 1665 | RSS: Main: 262111232 08:42:17 INFO - PID 1665 | 08:42:20 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 08:42:21 INFO - PID 1665 | RSS: Main: 261849088 08:42:21 INFO - PID 1665 | 08:42:23 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:42:23 INFO - PID 1665 | RSS: Main: 261218304 08:42:23 INFO - PID 1665 | 08:42:25 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:42:26 INFO - PID 1665 | RSS: Main: 261242880 08:42:26 INFO - PID 1665 | 08:42:28 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:42:29 INFO - PID 1665 | RSS: Main: 260915200 08:42:29 INFO - PID 1665 | 08:42:31 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:42:32 INFO - PID 1665 | RSS: Main: 261181440 08:42:32 INFO - PID 1665 | 08:42:35 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:42:36 INFO - PID 1665 | RSS: Main: 261468160 08:42:36 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 08:42:38 INFO - PID 1665 | RSS: Main: 263094272 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | __start_tp_report 08:42:38 INFO - PID 1665 | _x_x_mozilla_page_load 08:42:38 INFO - PID 1665 | _x_x_mozilla_page_load_details 08:42:38 INFO - PID 1665 | |i|pagename|runs| 08:42:38 INFO - PID 1665 | |0;ai-astar;102;114;119;115;115;118;117;115;125;172 08:42:38 INFO - PID 1665 | |1;audio-beat-detection;132;109;138;183;145;121;109;106;138;110 08:42:38 INFO - PID 1665 | |2;audio-dft;196;203;192;207;218;208;204;198;198;198 08:42:38 INFO - PID 1665 | |3;audio-fft;79;65;94;115;81;61;58;61;79;57 08:42:38 INFO - PID 1665 | |4;audio-oscillator;92;84;102;96;106;104;93;71;69;89 08:42:38 INFO - PID 1665 | |5;imaging-gaussian-blur;137;134;92;93;114;91;100;91;93;93 08:42:38 INFO - PID 1665 | |6;imaging-darkroom;98;98;98;97;98;107;107;107;137;156 08:42:38 INFO - PID 1665 | |7;imaging-desaturate;92;121;149;99;144;153;90;92;96;89 08:42:38 INFO - PID 1665 | |8;json-parse-financial;50;49;52;63;67;46;47;70;45;43 08:42:38 INFO - PID 1665 | |9;json-stringify-tinderbox;54;62;58;62;85;88;53;55;83;83 08:42:38 INFO - PID 1665 | |10;stanford-crypto-aes;80;75;93;76;80;89;109;106;103;89 08:42:38 INFO - PID 1665 | |11;stanford-crypto-ccm;138;135;144;141;154;154;139;151;187;137 08:42:38 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;189;201;233;200;186;216;204;200;167;165 08:42:38 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;63;98;105;90;67;66;67;66;69;57 08:42:38 INFO - PID 1665 | __end_tp_report 08:42:38 INFO - PID 1665 | __start_cc_report 08:42:38 INFO - PID 1665 | _x_x_mozilla_cycle_collect,1008 08:42:38 INFO - PID 1665 | __end_cc_report 08:42:38 INFO - PID 1665 | __startTimestamp1494517358664__endTimestamp 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | ------- Summary: start ------- 08:42:38 INFO - PID 1665 | Number of tests: 14 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:121.20 Median:117.50 stddev:18.75 (16.0%) stddev-sans-first:18.55 08:42:38 INFO - PID 1665 | Values: 102.0 114.0 119.0 115.0 115.0 118.0 117.0 115.0 125.0 172.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:135.00 stddev:23.80 (17.6%) stddev-sans-first:25.22 08:42:38 INFO - PID 1665 | Values: 132.0 109.0 138.0 183.0 145.0 121.0 109.0 106.0 138.0 110.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:202.20 Median:203.50 stddev:7.50 (3.7%) stddev-sans-first:7.61 08:42:38 INFO - PID 1665 | Values: 196.0 203.0 192.0 207.0 218.0 208.0 204.0 198.0 198.0 198.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:75.00 Median:72.00 stddev:18.66 (25.9%) stddev-sans-first:19.74 08:42:38 INFO - PID 1665 | Values: 79.0 65.0 94.0 115.0 81.0 61.0 58.0 61.0 79.0 57.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:86.50 stddev:12.83 (14.8%) stddev-sans-first:13.59 08:42:38 INFO - PID 1665 | Values: 92.0 84.0 102.0 96.0 106.0 104.0 93.0 71.0 69.0 89.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:103.80 Median:91.50 stddev:18.10 (19.8%) stddev-sans-first:14.68 08:42:38 INFO - PID 1665 | Values: 137.0 134.0 92.0 93.0 114.0 91.0 100.0 91.0 93.0 93.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:110.30 Median:97.50 stddev:20.04 (20.6%) stddev-sans-first:20.76 08:42:38 INFO - PID 1665 | Values: 98.0 98.0 98.0 97.0 98.0 107.0 107.0 107.0 137.0 156.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:112.50 Median:91.00 stddev:26.65 (29.3%) stddev-sans-first:27.21 08:42:38 INFO - PID 1665 | Values: 92.0 121.0 149.0 99.0 144.0 153.0 90.0 92.0 96.0 89.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:51.00 stddev:9.77 (19.2%) stddev-sans-first:10.30 08:42:38 INFO - PID 1665 | Values: 50.0 49.0 52.0 63.0 67.0 46.0 47.0 70.0 45.0 43.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:72.50 stddev:14.53 (20.0%) stddev-sans-first:14.46 08:42:38 INFO - PID 1665 | Values: 54.0 62.0 58.0 62.0 85.0 88.0 53.0 55.0 83.0 83.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:80.00 stddev:12.55 (15.7%) stddev-sans-first:12.78 08:42:38 INFO - PID 1665 | Values: 80.0 75.0 93.0 76.0 80.0 89.0 109.0 106.0 103.0 89.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:147.50 stddev:15.41 (10.4%) stddev-sans-first:15.92 08:42:38 INFO - PID 1665 | Values: 138.0 135.0 144.0 141.0 154.0 154.0 139.0 151.0 187.0 137.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.10 Median:200.50 stddev:20.66 (10.3%) stddev-sans-first:21.75 08:42:38 INFO - PID 1665 | Values: 189.0 201.0 233.0 200.0 186.0 216.0 204.0 200.0 167.0 165.0 08:42:38 INFO - PID 1665 | 08:42:38 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:67.00 stddev:16.49 (24.6%) stddev-sans-first:16.93 08:42:38 INFO - PID 1665 | Values: 63.0 98.0 105.0 90.0 67.0 66.0 67.0 66.0 69.0 57.0 08:42:38 INFO - PID 1665 | -------- Summary: end -------- 08:42:38 INFO - PID 1665 | 08:42:39 INFO - TEST-INFO | 1665: exit 0 08:42:39 INFO - TEST-OK | kraken | took 60737ms 08:42:39 INFO - SUITE-END | took 1175s 08:42:39 INFO - Completed test suite (00:19:35) 08:42:39 INFO - javascript benchmark 08:42:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7868.1211654804865, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3621.7826930384163, 3631.9092022699438, 3641.5448068991377, 3664.5052917856083, 3674.2835147146307, 4820.301056346836, 6237.53218508612, 6249.875002499947, 6315.160567587752, 6328.114064031033, 6262.780802671908, 6289.937100628992, 6308.936910630892, 6316.905246421307, 6324.778632747854, 5341.385740639837, 5441.455854414567, 5517.475839795223, 5593.3847276799515, 5661.122526008455, 5551.8889622207535, 5594.9720251398685, 5633.40849210831, 5643.887122257553, 5645.491905728483, 5419.918701219485, 5509.724513774336, 5561.805336813192, 5592.133219351007, 5599.7480113394995, 5506.91739623906, 5514.310711161105, 5547.33431988164, 5569.498745112959, 5575.8606034849045, 5534.806281780158, 5536.529395001389, 5544.390117087117, 5549.834534747708, 5553.583481238883, 5535.197396377503, 5652.265205523297, 6256.124142620037, 6283.717232724538, 6287.402696743802, 6751.122354093936, 6756.628385438799, 6779.084823548857, 6787.830304242384, 6794.728210871559, 6890.379865812101, 6899.689513971884, 6914.274001229899, 6926.445884329241, 6938.757143500004, 6251.78118765841, 6298.68506574674, 6304.275008374096, 6339.175907132043, 6347.428731414195, 5926.644401335933, 6971.372576468142, 7004.15950085993, 7037.859242815115, 7079.1858936223, 6380.425761681471, 6501.382368674968, 6532.902006469907, 6648.069270302161, 6656.467482601477, 6701.497387695942, 6771.255161932184, 6837.145356830396, 6846.760363387258, 6855.965720171367, 6452.774152904626, 6530.771423000172, 6655.600663960178, 6658.334166583303, 6661.966690166518, 5746.4253574643035, 6445.097686323918, 6623.9006414903815, 6732.192136943598, 6742.898856517156, 4513.616342610849, 5291.417944026154, 5292.814751483679, 5297.470252974749, 5335.253064570963, 4734.55021772931, 4749.572538471554, 4750.049990002013, 4751.691140075884, 4764.761761911891, 6293.622382657055, 6338.556301058881, 6344.968275158687, 6367.840803979845, 6391.712372943229, 5646.350669672958, 6772.322767723188, 6850.6574671266235, 6865.691043903036, 6878.037074809531, 6281.748730050838, 6346.11154438379, 6361.0, 6363.681815909186, 6382.074599183093, 5810.854728631818, 5878.265216847894, 5889.0, 5939.524838012949, 6008.579399441997, 7126.073610430611, 7126.180489243803, 7151.356377926116, 7168.641567921479, 7175.03137076488, 6897.965510172417, 6912.0, 6934.826629334307, 6944.652767361713, 6948.374646281859, 6759.459243260429, 6854.286428392902, 7072.787816365517, 7110.1112360954885, 7170.605616691132, 6590.637514936533, 6671.799846004434, 6708.262091170065, 6739.66301684904, 6784.525083244125, 5687.715614219355, 6839.247682754993, 6919.550229235084, 6921.550099243533, 6925.411340036153, 5393.568514518907, 6384.489240860627, 6443.871122577616, 6458.160439142881, 6500.772472963424, 6214.751409943552, 6244.500439964882, 6294.307626161027, 6655.700493477708, 6669.7665581704405, 6030.879382412415, 6294.590851594632, 6359.300476947625, 6382.585131966407, 6469.902951455826, 6540.117084193573, 6577.177852768404, 6588.374104460068, 6618.2719900811835, 6636.402723754885, 6470.191226096738, 6486.481081513373, 6487.675616219264, 6491.156149700509, 6515.218173819172], "value": 6146.9572524151135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.9855402891942, 824.7360844529751, 1185.2473679213701, 1282.7947528395448, 1284.1524593768108, 37420.06449838755, 37985.62014379855, 38000.049998750044, 38016.61983380169, 38085.61914380855, 38108.0, 38440.61559384405, 38494.4602215911, 38501.03747406316, 38602.42096368557, 7777.338926191266, 8332.958335208315, 8534.74395768128, 8556.486610803371, 8590.828183436328, 8452.78868028298, 8565.657373705044, 8572.442791218582, 8577.571121443934, 8589.742307730778, 7187.425005999507, 8558.70044548441, 8620.741377758677, 8623.913760862406, 8652.351073669468, 8473.661053557851, 8563.529005904673, 8579.184977427134, 8592.570371481432, 8595.226429621302, 8362.121977192368, 8385.0, 8393.160683931557, 8397.286230670401, 8444.957775211145, 37407.81296093529, 37517.62482375196, 37614.05964850901, 37676.05809854748, 37727.81136094302, 3559.804210768407, 3580.785152890835, 3599.190182209008, 3608.585012723544, 3617.4935509028755, 29960.95136670228, 30380.69619303801, 30444.238894027603, 30449.847750761324, 30545.541816872767, 2873.218604465224, 2930.956035659467, 3204.7275981541598, 3208.582884225059, 3210.341879914612, 251604.0, 298254.0174598248, 299227.0, 300998.0, 302856.0, 37080.0, 39154.45496042748, 39373.0, 48090.55728328156, 48659.0, 49754.751226243636, 50154.49845501571, 50295.0, 50428.0, 50517.73705657315, 14095.295235238196, 14104.365303561364, 14231.003829731819, 14254.144751314954, 14354.49759258421, 11428.94285528567, 11522.193446458836, 11551.306921584732, 11561.537538498535, 11667.533298667959, 12845.357732113356, 13288.867111328764, 13451.0, 13484.932575337061, 13894.81894039018, 5237.214417837278, 5992.940070599326, 6008.158857759917, 6029.57792954489, 6050.939490605126, 1027.8972102789662, 1039.5633833789827, 1042.4839704346398, 1069.1553672598716, 1077.0468135699805, 19484.610307793766, 22793.0, 24628.138015169385, 24895.377615559395, 24964.0, 962.8988956159642, 1105.3367979212408, 1107.479484642217, 1147.1167201254987, 1163.6567212672282, 11475.368854712904, 11990.520379184734, 12110.212836165621, 12682.429290681941, 12697.55558555446, 251538.0, 299727.0, 301582.0, 301906.0, 302360.0, 54691.72654136783, 55500.44499555034, 55531.0, 55796.44203557912, 55890.0, 1488.0773920169563, 1514.4623658601038, 1554.727922613538, 1559.376249500209, 1570.2698245315983, 24238.515229694956, 24241.878790605933, 24266.87866560656, 24312.87843560771, 24368.0, 39007.0, 39161.608383915795, 39264.803675981435, 39271.214575708895, 39309.82070537889, 251858.0, 253341.0, 253355.0, 254091.0, 254393.0, 30255.394892101594, 30658.540121897742, 34452.82773586216, 34862.82568587141, 35408.0, 44299.0, 45087.32369014533, 45165.32252016288, 46504.0, 46660.766696166305, 30139.0, 31191.844040780557, 31633.20916977094, 31648.367032659677, 31789.0, 30805.22986925345, 31201.687983120788, 31463.68536314699, 31518.36963260768, 31524.527132093495, 37402.625973739916, 37775.62224377721, 37822.62177378191, 37905.67330143432, 37913.43129852999, 29563.704362956094, 31076.844615776776, 31193.688063119986, 31771.682283177797, 32215.0, 24575.262742117764, 24602.138925137533, 24718.382040449134, 24892.75107248977, 25018.874905625355], "value": 17505.579776680184, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.85144719283073, 434.70874514075564, 440.56824312174086, 443.9378487011817, 466.7666166916542, 21063.36809895704, 21348.35954921351, 21764.0, 21920.23279185229, 21951.0122044508, 21468.46328841776, 22336.441588960242, 22458.55082898341, 22619.208327708493, 22664.09343626257, 5844.211031510756, 6022.066579680157, 6072.453479186872, 6098.4511393974535, 6102.694865256742, 5693.259876216086, 5962.403759624024, 5968.224130863004, 6017.06735456005, 6021.548383871227, 5864.2962844458725, 5932.2584676915385, 5950.226470558821, 5985.4912332451795, 6001.129836173752, 4757.858114052637, 4868.488808675109, 5379.843333683258, 5558.972205138969, 5752.28096487939, 4992.750362481861, 5189.377274727057, 5272.0, 5284.154535274338, 5307.3896501902, 26230.34424139412, 26495.072672456372, 26577.468450631073, 26690.733092669212, 26856.374389457367, 2223.4774827915426, 2263.0495192019393, 2280.2817112609473, 2285.3486756274383, 2314.351981445198, 17608.471745847644, 17647.558811029827, 18021.369252076114, 18162.45512634623, 18227.54431139219, 1909.9713504297447, 2128.9254876079262, 2174.5759576882515, 2195.3633446300555, 2201.559688062394, 125402.0, 144972.0, 145307.0, 147638.0, 160489.0, 12149.757004859854, 12492.562760303346, 12573.485302939449, 12669.683257918627, 12712.110152289251, 16072.437464688679, 16201.0, 16203.351865925466, 16221.756673649905, 16287.267072981744, 8807.119288071142, 8870.911290887138, 9002.819943601222, 9103.772405689911, 9153.95423022894, 8433.241008309158, 8490.27832634233, 8495.405321627426, 8497.320214382835, 8575.614097365633, 7843.960780196063, 7932.563708996061, 7993.240642138988, 7993.240642138988, 8023.277904988579, 2235.418791114322, 2249.5950728868634, 2260.7400148982747, 2262.6719125726677, 2436.8781560921884, 834.6577903059716, 941.7928055827707, 950.273041123541, 1005.7083445800782, 1007.7581380468635, 20515.692264616042, 20640.58718825615, 20694.48263793387, 20872.58254834895, 21104.577908442054, 980.67147505585, 983.7294743945472, 1003.6085926488676, 1003.9046290602381, 1013.3160116921049, 10392.42841643717, 10569.894301057046, 10588.947055264674, 10597.311174773615, 10721.624743133953, 504703.0, 506299.0, 514396.0, 516659.0, 518777.0, 113135.0, 117685.0, 117847.0, 120073.0, 120867.18699219519, 1195.3784032303154, 1233.210745123122, 1235.018160562337, 1262.2931158551241, 1262.5013119817804, 21307.78692213058, 22070.779292206873, 22107.115715371205, 22171.0, 22215.666764998216, 44091.77954110209, 53323.733381332844, 54019.459805403014, 55122.72438637781, 55263.723681381336, 50454.747726261136, 73405.0, 73841.0, 77115.0, 81265.18734812812, 7416.25837416254, 8664.826703466022, 8815.779605509913, 8893.377463577488, 9066.592003359996, 13820.792688109888, 13975.231362275172, 14015.159090454605, 14314.355853986805, 14608.634784130481, 9648.324617276723, 9853.753656158653, 11870.762584748083, 12031.097667674785, 12081.0, 9611.423314601147, 10401.53193106297, 10622.910179581071, 10810.0, 10959.890401095887, 11867.287962722265, 11897.940510297392, 12133.635990920286, 12184.695382615506, 12241.938790305992, 8779.736607901774, 8804.603792829466, 8808.383413161017, 9123.498207598646, 9180.862287065833, 10231.48842557884, 11692.298462092302, 11736.823947640964, 11855.466504007169, 12034.217775844892], "value": 10265.15236405321, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1251.2117366059383, 1333.9733205335895, 1381.7443772902009, 1460.664047269128, 1460.9269536523184, 11396.487158077885, 11546.595869144581, 11636.941815290922, 11735.47190376433, 11900.285982841036, 11441.313521188735, 11690.24013439128, 11741.354225517593, 11752.0, 11767.470463829126, 2489.1785710715512, 2537.1627362970175, 2544.033267358401, 2544.567423537994, 2545.6054311581734, 2423.933469273514, 2469.3579669286023, 2487.3035550046015, 2490.091116742385, 2492.950140997179, 2500.499900019994, 2501.8373805702663, 2543.1480454047824, 2544.9872750636314, 2548.6686730724978, 2459.4712136890566, 2480.268320845346, 2499.450120973392, 2512.560301947152, 2516.3331717094984, 2395.8921848516857, 2474.8886300116537, 2486.5151295497385, 2488.850668959868, 2502.5245203411478, 11712.0, 11846.466908989116, 11858.762824743457, 11871.940640296829, 11882.643520694306, 6587.2095348558005, 6811.352921472452, 6837.453003759687, 6843.62360070196, 6865.794026179223, 3959.8614048508452, 4335.0, 4338.2191205582985, 4402.933955990663, 4406.074724307867, 1252.3425201769053, 1261.438659796391, 1267.7781388257017, 1301.7982212757038, 1306.3664122900386, 16102.355905763756, 16415.83584164155, 16558.0, 16568.420105296256, 16730.74903876431, 5541.972290138524, 5629.831105066854, 5707.429257074259, 5762.827115186551, 5816.243888294579, 11646.475908584134, 11701.2979221247, 11725.472353744102, 11735.647930562096, 11841.348725819991, 6472.546921715435, 6545.37818907203, 6549.476041916635, 6550.8034758956355, 6562.2453417856605, 6023.126646636213, 6093.512518998469, 6150.108234306001, 6242.843928901813, 6269.0909818076125, 4202.957970420257, 4370.300751879684, 4375.16871794358, 4408.074304396048, 4411.117776444724, 1075.9946200268948, 1119.5018216893568, 1269.1940617707637, 1290.3354772292278, 1298.8506321772927, 797.1191833024425, 808.6159074439594, 824.4517395931758, 829.8630725930275, 856.9914300857037, 6151.72317245725, 6184.752609895644, 6304.5271604628915, 6436.195475565554, 6467.223933128054, 592.5911121326358, 592.9021711417654, 599.2688919518181, 614.640435345319, 616.3528295289927, 3475.4091804393315, 3867.9226415471535, 3968.920621587552, 4003.5195776507007, 4320.351947207944, 13968.650783730285, 14101.435942562388, 14658.120512769055, 14660.486882959045, 14671.853281467262, 13703.383347749375, 13706.314684265746, 14246.0, 14934.327955241823, 15009.699806003819, 7234.131904171533, 7891.763247102596, 8246.628901699438, 8281.29608983243, 8290.668373265122, 5784.797532086357, 6238.157848690369, 6642.767503137367, 7046.0, 7101.3307560777375, 11344.546218151512, 12907.096503244682, 13404.0, 13544.525941591997, 13572.592822215349, 12251.264924104582, 13303.334833258492, 13700.588982330546, 13897.0, 13955.511557095859, 9132.680356187502, 9266.536673166449, 9267.582958767007, 9270.72187834366, 9306.06939306064, 10231.7442063949, 10267.024632659886, 10533.736656583647, 10614.469276536296, 10621.522031508446, 8719.084496127814, 8780.824383512167, 8973.102689730975, 9015.594298256725, 9093.681721139728, 7614.314711675976, 8778.648854045767, 8934.553272336227, 8998.685046023358, 9065.954670226607, 8128.309093727099, 8228.711995080144, 8272.255497005299, 8273.503589784392, 8276.83446331082, 6027.789027384196, 6040.335563088145, 6158.507319414526, 6187.504999600112, 6224.439800417803, 5476.658218736435, 5852.239208902815, 6105.481034111973, 6205.5345849060595, 6290.182276304051], "value": 5272.780977719175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.92841824262496, 398.82651046794626, 406.4756464161232, 406.90437747129425, 409.92211479818843, 4818.180909245428, 6102.481289090421, 6283.968580157099, 6427.2929977702415, 6430.421262086411, 5745.683987380703, 6044.637321760686, 6280.4661603763625, 6305.558610897239, 6380.840478988028, 4237.38557909103, 4505.6170225530705, 4821.577634597793, 4842.0, 4846.4911184325665, 4730.989329534951, 4840.877633926116, 4842.443119041302, 4855.902881942359, 4871.902561948759, 3910.042039700277, 4359.542248063955, 4399.05420334628, 4694.366260554815, 4771.18889788735, 3913.491246137984, 4777.570018698317, 4787.976060119712, 4799.928001079987, 4811.518848115161, 3378.763486555942, 4250.766207858566, 4287.314029755255, 4296.677749168824, 4315.913681726379, 4282.571742825724, 4423.867283981485, 6000.7698421823425, 6028.336882942873, 6152.938470615281, 4353.956460435386, 5392.448175466827, 5416.756245968941, 5422.267993820823, 5442.394493624351, 5631.5776316776, 5827.796027139072, 5896.027155519377, 6388.0, 6716.3283671632935, 4411.933820992687, 4413.227685155085, 4415.448068991376, 4443.644508439317, 4447.599716025573, 6235.594686345418, 6886.662836301856, 6995.650217489105, 7159.212486626467, 7276.090488688914, 4420.668449866273, 4444.31113177458, 4448.666350023761, 4460.397846290774, 4470.0, 4551.065797036246, 4564.0, 4567.200739870509, 4569.81720731167, 4578.862634120982, 5057.367829021372, 5289.709066001406, 5420.728963551806, 5427.0, 5459.153831156177, 6050.515958723292, 6237.347103017704, 6256.874862502725, 6744.224414192333, 6757.918733002696, 1380.496118916588, 1480.208088672555, 1542.4113163441673, 1563.2262030295076, 1567.6237702951437, 885.4775682347317, 982.3123813330554, 1007.6473234367912, 1012.1953047327245, 1024.4570377699831, 5841.977653910633, 6333.22669652498, 6374.055912946503, 6385.840353991188, 6416.839579010562, 6597.043428702811, 6653.534252602368, 6776.7628133015105, 6799.3200679931615, 7044.471664625071, 5377.569794416438, 5600.5799565032785, 6480.157579514633, 6498.577592456475, 6507.219133703891, 2980.687027862087, 3180.030090822321, 3453.723702103876, 3718.2935242303874, 3725.2549490102087, 6405.29541750407, 6429.7749578764515, 6568.638724870082, 7076.115485564304, 7276.235995220426, 5907.6959985605645, 6422.293547709748, 7353.70585176587, 7358.77923662291, 7369.336759691546, 6187.855246779451, 6594.845901967136, 7040.295970402919, 7286.635668216568, 7458.589777562178, 7194.352508274281, 7195.424366050808, 7197.964010180125, 7198.424126069797, 7208.783736487913, 5451.563874890078, 6094.75620975156, 6672.566283191577, 6728.259891411843, 6978.232394436506, 6480.870382592416, 6583.275839657538, 7200.811866042044, 7378.372838308804, 7456.925430745623, 4490.236659767782, 4494.0, 4505.504394516666, 4518.118966801574, 4529.275315949432, 6386.361363863576, 6511.283758786435, 6990.860182796418, 7063.646817659199, 7096.409810419263, 5135.820246291362, 5589.552835773047, 6012.5791194617705, 6452.35476452351, 6499.4800415965665, 5502.394736578893, 5583.74873130702, 5874.559408044499, 6559.639219842955, 6809.727610895707, 5805.738741756547, 6131.540134489842, 6623.456450798993, 6690.364415380531, 6691.46468282546, 5911.467967882912, 6755.7973260802255, 6788.796336109925, 6807.115075040213, 6832.829179270558, 5239.554637855673, 6584.4732421405215, 6611.404973552403, 6639.269680335063, 6654.73381064752], "value": 4826.713554772114, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.8530257204987, 861.9999999999997, 862.5988915154452, 865.9307255419567, 883.9292856571475, 15927.0, 16392.26234819433, 16887.32450701972, 16902.239399227034, 19516.51208719783, 15478.845211547909, 17858.374956876512, 18376.724349134773, 18537.721934170997, 18653.81346186538, 995.2336700740426, 1001.0040010189871, 1009.6516701737888, 1016.9644062457816, 1017.3539802225603, 1011.9595216191343, 1012.8784545854471, 1014.2595904989362, 1015.8019186258682, 1019.3119644240145, 1019.5463018956567, 1070.347088276151, 1080.4543705428748, 1083.8320060390652, 1085.696005118568, 1056.487603512296, 1071.539238127605, 1077.8221593437074, 1080.3193987787683, 1083.0685610375072, 732.6300218389679, 767.7044337929881, 781.1212386065677, 781.2500000000007, 783.0, 16707.91646041762, 16963.49109526704, 16969.151542422955, 17140.485785426456, 17170.57073573158, 15290.388384464608, 17130.828691713174, 17451.04019278952, 17594.47216583492, 17633.677474189488, 13834.031617786759, 13894.791578126336, 14133.929330353385, 14135.081219720685, 14782.408703651841, 3539.7345199109914, 3596.9820150899077, 3605.675489205958, 3608.566971963381, 3610.332088563624, 119474.80525194724, 139011.0, 139078.0, 139602.30198849042, 140423.1915361687, 11891.28652280866, 13553.0, 13556.59330220095, 13853.168809871238, 13907.374168162458, 22096.88951555232, 26014.34964125874, 26419.0, 26500.867495662784, 26923.461530769277, 7199.784006479814, 7377.852442951218, 7430.331270185709, 7440.813979650444, 7462.925370746331, 3682.1899182179877, 3756.7745935243975, 3756.9812150939615, 3757.680597149273, 3762.811859407019, 4335.739855608674, 4371.69398142127, 4440.245158323029, 4441.444819397575, 4441.822327106879, 953.3517208298424, 969.8545218217324, 970.6214576315101, 974.9951250243703, 975.6292608808608, 556.7271193589268, 562.7917670461874, 563.5266376243975, 563.6646195513663, 575.6057100885906, 10039.598416063422, 10814.891851081546, 11163.497642606102, 11923.0, 12111.152219344562, 630.1021045010841, 649.6426965169233, 654.5320096131289, 669.4477056428466, 684.1892357556312, 4995.475475075064, 5453.23654688344, 5576.191452239403, 5623.268975033219, 5664.915026274609, 105801.0, 105976.0, 106618.0, 106721.4663926691, 108125.0, 72691.27308726845, 96466.0, 96624.0, 96798.51600741952, 96918.0, 68930.0, 76975.61512192515, 82256.0, 87547.12452875389, 89048.0, 11703.414829258674, 12074.335911524951, 12397.75204495923, 12478.875211248134, 12771.169873958162, 38301.808490957366, 39413.0, 39479.60520394874, 40076.7996160029, 40498.59501405066, 51353.0, 66164.66917665381, 69003.96494052492, 70377.0, 72421.27578724145, 16176.191190440666, 16287.51137465878, 16668.166591670124, 17025.829741702426, 17078.573035674206, 17657.64684706273, 18703.719444208076, 18712.25150993945, 18801.05994700287, 19092.949887755753, 12953.0, 15236.552527509868, 16160.838391615933, 17690.734638980168, 17723.20245588926, 13367.663233676554, 16169.514914552583, 17355.2190151446, 17463.738043929097, 17463.912680436515, 13439.46242150303, 16671.666566668842, 16701.49895503137, 16755.796763535956, 17037.318507260068, 10290.845637315297, 10634.41510716887, 10854.403007834644, 10987.011168994828, 11464.770704585695, 13949.930250348683, 14150.504732334319, 14184.36170372315, 14265.286735663383, 14390.136591804107], "value": 8439.223742040569, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 119.0, 115.0, 115.0, 118.0, 117.0, 115.0, 125.0, 172.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 109.0, 138.0, 183.0, 145.0, 121.0, 109.0, 106.0, 138.0, 110.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 203.0, 192.0, 207.0, 218.0, 208.0, 204.0, 198.0, 198.0, 198.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 65.0, 94.0, 115.0, 81.0, 61.0, 58.0, 61.0, 79.0, 57.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 84.0, 102.0, 96.0, 106.0, 104.0, 93.0, 71.0, 69.0, 89.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 92.0, 93.0, 114.0, 91.0, 100.0, 91.0, 93.0, 93.0], "value": 103.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 98.0, 97.0, 98.0, 107.0, 107.0, 107.0, 137.0, 156.0], "value": 110.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 121.0, 149.0, 99.0, 144.0, 153.0, 90.0, 92.0, 96.0, 89.0], "value": 112.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 63.0, 67.0, 46.0, 47.0, 70.0, 45.0, 43.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 62.0, 58.0, 62.0, 85.0, 88.0, 53.0, 55.0, 83.0, 83.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 93.0, 76.0, 80.0, 89.0, 109.0, 106.0, 103.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 144.0, 141.0, 154.0, 154.0, 139.0, 151.0, 187.0, 137.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 201.0, 233.0, 200.0, 186.0, 216.0, 204.0, 200.0, 167.0, 165.0], "value": 196.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 98.0, 105.0, 90.0, 67.0, 66.0, 67.0, 66.0, 69.0, 57.0], "value": 74.8, "unit": "score"}]}]} 08:42:39 INFO - Return code: 0 08:42:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:42:40 INFO - # TBPL SUCCESS # 08:42:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:42:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:42:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:42:40 INFO - 2017-05-11 08:42:40.042 screenresolution[1672:11836] starting screenresolution argv=screenresolution get 08:42:40 INFO - 2017-05-11 08:42:40.045 screenresolution[1672:11836] Display 0: 1600x1200x32@60 08:42:40 INFO - 2017-05-11 08:42:40.051 screenresolution[1673:11839] starting screenresolution argv=screenresolution list 08:42:40 INFO - Available Modes on Display 0 08:42:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:42:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:42:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:42:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:42:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:42:40 INFO - Intel Iris: 08:42:40 INFO - Chipset Model: Intel Iris 08:42:40 INFO - Type: GPU 08:42:40 INFO - Bus: Built-In 08:42:40 INFO - VRAM (Dynamic, Max): 1536 MB 08:42:40 INFO - Vendor: Intel (0x8086) 08:42:40 INFO - Device ID: 0x0a2e 08:42:40 INFO - Revision ID: 0x0009 08:42:40 INFO - Displays: 08:42:40 INFO - Display: 08:42:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:42:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:42:40 INFO - Display Serial Number: 1600x1200 60 08:42:40 INFO - Main Display: Yes 08:42:40 INFO - Mirror: Off 08:42:40 INFO - Online: Yes 08:42:40 INFO - Rotation: Supported 08:42:40 INFO - Return code: 0 08:42:40 INFO - Running post-action listener: _resource_record_post_action 08:42:40 INFO - [mozharness: 2017-05-11 15:42:40.285596Z] Finished run-tests step (success) 08:42:40 INFO - Running post-run listener: _resource_record_post_run 08:42:40 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 34968576; Write bytes: 528885760; Read time: 327; Write time: 2153 08:42:40 INFO - TinderboxPrint: CPU usage
54.2% 08:42:40 INFO - TinderboxPrint: I/O read bytes / time
34,968,576 / 327 08:42:40 INFO - TinderboxPrint: I/O write bytes / time
528,885,760 / 2,153 08:42:40 INFO - TinderboxPrint: CPU idle
2,195.5 (45.8%) 08:42:40 INFO - TinderboxPrint: CPU system
967.7 (20.2%) 08:42:40 INFO - TinderboxPrint: CPU user
1,626.4 (34.0%) 08:42:40 INFO - TinderboxPrint: Swap in / out
244,912,128 / 0 08:42:40 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200083968; Write bytes: 193421312; Read time: 15532; Write time: 352 08:42:40 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 33985536; Write bytes: 314660864; Read time: 311; Write time: 1742 08:42:40 INFO - Running post-run listener: _upload_blobber_files 08:42:40 INFO - Blob upload gear active. 08:42:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:42:40 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 08:42:40 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'] 08:42:40 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 08:42:41 INFO - (blobuploader) - INFO - Open directory for files ... 08:42:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:42:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:42:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:41 INFO - (blobuploader) - INFO - Done attempting. 08:42:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:42:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:42:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:42 INFO - (blobuploader) - INFO - Done attempting. 08:42:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:42:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:42:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:43 INFO - (blobuploader) - INFO - Done attempting. 08:42:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:42:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:42:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:45 INFO - (blobuploader) - INFO - Done attempting. 08:42:45 INFO - (blobuploader) - INFO - Iteration through files over. 08:42:45 INFO - Return code: 0 08:42:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:42:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:42:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990a0724b5069c7dc28aaa6728ed9b874dc24a67749469755c2fbf8e53e591b45b1bd9a4b936a668e5ed0f66806f158f1b34f0295555e909f8ac4032f949e7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54477ba7a95bbcdefaf1879ae4732a23d5c28c6a5276f14b220d6a1d22500a1f859bd5f57e7732c963b8a15454825abc69224ba035afd1c80a7fe82c4997e5c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/269ee4a173cbdc242be1be7ff3f2fec122050aa07fbe2881122da4c26028e1dfd0ac0ca4b4dab838ee12f1f91bd5ff56e5591db348aaee71e6e9491e374aac0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6eee43145135c4c845b8cb45529f9b8035087bdee223b90915c87c27a1df4c447cab624437414f9c26ab84c036670a22696b153ed079009191ce2270108008a0"} 08:42:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:42:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:42:45 INFO - Contents: 08:42:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990a0724b5069c7dc28aaa6728ed9b874dc24a67749469755c2fbf8e53e591b45b1bd9a4b936a668e5ed0f66806f158f1b34f0295555e909f8ac4032f949e7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54477ba7a95bbcdefaf1879ae4732a23d5c28c6a5276f14b220d6a1d22500a1f859bd5f57e7732c963b8a15454825abc69224ba035afd1c80a7fe82c4997e5c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/269ee4a173cbdc242be1be7ff3f2fec122050aa07fbe2881122da4c26028e1dfd0ac0ca4b4dab838ee12f1f91bd5ff56e5591db348aaee71e6e9491e374aac0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6eee43145135c4c845b8cb45529f9b8035087bdee223b90915c87c27a1df4c447cab624437414f9c26ab84c036670a22696b153ed079009191ce2270108008a0"} 08:42:45 INFO - Running post-run listener: copy_logs_to_upload_dir 08:42:45 INFO - Copying logs to upload dir... 08:42:45 INFO - mkdir: /builds/slave/test/build/upload/logs 08:42:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2485.293522 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 25 secs) (at 2017-05-11 08:42:45.981319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 08:42:45.985458) ========= 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.fsAuMCXGip/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.GrcLZc6aPP/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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/990a0724b5069c7dc28aaa6728ed9b874dc24a67749469755c2fbf8e53e591b45b1bd9a4b936a668e5ed0f66806f158f1b34f0295555e909f8ac4032f949e7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54477ba7a95bbcdefaf1879ae4732a23d5c28c6a5276f14b220d6a1d22500a1f859bd5f57e7732c963b8a15454825abc69224ba035afd1c80a7fe82c4997e5c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/269ee4a173cbdc242be1be7ff3f2fec122050aa07fbe2881122da4c26028e1dfd0ac0ca4b4dab838ee12f1f91bd5ff56e5591db348aaee71e6e9491e374aac0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6eee43145135c4c845b8cb45529f9b8035087bdee223b90915c87c27a1df4c447cab624437414f9c26ab84c036670a22696b153ed079009191ce2270108008a0"} build_url:https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/XirYgAHeR1Gti4CPSijIMQ/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/990a0724b5069c7dc28aaa6728ed9b874dc24a67749469755c2fbf8e53e591b45b1bd9a4b936a668e5ed0f66806f158f1b34f0295555e909f8ac4032f949e7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54477ba7a95bbcdefaf1879ae4732a23d5c28c6a5276f14b220d6a1d22500a1f859bd5f57e7732c963b8a15454825abc69224ba035afd1c80a7fe82c4997e5c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/269ee4a173cbdc242be1be7ff3f2fec122050aa07fbe2881122da4c26028e1dfd0ac0ca4b4dab838ee12f1f91bd5ff56e5591db348aaee71e6e9491e374aac0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6eee43145135c4c845b8cb45529f9b8035087bdee223b90915c87c27a1df4c447cab624437414f9c26ab84c036670a22696b153ed079009191ce2270108008a0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 08:42:46.019121) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:42:46.019750) ========= 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.fsAuMCXGip/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.GrcLZc6aPP/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.005030 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:42:46.069878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 08:42:46.070222) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 08:42:46.070593) ========= ========= Total master_lag: 0.17 =========