builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0108 starttime: 1493552436.42 results: success (0) buildid: 20170430030208 builduid: f65b162368ab4a5aa132a607e7dff60d revision: 2fe636103d7167f3a5d57f61bd19fddcc878ca3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:36.423425) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:36.423993) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:36.424382) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:36.451571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:36.452273) ========= 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.dTSW27K84h/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.CU4qugapil/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-04-30 04:40:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 41.3M=0s 2017-04-30 04:40:37 (41.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.565345 ========= master_lag: 0.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:37.189713) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:37.190047) ========= 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.dTSW27K84h/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.CU4qugapil/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.021413 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:37.245989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-30 04:40:37.246983) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2fe636103d7167f3a5d57f61bd19fddcc878ca3c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2fe636103d7167f3a5d57f61bd19fddcc878ca3c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dTSW27K84h/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.CU4qugapil/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-04-30 04:40:37,327 truncating revision to first 12 chars 2017-04-30 04:40:37,327 Setting DEBUG logging. 2017-04-30 04:40:37,327 attempt 1/10 2017-04-30 04:40:37,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2fe636103d71?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-30 04:40:38,234 unpacking tar archive at: mozilla-central-2fe636103d71/testing/mozharness/ program finished with exit code 0 elapsedTime=1.168303 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-30 04:40:38.441242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:38.441568) ========= script_repo_revision: 2fe636103d7167f3a5d57f61bd19fddcc878ca3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:38.442015) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:38.442348) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-30 04:40:38.462998) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-04-30 04:40:38.463338) ========= /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.dTSW27K84h/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.CU4qugapil/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:40:38 INFO - MultiFileLogger online at 20170430 04:40:38 in /builds/slave/test 04:40:38 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 04:40:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:40:38 INFO - {'append_to_log': False, 04:40:38 INFO - 'base_work_dir': '/builds/slave/test', 04:40:38 INFO - 'blob_upload_branch': 'Firefox', 04:40:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:40:38 INFO - 'branch': 'Firefox', 04:40:38 INFO - 'buildbot_json_path': 'buildprops.json', 04:40:38 INFO - 'config_files': ('talos/mac_config.py',), 04:40:38 INFO - 'default_actions': ('clobber', 04:40:38 INFO - 'read-buildbot-config', 04:40:38 INFO - 'download-and-extract', 04:40:38 INFO - 'populate-webroot', 04:40:38 INFO - 'create-virtualenv', 04:40:38 INFO - 'install', 04:40:38 INFO - 'run-tests'), 04:40:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:40:38 INFO - 'download_minidump_stackwalk': True, 04:40:38 INFO - 'download_symbols': 'ondemand', 04:40:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:40:38 INFO - 'tooltool.py': '/tools/tooltool.py', 04:40:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:40:38 INFO - '/tools/misc-python/virtualenv.py')}, 04:40:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:40:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:40:38 INFO - 'gecko_profile': False, 04:40:38 INFO - 'gecko_profile_interval': 0, 04:40:38 INFO - 'installer_path': 'installer.exe', 04:40:38 INFO - 'log_level': 'info', 04:40:38 INFO - 'log_name': 'talos', 04:40:38 INFO - 'log_to_console': True, 04:40:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:40:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:40:38 INFO - 'opt_config_files': (), 04:40:38 INFO - 'pip_index': False, 04:40:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:40:38 INFO - 'cmd': ('bash', 04:40:38 INFO - '-c', 04:40:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:40:38 INFO - 'enabled': True, 04:40:38 INFO - 'halt_on_failure': False, 04:40:38 INFO - 'name': 'check_screen_resolution'},), 04:40:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:40:38 INFO - 'cmd': ('bash', 04:40:38 INFO - '-c', 04:40:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:40:38 INFO - 'enabled': True, 04:40:38 INFO - 'halt_on_failure': False, 04:40:38 INFO - 'name': 'check_screen_resolution'},), 04:40:38 INFO - 'run_cmd_checks_enabled': True, 04:40:38 INFO - 'suite': 'dromaeojs-e10s', 04:40:38 INFO - 'system_bits': '32', 04:40:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:40:38 INFO - 'title': 't-yosemite-r7-0108', 04:40:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:40:38 INFO - 'use_talos_json': True, 04:40:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:40:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:40:38 INFO - 'work_dir': 'build'} 04:40:38 INFO - [mozharness: 2017-04-30 11:40:38.667114Z] Running clobber step. 04:40:38 INFO - Running pre-action listener: _resource_record_pre_action 04:40:38 INFO - Running main action method: clobber 04:40:38 INFO - rmtree: /builds/slave/test/build 04:40:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:40:40 INFO - Running post-action listener: _resource_record_post_action 04:40:40 INFO - [mozharness: 2017-04-30 11:40:40.414341Z] Finished clobber step (success) 04:40:40 INFO - [mozharness: 2017-04-30 11:40:40.414474Z] Running read-buildbot-config step. 04:40:40 INFO - Running pre-action listener: _resource_record_pre_action 04:40:40 INFO - Running main action method: read_buildbot_config 04:40:40 INFO - Using buildbot properties: 04:40:40 INFO - { 04:40:40 INFO - "project": "", 04:40:40 INFO - "product": "firefox", 04:40:40 INFO - "script_repo_revision": "production", 04:40:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:40:40 INFO - "repository": "", 04:40:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:40:40 INFO - "buildid": "20170430030208", 04:40:40 INFO - "stage_platform": "macosx64", 04:40:40 INFO - "basedir": "/builds/slave/test", 04:40:40 INFO - "nightly_build": "True", 04:40:40 INFO - "buildnumber": 133, 04:40:40 INFO - "slavename": "t-yosemite-r7-0108", 04:40:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:40:40 INFO - "platform": "yosemite_r7", 04:40:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:40:40 INFO - "branch": "mozilla-central", 04:40:40 INFO - "repo_path": "mozilla-central", 04:40:40 INFO - "revision": "2fe636103d7167f3a5d57f61bd19fddcc878ca3c", 04:40:40 INFO - "pgo_build": "False", 04:40:40 INFO - "builduid": "f65b162368ab4a5aa132a607e7dff60d", 04:40:40 INFO - "slavebuilddir": "test" 04:40:40 INFO - } 04:40:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:40:40 INFO - Running post-action listener: _resource_record_post_action 04:40:40 INFO - [mozharness: 2017-04-30 11:40:40.417550Z] Finished read-buildbot-config step (success) 04:40:40 INFO - [mozharness: 2017-04-30 11:40:40.417650Z] Running download-and-extract step. 04:40:40 INFO - Running pre-action listener: _resource_record_pre_action 04:40:40 INFO - Running main action method: download_and_extract 04:40:40 INFO - mkdir: /builds/slave/test/build/tests 04:40: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')]} 04:40:40 INFO - trying https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:40:40 INFO - Downloading https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/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 04:40:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/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 04:40:41 INFO - Downloaded 1325 bytes. 04:40:41 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:40:41 INFO - Using the following test package requirements: 04:40:41 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:40:41 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:40:41 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:40:41 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:40:41 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'jsshell-mac.zip'], 04:40:41 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:40:41 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:40:41 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:40:41 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:40:41 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:40:41 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:40:41 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:40:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:40:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:40:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:40:41 INFO - Fetch https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:40:43 INFO - Content-Length response header: 52701315 04:40:43 INFO - Bytes received: 52701315 04:40:59 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 04:40:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:40:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:40:59 INFO - Fetch https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:41:01 INFO - Content-Length response header: 52701315 04:41:01 INFO - Bytes received: 52701315 04:41:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:41:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:41:16 INFO - Fetch https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:41:18 INFO - Content-Length response header: 14408811 04:41:18 INFO - Bytes received: 14408811 04:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:18 INFO - trying https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:41:18 INFO - Downloading https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/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 04:41:50 WARNING - URL Error: https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:41:50 INFO - Running command: ['nslookup', 'queue.taskcluster.net'] 04:41:50 INFO - Copy/paste: nslookup queue.taskcluster.net 04:41:50 INFO - Server: 10.26.75.40 04:41:50 INFO - Address: 10.26.75.40#53 04:41:50 INFO - queue.taskcluster.net canonical name = toyama-73636.herokussl.com. 04:41:50 INFO - toyama-73636.herokussl.com canonical name = elb081832-1105536650.us-east-1.elb.amazonaws.com. 04:41:50 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 04:41:50 INFO - Address: 23.23.146.199 04:41:50 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 04:41:50 INFO - Address: 54.235.149.195 04:41:50 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 04:41:50 INFO - Address: 50.19.237.146 04:41:50 INFO - Return code: 0 04:41:50 INFO - retry: attempt #1 caught URLError exception: 04:41:50 INFO - retry: Failed, sleeping 30 seconds before retrying 04:42:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #2 04:42:22 INFO - Downloaded 71273067 bytes. 04:42:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:42:22 INFO - mkdir: /builds/slave/test/properties 04:42:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:42:22 INFO - Writing to file /builds/slave/test/properties/build_url 04:42:22 INFO - Contents: 04:42:22 INFO - build_url:https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:42:23 INFO - Running post-action listener: _resource_record_post_action 04:42:23 INFO - Running post-action listener: set_extra_try_arguments 04:42:23 INFO - [mozharness: 2017-04-30 11:42:23.214253Z] Finished download-and-extract step (success) 04:42:23 INFO - [mozharness: 2017-04-30 11:42:23.214402Z] Running populate-webroot step. 04:42:23 INFO - Running pre-action listener: _resource_record_pre_action 04:42:23 INFO - Running main action method: populate_webroot 04:42:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:42:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:42:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:42:23 INFO - '--tppagecycles', 04:42:23 INFO - '7'], 04:42:23 INFO - 'tests': ['tsvgm']}}, 04:42:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:42:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:42:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:42:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:42:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['damp', 'tps']}, 04:42:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'tests': ['damp', 'tps']}, 04:42:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['dromaeo_dom']}, 04:42:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:42:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:42:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:42:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['a11yr', 04:42:23 INFO - 'ts_paint', 04:42:23 INFO - 'tpaint', 04:42:23 INFO - 'sessionrestore', 04:42:23 INFO - 'sessionrestore_no_auto_restore', 04:42:23 INFO - 'tabpaint']}, 04:42:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:42:23 INFO - 'ts_paint', 04:42:23 INFO - 'tpaint', 04:42:23 INFO - 'sessionrestore', 04:42:23 INFO - 'sessionrestore_no_auto_restore', 04:42:23 INFO - 'tabpaint']}, 04:42:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:42:23 INFO - 'ts_paint', 04:42:23 INFO - 'tpaint', 04:42:23 INFO - 'sessionrestore', 04:42:23 INFO - 'sessionrestore_no_auto_restore', 04:42:23 INFO - 'tabpaint']}, 04:42:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:42:23 INFO - 'ts_paint', 04:42:23 INFO - 'tpaint', 04:42:23 INFO - 'sessionrestore', 04:42:23 INFO - 'sessionrestore_no_auto_restore', 04:42:23 INFO - 'tabpaint']}, 04:42:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['a11yr', 04:42:23 INFO - 'ts_paint', 04:42:23 INFO - 'tpaint', 04:42:23 INFO - 'sessionrestore', 04:42:23 INFO - 'sessionrestore_no_auto_restore', 04:42:23 INFO - 'tabpaint']}, 04:42:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['a11yr', 04:42:23 INFO - 'ts_paint', 04:42:23 INFO - 'tpaint', 04:42:23 INFO - 'sessionrestore', 04:42:23 INFO - 'sessionrestore_no_auto_restore', 04:42:23 INFO - 'tabpaint']}, 04:42:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:42:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:42:23 INFO - 'bloom_basic_ref']}, 04:42:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['tsvgx', 04:42:23 INFO - 'tsvgr_opacity', 04:42:23 INFO - 'tart', 04:42:23 INFO - 'tscrollx', 04:42:23 INFO - 'cart', 04:42:23 INFO - 'tsvg_static']}, 04:42:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:42:23 INFO - 'tsvgr_opacity', 04:42:23 INFO - 'tart', 04:42:23 INFO - 'tscrollx', 04:42:23 INFO - 'cart', 04:42:23 INFO - 'tsvg_static']}, 04:42:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'talos_options': ['--disable-e10s'], 04:42:23 INFO - 'tests': ['tp5o']}, 04:42:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:42:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'talos_options': ['--disable-e10s', 04:42:23 INFO - '--xperf_path', 04:42:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:23 INFO - 'tests': ['tp5n']}, 04:42:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:42:23 INFO - 'talos_options': ['--xperf_path', 04:42:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:23 INFO - 'tests': ['tp5n']}}, 04:42:23 INFO - 'talos.zip': {'path': '', 04:42:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:42:23 INFO - Running post-action listener: _resource_record_post_action 04:42:23 INFO - [mozharness: 2017-04-30 11:42:23.233042Z] Finished populate-webroot step (success) 04:42:23 INFO - [mozharness: 2017-04-30 11:42:23.233156Z] Running create-virtualenv step. 04:42:23 INFO - Running pre-action listener: _resource_record_pre_action 04:42:23 INFO - Running main action method: create_virtualenv 04:42:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:42:23 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 04:42:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:42:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:42:23 INFO - Using real prefix '/tools/python27' 04:42:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:42:24 INFO - Installing distribute.............................................................................................................................................................................................done. 04:42:26 INFO - Installing pip.................done. 04:42:26 INFO - Return code: 0 04:42:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:42:27 INFO - Reading from file tmpfile_stdout 04:42:27 INFO - Output received: 04:42:27 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) 04:42:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:42:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:27 INFO - 'HOME': '/Users/cltbld', 04:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:27 INFO - 'LOGNAME': 'cltbld', 04:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:27 INFO - 'MOZ_NO_REMOTE': '1', 04:42:27 INFO - 'NO_EM_RESTART': '1', 04:42:27 INFO - 'PAGER': '/bin/cat', 04:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:27 INFO - 'PWD': '/builds/slave/test', 04:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:27 INFO - 'SHELL': '/bin/bash', 04:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:27 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:27 INFO - 'USER': 'cltbld', 04:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:27 INFO - 'XPC_FLAGS': '0x0', 04:42:27 INFO - 'XPC_SERVICE_NAME': '0', 04:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:27 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 04:42:27 INFO - Cleaning up... 04:42:27 INFO - Return code: 0 04:42:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:42:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:27 INFO - 'HOME': '/Users/cltbld', 04:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:27 INFO - 'LOGNAME': 'cltbld', 04:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:27 INFO - 'MOZ_NO_REMOTE': '1', 04:42:27 INFO - 'NO_EM_RESTART': '1', 04:42:27 INFO - 'PAGER': '/bin/cat', 04:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:27 INFO - 'PWD': '/builds/slave/test', 04:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:27 INFO - 'SHELL': '/bin/bash', 04:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:27 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:27 INFO - 'USER': 'cltbld', 04:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:27 INFO - 'XPC_FLAGS': '0x0', 04:42:27 INFO - 'XPC_SERVICE_NAME': '0', 04:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:27 INFO - Downloading/unpacking psutil>=3.1.1 04:42:27 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 04:42:27 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 04:42:27 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 04:42:27 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 04:42:27 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 04:42:27 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 04:42:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:42:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:31 INFO - Installing collected packages: psutil 04:42:31 INFO - Running setup.py install for psutil 04:42:31 INFO - building 'psutil._psutil_osx' extension 04:42:31 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 04:42:32 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 04:42:32 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 04:42:32 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 04:42:32 INFO - building 'psutil._psutil_posix' extension 04:42:32 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 04:42:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:42:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:42:32 INFO - ^ 04:42:32 INFO - 1 warning generated. 04:42:32 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 04:42:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:32 INFO - Successfully installed psutil 04:42:32 INFO - Cleaning up... 04:42:32 INFO - Return code: 0 04:42:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:42:32 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')]} 04:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:32 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')]} 04:42:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:32 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 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:32 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 04:42:32 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 04:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:32 INFO - 'HOME': '/Users/cltbld', 04:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:32 INFO - 'LOGNAME': 'cltbld', 04:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:32 INFO - 'MOZ_NO_REMOTE': '1', 04:42:32 INFO - 'NO_EM_RESTART': '1', 04:42:32 INFO - 'PAGER': '/bin/cat', 04:42:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:32 INFO - 'PWD': '/builds/slave/test', 04:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:32 INFO - 'SHELL': '/bin/bash', 04:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:32 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:32 INFO - 'USER': 'cltbld', 04:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:32 INFO - 'XPC_FLAGS': '0x0', 04:42:32 INFO - 'XPC_SERVICE_NAME': '0', 04:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:42:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:42:35 INFO - Installing collected packages: mozsystemmonitor 04:42:35 INFO - Running setup.py install for mozsystemmonitor 04:42:35 INFO - Successfully installed mozsystemmonitor 04:42:35 INFO - Cleaning up... 04:42:35 INFO - Return code: 0 04:42:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:35 INFO - 'HOME': '/Users/cltbld', 04:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:35 INFO - 'LOGNAME': 'cltbld', 04:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:35 INFO - 'MOZ_NO_REMOTE': '1', 04:42:35 INFO - 'NO_EM_RESTART': '1', 04:42:35 INFO - 'PAGER': '/bin/cat', 04:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:35 INFO - 'PWD': '/builds/slave/test', 04:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:35 INFO - 'SHELL': '/bin/bash', 04:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:35 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:35 INFO - 'USER': 'cltbld', 04:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:35 INFO - 'XPC_FLAGS': '0x0', 04:42:35 INFO - 'XPC_SERVICE_NAME': '0', 04:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:36 INFO - Downloading/unpacking jsonschema==2.5.1 04:42:36 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 04:42:36 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 04:42:36 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 04:42:36 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 04:42:36 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 04:42:36 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 04:42:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:42:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:42:38 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 04:42:38 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 04:42:38 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 04:42:38 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 04:42:38 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 04:42:38 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 04:42:39 INFO - Downloading functools32-3.2.3-2.tar.gz 04:42:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:42:39 INFO - warning: no files found matching '*.txt' 04:42:39 INFO - no previously-included directories found matching 'build' 04:42:39 INFO - no previously-included directories found matching 'dist' 04:42:39 INFO - no previously-included directories found matching '.git*' 04:42:39 INFO - Installing collected packages: jsonschema, functools32 04:42:39 INFO - Running setup.py install for functools32 04:42:39 INFO - warning: no files found matching '*.txt' 04:42:39 INFO - no previously-included directories found matching 'build' 04:42:39 INFO - no previously-included directories found matching 'dist' 04:42:39 INFO - no previously-included directories found matching '.git*' 04:42:39 INFO - Successfully installed jsonschema functools32 04:42:39 INFO - Cleaning up... 04:42:39 INFO - Return code: 0 04:42:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:39 INFO - 'HOME': '/Users/cltbld', 04:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:39 INFO - 'LOGNAME': 'cltbld', 04:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:39 INFO - 'MOZ_NO_REMOTE': '1', 04:42:39 INFO - 'NO_EM_RESTART': '1', 04:42:39 INFO - 'PAGER': '/bin/cat', 04:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:39 INFO - 'PWD': '/builds/slave/test', 04:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:39 INFO - 'SHELL': '/bin/bash', 04:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:39 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:39 INFO - 'USER': 'cltbld', 04:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:39 INFO - 'XPC_FLAGS': '0x0', 04:42:39 INFO - 'XPC_SERVICE_NAME': '0', 04:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:42:39 INFO - Cleaning up... 04:42:39 INFO - Return code: 0 04:42:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:39 INFO - 'HOME': '/Users/cltbld', 04:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:39 INFO - 'LOGNAME': 'cltbld', 04:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:39 INFO - 'MOZ_NO_REMOTE': '1', 04:42:39 INFO - 'NO_EM_RESTART': '1', 04:42:39 INFO - 'PAGER': '/bin/cat', 04:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:39 INFO - 'PWD': '/builds/slave/test', 04:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:39 INFO - 'SHELL': '/bin/bash', 04:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:39 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:39 INFO - 'USER': 'cltbld', 04:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:39 INFO - 'XPC_FLAGS': '0x0', 04:42:39 INFO - 'XPC_SERVICE_NAME': '0', 04:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:40 INFO - Downloading/unpacking blobuploader==1.2.4 04:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:42:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:42:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:42:43 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 04:42:43 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 04:42:43 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 04:42:43 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 04:42:43 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 04:42:43 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 04:42:43 INFO - Downloading docopt-0.6.1.tar.gz 04:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:42:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:42:43 INFO - Running setup.py install for blobuploader 04:42:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:42:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:42:44 INFO - Running setup.py install for requests 04:42:44 INFO - Running setup.py install for docopt 04:42:44 INFO - Successfully installed blobuploader requests docopt 04:42:44 INFO - Cleaning up... 04:42:44 INFO - Return code: 0 04:42:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:44 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 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:44 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 04:42:44 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 04:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:44 INFO - 'HOME': '/Users/cltbld', 04:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:44 INFO - 'LOGNAME': 'cltbld', 04:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:44 INFO - 'MOZ_NO_REMOTE': '1', 04:42:44 INFO - 'NO_EM_RESTART': '1', 04:42:44 INFO - 'PAGER': '/bin/cat', 04:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:44 INFO - 'PWD': '/builds/slave/test', 04:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:44 INFO - 'SHELL': '/bin/bash', 04:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:44 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:44 INFO - 'USER': 'cltbld', 04:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:44 INFO - 'XPC_FLAGS': '0x0', 04:42:44 INFO - 'XPC_SERVICE_NAME': '0', 04:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-oKecPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-XCyJnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-H93N2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4YpcD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-9NO3rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-tTw4bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-jr0JOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-zJEhPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:45 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ISzUJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Xp0qyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-boUIHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-CufhyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Ky8ZwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-TxuGZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-h5txnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1nbmT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:46 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1Uk2Aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:42:46 INFO - Running setup.py install for manifestparser 04:42:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozcrash 04:42:47 INFO - Running setup.py install for mozdebug 04:42:47 INFO - Running setup.py install for mozdevice 04:42:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozfile 04:42:47 INFO - Running setup.py install for mozhttpd 04:42:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozinfo 04:42:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozInstall 04:42:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Running setup.py install for mozleak 04:42:48 INFO - Running setup.py install for mozlog 04:42:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Running setup.py install for moznetwork 04:42:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Running setup.py install for mozprocess 04:42:48 INFO - Running setup.py install for mozprofile 04:42:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Running setup.py install for mozrunner 04:42:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:42:49 INFO - Running setup.py install for mozscreenshot 04:42:49 INFO - Running setup.py install for moztest 04:42:49 INFO - Running setup.py install for mozversion 04:42:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:42:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:42:49 INFO - Cleaning up... 04:42:49 INFO - Return code: 0 04:42:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:49 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')]} 04:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:49 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')]} 04:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:49 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 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:49 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 04:42:49 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 04:42:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:49 INFO - 'HOME': '/Users/cltbld', 04:42:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:49 INFO - 'LOGNAME': 'cltbld', 04:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:49 INFO - 'MOZ_NO_REMOTE': '1', 04:42:49 INFO - 'NO_EM_RESTART': '1', 04:42:49 INFO - 'PAGER': '/bin/cat', 04:42:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:49 INFO - 'PWD': '/builds/slave/test', 04:42:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:49 INFO - 'SHELL': '/bin/bash', 04:42:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:49 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:49 INFO - 'USER': 'cltbld', 04:42:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:49 INFO - 'XPC_FLAGS': '0x0', 04:42:49 INFO - 'XPC_SERVICE_NAME': '0', 04:42:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:49 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-QaW0oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:49 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)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:49 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-lh58WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:49 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)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:49 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-7uDSeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1b9ba7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-KE1NUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-B76Lon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-cjQesH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-wI9R7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-K1kDId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gMjwfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-JseQuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:50 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-tI1mDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:50 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)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:51 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ekWyP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:51 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)) 04:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:51 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Lpulua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:51 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)) 04:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:51 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-a7SiUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:51 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)) 04:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:51 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-cIsKVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:51 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)) 04:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:51 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Yaewwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:51 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)) 04:42:51 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)) 04:42:51 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)) 04:42:51 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)) 04:42:51 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)) 04:42:51 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)) 04:42:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:42:51 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 04:42:51 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 04:42:51 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 04:42:51 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 04:42:51 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 04:42:51 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 04:42:54 INFO - Downloading blessings-1.6.tar.gz 04:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:42:54 INFO - Installing collected packages: blessings 04:42:54 INFO - Running setup.py install for blessings 04:42:54 INFO - Successfully installed blessings 04:42:54 INFO - Cleaning up... 04:42:54 INFO - Return code: 0 04:42:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:42:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:54 INFO - Reading from file tmpfile_stdout 04:42:54 INFO - Current package versions: 04:42:54 INFO - blessings == 1.6 04:42:54 INFO - blobuploader == 1.2.4 04:42:54 INFO - docopt == 0.6.1 04:42:54 INFO - functools32 == 3.2.3-2 04:42:54 INFO - jsonschema == 2.5.1 04:42:54 INFO - manifestparser == 1.1 04:42:54 INFO - mozInstall == 1.12 04:42:54 INFO - mozcrash == 1.0 04:42:54 INFO - mozdebug == 0.1 04:42:54 INFO - mozdevice == 0.50 04:42:54 INFO - mozfile == 1.2 04:42:54 INFO - mozhttpd == 0.7 04:42:54 INFO - mozinfo == 0.9 04:42:54 INFO - mozleak == 0.1 04:42:54 INFO - mozlog == 3.4 04:42:54 INFO - moznetwork == 0.27 04:42:54 INFO - mozprocess == 0.25 04:42:54 INFO - mozprofile == 0.28 04:42:54 INFO - mozrunner == 6.13 04:42:54 INFO - mozscreenshot == 0.1 04:42:54 INFO - mozsystemmonitor == 0.3 04:42:54 INFO - moztest == 0.8 04:42:54 INFO - mozversion == 1.4 04:42:54 INFO - psutil == 3.1.1 04:42:54 INFO - requests == 1.2.3 04:42:54 INFO - wsgiref == 0.1.2 04:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:54 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')]} 04:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:54 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')]} 04:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:54 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 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:54 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 04:42:54 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 04:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:54 INFO - 'HOME': '/Users/cltbld', 04:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:54 INFO - 'LOGNAME': 'cltbld', 04:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:54 INFO - 'MOZ_NO_REMOTE': '1', 04:42:54 INFO - 'NO_EM_RESTART': '1', 04:42:54 INFO - 'PAGER': '/bin/cat', 04:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:54 INFO - 'PWD': '/builds/slave/test', 04:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:54 INFO - 'SHELL': '/bin/bash', 04:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:54 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:54 INFO - 'USER': 'cltbld', 04:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:54 INFO - 'XPC_FLAGS': '0x0', 04:42:54 INFO - 'XPC_SERVICE_NAME': '0', 04:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 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)) 04:42:55 INFO - Cleaning up... 04:42:55 INFO - Return code: 0 04:42:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dcdeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dce1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1624a41b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, '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.dTSW27K84h/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.CU4qugapil/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:42:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:55 INFO - 'HOME': '/Users/cltbld', 04:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:55 INFO - 'LOGNAME': 'cltbld', 04:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:55 INFO - 'MOZ_NO_REMOTE': '1', 04:42:55 INFO - 'NO_EM_RESTART': '1', 04:42:55 INFO - 'PAGER': '/bin/cat', 04:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:55 INFO - 'PWD': '/builds/slave/test', 04:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:55 INFO - 'SHELL': '/bin/bash', 04:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:42:55 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:55 INFO - 'USER': 'cltbld', 04:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:55 INFO - 'XPC_FLAGS': '0x0', 04:42:55 INFO - 'XPC_SERVICE_NAME': '0', 04:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:42:55 INFO - Cleaning up... 04:42:55 INFO - Return code: 0 04:42:55 INFO - Running post-action listener: _resource_record_post_action 04:42:55 INFO - Running post-action listener: _start_resource_monitoring 04:42:55 INFO - Starting resource monitoring. 04:42:55 INFO - [mozharness: 2017-04-30 11:42:55.375881Z] Finished create-virtualenv step (success) 04:42:55 INFO - [mozharness: 2017-04-30 11:42:55.376936Z] Running install step. 04:42:55 INFO - Running pre-action listener: _resource_record_pre_action 04:42:55 INFO - Running main action method: install 04:42:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:55 INFO - Reading from file tmpfile_stdout 04:42:55 INFO - Detecting whether we're running mozinstall >=1.0... 04:42:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:42:55 INFO - Reading from file tmpfile_stdout 04:42:55 INFO - Output received: 04:42:55 INFO - Usage: mozinstall [options] installer 04:42:55 INFO - Options: 04:42:55 INFO - -h, --help show this help message and exit 04:42:55 INFO - -d DEST, --destination=DEST 04:42:55 INFO - Directory to install application into. [default: 04:42:55 INFO - "/builds/slave/test"] 04:42:55 INFO - --app=APP Application being installed. [default: firefox] 04:42:55 INFO - mkdir: /builds/slave/test/build/application 04:42:55 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'] 04:42:55 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 04:43:15 INFO - Reading from file tmpfile_stdout 04:43:15 INFO - Output received: 04:43:15 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:43:15 INFO - Running post-action listener: _resource_record_post_action 04:43:15 INFO - [mozharness: 2017-04-30 11:43:15.294356Z] Finished install step (success) 04:43:15 INFO - [mozharness: 2017-04-30 11:43:15.294508Z] Running run-tests step. 04:43:15 INFO - Running pre-action listener: _resource_record_pre_action 04:43:15 INFO - Running main action method: run_tests 04:43:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:15 INFO - 2017-04-30 04:43:15.308 screenresolution[2380:36375] starting screenresolution argv=screenresolution get 04:43:15 INFO - 2017-04-30 04:43:15.311 screenresolution[2380:36375] Display 0: 1600x1200x32@60 04:43:15 INFO - 2017-04-30 04:43:15.317 screenresolution[2381:36378] starting screenresolution argv=screenresolution list 04:43:15 INFO - Available Modes on Display 0 04:43:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:43:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:43:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:43:15 INFO - Intel Iris: 04:43:15 INFO - Chipset Model: Intel Iris 04:43:15 INFO - Type: GPU 04:43:15 INFO - Bus: Built-In 04:43:15 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:15 INFO - Vendor: Intel (0x8086) 04:43:15 INFO - Device ID: 0x0a2e 04:43:15 INFO - Revision ID: 0x0009 04:43:15 INFO - Displays: 04:43:15 INFO - Display: 04:43:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:43:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:15 INFO - Display Serial Number: 1600x1200 60 04:43:15 INFO - Main Display: Yes 04:43:15 INFO - Mirror: Off 04:43:15 INFO - Online: Yes 04:43:15 INFO - Rotation: Supported 04:43:15 INFO - Return code: 0 04:43:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:43:15 INFO - Python 2.7.3 04:43:15 INFO - Return code: 0 04:43:15 INFO - grabbing minidump binary from tooltool 04:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:15 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 0x10dabcd30>, '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 0x10d9e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dab1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:43:15 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 04:43:15 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 04:43:15 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 04:43:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:43:15 INFO - Return code: 0 04:43:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:43:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:43:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:43:15 INFO - ENV: RUST_BACKTRACE is now 1 04:43:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:43:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:43:15 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--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 04:43:15 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --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 04:43:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSW27K84h/Render', 04:43:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:15 INFO - 'HOME': '/Users/cltbld', 04:43:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:15 INFO - 'LOGNAME': 'cltbld', 04:43:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:43:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:15 INFO - 'MOZ_NO_REMOTE': '1', 04:43:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:43:15 INFO - 'NO_EM_RESTART': '1', 04:43:15 INFO - 'PAGER': '/bin/cat', 04:43:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:15 INFO - 'PWD': '/builds/slave/test', 04:43:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:43:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:15 INFO - 'RUST_BACKTRACE': '1', 04:43:15 INFO - 'SHELL': '/bin/bash', 04:43:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU4qugapil/Listeners', 04:43:15 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:43:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:15 INFO - 'USER': 'cltbld', 04:43:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:15 INFO - 'XPC_FLAGS': '0x0', 04:43:15 INFO - 'XPC_SERVICE_NAME': '0', 04:43:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:15 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--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 04:43:15 INFO - mozversion application_buildid: 20170430030208 04:43:15 INFO - mozversion application_changeset: 2fe636103d7167f3a5d57f61bd19fddcc878ca3c 04:43:15 INFO - mozversion application_display_name: Nightly 04:43:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:43:15 INFO - mozversion application_name: Firefox 04:43:15 INFO - mozversion application_remotingname: firefox 04:43:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:43:15 INFO - mozversion application_vendor: Mozilla 04:43:15 INFO - mozversion application_version: 55.0a1 04:43:15 INFO - mozversion platform_buildid: 20170430030208 04:43:15 INFO - mozversion platform_changeset: 2fe636103d7167f3a5d57f61bd19fddcc878ca3c 04:43:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:43:15 INFO - mozversion platform_version: 55.0a1 04:43:15 INFO - using testdate: 1493552595 04:43:15 INFO - actual date: 1493552595 04:43:15 INFO - starting webserver on 'localhost:58765' 04:43:15 INFO - SUITE-START | Running 2 tests 04:43:15 INFO - TEST-START | dromaeo_css 04:43:15 INFO - operating with platform_type : mac_ 04:43:15 INFO - Initialising browser for dromaeo_css test... 04:43:15 INFO - TEST-INFO | started process 2391 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpyqYbwy/profile http://localhost:58765/getInfo.html) 04:43:18 INFO - PID 2391 | 2017-04-30 04:43:18.917 plugin-container[2394:36565] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a43, name = 'com.apple.tsm.portname' 04:43:18 INFO - PID 2391 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:18 INFO - PID 2391 | 2017-04-30 04:43:18.918 plugin-container[2394:36565] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:43:18 INFO - PID 2391 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:18 INFO - PID 2391 | 2017-04-30 04:43:18.918 plugin-container[2394:36565] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:19 INFO - PID 2391 | __metrics Screen width/height:1600/1200 04:43:19 INFO - PID 2391 | colorDepth:24 04:43:19 INFO - PID 2391 | Browser inner width/height: 1024/689 04:43:19 INFO - PID 2391 | __metrics 04:43:19 INFO - TEST-INFO | 2391: exit 0 04:43:19 INFO - Browser initialized. 04:43:19 INFO - Running cycle 1/1 for dromaeo_css test... 04:43:20 INFO - TEST-INFO | started process 2395 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpyqYbwy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:22 INFO - PID 2395 | 2017-04-30 04:43:22.454 plugin-container[2396:36694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9547, name = 'com.apple.tsm.portname' 04:43:22 INFO - PID 2395 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:22 INFO - PID 2395 | 2017-04-30 04:43:22.455 plugin-container[2396:36694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 04:43:22 INFO - PID 2395 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:22 INFO - PID 2395 | 2017-04-30 04:43:22.455 plugin-container[2396:36694] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:32 INFO - PID 2395 | RSS: Main: 234901504 04:43:32 INFO - PID 2395 | 04:46:36 INFO - PID 2395 | Cycle 1(1): loaded http://localhost:58765/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58765/tests/dromaeo/cssquery-ext.html) 04:46:37 INFO - PID 2395 | RSS: Main: 251150336 04:46:37 INFO - PID 2395 | 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | A coding exception was thrown and uncaught in a Task. 04:48:20 INFO - PID 2395 | 04:48:20 ERROR - PID 2395 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:48:20 INFO - PID 2395 | Full stack: 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | A coding exception was thrown and uncaught in a Task. 04:48:20 INFO - PID 2395 | 04:48:20 ERROR - PID 2395 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:48:20 INFO - PID 2395 | Full stack: 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | A coding exception was thrown and uncaught in a Task. 04:48:20 INFO - PID 2395 | 04:48:20 ERROR - PID 2395 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:48:20 INFO - PID 2395 | Full stack: 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | ************************* 04:48:20 INFO - PID 2395 | A coding exception was thrown and uncaught in a Task. 04:48:20 INFO - PID 2395 | 04:48:20 ERROR - PID 2395 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:48:20 INFO - PID 2395 | Full stack: 04:48:20 INFO - PID 2395 | ************************* 04:49:39 INFO - PID 2395 | Cycle 1(1): loaded http://localhost:58765/tests/dromaeo/cssquery-ext.html (next: http://localhost:58765/tests/dromaeo/cssquery-jquery.html) 04:49:40 INFO - PID 2395 | RSS: Main: 256249856 04:49:40 INFO - PID 2395 | 04:52:42 INFO - PID 2395 | Cycle 1(1): loaded http://localhost:58765/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58765/tests/dromaeo/cssquery-mootools.html) 04:52:42 INFO - PID 2395 | RSS: Main: 266199040 04:52:42 INFO - PID 2395 | 04:55:44 INFO - PID 2395 | Cycle 1(1): loaded http://localhost:58765/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58765/tests/dromaeo/cssquery-prototype.html) 04:55:44 INFO - PID 2395 | RSS: Main: 258568192 04:55:44 INFO - PID 2395 | 04:58:47 INFO - PID 2395 | Cycle 1(1): loaded http://localhost:58765/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58765/tests/dromaeo/cssquery-yui.html) 04:58:48 INFO - PID 2395 | RSS: Main: 267730944 04:58:48 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | Cycle 1(1): loaded http://localhost:58765/tests/dromaeo/cssquery-yui.html (next: http://localhost:58765/tests/dromaeo/cssquery-dojo.html) 05:01:50 INFO - PID 2395 | RSS: Main: 262537216 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | __start_tp_report 05:01:50 INFO - PID 2395 | _x_x_mozilla_page_load 05:01:50 INFO - PID 2395 | _x_x_mozilla_page_load_details 05:01:50 INFO - PID 2395 | |i|pagename|runs| 05:01:50 INFO - PID 2395 | |0;dojo.html;3257.527658489519;3566.233259849132;3570.6607872252125;3608.7654302470337;3619.3666108431025;6137.907931381022;6213.751449942002;6227.034809604507;6239.563230573862;6294.874102517947;6157.261128664555;6181.969090154555;6208.16189814375;6227.221597300338;6317.77887773928;4730.574248317659;5132.101882170624;5375.408705042442;5399.19012148179;5428.891422171554;5464.9453505465035;5532.1425179097305;5533.6956467394275;5565.471280228372;5589.580781441387;5332.440093790135;5420.593455490854;5448.291722076145;5489.34127904652;5533.197686335479;5403.189521571757;5403.540699040586;5413.2692086568595;5419.241306217133;5484.369297530795;5334.3598768147685;5432.538234250093;5435.972820135914;5449.863753406157;5472.452754724535;5994.8501287467725;6289.213848268966;6305.180326557564;6314.810555683337;6325.525585581054;6419.8395040123905;6496.382843629847;6529.444997175246;6592.670366481657;6651.16860392451;6752.2234942981695;6760.966195169042;6777.288384719632;6785.8642827143185;6787.457003439713;6208.875822483525;6353.3329000455215;6373.298937116913;6385.169927909388;6386.36136386367;6561.311062338481;6670.9332906670315;6771.5259931805285;6871.381575658214;6882.931170688329;6310.05349197624;6377.298497165308;6382.808515744535;6435.163428754326;6515.348465153542;5104.489551044866;6631.10480085192;6693.765718199839;6716.731330746813;6750.696218670171;5478.369987451414;6541.313162117909;6560.967195163994;6608.50436217276;6686.398224159849;6405.871882562323;6455.838604034937;6534.8366290843105;6624.96687516569;6668.533202675863;4519.728816271035;5268.446813084647;5280.075986702312;5334.199870019528;5340.385855626575;4720.315554244684;4738.289256611536;4739.857804265877;4745.145873742759;4756.286557016475;4346.956530434719;6017.217761690952;6371.980483122678;6410.0705397717065;6428.357164283534;5597.608167428322;6473.579217350857;6628.8674226515195;6708.496862735315;6734.595924244561;5453.64551304164;6093.878122437615;6260.968695156586;6339.366063393716;6339.841503962438;5805.535557155417;5863.530917526673;5868.530517558671;5875.206847075676;5926.851828704317;6560.835979100465;6776.966115169393;6836.487263455161;6851.109355783717;6906.689198986158;6704.363085506869;6736.629485378351;6744.62904540255;6769.3907548320885;6773.322667733188;6803.659817009228;6904.827379315357;6907.067545881242;6907.620080895599;6938.098047253825;6338.239411270677;6471.255805582418;6513.185851768529;6606.636634985122;6627.105340778933;6596.439302659328;6686.063951046955;6696.263411024686;6726.125603671687;6739.764108256188;6281.96859015702;6324.6521441321165;6506.902396464152;6521.64130972801;6524.249711283073;6438.4527315176865;6484.578502397329;6517.576357536745;6520.478361730955;6625.834354140992;5290.920636190383;5576.745232322695;6265.248170219603;6334.144890439732;6360.395762402564;6431.389018043278;6436.195475565554;6475.255345635313;6547.476201903741;6564.212294524687;6367.204099487564;6392.296847346881;6394.23269207698;6407.647579383179;6412.487001039813 05:01:50 INFO - PID 2395 | |1;ext.html;774.4849674966148;790.758818560339;836.577528348184;1224.3939250071217;1275.5089290623118;37974;38347.61652383475;38466;38546.22907541851;38640.84077477676;38917.832465026026;38960.22079558407;39040.80479597605;39078.41382379267;39770.80114599423;6734.360235777618;6984.3714065734075;8037.156098609649;8545.359098067638;8557.101504342048;8435.873461898076;8468.915310846874;8530;8549.145085491464;8560.143985601453;7658.9617051914665;8516.78708032298;8570.18583234595;8587.227149556538;8588.01237857645;8510.234078932895;8513.787155321104;8566.271866891322;8590.398672092955;8659.653613855438;7181.784546463562;8264.132266112092;8309.210624990677;8346.290565301957;8353.707620233263;33094.33811323784;37501.62498375009;37582.62417375846;37783.811080944695;37841.0539736506;3678.1172518595345;3718.6839118674943;3722.8324725387424;3723.050622091376;3731.3843215869347;30890.84554577235;31037.068887933172;31057.689423105705;31196.688033119834;31206.37587248265;3167.794093383946;3190.090824115139;3193.393255281512;3196.8081915085168;3206.7915585486867;307768.4611576928;308215.4589227062;310766;320717;323455;47153.5284647156;47245;47267.763661182165;47317;47414.5258547417;50788;51074;52270.47729522732;55381.169282460796;55782.16326755102;12217.450214740358;14371.353289102015;14394.568162955127;14538.273086345853;14559.417623295161;11387.0321022714;11458.369789661481;11491.195616306779;11589.478473468715;11604.88395116055;14227;14239.074460160056;14243.43026278958;14249.78625320621;14386.280685965869;6221.813345599639;6274.6548939808745;6303.936960630335;6311.368863113744;6342.334054924258;938.7465384346189;1037.486444210121;1040.7190058684114;1045.3727763341933;1082.8484012238293;23433.648495272584;24627.891744871526;24837.87581062083;24945.501089978097;25061.498770024496;1105.7891608688633;1147.9655610331704;1166.3876464856019;1176.1766763265748;1188.3404710385646;12203.816942745865;12492.937535312265;12761.489540418464;12797.936010319889;12841;335858;336542;337126;337917;339224;54853.902921941335;55015;55093.62265943304;55110.173347399825;55217.44782552203;1440.2942558146642;1496.4313560846808;1522.5584580471539;1534.539638108563;1557.2525187909641;20740.170393184104;24317.635235471836;24341.756582433947;24390.195127560557;24459.899304530983;35433;38673.8397848065;38949.83150505489;38956;39126;297266;297834;298068;298435.01564984943;298568;25147.49705005853;27538.862305688344;29635.40729185361;36696.63303366932;42324.36513452239;33813.830930845186;39790.40314395229;45846.54153458556;45874;45975.08049838918;28350.2221334791;30049.3990120192;31355;31445;31561.52657710182;21450.785492145504;30213.546796798506;31343;31409.371812563164;31458.52812207773;36359.81820090883;38089.809550952064;38101.237975240896;38137.80931095438;38164.04589885164;26871.865640671673;29876.103716888527;30576;31371.686283136878;31790.52314215335;23486.530269394858;24327;24598.631020535064;24992.250232493054;25126.246212613652 05:01:50 INFO - PID 2395 | |2;jquery.html;389.4528187896006;436.5612559377825;436.62232169173654;446.40850872593813;461.05023651278367;19505.90247048763;21257.36227913163;21867.906604669744;22110;22122;21775.45561360967;23084;23758.287251382444;23758.287251382444;23923.043078276893;5867.855768125217;6078.787242446515;6268.561200715952;6284.497240220794;6295.842603934915;4748.857534273978;6205.906911396332;6210.223722034746;6212.099245609384;6212.968935155318;5986.551008674345;6078.848028799272;6107.236595425572;6135.938640613582;6177.227846519185;6143.218466644667;6149.508039356885;6164.969175154118;6191.907121393206;6192.102145188967;5353.892922141536;5418.699512117103;5434.2935418395355;5478.726063696839;5554.527865131468;26839.86580067107;26859.597106043424;27377;27429.03998360048;27594.5860812088;2255.165588732163;2317.4206448387904;2319.512902290521;2320.3155069254612;2320.431494283894;17784.110794460354;17935.01357425341;18070.18684159216;18425.907870460695;18558.536036599056;2161.319184456907;2166.696662467256;2234.910603575855;2239.641657334819;2239.798418142375;148135.2593237027;150480;150710;150960;152430;12443.813342799865;12925.482980680856;12964.157329773534;13007.739845203043;13046.152000119962;16827.57931051709;16827.831721682873;16845.747313790303;16847.57881052959;16891.91554042222;7315.743948961761;7525.096988361432;8826.485426845713;9220.170184683411;9347.345685802065;8804.955975220082;8819.073997230327;8833.33749968755;8908.510031948175;9007.36948413618;7939.404544659058;8038.397120216007;8078.192180781993;8105.959470202612;8120.106788253405;1864.2915692036938;2002.67823236666;2061.1041486711197;2179.585878683045;2217.966730499044;1000.4047591682937;1026.1329176845554;1026.3174988632475;1060.2472244706191;1067.4662668665667;16280.43018494347;21682;21861.12555497794;21949.122035118737;22045.77954220437;1046.1735229168871;1047.350642601592;1075.295681328731;1077.2297807067828;1080.400377790318;9690.903090969001;10849.240553161202;11398.373089479992;11401.144914131473;11431.428428578538;535934;540086;540821;552990;553817.2309138428;112079;129634;131274.34362828315;133683;137833.9324910127;1197.6766273106211;1234.802431610938;1269.1052807770684;1273.6815796050987;1276.183242724657;17630.73553896667;22312.772797496295;22593.096276149427;22657;22878.65682014738;57721;57966;58171.70914145402;58376.4162358388;59034.11448828185;79969.80045299209;79972.40055198748;81528.59235703784;81699.59150204448;82653;8685.565721714016;8757.47455152693;8824.735257942271;9199.862002070107;9232.861507077534;13429.26139062361;14132.434702612192;14147;14218.573442796733;14489.492867749577;11449.542018319175;12203.511859525875;12214.938925305316;12220.594631617478;12401.503939842305;10170.898291016994;10922.781544369227;11378.886211138113;11421.600243991421;11762.176647634584;11110.44447777624;12244.632661020183;12392.876071239172;12475.438605262967;12630.305333206761;7333.413326933939;8748.300135989233;8770;8843.336749743667;9216;12027.518899243933;12044.337561433855;12130.757384852077;12214.083943704436;12330.075244356529 05:01:50 INFO - PID 2395 | |3;mootools.html;1375.8417781955075;1411.8658727420898;1424.0458892541997;1430.6995530938502;1447.0594113826014;9291.953540232289;11242.325460472368;11803;11815.877491638292;12080.577179798709;11601;11813.409329533531;11845.881541184586;11886;11908.404579770999;2501.362152651076;2529.8735063246763;2549.668543089396;2554.246497283297;2555.1567982565707;2469.1851688942697;2517.5216708825265;2520.042383894118;2520.7227205007434;2525.949481010379;2503.0613519930025;2510.3975045989014;2549.4646124313917;2549.923502294934;2552.680914885639;2249.7513879796707;2454.435479839638;2469.753024697525;2490.1657944588583;2498.7876030537427;2239.921602743896;2396.113438027941;2448.608222684362;2503.5243303772227;2524.886380112899;11785.233959792671;11889.762204755856;11892.821607675893;11900.821487677691;11901.4644341004;6653.833654158636;6845.110135682379;6873.621950792704;6886.414654754352;6926.307369263084;3984.9601503984877;4308.3537469379535;4379.890502737425;4413.3159360299205;4449.488308844492;1107.413071072333;1129.593346395297;1268.6447794617523;1271.3516106785514;1318.5648735917125;16141.515754527383;16428.75356869648;16526.504204873872;16579.336826526924;16815.83184168155;5022.723750193774;5392.649477783932;5639.971800140973;5714.428557144252;5822.534197264209;11878.168528202943;11909.464074116686;11957.162998590014;11973.161878668583;11997.820032699516;6607.900881486781;6615.536912416181;6638.070670106189;6644;6655.86688266232;5944.346121926585;6052.54605904559;6161.815145545641;6203.596766210272;6211.409916057967;3720.479132921393;4276.35854621803;4405.40527028847;4433.1577000369825;4455.485135054098;1252.1530742155248;1270.4537049068804;1287.2726909296218;1287.382056612831;1302.2251760202664;777.8211011467395;796.6056801883105;806.3106044332012;829.5976451421056;835.3567752830287;6204.689765511706;6337.112804207414;6484.837879053061;7077.5753454792875;7294.067072225865;859.9355048371397;939.7932454860056;943.9103285187896;950.8383574792302;974.9951250243703;4330.112326972963;4347.978260108679;4382.452193475816;4523.185826551252;4534.863954081383;14367.856321436651;15100.848991510164;15108.39566417353;15122.017068890487;15156.166410847509;14428.567142985727;14758.040727352687;15044.323005464781;15125.697486050438;15163.393464261526;7546.434017448603;7964.292140672199;8539.573021348908;8741;8775.42959707617;5853;6546.170952672257;7086.929130708627;7093.609851458219;7227.891581626385;11464.828027579426;12380.752384952071;12565.43455544521;13359.732805343645;13697;13768.449262029808;13859.79210311826;13888.722225555635;14043.789343160066;14050.718985620435;9323.300752443458;9384.577694003923;9453.33826632129;9510.85733713981;9518.571664274985;10955.780884382428;10972;10998.395088270221;11006.779864402828;11086.22396432242;8995.100489950952;9368.06319368085;9629.903700963181;9688.75778105553;9712.854307185256;9386.296027797805;9396.342256042011;9512.38169518979;9616.423014619146;9620.13418792312;8303.418760686694;8459.915400845914;8465.98408191021;8470.66117355299;8473.95763021181;6087.69561521931;6126.326104128453;6219.40915613016;6262.405071518198;6305.306416294296;6135.417135372132;6151.72317245716;6289.465395441256;6351.30135685065;6433.967830160819 05:01:50 INFO - PID 2395 | |4;prototype.html;388.7978251309321;404.75335965227094;409.4779156575366;416.9784029128634;421.17870153201267;5185.585153187745;5704.458076482738;6174.6295222286635;6316.021016742407;6317.34671437996;5156.819511317105;5290.365156181254;6159.10692949522;6257.749690012405;6274.309825919146;4314.935275970847;4490.528494508078;4625.537446255381;4779.713217206961;4798.976005119987;4131.710780245384;4782.665213435061;4787.281907713854;4797.736124513151;4833.057553777015;4723.4568024677255;4764.190087685084;4775.546323099317;4778.259369797687;4786.593139583139;4251.086016506435;4571.817127314904;4691.179043667345;4716.221823399118;4767.308740232665;3499.30013997199;3580.785152890809;3833.677381303446;4220.725652832556;4234.745915245096;5956.940430595725;6113.34939566319;6134.171886795315;6134.386561343875;6155.261368635747;5632.408597097327;5672.475748292011;5701.629394089412;5705.686187259699;5721.7048263464;5315.096433606296;6425.939719946204;6741.629210393427;6747.595144291309;6775.457963362919;4584.037352156051;4680.9297860532115;4815.374001379798;4854.320395144648;4861;6872.828179295508;7033.929660703378;7107.82230444243;7133.8216544586285;7141.8928716068785;4650.67445278834;4655.231886738718;4669.859904202879;4670.206064968964;4691.507391723888;3958.267720471722;4594.494605593382;4750.596199323028;4756.833510827105;4766.428028636586;5151.484851514819;5251.6061295403;5411.78352865881;5523.530499907472;5560.277163968658;5352.090144675414;6730.865382692319;6751.729930802811;6755;6755.7973260802255;1479.186447453896;1563.7802514039067;1620.8865379423562;1739.9734156847521;1743.9215235314186;1184.7867383870814;1194.205853107691;1195.5158160944832;1199.8800119988107;1211.4003568233782;6088.025915853531;6312.076580267399;6343.17728610773;6364.968175159094;6389.265234498;5261.473852614785;6642.435392991553;6931.757388491378;6968.303169682991;6982;6372.203474565679;6378.266499352634;6381.809095452274;6394.936050639434;6413.615183089029;2824.929376765597;3480.3387356402204;3568.1971556399603;3626.782393056425;3631.3826649469124;5444.911017796457;5834.620749651259;6285.748570057238;7119.5728256303755;7124.786256412316;5939.346671866091;6058.909116363258;6365.4589359904;7078.186008608973;7146.821329466701;6051.273847138284;6485.351464853571;6532.902006469907;7081.681324340417;7123.25205853378;6539.005603291042;6887.276835932155;6888.173419189729;6894;6905.343992320722;5949.970250148722;6536.967315163584;6651.63416012124;6693.866122677422;6763.2222294434805;7183.8563228736175;7235.2764723527225;7262.89105663426;7273.709051637875;7299.379552738077;4234.661227101886;4411.911761764811;4430.446194225722;4434.645228381786;4450.977745111254;6881.449484041164;6923.9307606923285;7005.754798582026;7007.614581198083;7025.297470252934;6743.224529179207;6749.055132281386;6760.7886254028945;6784.321567843176;6797.592144471348;6634.635095069818;6672.566283191577;6676.298988606321;6748.595084294958;6762.222344430454;6636.104125942936;6651.401373876374;6656.633885136364;6664.300248473841;6670.699818508276;5850.795222167204;6438.812787670133;6553.311902250195;6795.38841504267;6818.28408017151;5433.320834895638;5701.774118564528;6135.699112832688;6545.6399898004165;6578.703958321983 05:01:50 INFO - PID 2395 | |5;yui.html;839.1482645115213;846.792535828722;847.51268020888;873.5544872115223;878.5563290538279;15515.612109697235;16205.594860128504;16802.747958780612;16820.411285605005;19314.034298285063;17917.76499720424;18213.90893045533;18272.903625782463;18490.815091849112;18627.90686046571;821.8849361089452;930.4975313330827;1041.7031146123356;1042.067349722002;1046.8586740789972;962.8748262725836;997.306871724152;1007.6573964851949;1025.179856115105;1025.789713108811;1045.4354648489807;1059.369675043352;1059.4649701900569;1062.7502536903821;1069.3370110531482;1035.5391850626474;1050.123147172112;1054.1935419404163;1056.984145237822;1057.8095942730306;752.9887051694229;755.5957562703927;781.2187812187813;785.2304741353505;785.9528428294263;16941.576460588585;16960;17142.742858857127;17372.739408908878;17627.823721762874;17262.56843578908;17266.482005539852;17588.38440654571;17655.382061627908;17792;11963.820542691867;14014.719705605932;14183.57449276513;14258.714825703428;14303.427862885575;3487.5989261235027;3516.788992660423;3523.066387407339;3533.7349698772696;3535.2399234164777;131833.34083329522;133564.99999999904;133926;135634;136015.95976060367;13268.93365533166;13294.667633309246;13343.866561334456;13348.73302533944;13572.86427135716;22265.888670556542;24743.62884556733;25005.874970625395;25448.872755636105;25775.226743197734;7565.394768418623;7826.373890088779;7838.216178382117;7852.450328476952;7902.24928631779;3838.7312888097563;3841.4429907663234;3860.9806950965067;3923.74495657787;3926.705497087673;4426.0483995940385;4463.107378524308;4494.438195225597;4503.189425903303;4513.029698614813;939.8778158839307;958.9952050239843;969.5733877094056;977.6969139566758;980.5391466010964;570.4409678515078;640.503609702475;673.9258681545026;676.6008055247428;684.1414025398094;11051.557937682403;12045.698857528632;12175.573854915212;12188.939055304667;12295.69260768488;656.5765081522392;660.521122186411;662.9602223866583;675.1391975231619;688.8312363470982;5197.688367670992;5276.841694749163;5327.254184414185;5508.118701007819;5556.333240011144;103225;103490.96509034964;104162.47918760357;104915;105124;92386;93636;95017.52491237638;95106;96705.51647241719;85889;86884.13115868761;86934.56532717295;87715.56142219248;88267.55866220628;11070.39112848801;11168.385738784445;11529.538818447503;11702.414879255834;11744.471498782405;42949.926251842706;44674;44834.77582612066;45304.093918120794;45391;57081.714591426775;57533;57652.711736442725;58168.709156453944;58435.831283374944;14352.641183970485;14636.634084147981;14684.632884177981;14710.264486775832;14710.411583536967;15503.612409689396;15506.5348039559;15638.218089095728;15731.292091856123;15996;12363.93818030904;14442.711145777235;15003.2498375083;16364.75452868184;17805.46583602494;16733.832661673227;17027.40404085851;17081.658366832842;17607.20767565437;17614.29542818273;16213.513594592181;16674.49976500707;16926.238319275908;17098;17167.57081072983;8977.461352318882;12140.025693705098;12179;12182.964448021663;12292.692682683004;12219.877801221874;13250.478392600662;13737.598553309486;14029.649258768612;14095.013349065466 05:01:50 INFO - PID 2395 | __end_tp_report 05:01:50 INFO - PID 2395 | __start_cc_report 05:01:50 INFO - PID 2395 | _x_x_mozilla_cycle_collect,412 05:01:50 INFO - PID 2395 | __end_cc_report 05:01:50 INFO - PID 2395 | __startTimestamp1493553710433__endTimestamp 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | ------- Summary: start ------- 05:01:50 INFO - PID 2395 | Number of tests: 6 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | [#0] dojo.html Cycles:165 Average:6090.28 Median:6371.98 stddev:746.18 (11.7%) stddev-sans-first:714.62 05:01:50 INFO - PID 2395 | Values: 3257.5 3566.2 3570.7 3608.8 3619.4 6137.9 6213.8 6227.0 6239.6 6294.9 6157.3 6182.0 6208.2 6227.2 6317.8 4730.6 5132.1 5375.4 5399.2 5428.9 5464.9 5532.1 5533.7 5565.5 5589.6 5332.4 5420.6 5448.3 5489.3 5533.2 5403.2 5403.5 5413.3 5419.2 5484.4 5334.4 5432.5 5436.0 5449.9 5472.5 5994.9 6289.2 6305.2 6314.8 6325.5 6419.8 6496.4 6529.4 6592.7 6651.2 6752.2 6761.0 6777.3 6785.9 6787.5 6208.9 6353.3 6373.3 6385.2 6386.4 6561.3 6670.9 6771.5 6871.4 6882.9 6310.1 6377.3 6382.8 6435.2 6515.3 5104.5 6631.1 6693.8 6716.7 6750.7 5478.4 6541.3 6561.0 6608.5 6686.4 6405.9 6455.8 6534.8 6625.0 6668.5 4519.7 5268.4 5280.1 5334.2 5340.4 4720.3 4738.3 4739.9 4745.1 4756.3 4347.0 6017.2 6372.0 6410.1 6428.4 5597.6 6473.6 6628.9 6708.5 6734.6 5453.6 6093.9 6261.0 6339.4 6339.8 5805.5 5863.5 5868.5 5875.2 5926.9 6560.8 6777.0 6836.5 6851.1 6906.7 6704.4 6736.6 6744.6 6769.4 6773.3 6803.7 6904.8 6907.1 6907.6 6938.1 6338.2 6471.3 6513.2 6606.6 6627.1 6596.4 6686.1 6696.3 6726.1 6739.8 6282.0 6324.7 6506.9 6521.6 6524.2 6438.5 6484.6 6517.6 6520.5 6625.8 5290.9 5576.7 6265.2 6334.1 6360.4 6431.4 6436.2 6475.3 6547.5 6564.2 6367.2 6392.3 6394.2 6407.6 6412.5 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | [#1] ext.html Cycles:180 Average:46528.67 Median:336200.00 stddev:83266.99 (24.8%) stddev-sans-first:83429.71 05:01:50 INFO - PID 2395 | Values: 774.5 790.8 836.6 1224.4 1275.5 37974.0 38347.6 38466.0 38546.2 38640.8 38917.8 38960.2 39040.8 39078.4 39770.8 6734.4 6984.4 8037.2 8545.4 8557.1 8435.9 8468.9 8530.0 8549.1 8560.1 7659.0 8516.8 8570.2 8587.2 8588.0 8510.2 8513.8 8566.3 8590.4 8659.7 7181.8 8264.1 8309.2 8346.3 8353.7 33094.3 37501.6 37582.6 37783.8 37841.1 3678.1 3718.7 3722.8 3723.1 3731.4 30890.8 31037.1 31057.7 31196.7 31206.4 3167.8 3190.1 3193.4 3196.8 3206.8 307768.5 308215.5 310766.0 320717.0 323455.0 47153.5 47245.0 47267.8 47317.0 47414.5 50788.0 51074.0 52270.5 55381.2 55782.2 12217.5 14371.4 14394.6 14538.3 14559.4 11387.0 11458.4 11491.2 11589.5 11604.9 14227.0 14239.1 14243.4 14249.8 14386.3 6221.8 6274.7 6303.9 6311.4 6342.3 938.7 1037.5 1040.7 1045.4 1082.8 23433.6 24627.9 24837.9 24945.5 25061.5 1105.8 1148.0 1166.4 1176.2 1188.3 12203.8 12492.9 12761.5 12797.9 12841.0 335858.0 336542.0 337126.0 337917.0 339224.0 54853.9 55015.0 55093.6 55110.2 55217.4 1440.3 1496.4 1522.6 1534.5 1557.3 20740.2 24317.6 24341.8 24390.2 24459.9 35433.0 38673.8 38949.8 38956.0 39126.0 297266.0 297834.0 298068.0 298435.0 298568.0 25147.5 27538.9 29635.4 36696.6 42324.4 33813.8 39790.4 45846.5 45874.0 45975.1 28350.2 30049.4 31355.0 31445.0 31561.5 21450.8 30213.5 31343.0 31409.4 31458.5 36359.8 38089.8 38101.2 38137.8 38164.0 26871.9 29876.1 30576.0 31371.7 31790.5 23486.5 24327.0 24598.6 24992.3 25126.2 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | [#2] jquery.html Cycles:180 Average:35592.97 Median:2239.72 stddev:92361.51 (4123.8%) stddev-sans-first:92582.79 05:01:50 INFO - PID 2395 | Values: 389.5 436.6 436.6 446.4 461.1 19505.9 21257.4 21867.9 22110.0 22122.0 21775.5 23084.0 23758.3 23758.3 23923.0 5867.9 6078.8 6268.6 6284.5 6295.8 4748.9 6205.9 6210.2 6212.1 6213.0 5986.6 6078.8 6107.2 6135.9 6177.2 6143.2 6149.5 6165.0 6191.9 6192.1 5353.9 5418.7 5434.3 5478.7 5554.5 26839.9 26859.6 27377.0 27429.0 27594.6 2255.2 2317.4 2319.5 2320.3 2320.4 17784.1 17935.0 18070.2 18425.9 18558.5 2161.3 2166.7 2234.9 2239.6 2239.8 148135.3 150480.0 150710.0 150960.0 152430.0 12443.8 12925.5 12964.2 13007.7 13046.2 16827.6 16827.8 16845.7 16847.6 16891.9 7315.7 7525.1 8826.5 9220.2 9347.3 8805.0 8819.1 8833.3 8908.5 9007.4 7939.4 8038.4 8078.2 8106.0 8120.1 1864.3 2002.7 2061.1 2179.6 2218.0 1000.4 1026.1 1026.3 1060.2 1067.5 16280.4 21682.0 21861.1 21949.1 22045.8 1046.2 1047.4 1075.3 1077.2 1080.4 9690.9 10849.2 11398.4 11401.1 11431.4 535934.0 540086.0 540821.0 552990.0 553817.2 112079.0 129634.0 131274.3 133683.0 137833.9 1197.7 1234.8 1269.1 1273.7 1276.2 17630.7 22312.8 22593.1 22657.0 22878.7 57721.0 57966.0 58171.7 58376.4 59034.1 79969.8 79972.4 81528.6 81699.6 82653.0 8685.6 8757.5 8824.7 9199.9 9232.9 13429.3 14132.4 14147.0 14218.6 14489.5 11449.5 12203.5 12214.9 12220.6 12401.5 10170.9 10922.8 11378.9 11421.6 11762.2 11110.4 12244.6 12392.9 12475.4 12630.3 7333.4 8748.3 8770.0 8843.3 9216.0 12027.5 12044.3 12130.8 12214.1 12330.1 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | [#3] mootools.html Cycles:180 Average:7170.61 Median:4130.66 stddev:4503.64 (109.0%) stddev-sans-first:4495.23 05:01:50 INFO - PID 2395 | Values: 1375.8 1411.9 1424.0 1430.7 1447.1 9292.0 11242.3 11803.0 11815.9 12080.6 11601.0 11813.4 11845.9 11886.0 11908.4 2501.4 2529.9 2549.7 2554.2 2555.2 2469.2 2517.5 2520.0 2520.7 2525.9 2503.1 2510.4 2549.5 2549.9 2552.7 2249.8 2454.4 2469.8 2490.2 2498.8 2239.9 2396.1 2448.6 2503.5 2524.9 11785.2 11889.8 11892.8 11900.8 11901.5 6653.8 6845.1 6873.6 6886.4 6926.3 3985.0 4308.4 4379.9 4413.3 4449.5 1107.4 1129.6 1268.6 1271.4 1318.6 16141.5 16428.8 16526.5 16579.3 16815.8 5022.7 5392.6 5640.0 5714.4 5822.5 11878.2 11909.5 11957.2 11973.2 11997.8 6607.9 6615.5 6638.1 6644.0 6655.9 5944.3 6052.5 6161.8 6203.6 6211.4 3720.5 4276.4 4405.4 4433.2 4455.5 1252.2 1270.5 1287.3 1287.4 1302.2 777.8 796.6 806.3 829.6 835.4 6204.7 6337.1 6484.8 7077.6 7294.1 859.9 939.8 943.9 950.8 975.0 4330.1 4348.0 4382.5 4523.2 4534.9 14367.9 15100.8 15108.4 15122.0 15156.2 14428.6 14758.0 15044.3 15125.7 15163.4 7546.4 7964.3 8539.6 8741.0 8775.4 5853.0 6546.2 7086.9 7093.6 7227.9 11464.8 12380.8 12565.4 13359.7 13697.0 13768.4 13859.8 13888.7 14043.8 14050.7 9323.3 9384.6 9453.3 9510.9 9518.6 10955.8 10972.0 10998.4 11006.8 11086.2 8995.1 9368.1 9629.9 9688.8 9712.9 9386.3 9396.3 9512.4 9616.4 9620.1 8303.4 8459.9 8466.0 8470.7 8474.0 6087.7 6126.3 6219.4 6262.4 6305.3 6135.4 6151.7 6289.5 6351.3 6434.0 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | [#4] prototype.html Cycles:180 Average:5429.09 Median:6055.09 stddev:1670.93 (27.6%) stddev-sans-first:1632.23 05:01:50 INFO - PID 2395 | Values: 388.8 404.8 409.5 417.0 421.2 5185.6 5704.5 6174.6 6316.0 6317.3 5156.8 5290.4 6159.1 6257.7 6274.3 4314.9 4490.5 4625.5 4779.7 4799.0 4131.7 4782.7 4787.3 4797.7 4833.1 4723.5 4764.2 4775.5 4778.3 4786.6 4251.1 4571.8 4691.2 4716.2 4767.3 3499.3 3580.8 3833.7 4220.7 4234.7 5956.9 6113.3 6134.2 6134.4 6155.3 5632.4 5672.5 5701.6 5705.7 5721.7 5315.1 6425.9 6741.6 6747.6 6775.5 4584.0 4680.9 4815.4 4854.3 4861.0 6872.8 7033.9 7107.8 7133.8 7141.9 4650.7 4655.2 4669.9 4670.2 4691.5 3958.3 4594.5 4750.6 4756.8 4766.4 5151.5 5251.6 5411.8 5523.5 5560.3 5352.1 6730.9 6751.7 6755.0 6755.8 1479.2 1563.8 1620.9 1740.0 1743.9 1184.8 1194.2 1195.5 1199.9 1211.4 6088.0 6312.1 6343.2 6365.0 6389.3 5261.5 6642.4 6931.8 6968.3 6982.0 6372.2 6378.3 6381.8 6394.9 6413.6 2824.9 3480.3 3568.2 3626.8 3631.4 5444.9 5834.6 6285.7 7119.6 7124.8 5939.3 6058.9 6365.5 7078.2 7146.8 6051.3 6485.4 6532.9 7081.7 7123.3 6539.0 6887.3 6888.2 6894.0 6905.3 5950.0 6537.0 6651.6 6693.9 6763.2 7183.9 7235.3 7262.9 7273.7 7299.4 4234.7 4411.9 4430.4 4434.6 4451.0 6881.4 6923.9 7005.8 7007.6 7025.3 6743.2 6749.1 6760.8 6784.3 6797.6 6634.6 6672.6 6676.3 6748.6 6762.2 6636.1 6651.4 6656.6 6664.3 6670.7 5850.8 6438.8 6553.3 6795.4 6818.3 5433.3 5701.8 6135.7 6545.6 6578.7 05:01:50 INFO - PID 2395 | 05:01:50 INFO - PID 2395 | [#5] yui.html Cycles:180 Average:22366.50 Median:17641.60 stddev:32121.72 (182.1%) stddev-sans-first:32171.16 05:01:50 INFO - PID 2395 | Values: 839.1 846.8 847.5 873.6 878.6 15515.6 16205.6 16802.7 16820.4 19314.0 17917.8 18213.9 18272.9 18490.8 18627.9 821.9 930.5 1041.7 1042.1 1046.9 962.9 997.3 1007.7 1025.2 1025.8 1045.4 1059.4 1059.5 1062.8 1069.3 1035.5 1050.1 1054.2 1057.0 1057.8 753.0 755.6 781.2 785.2 786.0 16941.6 16960.0 17142.7 17372.7 17627.8 17262.6 17266.5 17588.4 17655.4 17792.0 11963.8 14014.7 14183.6 14258.7 14303.4 3487.6 3516.8 3523.1 3533.7 3535.2 131833.3 133565.0 133926.0 135634.0 136016.0 13268.9 13294.7 13343.9 13348.7 13572.9 22265.9 24743.6 25005.9 25448.9 25775.2 7565.4 7826.4 7838.2 7852.5 7902.2 3838.7 3841.4 3861.0 3923.7 3926.7 4426.0 4463.1 4494.4 4503.2 4513.0 939.9 959.0 969.6 977.7 980.5 570.4 640.5 673.9 676.6 684.1 11051.6 12045.7 12175.6 12188.9 12295.7 656.6 660.5 663.0 675.1 688.8 5197.7 5276.8 5327.3 5508.1 5556.3 103225.0 103491.0 104162.5 104915.0 105124.0 92386.0 93636.0 95017.5 95106.0 96705.5 85889.0 86884.1 86934.6 87715.6 88267.6 11070.4 11168.4 11529.5 11702.4 11744.5 42949.9 44674.0 44834.8 45304.1 45391.0 57081.7 57533.0 57652.7 58168.7 58435.8 14352.6 14636.6 14684.6 14710.3 14710.4 15503.6 15506.5 15638.2 15731.3 15996.0 12363.9 14442.7 15003.2 16364.8 17805.5 16733.8 17027.4 17081.7 17607.2 17614.3 16213.5 16674.5 16926.2 17098.0 17167.6 8977.5 12140.0 12179.0 12183.0 12292.7 12219.9 13250.5 13737.6 14029.6 14095.0 05:01:50 INFO - PID 2395 | -------- Summary: end -------- 05:01:50 INFO - PID 2395 | 05:01:51 INFO - TEST-INFO | 2395: exit 0 05:01:51 INFO - TEST-OK | dromaeo_css | took 1115164ms 05:01:51 INFO - TEST-START | kraken 05:01:51 INFO - operating with platform_type : mac_ 05:01:51 INFO - Initialising browser for kraken test... 05:01:51 INFO - TEST-INFO | started process 2403 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpsPscG8/profile http://localhost:58765/getInfo.html) 05:01:53 INFO - PID 2403 | 2017-04-30 05:01:53.963 plugin-container[2404:38909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 05:01:53 INFO - PID 2403 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:53 INFO - PID 2403 | 2017-04-30 05:01:53.964 plugin-container[2404:38909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 05:01:53 INFO - PID 2403 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:53 INFO - PID 2403 | 2017-04-30 05:01:53.964 plugin-container[2404:38909] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:54 INFO - PID 2403 | __metrics Screen width/height:1600/1200 05:01:54 INFO - PID 2403 | colorDepth:24 05:01:54 INFO - PID 2403 | Browser inner width/height: 1024/689 05:01:54 INFO - PID 2403 | __metrics 05:01:54 INFO - TEST-INFO | 2403: exit 0 05:01:54 INFO - Browser initialized. 05:01:54 INFO - Running cycle 1/1 for kraken test... 05:01:54 INFO - TEST-INFO | started process 2405 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpsPscG8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:57 INFO - PID 2405 | 2017-04-30 05:01:57.305 plugin-container[2406:39037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 05:01:57 INFO - PID 2405 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:57 INFO - PID 2405 | 2017-04-30 05:01:57.305 plugin-container[2406:39037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:01:57 INFO - PID 2405 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:57 INFO - PID 2405 | 2017-04-30 05:01:57.305 plugin-container[2406:39037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:02:07 INFO - PID 2405 | RSS: Main: 234901504 05:02:07 INFO - PID 2405 | 05:02:09 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58765/tests/kraken/driver.html?testName=audio-beat-detection) 05:02:10 INFO - PID 2405 | RSS: Main: 247914496 05:02:10 INFO - PID 2405 | 05:02:13 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58765/tests/kraken/driver.html?testName=audio-dft) 05:02:14 INFO - PID 2405 | RSS: Main: 256397312 05:02:14 INFO - PID 2405 | 05:02:17 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58765/tests/kraken/driver.html?testName=audio-fft) 05:02:18 INFO - PID 2405 | RSS: Main: 258740224 05:02:18 INFO - PID 2405 | 05:02:19 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58765/tests/kraken/driver.html?testName=audio-oscillator) 05:02:20 INFO - PID 2405 | RSS: Main: 259801088 05:02:20 INFO - PID 2405 | 05:02:22 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58765/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:02:23 INFO - PID 2405 | RSS: Main: 260403200 05:02:23 INFO - PID 2405 | 05:02:26 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58765/tests/kraken/driver.html?testName=imaging-darkroom) 05:02:26 INFO - PID 2405 | RSS: Main: 265805824 05:02:26 INFO - PID 2405 | 05:02:30 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58765/tests/kraken/driver.html?testName=imaging-desaturate) 05:02:30 INFO - PID 2405 | RSS: Main: 268308480 05:02:30 INFO - PID 2405 | 05:02:33 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58765/tests/kraken/driver.html?testName=json-parse-financial) 05:02:34 INFO - PID 2405 | RSS: Main: 267923456 05:02:34 INFO - PID 2405 | 05:02:35 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58765/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:02:36 INFO - PID 2405 | RSS: Main: 267276288 05:02:36 INFO - PID 2405 | 05:02:38 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:02:38 INFO - PID 2405 | RSS: Main: 267497472 05:02:38 INFO - PID 2405 | 05:02:40 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:02:41 INFO - PID 2405 | RSS: Main: 267051008 05:02:41 INFO - PID 2405 | 05:02:44 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:02:45 INFO - PID 2405 | RSS: Main: 267177984 05:02:45 INFO - PID 2405 | 05:02:48 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:02:49 INFO - PID 2405 | RSS: Main: 264544256 05:02:49 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | Cycle 1(1): loaded http://localhost:58765/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58765/tests/kraken/driver.html?testName=ai-astar) 05:02:51 INFO - PID 2405 | RSS: Main: 266248192 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | __start_tp_report 05:02:51 INFO - PID 2405 | _x_x_mozilla_page_load 05:02:51 INFO - PID 2405 | _x_x_mozilla_page_load_details 05:02:51 INFO - PID 2405 | |i|pagename|runs| 05:02:51 INFO - PID 2405 | |0;ai-astar;103;114;114;114;114;116;113;114;116;115 05:02:51 INFO - PID 2405 | |1;audio-beat-detection;177;142;141;150;110;111;117;139;158;142 05:02:51 INFO - PID 2405 | |2;audio-dft;192;198;200;241;195;278;272;202;196;202 05:02:51 INFO - PID 2405 | |3;audio-fft;85;56;58;83;58;58;62;60;82;47 05:02:51 INFO - PID 2405 | |4;audio-oscillator;82;71;84;72;69;72;86;70;72;108 05:02:51 INFO - PID 2405 | |5;imaging-gaussian-blur;93;79;137;136;88;89;129;89;90;89 05:02:51 INFO - PID 2405 | |6;imaging-darkroom;97;123;103;152;108;153;156;154;96;95 05:02:51 INFO - PID 2405 | |7;imaging-desaturate;89;93;90;88;91;91;93;93;93;92 05:02:51 INFO - PID 2405 | |8;json-parse-financial;50;50;50;49;52;51;50;51;51;49 05:02:51 INFO - PID 2405 | |9;json-stringify-tinderbox;57;56;62;56;66;59;55;58;59;59 05:02:51 INFO - PID 2405 | |10;stanford-crypto-aes;84;82;80;93;106;104;82;82;79;84 05:02:51 INFO - PID 2405 | |11;stanford-crypto-ccm;160;154;149;161;147;134;148;137;196;138 05:02:51 INFO - PID 2405 | |12;stanford-crypto-pbkdf2;194;234;227;179;197;227;235;179;185;188 05:02:51 INFO - PID 2405 | |13;stanford-crypto-sha256-iterative;69;73;91;100;94;91;101;71;68;67 05:02:51 INFO - PID 2405 | __end_tp_report 05:02:51 INFO - PID 2405 | __start_cc_report 05:02:51 INFO - PID 2405 | _x_x_mozilla_cycle_collect,992 05:02:51 INFO - PID 2405 | __end_cc_report 05:02:51 INFO - PID 2405 | __startTimestamp1493553771392__endTimestamp 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | ------- Summary: start ------- 05:02:51 INFO - PID 2405 | Number of tests: 14 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.00 stddev:3.74 (3.3%) stddev-sans-first:1.01 05:02:51 INFO - PID 2405 | Values: 103.0 114.0 114.0 114.0 114.0 116.0 113.0 114.0 116.0 115.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:142.00 stddev:21.23 (15.0%) stddev-sans-first:17.41 05:02:51 INFO - PID 2405 | Values: 177.0 142.0 141.0 150.0 110.0 111.0 117.0 139.0 158.0 142.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#2] audio-dft Cycles:10 Average:217.60 Median:202.00 stddev:33.28 (16.5%) stddev-sans-first:33.99 05:02:51 INFO - PID 2405 | Values: 192.0 198.0 200.0 241.0 195.0 278.0 272.0 202.0 196.0 202.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#3] audio-fft Cycles:10 Average:64.90 Median:61.00 stddev:13.33 (21.9%) stddev-sans-first:11.99 05:02:51 INFO - PID 2405 | Values: 85.0 56.0 58.0 83.0 58.0 58.0 62.0 60.0 82.0 47.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:72.00 stddev:12.08 (16.8%) stddev-sans-first:12.76 05:02:51 INFO - PID 2405 | Values: 82.0 71.0 84.0 72.0 69.0 72.0 86.0 70.0 72.0 108.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#5] imaging-gaussian-blur Cycles:10 Average:101.90 Median:89.00 stddev:22.53 (25.3%) stddev-sans-first:23.66 05:02:51 INFO - PID 2405 | Values: 93.0 79.0 137.0 136.0 88.0 89.0 129.0 89.0 90.0 89.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#6] imaging-darkroom Cycles:10 Average:123.70 Median:155.00 stddev:27.08 (17.5%) stddev-sans-first:26.94 05:02:51 INFO - PID 2405 | Values: 97.0 123.0 103.0 152.0 108.0 153.0 156.0 154.0 96.0 95.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:92.50 stddev:1.83 (2.0%) stddev-sans-first:1.74 05:02:51 INFO - PID 2405 | Values: 89.0 93.0 90.0 88.0 91.0 91.0 93.0 93.0 93.0 92.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:0.95 (1.9%) stddev-sans-first:1.00 05:02:51 INFO - PID 2405 | Values: 50.0 50.0 50.0 49.0 52.0 51.0 50.0 51.0 51.0 49.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:3.27 (5.5%) stddev-sans-first:3.41 05:02:51 INFO - PID 2405 | Values: 57.0 56.0 62.0 56.0 66.0 59.0 55.0 58.0 59.0 59.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:82.00 stddev:9.94 (12.1%) stddev-sans-first:10.45 05:02:51 INFO - PID 2405 | Values: 84.0 82.0 80.0 93.0 106.0 104.0 82.0 82.0 79.0 84.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:151.50 stddev:17.88 (11.8%) stddev-sans-first:18.76 05:02:51 INFO - PID 2405 | Values: 160.0 154.0 149.0 161.0 147.0 134.0 148.0 137.0 196.0 138.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.50 Median:212.00 stddev:23.41 (11.0%) stddev-sans-first:24.52 05:02:51 INFO - PID 2405 | Values: 194.0 234.0 227.0 179.0 197.0 227.0 235.0 179.0 185.0 188.0 05:02:51 INFO - PID 2405 | 05:02:51 INFO - PID 2405 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.50 Median:72.00 stddev:14.07 (19.5%) stddev-sans-first:14.04 05:02:51 INFO - PID 2405 | Values: 69.0 73.0 91.0 100.0 94.0 91.0 101.0 71.0 68.0 67.0 05:02:51 INFO - PID 2405 | -------- Summary: end -------- 05:02:51 INFO - PID 2405 | 05:02:52 INFO - TEST-INFO | 2405: exit 0 05:02:52 INFO - TEST-OK | kraken | took 61387ms 05:02:52 INFO - SUITE-END | took 1176s 05:02:52 INFO - Completed test suite (00:19:36) 05:02:52 INFO - javascript benchmark 05:02:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7963.005029234545, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.527658489519, 3566.233259849132, 3570.6607872252125, 3608.7654302470337, 3619.3666108431025, 6137.907931381022, 6213.751449942002, 6227.034809604507, 6239.563230573862, 6294.874102517947, 6157.261128664555, 6181.969090154555, 6208.16189814375, 6227.221597300338, 6317.77887773928, 4730.574248317659, 5132.101882170624, 5375.408705042442, 5399.19012148179, 5428.891422171554, 5464.9453505465035, 5532.1425179097305, 5533.6956467394275, 5565.471280228372, 5589.580781441387, 5332.440093790135, 5420.593455490854, 5448.291722076145, 5489.34127904652, 5533.197686335479, 5403.189521571757, 5403.540699040586, 5413.2692086568595, 5419.241306217133, 5484.369297530795, 5334.3598768147685, 5432.538234250093, 5435.972820135914, 5449.863753406157, 5472.452754724535, 5994.8501287467725, 6289.213848268966, 6305.180326557564, 6314.810555683337, 6325.525585581054, 6419.8395040123905, 6496.382843629847, 6529.444997175246, 6592.670366481657, 6651.16860392451, 6752.2234942981695, 6760.966195169042, 6777.288384719632, 6785.8642827143185, 6787.457003439713, 6208.875822483525, 6353.3329000455215, 6373.298937116913, 6385.169927909388, 6386.36136386367, 6561.311062338481, 6670.9332906670315, 6771.5259931805285, 6871.381575658214, 6882.931170688329, 6310.05349197624, 6377.298497165308, 6382.808515744535, 6435.163428754326, 6515.348465153542, 5104.489551044866, 6631.10480085192, 6693.765718199839, 6716.731330746813, 6750.696218670171, 5478.369987451414, 6541.313162117909, 6560.967195163994, 6608.50436217276, 6686.398224159849, 6405.871882562323, 6455.838604034937, 6534.8366290843105, 6624.96687516569, 6668.533202675863, 4519.728816271035, 5268.446813084647, 5280.075986702312, 5334.199870019528, 5340.385855626575, 4720.315554244684, 4738.289256611536, 4739.857804265877, 4745.145873742759, 4756.286557016475, 4346.956530434719, 6017.217761690952, 6371.980483122678, 6410.0705397717065, 6428.357164283534, 5597.608167428322, 6473.579217350857, 6628.8674226515195, 6708.496862735315, 6734.595924244561, 5453.64551304164, 6093.878122437615, 6260.968695156586, 6339.366063393716, 6339.841503962438, 5805.535557155417, 5863.530917526673, 5868.530517558671, 5875.206847075676, 5926.851828704317, 6560.835979100465, 6776.966115169393, 6836.487263455161, 6851.109355783717, 6906.689198986158, 6704.363085506869, 6736.629485378351, 6744.62904540255, 6769.3907548320885, 6773.322667733188, 6803.659817009228, 6904.827379315357, 6907.067545881242, 6907.620080895599, 6938.098047253825, 6338.239411270677, 6471.255805582418, 6513.185851768529, 6606.636634985122, 6627.105340778933, 6596.439302659328, 6686.063951046955, 6696.263411024686, 6726.125603671687, 6739.764108256188, 6281.96859015702, 6324.6521441321165, 6506.902396464152, 6521.64130972801, 6524.249711283073, 6438.4527315176865, 6484.578502397329, 6517.576357536745, 6520.478361730955, 6625.834354140992, 5290.920636190383, 5576.745232322695, 6265.248170219603, 6334.144890439732, 6360.395762402564, 6431.389018043278, 6436.195475565554, 6475.255345635313, 6547.476201903741, 6564.212294524687, 6367.204099487564, 6392.296847346881, 6394.23269207698, 6407.647579383179, 6412.487001039813], "value": 6042.39744557776, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.4849674966148, 790.758818560339, 836.577528348184, 1224.3939250071217, 1275.5089290623118, 37974.0, 38347.61652383475, 38466.0, 38546.22907541851, 38640.84077477676, 38917.832465026026, 38960.22079558407, 39040.80479597605, 39078.41382379267, 39770.80114599423, 6734.360235777618, 6984.3714065734075, 8037.156098609649, 8545.359098067638, 8557.101504342048, 8435.873461898076, 8468.915310846874, 8530.0, 8549.145085491464, 8560.143985601453, 7658.9617051914665, 8516.78708032298, 8570.18583234595, 8587.227149556538, 8588.01237857645, 8510.234078932895, 8513.787155321104, 8566.271866891322, 8590.398672092955, 8659.653613855438, 7181.784546463562, 8264.132266112092, 8309.210624990677, 8346.290565301957, 8353.707620233263, 33094.33811323784, 37501.62498375009, 37582.62417375846, 37783.811080944695, 37841.0539736506, 3678.1172518595345, 3718.6839118674943, 3722.8324725387424, 3723.050622091376, 3731.3843215869347, 30890.84554577235, 31037.068887933172, 31057.689423105705, 31196.688033119834, 31206.37587248265, 3167.794093383946, 3190.090824115139, 3193.393255281512, 3196.8081915085168, 3206.7915585486867, 307768.4611576928, 308215.4589227062, 310766.0, 320717.0, 323455.0, 47153.5284647156, 47245.0, 47267.763661182165, 47317.0, 47414.5258547417, 50788.0, 51074.0, 52270.47729522732, 55381.169282460796, 55782.16326755102, 12217.450214740358, 14371.353289102015, 14394.568162955127, 14538.273086345853, 14559.417623295161, 11387.0321022714, 11458.369789661481, 11491.195616306779, 11589.478473468715, 11604.88395116055, 14227.0, 14239.074460160056, 14243.43026278958, 14249.78625320621, 14386.280685965869, 6221.813345599639, 6274.6548939808745, 6303.936960630335, 6311.368863113744, 6342.334054924258, 938.7465384346189, 1037.486444210121, 1040.7190058684114, 1045.3727763341933, 1082.8484012238293, 23433.648495272584, 24627.891744871526, 24837.87581062083, 24945.501089978097, 25061.498770024496, 1105.7891608688633, 1147.9655610331704, 1166.3876464856019, 1176.1766763265748, 1188.3404710385646, 12203.816942745865, 12492.937535312265, 12761.489540418464, 12797.936010319889, 12841.0, 335858.0, 336542.0, 337126.0, 337917.0, 339224.0, 54853.902921941335, 55015.0, 55093.62265943304, 55110.173347399825, 55217.44782552203, 1440.2942558146642, 1496.4313560846808, 1522.5584580471539, 1534.539638108563, 1557.2525187909641, 20740.170393184104, 24317.635235471836, 24341.756582433947, 24390.195127560557, 24459.899304530983, 35433.0, 38673.8397848065, 38949.83150505489, 38956.0, 39126.0, 297266.0, 297834.0, 298068.0, 298435.01564984943, 298568.0, 25147.49705005853, 27538.862305688344, 29635.40729185361, 36696.63303366932, 42324.36513452239, 33813.830930845186, 39790.40314395229, 45846.54153458556, 45874.0, 45975.08049838918, 28350.2221334791, 30049.3990120192, 31355.0, 31445.0, 31561.52657710182, 21450.785492145504, 30213.546796798506, 31343.0, 31409.371812563164, 31458.52812207773, 36359.81820090883, 38089.809550952064, 38101.237975240896, 38137.80931095438, 38164.04589885164, 26871.865640671673, 29876.103716888527, 30576.0, 31371.686283136878, 31790.52314215335, 23486.530269394858, 24327.0, 24598.631020535064, 24992.250232493054, 25126.246212613652], "value": 17691.14492755159, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.4528187896006, 436.5612559377825, 436.62232169173654, 446.40850872593813, 461.05023651278367, 19505.90247048763, 21257.36227913163, 21867.906604669744, 22110.0, 22122.0, 21775.45561360967, 23084.0, 23758.287251382444, 23758.287251382444, 23923.043078276893, 5867.855768125217, 6078.787242446515, 6268.561200715952, 6284.497240220794, 6295.842603934915, 4748.857534273978, 6205.906911396332, 6210.223722034746, 6212.099245609384, 6212.968935155318, 5986.551008674345, 6078.848028799272, 6107.236595425572, 6135.938640613582, 6177.227846519185, 6143.218466644667, 6149.508039356885, 6164.969175154118, 6191.907121393206, 6192.102145188967, 5353.892922141536, 5418.699512117103, 5434.2935418395355, 5478.726063696839, 5554.527865131468, 26839.86580067107, 26859.597106043424, 27377.0, 27429.03998360048, 27594.5860812088, 2255.165588732163, 2317.4206448387904, 2319.512902290521, 2320.3155069254612, 2320.431494283894, 17784.110794460354, 17935.01357425341, 18070.18684159216, 18425.907870460695, 18558.536036599056, 2161.319184456907, 2166.696662467256, 2234.910603575855, 2239.641657334819, 2239.798418142375, 148135.2593237027, 150480.0, 150710.0, 150960.0, 152430.0, 12443.813342799865, 12925.482980680856, 12964.157329773534, 13007.739845203043, 13046.152000119962, 16827.57931051709, 16827.831721682873, 16845.747313790303, 16847.57881052959, 16891.91554042222, 7315.743948961761, 7525.096988361432, 8826.485426845713, 9220.170184683411, 9347.345685802065, 8804.955975220082, 8819.073997230327, 8833.33749968755, 8908.510031948175, 9007.36948413618, 7939.404544659058, 8038.397120216007, 8078.192180781993, 8105.959470202612, 8120.106788253405, 1864.2915692036938, 2002.67823236666, 2061.1041486711197, 2179.585878683045, 2217.966730499044, 1000.4047591682937, 1026.1329176845554, 1026.3174988632475, 1060.2472244706191, 1067.4662668665667, 16280.43018494347, 21682.0, 21861.12555497794, 21949.122035118737, 22045.77954220437, 1046.1735229168871, 1047.350642601592, 1075.295681328731, 1077.2297807067828, 1080.400377790318, 9690.903090969001, 10849.240553161202, 11398.373089479992, 11401.144914131473, 11431.428428578538, 535934.0, 540086.0, 540821.0, 552990.0, 553817.2309138428, 112079.0, 129634.0, 131274.34362828315, 133683.0, 137833.9324910127, 1197.6766273106211, 1234.802431610938, 1269.1052807770684, 1273.6815796050987, 1276.183242724657, 17630.73553896667, 22312.772797496295, 22593.096276149427, 22657.0, 22878.65682014738, 57721.0, 57966.0, 58171.70914145402, 58376.4162358388, 59034.11448828185, 79969.80045299209, 79972.40055198748, 81528.59235703784, 81699.59150204448, 82653.0, 8685.565721714016, 8757.47455152693, 8824.735257942271, 9199.862002070107, 9232.861507077534, 13429.26139062361, 14132.434702612192, 14147.0, 14218.573442796733, 14489.492867749577, 11449.542018319175, 12203.511859525875, 12214.938925305316, 12220.594631617478, 12401.503939842305, 10170.898291016994, 10922.781544369227, 11378.886211138113, 11421.600243991421, 11762.176647634584, 11110.44447777624, 12244.632661020183, 12392.876071239172, 12475.438605262967, 12630.305333206761, 7333.413326933939, 8748.300135989233, 8770.0, 8843.336749743667, 9216.0, 12027.518899243933, 12044.337561433855, 12130.757384852077, 12214.083943704436, 12330.075244356529], "value": 10596.515878882432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.8417781955075, 1411.8658727420898, 1424.0458892541997, 1430.6995530938502, 1447.0594113826014, 9291.953540232289, 11242.325460472368, 11803.0, 11815.877491638292, 12080.577179798709, 11601.0, 11813.409329533531, 11845.881541184586, 11886.0, 11908.404579770999, 2501.362152651076, 2529.8735063246763, 2549.668543089396, 2554.246497283297, 2555.1567982565707, 2469.1851688942697, 2517.5216708825265, 2520.042383894118, 2520.7227205007434, 2525.949481010379, 2503.0613519930025, 2510.3975045989014, 2549.4646124313917, 2549.923502294934, 2552.680914885639, 2249.7513879796707, 2454.435479839638, 2469.753024697525, 2490.1657944588583, 2498.7876030537427, 2239.921602743896, 2396.113438027941, 2448.608222684362, 2503.5243303772227, 2524.886380112899, 11785.233959792671, 11889.762204755856, 11892.821607675893, 11900.821487677691, 11901.4644341004, 6653.833654158636, 6845.110135682379, 6873.621950792704, 6886.414654754352, 6926.307369263084, 3984.9601503984877, 4308.3537469379535, 4379.890502737425, 4413.3159360299205, 4449.488308844492, 1107.413071072333, 1129.593346395297, 1268.6447794617523, 1271.3516106785514, 1318.5648735917125, 16141.515754527383, 16428.75356869648, 16526.504204873872, 16579.336826526924, 16815.83184168155, 5022.723750193774, 5392.649477783932, 5639.971800140973, 5714.428557144252, 5822.534197264209, 11878.168528202943, 11909.464074116686, 11957.162998590014, 11973.161878668583, 11997.820032699516, 6607.900881486781, 6615.536912416181, 6638.070670106189, 6644.0, 6655.86688266232, 5944.346121926585, 6052.54605904559, 6161.815145545641, 6203.596766210272, 6211.409916057967, 3720.479132921393, 4276.35854621803, 4405.40527028847, 4433.1577000369825, 4455.485135054098, 1252.1530742155248, 1270.4537049068804, 1287.2726909296218, 1287.382056612831, 1302.2251760202664, 777.8211011467395, 796.6056801883105, 806.3106044332012, 829.5976451421056, 835.3567752830287, 6204.689765511706, 6337.112804207414, 6484.837879053061, 7077.5753454792875, 7294.067072225865, 859.9355048371397, 939.7932454860056, 943.9103285187896, 950.8383574792302, 974.9951250243703, 4330.112326972963, 4347.978260108679, 4382.452193475816, 4523.185826551252, 4534.863954081383, 14367.856321436651, 15100.848991510164, 15108.39566417353, 15122.017068890487, 15156.166410847509, 14428.567142985727, 14758.040727352687, 15044.323005464781, 15125.697486050438, 15163.393464261526, 7546.434017448603, 7964.292140672199, 8539.573021348908, 8741.0, 8775.42959707617, 5853.0, 6546.170952672257, 7086.929130708627, 7093.609851458219, 7227.891581626385, 11464.828027579426, 12380.752384952071, 12565.43455544521, 13359.732805343645, 13697.0, 13768.449262029808, 13859.79210311826, 13888.722225555635, 14043.789343160066, 14050.718985620435, 9323.300752443458, 9384.577694003923, 9453.33826632129, 9510.85733713981, 9518.571664274985, 10955.780884382428, 10972.0, 10998.395088270221, 11006.779864402828, 11086.22396432242, 8995.100489950952, 9368.06319368085, 9629.903700963181, 9688.75778105553, 9712.854307185256, 9386.296027797805, 9396.342256042011, 9512.38169518979, 9616.423014619146, 9620.13418792312, 8303.418760686694, 8459.915400845914, 8465.98408191021, 8470.66117355299, 8473.95763021181, 6087.69561521931, 6126.326104128453, 6219.40915613016, 6262.405071518198, 6305.306416294296, 6135.417135372132, 6151.72317245716, 6289.465395441256, 6351.30135685065, 6433.967830160819], "value": 5427.936259373205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.7978251309321, 404.75335965227094, 409.4779156575366, 416.9784029128634, 421.17870153201267, 5185.585153187745, 5704.458076482738, 6174.6295222286635, 6316.021016742407, 6317.34671437996, 5156.819511317105, 5290.365156181254, 6159.10692949522, 6257.749690012405, 6274.309825919146, 4314.935275970847, 4490.528494508078, 4625.537446255381, 4779.713217206961, 4798.976005119987, 4131.710780245384, 4782.665213435061, 4787.281907713854, 4797.736124513151, 4833.057553777015, 4723.4568024677255, 4764.190087685084, 4775.546323099317, 4778.259369797687, 4786.593139583139, 4251.086016506435, 4571.817127314904, 4691.179043667345, 4716.221823399118, 4767.308740232665, 3499.30013997199, 3580.785152890809, 3833.677381303446, 4220.725652832556, 4234.745915245096, 5956.940430595725, 6113.34939566319, 6134.171886795315, 6134.386561343875, 6155.261368635747, 5632.408597097327, 5672.475748292011, 5701.629394089412, 5705.686187259699, 5721.7048263464, 5315.096433606296, 6425.939719946204, 6741.629210393427, 6747.595144291309, 6775.457963362919, 4584.037352156051, 4680.9297860532115, 4815.374001379798, 4854.320395144648, 4861.0, 6872.828179295508, 7033.929660703378, 7107.82230444243, 7133.8216544586285, 7141.8928716068785, 4650.67445278834, 4655.231886738718, 4669.859904202879, 4670.206064968964, 4691.507391723888, 3958.267720471722, 4594.494605593382, 4750.596199323028, 4756.833510827105, 4766.428028636586, 5151.484851514819, 5251.6061295403, 5411.78352865881, 5523.530499907472, 5560.277163968658, 5352.090144675414, 6730.865382692319, 6751.729930802811, 6755.0, 6755.7973260802255, 1479.186447453896, 1563.7802514039067, 1620.8865379423562, 1739.9734156847521, 1743.9215235314186, 1184.7867383870814, 1194.205853107691, 1195.5158160944832, 1199.8800119988107, 1211.4003568233782, 6088.025915853531, 6312.076580267399, 6343.17728610773, 6364.968175159094, 6389.265234498, 5261.473852614785, 6642.435392991553, 6931.757388491378, 6968.303169682991, 6982.0, 6372.203474565679, 6378.266499352634, 6381.809095452274, 6394.936050639434, 6413.615183089029, 2824.929376765597, 3480.3387356402204, 3568.1971556399603, 3626.782393056425, 3631.3826649469124, 5444.911017796457, 5834.620749651259, 6285.748570057238, 7119.5728256303755, 7124.786256412316, 5939.346671866091, 6058.909116363258, 6365.4589359904, 7078.186008608973, 7146.821329466701, 6051.273847138284, 6485.351464853571, 6532.902006469907, 7081.681324340417, 7123.25205853378, 6539.005603291042, 6887.276835932155, 6888.173419189729, 6894.0, 6905.343992320722, 5949.970250148722, 6536.967315163584, 6651.63416012124, 6693.866122677422, 6763.2222294434805, 7183.8563228736175, 7235.2764723527225, 7262.89105663426, 7273.709051637875, 7299.379552738077, 4234.661227101886, 4411.911761764811, 4430.446194225722, 4434.645228381786, 4450.977745111254, 6881.449484041164, 6923.9307606923285, 7005.754798582026, 7007.614581198083, 7025.297470252934, 6743.224529179207, 6749.055132281386, 6760.7886254028945, 6784.321567843176, 6797.592144471348, 6634.635095069818, 6672.566283191577, 6676.298988606321, 6748.595084294958, 6762.222344430454, 6636.104125942936, 6651.401373876374, 6656.633885136364, 6664.300248473841, 6670.699818508276, 5850.795222167204, 6438.812787670133, 6553.311902250195, 6795.38841504267, 6818.28408017151, 5433.320834895638, 5701.774118564528, 6135.699112832688, 6545.6399898004165, 6578.703958321983], "value": 4920.503111189656, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.1482645115213, 846.792535828722, 847.51268020888, 873.5544872115223, 878.5563290538279, 15515.612109697235, 16205.594860128504, 16802.747958780612, 16820.411285605005, 19314.034298285063, 17917.76499720424, 18213.90893045533, 18272.903625782463, 18490.815091849112, 18627.90686046571, 821.8849361089452, 930.4975313330827, 1041.7031146123356, 1042.067349722002, 1046.8586740789972, 962.8748262725836, 997.306871724152, 1007.6573964851949, 1025.179856115105, 1025.789713108811, 1045.4354648489807, 1059.369675043352, 1059.4649701900569, 1062.7502536903821, 1069.3370110531482, 1035.5391850626474, 1050.123147172112, 1054.1935419404163, 1056.984145237822, 1057.8095942730306, 752.9887051694229, 755.5957562703927, 781.2187812187813, 785.2304741353505, 785.9528428294263, 16941.576460588585, 16960.0, 17142.742858857127, 17372.739408908878, 17627.823721762874, 17262.56843578908, 17266.482005539852, 17588.38440654571, 17655.382061627908, 17792.0, 11963.820542691867, 14014.719705605932, 14183.57449276513, 14258.714825703428, 14303.427862885575, 3487.5989261235027, 3516.788992660423, 3523.066387407339, 3533.7349698772696, 3535.2399234164777, 131833.34083329522, 133564.99999999904, 133926.0, 135634.0, 136015.95976060367, 13268.93365533166, 13294.667633309246, 13343.866561334456, 13348.73302533944, 13572.86427135716, 22265.888670556542, 24743.62884556733, 25005.874970625395, 25448.872755636105, 25775.226743197734, 7565.394768418623, 7826.373890088779, 7838.216178382117, 7852.450328476952, 7902.24928631779, 3838.7312888097563, 3841.4429907663234, 3860.9806950965067, 3923.74495657787, 3926.705497087673, 4426.0483995940385, 4463.107378524308, 4494.438195225597, 4503.189425903303, 4513.029698614813, 939.8778158839307, 958.9952050239843, 969.5733877094056, 977.6969139566758, 980.5391466010964, 570.4409678515078, 640.503609702475, 673.9258681545026, 676.6008055247428, 684.1414025398094, 11051.557937682403, 12045.698857528632, 12175.573854915212, 12188.939055304667, 12295.69260768488, 656.5765081522392, 660.521122186411, 662.9602223866583, 675.1391975231619, 688.8312363470982, 5197.688367670992, 5276.841694749163, 5327.254184414185, 5508.118701007819, 5556.333240011144, 103225.0, 103490.96509034964, 104162.47918760357, 104915.0, 105124.0, 92386.0, 93636.0, 95017.52491237638, 95106.0, 96705.51647241719, 85889.0, 86884.13115868761, 86934.56532717295, 87715.56142219248, 88267.55866220628, 11070.39112848801, 11168.385738784445, 11529.538818447503, 11702.414879255834, 11744.471498782405, 42949.926251842706, 44674.0, 44834.77582612066, 45304.093918120794, 45391.0, 57081.714591426775, 57533.0, 57652.711736442725, 58168.709156453944, 58435.831283374944, 14352.641183970485, 14636.634084147981, 14684.632884177981, 14710.264486775832, 14710.411583536967, 15503.612409689396, 15506.5348039559, 15638.218089095728, 15731.292091856123, 15996.0, 12363.93818030904, 14442.711145777235, 15003.2498375083, 16364.75452868184, 17805.46583602494, 16733.832661673227, 17027.40404085851, 17081.658366832842, 17607.20767565437, 17614.29542818273, 16213.513594592181, 16674.49976500707, 16926.238319275908, 17098.0, 17167.57081072983, 8977.461352318882, 12140.025693705098, 12179.0, 12182.964448021663, 12292.692682683004, 12219.877801221874, 13250.478392600662, 13737.598553309486, 14029.649258768612, 14095.013349065466], "value": 8426.736063054035, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 114.0, 114.0, 116.0, 113.0, 114.0, 116.0, 115.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 142.0, 141.0, 150.0, 110.0, 111.0, 117.0, 139.0, 158.0, 142.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 198.0, 200.0, 241.0, 195.0, 278.0, 272.0, 202.0, 196.0, 202.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 56.0, 58.0, 83.0, 58.0, 58.0, 62.0, 60.0, 82.0, 47.0], "value": 64.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 84.0, 72.0, 69.0, 72.0, 86.0, 70.0, 72.0, 108.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 79.0, 137.0, 136.0, 88.0, 89.0, 129.0, 89.0, 90.0, 89.0], "value": 101.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 123.0, 103.0, 152.0, 108.0, 153.0, 156.0, 154.0, 96.0, 95.0], "value": 123.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 90.0, 88.0, 91.0, 91.0, 93.0, 93.0, 93.0, 92.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 49.0, 52.0, 51.0, 50.0, 51.0, 51.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 62.0, 56.0, 66.0, 59.0, 55.0, 58.0, 59.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 80.0, 93.0, 106.0, 104.0, 82.0, 82.0, 79.0, 84.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 154.0, 149.0, 161.0, 147.0, 134.0, 148.0, 137.0, 196.0, 138.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 234.0, 227.0, 179.0, 197.0, 227.0, 235.0, 179.0, 185.0, 188.0], "value": 204.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 91.0, 100.0, 94.0, 91.0, 101.0, 71.0, 68.0, 67.0], "value": 82.5, "unit": "score"}]}]} 05:02:52 INFO - Return code: 0 05:02:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:02:52 ERROR - # TBPL SUCCESS # 05:02:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:02:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:02:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:02:52 INFO - 2017-04-30 05:02:52.713 screenresolution[2411:39432] starting screenresolution argv=screenresolution get 05:02:52 INFO - 2017-04-30 05:02:52.717 screenresolution[2411:39432] Display 0: 1600x1200x32@60 05:02:52 INFO - 2017-04-30 05:02:52.723 screenresolution[2412:39435] starting screenresolution argv=screenresolution list 05:02:52 INFO - Available Modes on Display 0 05:02:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:02:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:02:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:02:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:02:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:02:52 INFO - Intel Iris: 05:02:52 INFO - Chipset Model: Intel Iris 05:02:52 INFO - Type: GPU 05:02:52 INFO - Bus: Built-In 05:02:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:02:52 INFO - Vendor: Intel (0x8086) 05:02:52 INFO - Device ID: 0x0a2e 05:02:52 INFO - Revision ID: 0x0009 05:02:52 INFO - Displays: 05:02:52 INFO - Display: 05:02:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:02:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:02:52 INFO - Display Serial Number: 1600x1200 60 05:02:52 INFO - Main Display: Yes 05:02:52 INFO - Mirror: Off 05:02:52 INFO - Online: Yes 05:02:52 INFO - Rotation: Supported 05:02:52 INFO - Return code: 0 05:02:52 INFO - Running post-action listener: _resource_record_post_action 05:02:52 INFO - [mozharness: 2017-04-30 12:02:52.957675Z] Finished run-tests step (success) 05:02:52 INFO - Running post-run listener: _resource_record_post_run 05:02:53 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 1884672; Write bytes: 518410752; Read time: 13; Write time: 1786 05:02:53 INFO - TinderboxPrint: CPU usage
54.2% 05:02:53 INFO - TinderboxPrint: I/O read bytes / time
1,884,672 / 13 05:02:53 INFO - TinderboxPrint: I/O write bytes / time
518,410,752 / 1,786 05:02:53 INFO - TinderboxPrint: CPU idle
2,195.7 (45.8%) 05:02:53 INFO - TinderboxPrint: CPU system
960.8 (20.1%) 05:02:53 INFO - TinderboxPrint: CPU user
1,633.1 (34.1%) 05:02:53 INFO - TinderboxPrint: Swap in / out
210,264,064 / 0 05:02:53 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194886144; Write bytes: 192667648; Read time: 15049; Write time: 382 05:02:53 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1884672; Write bytes: 308900352; Read time: 13; Write time: 1354 05:02:53 INFO - Running post-run listener: _upload_blobber_files 05:02:53 INFO - Blob upload gear active. 05:02:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:02:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:02:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:02:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:02:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:02:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:02:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:02:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:54 INFO - (blobuploader) - INFO - Done attempting. 05:02:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:02:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:02:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:55 INFO - (blobuploader) - INFO - Done attempting. 05:02:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:02:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:02:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:56 INFO - (blobuploader) - INFO - Done attempting. 05:02:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:02:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:58 INFO - (blobuploader) - INFO - Done attempting. 05:02:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:02:58 INFO - Return code: 0 05:02:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:02:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:02:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1f36e29ed0dd58d84414c496b6c8d211e564d584eeb8821e7a6a62856268ae07ff40322170393d053220a3aa66d67ee5ea04f40fea609a44644d8e774e41e5a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a55093a0c6af2c6176ddc12c9af066b25bb1b8039a1276c0dad7aecdab4e199976f64a8619173355664fcd39cf570edbfa2271426689423fe6c043c5a799dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85bfd43cf08547e5c6ee8cd2f5bc3eacafc9eca361e977a59d379cafccfe31d351755dbae2ce65d2432c9cedbb3edbb8224fb8fca90aa63224edaad9688f9cf5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18ee16661e38634784e62c80afc1a9999a3f8b2f0fc7cb5ab87074b178269c3073f3410afe4e5108b51a62c3958a054ec9eea9e87b35273e97adb0fc4dd0a56"} 05:02:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:02:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:02:58 INFO - Contents: 05:02:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1f36e29ed0dd58d84414c496b6c8d211e564d584eeb8821e7a6a62856268ae07ff40322170393d053220a3aa66d67ee5ea04f40fea609a44644d8e774e41e5a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a55093a0c6af2c6176ddc12c9af066b25bb1b8039a1276c0dad7aecdab4e199976f64a8619173355664fcd39cf570edbfa2271426689423fe6c043c5a799dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85bfd43cf08547e5c6ee8cd2f5bc3eacafc9eca361e977a59d379cafccfe31d351755dbae2ce65d2432c9cedbb3edbb8224fb8fca90aa63224edaad9688f9cf5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18ee16661e38634784e62c80afc1a9999a3f8b2f0fc7cb5ab87074b178269c3073f3410afe4e5108b51a62c3958a054ec9eea9e87b35273e97adb0fc4dd0a56"} 05:02:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:02:58 INFO - Copying logs to upload dir... 05:02:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:02:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.351745 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-04-30 05:02:58.863160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-30 05:02:58.866636) ========= 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.dTSW27K84h/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.CU4qugapil/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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/d1f36e29ed0dd58d84414c496b6c8d211e564d584eeb8821e7a6a62856268ae07ff40322170393d053220a3aa66d67ee5ea04f40fea609a44644d8e774e41e5a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a55093a0c6af2c6176ddc12c9af066b25bb1b8039a1276c0dad7aecdab4e199976f64a8619173355664fcd39cf570edbfa2271426689423fe6c043c5a799dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85bfd43cf08547e5c6ee8cd2f5bc3eacafc9eca361e977a59d379cafccfe31d351755dbae2ce65d2432c9cedbb3edbb8224fb8fca90aa63224edaad9688f9cf5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18ee16661e38634784e62c80afc1a9999a3f8b2f0fc7cb5ab87074b178269c3073f3410afe4e5108b51a62c3958a054ec9eea9e87b35273e97adb0fc4dd0a56"} build_url:https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/AMGXos4kTsChaunv2LSvgA/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/d1f36e29ed0dd58d84414c496b6c8d211e564d584eeb8821e7a6a62856268ae07ff40322170393d053220a3aa66d67ee5ea04f40fea609a44644d8e774e41e5a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a55093a0c6af2c6176ddc12c9af066b25bb1b8039a1276c0dad7aecdab4e199976f64a8619173355664fcd39cf570edbfa2271426689423fe6c043c5a799dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85bfd43cf08547e5c6ee8cd2f5bc3eacafc9eca361e977a59d379cafccfe31d351755dbae2ce65d2432c9cedbb3edbb8224fb8fca90aa63224edaad9688f9cf5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18ee16661e38634784e62c80afc1a9999a3f8b2f0fc7cb5ab87074b178269c3073f3410afe4e5108b51a62c3958a054ec9eea9e87b35273e97adb0fc4dd0a56"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-30 05:02:58.906995) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-30 05:02:58.907700) ========= 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.dTSW27K84h/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.CU4qugapil/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005020 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-30 05:02:58.960059) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-30 05:02:58.960805) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-30 05:02:58.961549) ========= ========= Total master_lag: 0.36 =========