builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0351 starttime: 1483964936.88 results: success (0) buildid: 20170109030209 builduid: b1fb8321ccc7409d9cee44e6f7191254 revision: 701868bfddcba5bdec516be33a86dcd525dc74cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:56.877043) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:56.877483) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:56.877757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:56.904696) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:56.904973) ========= 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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-01-09 04:28:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2017-01-09 04:28:57 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.644628 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:57.575062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:57.575563) ========= 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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.025479 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:57.634522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 04:28:57.635085) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 701868bfddcba5bdec516be33a86dcd525dc74cf --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 701868bfddcba5bdec516be33a86dcd525dc74cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eczEgq9c0W/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.KtpvlYY9Gg/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-01-09 04:28:57,740 truncating revision to first 12 chars 2017-01-09 04:28:57,740 Setting DEBUG logging. 2017-01-09 04:28:57,741 attempt 1/10 2017-01-09 04:28:57,741 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/701868bfddcb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 04:28:58,877 unpacking tar archive at: mozilla-central-701868bfddcb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.411608 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 04:28:59.075656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:59.075978) ========= script_repo_revision: 701868bfddcba5bdec516be33a86dcd525dc74cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:59.076363) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:59.076627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 04:28:59.096908) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-09 04:28:59.097317) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eczEgq9c0W/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.KtpvlYY9Gg/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:28:59 INFO - MultiFileLogger online at 20170109 04:28:59 in /builds/slave/test 04:28:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:28:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:59 INFO - {'append_to_log': False, 04:28:59 INFO - 'base_work_dir': '/builds/slave/test', 04:28:59 INFO - 'blob_upload_branch': 'Firefox', 04:28:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:59 INFO - 'branch': 'Firefox', 04:28:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:59 INFO - 'config_files': ('talos/mac_config.py',), 04:28:59 INFO - 'default_actions': ('clobber', 04:28:59 INFO - 'read-buildbot-config', 04:28:59 INFO - 'download-and-extract', 04:28:59 INFO - 'populate-webroot', 04:28:59 INFO - 'create-virtualenv', 04:28:59 INFO - 'install', 04:28:59 INFO - 'run-tests'), 04:28:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:59 INFO - 'download_minidump_stackwalk': True, 04:28:59 INFO - 'download_symbols': 'ondemand', 04:28:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:59 INFO - 'installer_path': 'installer.exe', 04:28:59 INFO - 'log_level': 'info', 04:28:59 INFO - 'log_name': 'talos', 04:28:59 INFO - 'log_to_console': True, 04:28:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:28:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:28:59 INFO - 'opt_config_files': (), 04:28:59 INFO - 'pip_index': False, 04:28:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:59 INFO - 'cmd': ('bash', 04:28:59 INFO - '-c', 04:28:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:59 INFO - 'enabled': True, 04:28:59 INFO - 'halt_on_failure': False, 04:28:59 INFO - 'name': 'check_screen_resolution'},), 04:28:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:59 INFO - 'cmd': ('bash', 04:28:59 INFO - '-c', 04:28:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:59 INFO - 'enabled': True, 04:28:59 INFO - 'halt_on_failure': False, 04:28:59 INFO - 'name': 'check_screen_resolution'},), 04:28:59 INFO - 'run_cmd_checks_enabled': True, 04:28:59 INFO - 'sps_profile': False, 04:28:59 INFO - 'sps_profile_interval': 0, 04:28:59 INFO - 'suite': 'dromaeojs', 04:28:59 INFO - 'system_bits': '32', 04:28:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:59 INFO - 'title': 't-yosemite-r7-0351', 04:28:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:59 INFO - 'use_talos_json': True, 04:28:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:59 INFO - 'work_dir': 'build'} 04:28:59 INFO - [mozharness: 2017-01-09 12:28:59.300679Z] Running clobber step. 04:28:59 INFO - Running pre-action listener: _resource_record_pre_action 04:28:59 INFO - Running main action method: clobber 04:28:59 INFO - rmtree: /builds/slave/test/build 04:28:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:02 INFO - Running post-action listener: _resource_record_post_action 04:29:02 INFO - [mozharness: 2017-01-09 12:29:02.150188Z] Finished clobber step (success) 04:29:02 INFO - [mozharness: 2017-01-09 12:29:02.150319Z] Running read-buildbot-config step. 04:29:02 INFO - Running pre-action listener: _resource_record_pre_action 04:29:02 INFO - Running main action method: read_buildbot_config 04:29:02 INFO - Using buildbot properties: 04:29:02 INFO - { 04:29:02 INFO - "project": "", 04:29:02 INFO - "product": "firefox", 04:29:02 INFO - "script_repo_revision": "production", 04:29:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:29:02 INFO - "repository": "", 04:29:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:29:02 INFO - "buildid": "20170109030209", 04:29:02 INFO - "stage_platform": "macosx64", 04:29:02 INFO - "basedir": "/builds/slave/test", 04:29:02 INFO - "nightly_build": "True", 04:29:02 INFO - "buildnumber": 71, 04:29:02 INFO - "slavename": "t-yosemite-r7-0351", 04:29:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:29:02 INFO - "platform": "yosemite_r7", 04:29:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:29:02 INFO - "branch": "mozilla-central", 04:29:02 INFO - "repo_path": "mozilla-central", 04:29:02 INFO - "revision": "701868bfddcba5bdec516be33a86dcd525dc74cf", 04:29:02 INFO - "pgo_build": "False", 04:29:02 INFO - "builduid": "b1fb8321ccc7409d9cee44e6f7191254", 04:29:02 INFO - "slavebuilddir": "test" 04:29:02 INFO - } 04:29:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:29:02 INFO - Running post-action listener: _resource_record_post_action 04:29:02 INFO - [mozharness: 2017-01-09 12:29:02.153721Z] Finished read-buildbot-config step (success) 04:29:02 INFO - [mozharness: 2017-01-09 12:29:02.153859Z] Running download-and-extract step. 04:29:02 INFO - Running pre-action listener: _resource_record_pre_action 04:29:02 INFO - Running main action method: download_and_extract 04:29:02 INFO - mkdir: /builds/slave/test/build/tests 04:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:02 INFO - trying https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:29:02 INFO - Downloading https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:29:03 INFO - Downloaded 1199 bytes. 04:29:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:29:03 INFO - Using the following test package requirements: 04:29:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:29:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:29:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:29:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'jsshell-mac.zip'], 04:29:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:29:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:29:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:29:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:29:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:29:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:29:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:29:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:29:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:29:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:03 INFO - Fetch https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:29:04 INFO - Expected file size: 16304963 04:29:04 INFO - Obtained file size: 16304963 04:29:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:29:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:29:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:11 INFO - Fetch https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:29:11 INFO - Expected file size: 16304963 04:29:11 INFO - Obtained file size: 16304963 04:29:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:29:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:29:18 INFO - Fetch https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:29:19 INFO - Expected file size: 14403537 04:29:19 INFO - Obtained file size: 14403537 04:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:19 INFO - trying https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:19 INFO - Downloading https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:29:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:29:21 INFO - Downloaded 69844095 bytes. 04:29:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:21 INFO - mkdir: /builds/slave/test/properties 04:29:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:21 INFO - Contents: 04:29:21 INFO - build_url:https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:22 INFO - Running post-action listener: _resource_record_post_action 04:29:22 INFO - Running post-action listener: set_extra_try_arguments 04:29:22 INFO - [mozharness: 2017-01-09 12:29:22.491035Z] Finished download-and-extract step (success) 04:29:22 INFO - [mozharness: 2017-01-09 12:29:22.491161Z] Running populate-webroot step. 04:29:22 INFO - Running pre-action listener: _resource_record_pre_action 04:29:22 INFO - Running main action method: populate_webroot 04:29:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:22 INFO - '--tppagecycles', 04:29:22 INFO - '7'], 04:29:22 INFO - 'tests': ['tsvgm']}}, 04:29:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['damp', 'tps']}, 04:29:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'tests': ['damp', 'tps']}, 04:29:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['dromaeo_dom']}, 04:29:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['basic_compositor_video']}, 04:29:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:29:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['a11yr', 04:29:22 INFO - 'ts_paint', 04:29:22 INFO - 'tpaint', 04:29:22 INFO - 'sessionrestore', 04:29:22 INFO - 'sessionrestore_no_auto_restore', 04:29:22 INFO - 'tabpaint']}, 04:29:22 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:22 INFO - 'ts_paint', 04:29:22 INFO - 'tpaint', 04:29:22 INFO - 'sessionrestore', 04:29:22 INFO - 'sessionrestore_no_auto_restore', 04:29:22 INFO - 'tabpaint']}, 04:29:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:22 INFO - 'ts_paint', 04:29:22 INFO - 'tpaint', 04:29:22 INFO - 'sessionrestore', 04:29:22 INFO - 'sessionrestore_no_auto_restore', 04:29:22 INFO - 'tabpaint']}, 04:29:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:22 INFO - 'ts_paint', 04:29:22 INFO - 'tpaint', 04:29:22 INFO - 'sessionrestore', 04:29:22 INFO - 'sessionrestore_no_auto_restore', 04:29:22 INFO - 'tabpaint']}, 04:29:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['a11yr', 04:29:22 INFO - 'ts_paint', 04:29:22 INFO - 'tpaint', 04:29:22 INFO - 'sessionrestore', 04:29:22 INFO - 'sessionrestore_no_auto_restore', 04:29:22 INFO - 'tabpaint']}, 04:29:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['a11yr', 04:29:22 INFO - 'ts_paint', 04:29:22 INFO - 'tpaint', 04:29:22 INFO - 'sessionrestore', 04:29:22 INFO - 'sessionrestore_no_auto_restore', 04:29:22 INFO - 'tabpaint']}, 04:29:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['tsvgx', 04:29:22 INFO - 'tsvgr_opacity', 04:29:22 INFO - 'tart', 04:29:22 INFO - 'tscrollx', 04:29:22 INFO - 'cart', 04:29:22 INFO - 'tsvg_static']}, 04:29:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:22 INFO - 'tsvgr_opacity', 04:29:22 INFO - 'tart', 04:29:22 INFO - 'tscrollx', 04:29:22 INFO - 'cart', 04:29:22 INFO - 'tsvg_static']}, 04:29:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'talos_options': ['--disable-e10s'], 04:29:22 INFO - 'tests': ['tp5o']}, 04:29:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:29:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'talos_options': ['--disable-e10s', 04:29:22 INFO - '--xperf_path', 04:29:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:22 INFO - 'tests': ['tp5n']}, 04:29:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:29:22 INFO - 'talos_options': ['--xperf_path', 04:29:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:22 INFO - 'tests': ['tp5n']}}, 04:29:22 INFO - 'talos.zip': {'path': '', 04:29:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:22 INFO - Running post-action listener: _resource_record_post_action 04:29:22 INFO - [mozharness: 2017-01-09 12:29:22.509590Z] Finished populate-webroot step (success) 04:29:22 INFO - [mozharness: 2017-01-09 12:29:22.509716Z] Running create-virtualenv step. 04:29:22 INFO - Running pre-action listener: _resource_record_pre_action 04:29:22 INFO - Running main action method: create_virtualenv 04:29:22 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:22 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:29:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:22 INFO - Using real prefix '/tools/python27' 04:29:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:23 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:26 INFO - Installing pip.................done. 04:29:26 INFO - Return code: 0 04:29:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:29:26 INFO - Reading from file tmpfile_stdout 04:29:26 INFO - Output received: 04:29:26 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:29:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:26 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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29:26 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:29:26 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:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:26 INFO - 'HOME': '/Users/cltbld', 04:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:26 INFO - 'LOGNAME': 'cltbld', 04:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:26 INFO - 'MOZ_NO_REMOTE': '1', 04:29:26 INFO - 'NO_EM_RESTART': '1', 04:29:26 INFO - 'PAGER': '/bin/cat', 04:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:26 INFO - 'PWD': '/builds/slave/test', 04:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:26 INFO - 'SHELL': '/bin/bash', 04:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:26 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:26 INFO - 'USER': 'cltbld', 04:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:26 INFO - 'XPC_FLAGS': '0x0', 04:29:26 INFO - 'XPC_SERVICE_NAME': '0', 04:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:26 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:29:26 INFO - Cleaning up... 04:29:26 INFO - Return code: 0 04:29:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:26 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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29:26 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:29:26 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:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:26 INFO - 'HOME': '/Users/cltbld', 04:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:26 INFO - 'LOGNAME': 'cltbld', 04:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:26 INFO - 'MOZ_NO_REMOTE': '1', 04:29:26 INFO - 'NO_EM_RESTART': '1', 04:29:26 INFO - 'PAGER': '/bin/cat', 04:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:26 INFO - 'PWD': '/builds/slave/test', 04:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:26 INFO - 'SHELL': '/bin/bash', 04:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:26 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:26 INFO - 'USER': 'cltbld', 04:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:26 INFO - 'XPC_FLAGS': '0x0', 04:29:26 INFO - 'XPC_SERVICE_NAME': '0', 04:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:27 INFO - Downloading/unpacking psutil>=3.1.1 04:29: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:29: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:29: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:29: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:29: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:29: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:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:30 INFO - Installing collected packages: psutil 04:29:30 INFO - Running setup.py install for psutil 04:29:30 INFO - building 'psutil._psutil_osx' extension 04:29:30 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:29: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:29: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:29:31 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:29:31 INFO - building 'psutil._psutil_posix' extension 04:29:31 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:29:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:31 INFO - ^ 04:29:31 INFO - 1 warning generated. 04:29:31 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:29:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:31 INFO - Successfully installed psutil 04:29:31 INFO - Cleaning up... 04:29:32 INFO - Return code: 0 04:29:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:29: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:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29: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:29: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:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:32 INFO - 'HOME': '/Users/cltbld', 04:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:32 INFO - 'LOGNAME': 'cltbld', 04:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:32 INFO - 'MOZ_NO_REMOTE': '1', 04:29:32 INFO - 'NO_EM_RESTART': '1', 04:29:32 INFO - 'PAGER': '/bin/cat', 04:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:32 INFO - 'PWD': '/builds/slave/test', 04:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:32 INFO - 'SHELL': '/bin/bash', 04:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:32 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:32 INFO - 'USER': 'cltbld', 04:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:32 INFO - 'XPC_FLAGS': '0x0', 04:29:32 INFO - 'XPC_SERVICE_NAME': '0', 04:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:29: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:29: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:29: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:29: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:29: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:29: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:29:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:29:34 INFO - Installing collected packages: mozsystemmonitor 04:29:34 INFO - Running setup.py install for mozsystemmonitor 04:29:35 INFO - Successfully installed mozsystemmonitor 04:29:35 INFO - Cleaning up... 04:29:35 INFO - Return code: 0 04:29:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:29: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:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29: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:29: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:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:35 INFO - 'HOME': '/Users/cltbld', 04:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:35 INFO - 'LOGNAME': 'cltbld', 04:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:35 INFO - 'MOZ_NO_REMOTE': '1', 04:29:35 INFO - 'NO_EM_RESTART': '1', 04:29:35 INFO - 'PAGER': '/bin/cat', 04:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:35 INFO - 'PWD': '/builds/slave/test', 04:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:35 INFO - 'SHELL': '/bin/bash', 04:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:35 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:35 INFO - 'USER': 'cltbld', 04:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:35 INFO - 'XPC_FLAGS': '0x0', 04:29:35 INFO - 'XPC_SERVICE_NAME': '0', 04:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:35 INFO - Downloading/unpacking jsonschema==2.5.1 04:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:29:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:29: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:29: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:29: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:29: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:29: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:29: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:29:38 INFO - Downloading functools32-3.2.3-2.tar.gz 04:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:29:38 INFO - warning: no files found matching '*.txt' 04:29:38 INFO - no previously-included directories found matching 'build' 04:29:38 INFO - no previously-included directories found matching 'dist' 04:29:38 INFO - no previously-included directories found matching '.git*' 04:29:38 INFO - Installing collected packages: jsonschema, functools32 04:29:38 INFO - Running setup.py install for functools32 04:29:38 INFO - warning: no files found matching '*.txt' 04:29:38 INFO - no previously-included directories found matching 'build' 04:29:38 INFO - no previously-included directories found matching 'dist' 04:29:38 INFO - no previously-included directories found matching '.git*' 04:29:38 INFO - Successfully installed jsonschema functools32 04:29:38 INFO - Cleaning up... 04:29:38 INFO - Return code: 0 04:29:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:29:38 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:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:38 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:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:38 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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29:38 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:29:38 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:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:38 INFO - 'HOME': '/Users/cltbld', 04:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:38 INFO - 'LOGNAME': 'cltbld', 04:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:38 INFO - 'MOZ_NO_REMOTE': '1', 04:29:38 INFO - 'NO_EM_RESTART': '1', 04:29:38 INFO - 'PAGER': '/bin/cat', 04:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:38 INFO - 'PWD': '/builds/slave/test', 04:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:38 INFO - 'SHELL': '/bin/bash', 04:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:38 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:38 INFO - 'USER': 'cltbld', 04:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:38 INFO - 'XPC_FLAGS': '0x0', 04:29:38 INFO - 'XPC_SERVICE_NAME': '0', 04:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:29:39 INFO - Cleaning up... 04:29:39 INFO - Return code: 0 04:29:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29: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:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29: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:29: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:29:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:39 INFO - 'HOME': '/Users/cltbld', 04:29:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:39 INFO - 'LOGNAME': 'cltbld', 04:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:39 INFO - 'MOZ_NO_REMOTE': '1', 04:29:39 INFO - 'NO_EM_RESTART': '1', 04:29:39 INFO - 'PAGER': '/bin/cat', 04:29:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:39 INFO - 'PWD': '/builds/slave/test', 04:29:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:39 INFO - 'SHELL': '/bin/bash', 04:29:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:39 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:39 INFO - 'USER': 'cltbld', 04:29:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:39 INFO - 'XPC_FLAGS': '0x0', 04:29:39 INFO - 'XPC_SERVICE_NAME': '0', 04:29:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:39 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29: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:29: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:29: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:29: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:29: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:29: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:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:29:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:29: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:29: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:29: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:29: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:29: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:29: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:29:43 INFO - Downloading docopt-0.6.1.tar.gz 04:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:29:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:29:43 INFO - Running setup.py install for blobuploader 04:29:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:29:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:29:43 INFO - Running setup.py install for requests 04:29:44 INFO - Running setup.py install for docopt 04:29:44 INFO - Successfully installed blobuploader requests docopt 04:29:44 INFO - Cleaning up... 04:29:44 INFO - Return code: 0 04:29:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29: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:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29: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:29: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:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:44 INFO - 'HOME': '/Users/cltbld', 04:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:44 INFO - 'LOGNAME': 'cltbld', 04:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:44 INFO - 'MOZ_NO_REMOTE': '1', 04:29:44 INFO - 'NO_EM_RESTART': '1', 04:29:44 INFO - 'PAGER': '/bin/cat', 04:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:44 INFO - 'PWD': '/builds/slave/test', 04:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:44 INFO - 'SHELL': '/bin/bash', 04:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:44 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:44 INFO - 'USER': 'cltbld', 04:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:44 INFO - 'XPC_FLAGS': '0x0', 04:29:44 INFO - 'XPC_SERVICE_NAME': '0', 04:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:44 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-PQZyIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:44 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-7NDwgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:44 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Ve8T3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:44 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-iYnaYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:44 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Ixmpeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-rkhzZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-hoQH4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-8onLuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-GQNW66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-UkvcHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-35ENNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Gwc7Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-9SRwCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-0emc5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-CkMpWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-dfkYRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-CS2tw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:29:46 INFO - Running setup.py install for manifestparser 04:29:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:29:46 INFO - Running setup.py install for mozcrash 04:29:46 INFO - Running setup.py install for mozdebug 04:29:46 INFO - Running setup.py install for mozdevice 04:29:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:29:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:29:46 INFO - Running setup.py install for mozfile 04:29:47 INFO - Running setup.py install for mozhttpd 04:29:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Running setup.py install for mozinfo 04:29:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Running setup.py install for mozInstall 04:29:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Running setup.py install for mozleak 04:29:47 INFO - Running setup.py install for mozlog 04:29:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Running setup.py install for moznetwork 04:29:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:29:47 INFO - Running setup.py install for mozprocess 04:29:48 INFO - Running setup.py install for mozprofile 04:29:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:29:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:29:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:29:48 INFO - Running setup.py install for mozrunner 04:29:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:48 INFO - Running setup.py install for mozscreenshot 04:29:48 INFO - Running setup.py install for moztest 04:29:48 INFO - Running setup.py install for mozversion 04:29:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:48 INFO - Cleaning up... 04:29:48 INFO - Return code: 0 04:29:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:48 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:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:48 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:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:48 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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29:48 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:29:48 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:29:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:48 INFO - 'HOME': '/Users/cltbld', 04:29:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:48 INFO - 'LOGNAME': 'cltbld', 04:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:48 INFO - 'MOZ_NO_REMOTE': '1', 04:29:48 INFO - 'NO_EM_RESTART': '1', 04:29:48 INFO - 'PAGER': '/bin/cat', 04:29:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:48 INFO - 'PWD': '/builds/slave/test', 04:29:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:48 INFO - 'SHELL': '/bin/bash', 04:29:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:48 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:48 INFO - 'USER': 'cltbld', 04:29:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:48 INFO - 'XPC_FLAGS': '0x0', 04:29:48 INFO - 'XPC_SERVICE_NAME': '0', 04:29:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-rohU2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29: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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Gwy1o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29: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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-YTEfLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:49 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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-0xWxxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-FTeZ8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:49 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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-lJg24S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:49 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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-deMgof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:49 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:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-eORv7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:49 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-YKhneR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29: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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-9EyDzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29: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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-E2wxjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29: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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-W9wCZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-kABz9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-mAHqCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-EvPcj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-1jhvC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:50 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-4g4bNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:50 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:29:50 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:29:50 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:29:50 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:29:50 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:29:50 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:29:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29:50 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:29:50 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:29:50 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:29:50 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:29:50 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:29:50 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:29:53 INFO - Downloading blessings-1.6.tar.gz 04:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:29:53 INFO - Installing collected packages: blessings 04:29:53 INFO - Running setup.py install for blessings 04:29:53 INFO - Successfully installed blessings 04:29:53 INFO - Cleaning up... 04:29:53 INFO - Return code: 0 04:29:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:29:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:54 INFO - Reading from file tmpfile_stdout 04:29:54 INFO - Current package versions: 04:29:54 INFO - blessings == 1.6 04:29:54 INFO - blobuploader == 1.2.4 04:29:54 INFO - docopt == 0.6.1 04:29:54 INFO - functools32 == 3.2.3-2 04:29:54 INFO - jsonschema == 2.5.1 04:29:54 INFO - manifestparser == 1.1 04:29:54 INFO - mozInstall == 1.12 04:29:54 INFO - mozcrash == 1.0 04:29:54 INFO - mozdebug == 0.1 04:29:54 INFO - mozdevice == 0.49 04:29:54 INFO - mozfile == 1.2 04:29:54 INFO - mozhttpd == 0.7 04:29:54 INFO - mozinfo == 0.9 04:29:54 INFO - mozleak == 0.1 04:29:54 INFO - mozlog == 3.4 04:29:54 INFO - moznetwork == 0.27 04:29:54 INFO - mozprocess == 0.24 04:29:54 INFO - mozprofile == 0.28 04:29:54 INFO - mozrunner == 6.13 04:29:54 INFO - mozscreenshot == 0.1 04:29:54 INFO - mozsystemmonitor == 0.3 04:29:54 INFO - moztest == 0.8 04:29:54 INFO - mozversion == 1.4 04:29:54 INFO - psutil == 3.1.1 04:29:54 INFO - requests == 1.2.3 04:29:54 INFO - wsgiref == 0.1.2 04:29:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29: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:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29: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:29: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:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:54 INFO - 'HOME': '/Users/cltbld', 04:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:54 INFO - 'LOGNAME': 'cltbld', 04:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:54 INFO - 'MOZ_NO_REMOTE': '1', 04:29:54 INFO - 'NO_EM_RESTART': '1', 04:29:54 INFO - 'PAGER': '/bin/cat', 04:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:54 INFO - 'PWD': '/builds/slave/test', 04:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:54 INFO - 'SHELL': '/bin/bash', 04:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:54 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:54 INFO - 'USER': 'cltbld', 04:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:54 INFO - 'XPC_FLAGS': '0x0', 04:29:54 INFO - 'XPC_SERVICE_NAME': '0', 04:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 INFO - Cleaning up... 04:29:54 INFO - Return code: 0 04:29:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:29: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:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:54 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 0x10f5e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee0a499580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, '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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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:29:54 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:29:54 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:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:54 INFO - 'HOME': '/Users/cltbld', 04:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:54 INFO - 'LOGNAME': 'cltbld', 04:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:54 INFO - 'MOZ_NO_REMOTE': '1', 04:29:54 INFO - 'NO_EM_RESTART': '1', 04:29:54 INFO - 'PAGER': '/bin/cat', 04:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:54 INFO - 'PWD': '/builds/slave/test', 04:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:54 INFO - 'SHELL': '/bin/bash', 04:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:29:54 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:54 INFO - 'USER': 'cltbld', 04:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:54 INFO - 'XPC_FLAGS': '0x0', 04:29:54 INFO - 'XPC_SERVICE_NAME': '0', 04:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:29:54 INFO - Cleaning up... 04:29:54 INFO - Return code: 0 04:29:54 INFO - Running post-action listener: _resource_record_post_action 04:29:54 INFO - Running post-action listener: _start_resource_monitoring 04:29:54 INFO - Starting resource monitoring. 04:29:54 INFO - [mozharness: 2017-01-09 12:29:54.794711Z] Finished create-virtualenv step (success) 04:29:54 INFO - [mozharness: 2017-01-09 12:29:54.795697Z] Running install step. 04:29:54 INFO - Running pre-action listener: _resource_record_pre_action 04:29:54 INFO - Running main action method: install 04:29:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:55 INFO - Reading from file tmpfile_stdout 04:29:55 INFO - Detecting whether we're running mozinstall >=1.0... 04:29:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:29:55 INFO - Reading from file tmpfile_stdout 04:29:55 INFO - Output received: 04:29:55 INFO - Usage: mozinstall [options] installer 04:29:55 INFO - Options: 04:29:55 INFO - -h, --help show this help message and exit 04:29:55 INFO - -d DEST, --destination=DEST 04:29:55 INFO - Directory to install application into. [default: 04:29:55 INFO - "/builds/slave/test"] 04:29:55 INFO - --app=APP Application being installed. [default: firefox] 04:29:55 INFO - mkdir: /builds/slave/test/build/application 04:29:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:30:16 INFO - Reading from file tmpfile_stdout 04:30:16 INFO - Output received: 04:30:16 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:30:16 INFO - Running post-action listener: _resource_record_post_action 04:30:16 INFO - [mozharness: 2017-01-09 12:30:16.860158Z] Finished install step (success) 04:30:16 INFO - [mozharness: 2017-01-09 12:30:16.860270Z] Running run-tests step. 04:30:16 INFO - Running pre-action listener: _resource_record_pre_action 04:30:16 INFO - Running main action method: run_tests 04:30:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:16 INFO - 2017-01-09 04:30:16.875 screenresolution[1632:7970] starting screenresolution argv=screenresolution get 04:30:16 INFO - 2017-01-09 04:30:16.878 screenresolution[1632:7970] Display 0: 1600x1200x32@60 04:30:16 INFO - 2017-01-09 04:30:16.884 screenresolution[1633:7972] starting screenresolution argv=screenresolution list 04:30:16 INFO - Available Modes on Display 0 04:30:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:17 INFO - Intel Iris: 04:30:17 INFO - Chipset Model: Intel Iris 04:30:17 INFO - Type: GPU 04:30:17 INFO - Bus: Built-In 04:30:17 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:17 INFO - Vendor: Intel (0x8086) 04:30:17 INFO - Device ID: 0x0a2e 04:30:17 INFO - Revision ID: 0x0009 04:30:17 INFO - Displays: 04:30:17 INFO - Display: 04:30:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:17 INFO - Display Serial Number: 1600x1200 60 04:30:17 INFO - Main Display: Yes 04:30:17 INFO - Mirror: Off 04:30:17 INFO - Online: Yes 04:30:17 INFO - Rotation: Supported 04:30:17 INFO - Return code: 0 04:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:17 INFO - Python 2.7.3 04:30:17 INFO - Return code: 0 04:30:17 INFO - grabbing minidump binary from tooltool 04:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:17 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 0x10f3c2d30>, '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 0x10f2e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:17 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:30:17 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:30:17 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:30:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:17 INFO - Return code: 0 04:30:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0351 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eczEgq9c0W/Render', 04:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:17 INFO - 'HOME': '/Users/cltbld', 04:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:17 INFO - 'LOGNAME': 'cltbld', 04:30:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:17 INFO - 'MOZ_NO_REMOTE': '1', 04:30:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:17 INFO - 'NO_EM_RESTART': '1', 04:30:17 INFO - 'PAGER': '/bin/cat', 04:30:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:17 INFO - 'PWD': '/builds/slave/test', 04:30:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:17 INFO - 'SHELL': '/bin/bash', 04:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KtpvlYY9Gg/Listeners', 04:30:17 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:17 INFO - 'USER': 'cltbld', 04:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:17 INFO - 'XPC_FLAGS': '0x0', 04:30:17 INFO - 'XPC_SERVICE_NAME': '0', 04:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:30:17 INFO - mozversion application_buildid: 20170109030209 04:30:17 INFO - mozversion application_changeset: 701868bfddcba5bdec516be33a86dcd525dc74cf 04:30:17 INFO - mozversion application_display_name: Nightly 04:30:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:17 INFO - mozversion application_name: Firefox 04:30:17 INFO - mozversion application_remotingname: firefox 04:30:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:30:17 INFO - mozversion application_vendor: Mozilla 04:30:17 INFO - mozversion application_version: 53.0a1 04:30:17 INFO - mozversion platform_buildid: 20170109030209 04:30:17 INFO - mozversion platform_changeset: 701868bfddcba5bdec516be33a86dcd525dc74cf 04:30:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:30:17 INFO - mozversion platform_version: 53.0a1 04:30:17 INFO - using testdate: 1483965017 04:30:17 INFO - actual date: 1483965017 04:30:17 INFO - starting webserver on 'localhost:49241' 04:30:17 INFO - SUITE-START | Running 2 tests 04:30:17 INFO - TEST-START | dromaeo_css 04:30:17 INFO - operating with platform_type : mac_ 04:30:17 INFO - Initialising browser for dromaeo_css test... 04:30:17 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpTQ3HNQ/profile http://localhost:49241/getInfo.html) 04:30:18 INFO - PROCESS | 1643 | 1483965018221 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 04:30:20 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 04:30:20 INFO - PROCESS | 1643 | colorDepth:24 04:30:20 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 04:30:20 INFO - PROCESS | 1643 | __metrics 04:30:20 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:30:20 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:30:20 INFO - TEST-INFO | 1643: exit 0 04:30:20 INFO - Browser initialized. 04:30:20 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:20 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpTQ3HNQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:32 INFO - PROCESS | 1647 | RSS: Main: 234364928 04:30:32 INFO - PROCESS | 1647 | 04:33:37 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:33:39 INFO - PROCESS | 1647 | RSS: Main: 332574720 04:33:39 INFO - PROCESS | 1647 | 04:35:21 INFO - PROCESS | 1647 | ************************* 04:35:21 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 04:35:21 INFO - PROCESS | 1647 | 04:35:21 INFO - PROCESS | 1647 | Full message: ReferenceError: fetch is not defined 04:35:21 INFO - PROCESS | 1647 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:35:21 INFO - PROCESS | 1647 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:35:21 INFO - PROCESS | 1647 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:35:21 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:35:21 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:35:21 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:35:21 INFO - PROCESS | 1647 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:35:21 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:35:21 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:35:21 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:35:21 INFO - PROCESS | 1647 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:35:21 INFO - PROCESS | 1647 | 04:35:21 INFO - PROCESS | 1647 | ************************* 04:36:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:36:43 INFO - PROCESS | 1647 | RSS: Main: 367575040 04:36:43 INFO - PROCESS | 1647 | 04:39:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:39:46 INFO - PROCESS | 1647 | RSS: Main: 338145280 04:39:46 INFO - PROCESS | 1647 | 04:42:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 04:42:49 INFO - PROCESS | 1647 | RSS: Main: 326234112 04:42:49 INFO - PROCESS | 1647 | 04:45:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 04:45:53 INFO - PROCESS | 1647 | RSS: Main: 331354112 04:45:53 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 04:48:57 INFO - PROCESS | 1647 | RSS: Main: 387342336 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | __start_tp_report 04:48:57 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 04:48:57 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 04:48:57 INFO - PROCESS | 1647 | |i|pagename|runs| 04:48:57 INFO - PROCESS | 1647 | |0;dojo.html;3331.833408329583;3342.782719123258;3354.8993530194093;3374.274530975843;3409.267007593367;4887.926681099786;5551.028570000254;5798.101294299385;5849.766009359626;5874.970625146873;5749.195112684228;5768.134779783035;5834.154047663086;5846.21076154719;5896.941030589682;5120.283160357553;5135.204043373283;5164.406093299281;5225.111731005738;5269.446708095652;5308.203769434596;5317.9468205318035;5321.946780532203;5343.625946183769;5414.485623865727;5154.30416893722;5295.0468915595175;5303.071962406563;5344.866378340534;5355.866103347428;5172.043172013189;5182.663126896739;5223.138182199932;5250.9737451312885;5251.422343542207;5229.163333866563;5235.240890070919;5251.054810134175;5257.737113144328;5299.33758280215;4760.33355330254;5797.536197104221;5813.389594092644;5815.098659707752;5821.068629019336;6120.508083830883;6155.261368635747;6171.352008039136;6225.8132256032395;6367.87264254717;5626.409227031185;5773.191753154561;6343.238811342622;6382.074599183139;6399.520035997319;5864.296284445893;5910.231669882931;5918.644881307136;5929.911051334277;5938.109283607407;6137.754489820357;6287.434130928238;6357.4596159326975;6377.74489020434;6383.2978372379;6141.416565426277;6172.321044685081;6178.66017369049;6204.503639708812;6253.937460625427;6187.381261873777;6266;6342.23893132827;6363.109164716943;6369.172007638977;6128.636540436954;6248.187735594343;6255.093011513396;6257.530685198628;6270.3416141305415;6278.654673992883;6292.716827742763;6296;6301.590396624205;6302.621842689453;4988.351514303117;5011.198208286657;5019.322391477177;5020.221865610836;5026.4973502649445;4499.965008048167;4518.367428560005;4542.341360502709;4567.497575266718;4583.449986001701;5752.539796816244;5779.797707080232;5803.332616749043;5878.412158784087;5965.522758179422;6278.968605157036;6322.114903913456;6332.208473940757;6365.809025729235;6387.169667943138;5910.527157827363;5937.10943358491;5941.31674857397;5946.910796338058;5994.610350327302;5358.4373640767935;5410.648307859977;5537.058700021006;5545.833624991256;5549.417311182348;6295.905561416674;6318.462930650946;6358;6390.073439351175;6451.129097571956;6311.337309582428;6330.8733825322315;6346.048092785934;6346.619202847844;6355.5551111421755;6396.296407395276;6416.037594360883;6440.355964403522;6452.032195170575;6479.028145778171;5108.59131269505;6110.40687992437;6162.907556386568;6189.071639253968;6204.255489341127;6324.747010119544;6327.96836015817;6331.90502142459;6355.14205582259;6361.61830290184;6154.415330543591;6182.072689096672;6189.50483961273;6195.473384762346;6198.690065496617;5938.881222375615;5975.880482390415;6101.450869421773;6129.9387006131155;6203.038529028008;6072.332043475302;6095.933211687849;6096.817095487142;6099.201365966966;6113.354685976905;6065.059915713072;6123.4488895999575;6125.724342404692;6180.196574445294;6234.064890266496;5236.214567814858;5985.581009329305;6043.12374705674;6056.9697151513965;6060.939390606038 04:48:57 INFO - PROCESS | 1647 | |1;ext.html;444.83763426349384;471.8678769944416;519.8232600915685;533.8798770276691;580.7996241296754;31170.84414577927;32863.67136328636;32868.342633147324;33007.50488742668;39770.60229397705;39928.20143597126;42004.15991680172;42098;42179.94550136248;42304;9154.992950775393;9364.578593963253;9374.578143983503;9376.812463750755;9396.389234699756;8480.999999999969;8699.173578510032;9298.16316531512;9407.294452916025;9416.670416535422;11101.500432480518;11344.376059316734;11387.145964052688;11548.30710157389;11571.826422603668;11102.6114086007;11149;11255.155863310285;11270.94364528172;11279.774404511945;8359.874601880976;8585.969683638003;8589.570521473965;11157;11212.383318917457;31969.84015079933;32282.515762263585;39040.609593904264;39725.60274397248;39828.800855995825;3902.4731661225655;4033.4756481657196;4044.2518134145275;4062.7765472898977;4172.958270417287;29538.85230573855;30240.803075268406;30696.53955190674;30700.2324941876;30803.921862734922;3200.647928727838;3259.364423937333;3353.9832300838584;3359.6136444308727;3368.8147151906637;488271;488526;489618;489652;489655.99999999645;61156.694216529526;61368.38631613716;61437.078443823375;61598.69200653968;61857;64566.677166614805;64717;64737.35262647407;64884.351156488774;64887;15727.68544629101;16000.959937604019;16003.839961600235;16006.359745610278;16015.839841601668;12376.947959423369;12483.188592741624;12505.437255323532;12506.937465312616;12514.249145051326;14793.964422490537;15123.697526049418;16381;16804.831951680328;16927.915360423118;5933.406659334119;6670.232923213795;6683.264840867598;6701;6720.428763555153;826.9586520673943;849.227203245044;876.333986170515;898.2140626951417;911.284641556375;29798;29966.400671986437;30004.84997574998;30520.236994075327;30613.081607551365;1061.4162210784039;1079.6760971708457;1100.834874768791;1116.966491005255;1172.46066809267;15388.30752616135;15455.53633391;15470.303836327392;15622;15837.445689400816;596145;601335;602327;602368;606475;60716.785664286464;61020;61135;61194.69402653047;61203.69398152981;1232.7657745028416;1464.2385959301105;1467.7945087687733;1473.3738161281626;1477.8891583131524;28832.855835721526;29383.412331753672;29437.55843662304;30312;30501.84749076329;50499;50954;51060.744696276284;51072;51293.48706512887;507750;509497;514592;514610;515856;39477.605223947394;40055.39916901307;40105.19789604133;40166.39750403805;40422.39366409565;45825.770871146764;46101.07797844092;46938.06123877435;46973.76513117413;47002.29496557623;38095.85712428632;38290.42564361481;38340;39426.60573394229;39774.60225397709;37175.81412092922;38247.80876095602;38611.6138838608;38785.80607096946;39038.21923561456;36503.72236971693;36610.901672949854;36999.44500832436;37502.249955001294;37966.81016594899;25899.61150582705;32398.676013240507;32658.83670581632;34133;34148.82925585356;24389.63415548804;24782.752172478045;24991.250262492154;25317.87341063283;25523.23430297094 04:48:57 INFO - PROCESS | 1647 | |2;jquery.html;372.65529385318575;393.1566789237086;395.501667898448;400.4333867577379;423.511357578113;14849.554513364603;20308;20743.688844667322;21796.8910155449;21838.126474941022;20831.27090551831;21380.78619213811;21801.236956706518;21976.230831920886;22242.332730018126;4843.39457567804;5482.91775623366;5524.977879092361;5879.723215674084;6063.727132279058;5976.252968378953;6036.69816509175;6093.603915745484;6095.634261944298;6118.082287656843;6011.699415029253;6020.096985452195;6041.4562689357945;7659.923400765977;7989;7922;7995.120536740983;8063.516189028677;8153.9184608153755;8156.184381561856;7849.8037549061155;7932.841343173162;7937.603119844042;7944.364450843918;7959.721409750631;29206.123816285544;29279.560806587917;29857.70142298571;30299.364076310983;30375.59455469889;2914.986975168628;2987.342784587387;2993.640763108419;3016.457037733207;3144.984275078633;13737.24445155516;17684.3043995243;17958;18215.63568728618;18554.628907421775;1974.535984043763;2039;2061.4743240473586;2069.658506346451;2072.1400618743205;102930;107341;119872;121152.78847211592;135393.32303338518;18494.630107397777;18644;18907.3382431617;23303.650445242994;23669.881650591982;41994;42073.368899466535;42266;42271;42384.57615423868;11004;11132.554697812158;11261.324320540794;11461.770764584662;11498.99793589791;8682.479051256945;8926.642934282556;8984.337897488986;9163.49600771964;9164.587593558306;10948.4525773711;10951.4524273786;11014.339139651647;11181.776364472666;11204.182201653204;3552.733544984085;4061.3907913812573;4134.586541345841;4167.895802604899;4178.749275043507;1020.4132623741378;1031.9638812641522;1038.190211634926;1051.517429668027;1052.2922813716405;19288.614227715367;20498.590028199353;21086.472838178863;21184.576308473745;21253.574928501654;1023.3246057601947;1028.4086650176177;1058.1376178414569;1060.6977011551671;1064.1805809526777;10280.22898282617;11056.226064175431;11098.44507774608;11128.554857805597;11134.49894754738;698296;737227;743524;761546;765121;144338;147666;148965.2551737256;150935;152851;978.4667356290696;989.391524212604;1010.4240582867695;1010.5957616953277;1011.4133802394593;17859.642807144046;18477.630447391246;18490.44528664142;18626;18677.71983420277;56944.715276423354;57150.7142464285;57777.71111144417;58447;58832;59899;82187.5890620543;83737.58131209548;84388;84463;4811.374521312207;5742.655440673573;5837;8141.185881411811;8643.654253829776;11030.669079927615;12885.355732213164;13208;13458;13468.461261549428;11088.279261847954;11548.769024619629;11613.593524226611;11900.702482438008;11909.166358355178;11605;11669.88330116688;11870.643880683594;11934.28394296345;12222.816657749963;9732.805343893224;11700.239484433485;12041.397930103634;12059.276443413422;12073.939630301793;7075.307870163561;8351.123132071289;8763.3427492937;8877.691800132141;8905.421147625384;10158.339707918962;11284.492197851281;11458.484368203284;11697.415129243675;11855 04:48:57 INFO - PROCESS | 1647 | |3;mootools.html;1317.6639956811011;1328.8471825740044;1454.7890555869399;1479.2086233864882;1482.2292407947862;7420.183779784219;7769.300762931335;7915.643796029178;8204.09754926959;8212.548309842956;7954.323882469983;8207.876881846778;8226.67093316268;8227.012758468976;8228.506289622626;1942.601766637836;2237.7090978172855;2277.6583512473094;2284.4288927768057;2286.6112760830697;2051.302557130575;2117.6823476478576;2201.856879302848;2257.661350797377;2263.7962583367494;2253.9774602253933;2277.1688333758143;2277.2485079923667;2278.111536500766;2278.1912421090524;1977.9307724229584;2185.25701261571;2214.6345852934246;2216.5899308627954;2219.755826859044;2231.2190733243397;2261.1068627892055;2287.633978563422;2303.8617682939075;2321.0599707118645;8130.430869839113;8300.169983001711;8305.626246818907;8311.501309921363;8338.916610833874;5015.27278544609;5142.640015198937;5153.175491921274;5162.2772811806;5170.663906846043;3279.475283954556;3436.29555941034;3468.0809585459874;3493.3537295600395;3536.1159710072484;1148.9195756297063;1204.151073493185;1232.6117273058958;1248.756492483966;1249.3003917806059;10509.474526273656;10524.526396312185;10594.046535811814;10693.358398496037;10694.144468442506;4439.644828413784;4528.02647430804;4640.628749700016;4645.814167433265;4648.279516674921;9384.483853387992;9386.061393860697;9395.57719902606;9407.811843763086;9480.099390558024;5192.870178245498;5203.583713302926;5204.4646829185485;5206.271122042917;5221.216817477409;4299.097189590158;4578.313253012008;4671.6262698984;4768.3085952536685;4774.928376074362;3081.476149054621;3551.538300020981;3680.392735198716;3819.866304679323;3826.8660596878976;1157.98097674048;1185.2532904270254;1224.8897599216114;1262.3561983388354;1273.7006803401193;903.4353529044347;924.9260059195249;931.1107891963159;932.911373419524;970.995020154137;5437;5524.309461317335;5538.806141785019;5728.684922329312;5750.7987220447085;982.7150126463246;1010.8685870836745;1020.3775696824931;1030.90206430389;1031.0668844695563;3539.9292014159573;3603.2973570153567;3819.1406933439753;3831.59768224338;3850.114473671071;9579.90420095804;9713.174380177623;9805;9807.31348805591;9809.754756131011;9729;9740.464274464972;9748.46383448903;9755.756106097262;9757.756056098655;7274.599897005715;7602.353799927068;7625.199354067854;7709.498882572615;7734.5359278443475;4712.81148754046;5509.5041446270025;5531.8893622126525;5595.216669666169;5715.31416230055;8841;9007.864882026644;9030.683926062557;9042.954785226031;9130.452172869649;9168.495732734496;9188.678396256098;9193.954030229806;9241.353105282566;9274.304427167854;6983.755568555076;7205.567665940061;7206.603636800026;7209.71161153548;7221.350078492961;6919.965400172967;7118.074650295428;7381.667824947783;7703.2296770324765;7824.217578242358;6939.791806245821;6972.58164510131;6976.127984002;7117.679704413417;7168.8924666129005;6135.969320153549;6332.52506062038;6922.896156557555;6969.686364113729;7156;6333.524985626004;6359.523035772243;6376.107344971801;6437.195350581177;6450.645214513059;4835.927461088157;4944.851654450372;4957;5006;5147.6911385317;4938.111139994836;4982.402111746614;4999.400071991385;5008.824691135793;5173.870653233699 04:48:57 INFO - PROCESS | 1647 | |4;prototype.html;326.53794880244453;352.58571178864844;360.15902866806005;360.624218606018;362.4219370104685;4267.359896015595;4297.2694641910875;5082.644214904958;5167.586593072555;5201.68789872607;5117.206832940891;5132.3327967364285;5134.845954621358;5146.551810287107;5169.586433085354;3426.6529785522057;3439.970417453702;3655.524781778365;3795.582485926546;3831.731778775487;3318.548709342281;3421.828908554575;3539.8938031859084;3578.4453409721536;3677.0807298175455;3187.7449804015737;3945.9013524661827;3998.6404622428367;4053.945974046754;4114.691398145136;3201.5677883485664;3826.770393776382;3924.154987633971;4075.714699971003;4078.2667366369324;3575.8390872410805;3598.4962105305276;3612.4039533476944;3615.692666123383;3622.275544891006;5040.59675225981;5056.924146137811;5090.567301779316;5094.79620815167;5135.486451354872;4172.561881002482;4463.5982761551295;4637.072585482883;4645.767711614406;4678.789454474523;4257.106007738379;5259.526642602184;5417.160340147283;5448.509634132947;5463.125899856043;3643.362411577963;3730.962690373116;3732.346839303111;3759.6992240620957;3794.2221844521805;4895.657303988723;5280.577553795687;5576.275084239063;5632.690991391589;5856.76572937082;3189.6650851660706;3339.649336819599;3562.28754249146;3724.5344331958504;3754.4931434256573;3779.5842457330223;3817.911895109936;3822.503074600284;3827.827747751358;3844.5386553613744;4071.3892916062237;4149.398337241144;4430.822767089345;4457.618138377049;4458.554144585581;4806.8798280043275;5435.939991701659;5454.809081682122;5467.6719396836315;5477.315335583052;1215.665691934725;1231.7228623559627;1234.3026190202506;1235.5799028330412;1247.9438425270885;741.855338209044;783.8745800671865;805.697863301262;849.6771226933726;857.9313654907717;4427.933580996288;4440.157334706061;4726.338312636233;4786.869842919937;4809.495003024702;4504.815164645144;5180.792768289301;5321.733913304397;5366.880082367521;5404.91892621611;4253.936190957138;4326.673326673326;4492.865214043584;4751.833685821049;4801.351817504662;2370.5258948210426;2489.962650560243;2664.720204378551;2789.595508651275;2816.6760822505266;5296.735163241822;5352.518273355339;5447.809326673548;5472.589555783253;5497.890042199214;4447.644188464915;4751.501092385319;5049.183602137159;5226.63413561047;5441.667166177169;4720.513038392895;4990.725510096907;5483.917741233805;5492.395836457987;5498.642588231752;4795.112904112751;5143.6399452037995;5159.871003224949;5293.24179832308;5365.7048862311385;4637.536246375335;4644.8374306899095;4752.334673145693;4868.977514721948;5210.270562121382;5006.449290577961;5422.593305502024;5589.664620122643;5598.964191624563;5650.660960342392;3455.51622772817;3739.3082279778328;3742.943855842219;3745.756525825813;3772.396416573335;5100.591952643854;5583.720813959367;5600.495955363874;5609.411011843697;5611.80358687444;4684.992726563805;5061.620378471703;5113.51421614946;5217.895642087213;5425.972870135624;5345.973270133624;5363.973180134074;5366.436524164907;5383.757730902041;5389.326334208224;4467.888302792456;5033.370828646419;5260.947390526046;5279.47205279469;5280.60395470349;5350.705711185767;5372.2747429098135;5401.864953376197;5406.135018397038;5445.863853403696;5265.657732247391;5277;5277.656952298088;5309.309789727311;5362.115250983544 04:48:57 INFO - PROCESS | 1647 | |5;yui.html;725.3870479444871;763.0271403959947;776.5806464509162;812.6464987730336;816.7100679258862;13483.325833708312;15818.683626327467;15841.524754257362;15861.524154275361;15899.125548094864;14285.142891426522;14438.422463101464;14468.421263149488;14468.638284042905;14474.42102315906;684.2370756606373;705.7247673407376;709.5600727548901;859.7291853066264;884.5002573545958;839.345310657688;872.0494660819705;888.7022847346115;890.4256754393413;891.2246345679268;893.8257039877227;947.2374738335637;949.1220620925616;949.4920217683541;949.6818565780471;734.2217249715292;752.7064444866506;909.7407238936871;941.9905800942006;947.4457442396207;702.9402500787439;704.7075463682617;707.6178863413751;707.6567864585672;709.1525626875834;14624.561263162122;15721.13533755643;15806.525804225892;15810.446634367741;15811.446599368966;13887.4445022199;14605.92697036508;14666.926665366711;14806.407743690348;14807.333669984877;11366.829497557543;11641.301521908712;11948.342841143734;11980.64058078259;12445.751084978341;3747;3767.811609419518;3787.4886890269736;3834.3864981603087;3938.5667576566557;93824.13660380004;101156;102213.4889325556;102296;104133;10480.947595261974;11338.376389298668;11340.886591133984;11408.372539510407;11691.649250522498;23210.883945580164;23957.880210599185;24054.39864003414;24064.51870962571;24065.759342406353;7761.0298712660915;7786.4160187985;7809.765707028798;8015.318697910627;8020.83958320842;4302.483701955785;4338.522762496123;4356.244431503182;4410.117976404732;4447.155040542287;3514.3498452786316;3630.2557975615464;3632.6730594246646;3636.0182750657173;3638.7089032877307;603.6016229288648;606.423897297569;622.2688341199073;634.876199141163;645.1129696667083;560.4563573333941;660.2341284110408;681.8056853796644;693.6913073682163;725.9782206533813;9565.713028609152;9859.605615775432;9990.80018399628;10315.84526232107;10353.896461035294;677.6510097299895;702.9929700703029;716.77421612192;725.4450345485708;726.2882375272263;3934.626210509997;4323.935140972888;4896.4124305083615;4909.2881532177635;4928.507149285043;67105.6644716783;71049.64475177694;71201.64399177971;75360;82549.58725206336;61456.07815882765;61782;63148;65175.67412162909;73478.63260683662;58307.42314421395;60935;61143.388566114656;69007.30992690008;69054.65472672705;9924.900750992398;10874.966878146563;11023.050817138746;11060.668179954615;11081.224314298244;41292;44139.55860441354;44311.55688443074;45809.541904580525;46592.301115482616;48203.4976152626;48721.53835384944;57724;58961.41038589559;59255.70372148111;11822.699503054486;12483.941434348642;12670.429830657828;13074.411651475897;13383.263920484467;14349.426022958965;14504.927475362556;14533.709325813214;14958.700825983637;15002.624934376729;13400.195988240737;13626.727465450835;13650.522231721841;13696.65758356049;14109.223992680501;13135.73728525405;13441.798373024218;13457.865421346052;13502.257375844423;13777.517786877412;12853.807192891927;12967.935160324138;13033.739325213632;13512.864871351161;13643.454261829416;10823.350598964087;11061.612843550438;11381.43092845338;11684.298942063504;11689.473973671375;7435.553866768011;8894.73315800527;9379.202767764817;9414;9420 04:48:57 INFO - PROCESS | 1647 | __end_tp_report 04:48:57 INFO - PROCESS | 1647 | __start_cc_report 04:48:57 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2352 04:48:57 INFO - PROCESS | 1647 | __end_cc_report 04:48:57 INFO - PROCESS | 1647 | __startTimestamp1483966137329__endTimestamp 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | ------- Summary: start ------- 04:48:57 INFO - PROCESS | 1647 | Number of tests: 6 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5793.22 Median:6043.12 stddev:654.25 (10.8%) stddev-sans-first:627.12 04:48:57 INFO - PROCESS | 1647 | Values: 3331.8 3342.8 3354.9 3374.3 3409.3 4887.9 5551.0 5798.1 5849.8 5875.0 5749.2 5768.1 5834.2 5846.2 5896.9 5120.3 5135.2 5164.4 5225.1 5269.4 5308.2 5317.9 5321.9 5343.6 5414.5 5154.3 5295.0 5303.1 5344.9 5355.9 5172.0 5182.7 5223.1 5251.0 5251.4 5229.2 5235.2 5251.1 5257.7 5299.3 4760.3 5797.5 5813.4 5815.1 5821.1 6120.5 6155.3 6171.4 6225.8 6367.9 5626.4 5773.2 6343.2 6382.1 6399.5 5864.3 5910.2 5918.6 5929.9 5938.1 6137.8 6287.4 6357.5 6377.7 6383.3 6141.4 6172.3 6178.7 6204.5 6253.9 6187.4 6266.0 6342.2 6363.1 6369.2 6128.6 6248.2 6255.1 6257.5 6270.3 6278.7 6292.7 6296.0 6301.6 6302.6 4988.4 5011.2 5019.3 5020.2 5026.5 4500.0 4518.4 4542.3 4567.5 4583.4 5752.5 5779.8 5803.3 5878.4 5965.5 6279.0 6322.1 6332.2 6365.8 6387.2 5910.5 5937.1 5941.3 5946.9 5994.6 5358.4 5410.6 5537.1 5545.8 5549.4 6295.9 6318.5 6358.0 6390.1 6451.1 6311.3 6330.9 6346.0 6346.6 6355.6 6396.3 6416.0 6440.4 6452.0 6479.0 5108.6 6110.4 6162.9 6189.1 6204.3 6324.7 6328.0 6331.9 6355.1 6361.6 6154.4 6182.1 6189.5 6195.5 6198.7 5938.9 5975.9 6101.5 6129.9 6203.0 6072.3 6095.9 6096.8 6099.2 6113.4 6065.1 6123.4 6125.7 6180.2 6234.1 5236.2 5985.6 6043.1 6057.0 6060.9 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:67505.40 Median:21470.35 stddev:143039.19 (666.2%) stddev-sans-first:143351.84 04:48:57 INFO - PROCESS | 1647 | Values: 444.8 471.9 519.8 533.9 580.8 31170.8 32863.7 32868.3 33007.5 39770.6 39928.2 42004.2 42098.0 42179.9 42304.0 9155.0 9364.6 9374.6 9376.8 9396.4 8481.0 8699.2 9298.2 9407.3 9416.7 11101.5 11344.4 11387.1 11548.3 11571.8 11102.6 11149.0 11255.2 11270.9 11279.8 8359.9 8586.0 8589.6 11157.0 11212.4 31969.8 32282.5 39040.6 39725.6 39828.8 3902.5 4033.5 4044.3 4062.8 4173.0 29538.9 30240.8 30696.5 30700.2 30803.9 3200.6 3259.4 3354.0 3359.6 3368.8 488271.0 488526.0 489618.0 489652.0 489656.0 61156.7 61368.4 61437.1 61598.7 61857.0 64566.7 64717.0 64737.4 64884.4 64887.0 15727.7 16001.0 16003.8 16006.4 16015.8 12376.9 12483.2 12505.4 12506.9 12514.2 14794.0 15123.7 16381.0 16804.8 16927.9 5933.4 6670.2 6683.3 6701.0 6720.4 827.0 849.2 876.3 898.2 911.3 29798.0 29966.4 30004.8 30520.2 30613.1 1061.4 1079.7 1100.8 1117.0 1172.5 15388.3 15455.5 15470.3 15622.0 15837.4 596145.0 601335.0 602327.0 602368.0 606475.0 60716.8 61020.0 61135.0 61194.7 61203.7 1232.8 1464.2 1467.8 1473.4 1477.9 28832.9 29383.4 29437.6 30312.0 30501.8 50499.0 50954.0 51060.7 51072.0 51293.5 507750.0 509497.0 514592.0 514610.0 515856.0 39477.6 40055.4 40105.2 40166.4 40422.4 45825.8 46101.1 46938.1 46973.8 47002.3 38095.9 38290.4 38340.0 39426.6 39774.6 37175.8 38247.8 38611.6 38785.8 39038.2 36503.7 36610.9 36999.4 37502.2 37966.8 25899.6 32398.7 32658.8 34133.0 34148.8 24389.6 24782.8 24991.3 25317.9 25523.2 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:41610.07 Median:21799.06 stddev:122801.87 (563.3%) stddev-sans-first:123107.33 04:48:57 INFO - PROCESS | 1647 | Values: 372.7 393.2 395.5 400.4 423.5 14849.6 20308.0 20743.7 21796.9 21838.1 20831.3 21380.8 21801.2 21976.2 22242.3 4843.4 5482.9 5525.0 5879.7 6063.7 5976.3 6036.7 6093.6 6095.6 6118.1 6011.7 6020.1 6041.5 7659.9 7989.0 7922.0 7995.1 8063.5 8153.9 8156.2 7849.8 7932.8 7937.6 7944.4 7959.7 29206.1 29279.6 29857.7 30299.4 30375.6 2915.0 2987.3 2993.6 3016.5 3145.0 13737.2 17684.3 17958.0 18215.6 18554.6 1974.5 2039.0 2061.5 2069.7 2072.1 102930.0 107341.0 119872.0 121152.8 135393.3 18494.6 18644.0 18907.3 23303.7 23669.9 41994.0 42073.4 42266.0 42271.0 42384.6 11004.0 11132.6 11261.3 11461.8 11499.0 8682.5 8926.6 8984.3 9163.5 9164.6 10948.5 10951.5 11014.3 11181.8 11204.2 3552.7 4061.4 4134.6 4167.9 4178.7 1020.4 1032.0 1038.2 1051.5 1052.3 19288.6 20498.6 21086.5 21184.6 21253.6 1023.3 1028.4 1058.1 1060.7 1064.2 10280.2 11056.2 11098.4 11128.6 11134.5 698296.0 737227.0 743524.0 761546.0 765121.0 144338.0 147666.0 148965.3 150935.0 152851.0 978.5 989.4 1010.4 1010.6 1011.4 17859.6 18477.6 18490.4 18626.0 18677.7 56944.7 57150.7 57777.7 58447.0 58832.0 59899.0 82187.6 83737.6 84388.0 84463.0 4811.4 5742.7 5837.0 8141.2 8643.7 11030.7 12885.4 13208.0 13458.0 13468.5 11088.3 11548.8 11613.6 11900.7 11909.2 11605.0 11669.9 11870.6 11934.3 12222.8 9732.8 11700.2 12041.4 12059.3 12073.9 7075.3 8351.1 8763.3 8877.7 8905.4 10158.3 11284.5 11458.5 11697.4 11855.0 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5348.76 Median:5198.23 stddev:2899.10 (55.8%) stddev-sans-first:2891.40 04:48:57 INFO - PROCESS | 1647 | Values: 1317.7 1328.8 1454.8 1479.2 1482.2 7420.2 7769.3 7915.6 8204.1 8212.5 7954.3 8207.9 8226.7 8227.0 8228.5 1942.6 2237.7 2277.7 2284.4 2286.6 2051.3 2117.7 2201.9 2257.7 2263.8 2254.0 2277.2 2277.2 2278.1 2278.2 1977.9 2185.3 2214.6 2216.6 2219.8 2231.2 2261.1 2287.6 2303.9 2321.1 8130.4 8300.2 8305.6 8311.5 8338.9 5015.3 5142.6 5153.2 5162.3 5170.7 3279.5 3436.3 3468.1 3493.4 3536.1 1148.9 1204.2 1232.6 1248.8 1249.3 10509.5 10524.5 10594.0 10693.4 10694.1 4439.6 4528.0 4640.6 4645.8 4648.3 9384.5 9386.1 9395.6 9407.8 9480.1 5192.9 5203.6 5204.5 5206.3 5221.2 4299.1 4578.3 4671.6 4768.3 4774.9 3081.5 3551.5 3680.4 3819.9 3826.9 1158.0 1185.3 1224.9 1262.4 1273.7 903.4 924.9 931.1 932.9 971.0 5437.0 5524.3 5538.8 5728.7 5750.8 982.7 1010.9 1020.4 1030.9 1031.1 3539.9 3603.3 3819.1 3831.6 3850.1 9579.9 9713.2 9805.0 9807.3 9809.8 9729.0 9740.5 9748.5 9755.8 9757.8 7274.6 7602.4 7625.2 7709.5 7734.5 4712.8 5509.5 5531.9 5595.2 5715.3 8841.0 9007.9 9030.7 9043.0 9130.5 9168.5 9188.7 9194.0 9241.4 9274.3 6983.8 7205.6 7206.6 7209.7 7221.4 6920.0 7118.1 7381.7 7703.2 7824.2 6939.8 6972.6 6976.1 7117.7 7168.9 6136.0 6332.5 6922.9 6969.7 7156.0 6333.5 6359.5 6376.1 6437.2 6450.6 4835.9 4944.9 4957.0 5006.0 5147.7 4938.1 4982.4 4999.4 5008.8 5173.9 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4308.80 Median:4798.23 stddev:1314.37 (27.4%) stddev-sans-first:1283.63 04:48:57 INFO - PROCESS | 1647 | Values: 326.5 352.6 360.2 360.6 362.4 4267.4 4297.3 5082.6 5167.6 5201.7 5117.2 5132.3 5134.8 5146.6 5169.6 3426.7 3440.0 3655.5 3795.6 3831.7 3318.5 3421.8 3539.9 3578.4 3677.1 3187.7 3945.9 3998.6 4053.9 4114.7 3201.6 3826.8 3924.2 4075.7 4078.3 3575.8 3598.5 3612.4 3615.7 3622.3 5040.6 5056.9 5090.6 5094.8 5135.5 4172.6 4463.6 4637.1 4645.8 4678.8 4257.1 5259.5 5417.2 5448.5 5463.1 3643.4 3731.0 3732.3 3759.7 3794.2 4895.7 5280.6 5576.3 5632.7 5856.8 3189.7 3339.6 3562.3 3724.5 3754.5 3779.6 3817.9 3822.5 3827.8 3844.5 4071.4 4149.4 4430.8 4457.6 4458.6 4806.9 5435.9 5454.8 5467.7 5477.3 1215.7 1231.7 1234.3 1235.6 1247.9 741.9 783.9 805.7 849.7 857.9 4427.9 4440.2 4726.3 4786.9 4809.5 4504.8 5180.8 5321.7 5366.9 5404.9 4253.9 4326.7 4492.9 4751.8 4801.4 2370.5 2490.0 2664.7 2789.6 2816.7 5296.7 5352.5 5447.8 5472.6 5497.9 4447.6 4751.5 5049.2 5226.6 5441.7 4720.5 4990.7 5483.9 5492.4 5498.6 4795.1 5143.6 5159.9 5293.2 5365.7 4637.5 4644.8 4752.3 4869.0 5210.3 5006.4 5422.6 5589.7 5599.0 5650.7 3455.5 3739.3 3742.9 3745.8 3772.4 5100.6 5583.7 5600.5 5609.4 5611.8 4685.0 5061.6 5113.5 5217.9 5426.0 5346.0 5364.0 5366.4 5383.8 5389.3 4467.9 5033.4 5260.9 5279.5 5280.6 5350.7 5372.3 5401.9 5406.1 5445.9 5265.7 5277.0 5277.7 5309.3 5362.1 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:18021.00 Median:4383.18 stddev:23877.70 (544.8%) stddev-sans-first:23909.36 04:48:57 INFO - PROCESS | 1647 | Values: 725.4 763.0 776.6 812.6 816.7 13483.3 15818.7 15841.5 15861.5 15899.1 14285.1 14438.4 14468.4 14468.6 14474.4 684.2 705.7 709.6 859.7 884.5 839.3 872.0 888.7 890.4 891.2 893.8 947.2 949.1 949.5 949.7 734.2 752.7 909.7 942.0 947.4 702.9 704.7 707.6 707.7 709.2 14624.6 15721.1 15806.5 15810.4 15811.4 13887.4 14605.9 14666.9 14806.4 14807.3 11366.8 11641.3 11948.3 11980.6 12445.8 3747.0 3767.8 3787.5 3834.4 3938.6 93824.1 101156.0 102213.5 102296.0 104133.0 10480.9 11338.4 11340.9 11408.4 11691.6 23210.9 23957.9 24054.4 24064.5 24065.8 7761.0 7786.4 7809.8 8015.3 8020.8 4302.5 4338.5 4356.2 4410.1 4447.2 3514.3 3630.3 3632.7 3636.0 3638.7 603.6 606.4 622.3 634.9 645.1 560.5 660.2 681.8 693.7 726.0 9565.7 9859.6 9990.8 10315.8 10353.9 677.7 703.0 716.8 725.4 726.3 3934.6 4323.9 4896.4 4909.3 4928.5 67105.7 71049.6 71201.6 75360.0 82549.6 61456.1 61782.0 63148.0 65175.7 73478.6 58307.4 60935.0 61143.4 69007.3 69054.7 9924.9 10875.0 11023.1 11060.7 11081.2 41292.0 44139.6 44311.6 45809.5 46592.3 48203.5 48721.5 57724.0 58961.4 59255.7 11822.7 12483.9 12670.4 13074.4 13383.3 14349.4 14504.9 14533.7 14958.7 15002.6 13400.2 13626.7 13650.5 13696.7 14109.2 13135.7 13441.8 13457.9 13502.3 13777.5 12853.8 12967.9 13033.7 13512.9 13643.5 10823.4 11061.6 11381.4 11684.3 11689.5 7435.6 8894.7 9379.2 9414.0 9420.0 04:48:57 INFO - PROCESS | 1647 | -------- Summary: end -------- 04:48:57 INFO - PROCESS | 1647 | 04:48:57 INFO - TEST-INFO | 1647: exit 0 04:48:57 INFO - TEST-OK | dromaeo_css | took 1120521ms 04:48:57 INFO - TEST-START | kraken 04:48:57 INFO - operating with platform_type : mac_ 04:48:57 INFO - Initialising browser for kraken test... 04:48:58 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpqY93BU/profile http://localhost:49241/getInfo.html) 04:48:58 INFO - PROCESS | 1652 | 1483966138511 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 04:49:00 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 04:49:00 INFO - PROCESS | 1652 | colorDepth:24 04:49:00 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 04:49:00 INFO - PROCESS | 1652 | __metrics 04:49:00 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:49:00 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:01 INFO - TEST-INFO | 1652: exit 0 04:49:01 INFO - Browser initialized. 04:49:01 INFO - Running cycle 1/1 for kraken test... 04:49:01 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpqY93BU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:13 INFO - PROCESS | 1654 | RSS: Main: 234909696 04:49:13 INFO - PROCESS | 1654 | 04:49:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:16 INFO - PROCESS | 1654 | RSS: Main: 286629888 04:49:16 INFO - PROCESS | 1654 | 04:49:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 04:49:20 INFO - PROCESS | 1654 | RSS: Main: 286429184 04:49:20 INFO - PROCESS | 1654 | 04:49:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 04:49:24 INFO - PROCESS | 1654 | RSS: Main: 378007552 04:49:24 INFO - PROCESS | 1654 | 04:49:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 04:49:26 INFO - PROCESS | 1654 | RSS: Main: 283746304 04:49:26 INFO - PROCESS | 1654 | 04:49:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:29 INFO - PROCESS | 1654 | RSS: Main: 286183424 04:49:29 INFO - PROCESS | 1654 | 04:49:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 04:49:32 INFO - PROCESS | 1654 | RSS: Main: 328372224 04:49:32 INFO - PROCESS | 1654 | 04:49:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 04:49:36 INFO - PROCESS | 1654 | RSS: Main: 330731520 04:49:36 INFO - PROCESS | 1654 | 04:49:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 04:49:40 INFO - PROCESS | 1654 | RSS: Main: 326311936 04:49:40 INFO - PROCESS | 1654 | 04:49:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:49:42 INFO - PROCESS | 1654 | RSS: Main: 303185920 04:49:42 INFO - PROCESS | 1654 | 04:49:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:49:44 INFO - PROCESS | 1654 | RSS: Main: 306229248 04:49:44 INFO - PROCESS | 1654 | 04:49:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:49:47 INFO - PROCESS | 1654 | RSS: Main: 308137984 04:49:47 INFO - PROCESS | 1654 | 04:49:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:49:51 INFO - PROCESS | 1654 | RSS: Main: 327335936 04:49:51 INFO - PROCESS | 1654 | 04:49:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:49:54 INFO - PROCESS | 1654 | RSS: Main: 321282048 04:49:54 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 04:49:56 INFO - PROCESS | 1654 | RSS: Main: 365465600 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | __start_tp_report 04:49:56 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 04:49:56 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 04:49:56 INFO - PROCESS | 1654 | |i|pagename|runs| 04:49:56 INFO - PROCESS | 1654 | |0;ai-astar;113;114;113;111;120;166;152;112;112;112 04:49:56 INFO - PROCESS | 1654 | |1;audio-beat-detection;148;110;109;146;136;138;140;112;130;107 04:49:56 INFO - PROCESS | 1654 | |2;audio-dft;183;183;184;185;182;181;182;181;183;183 04:49:56 INFO - PROCESS | 1654 | |3;audio-fft;79;59;75;111;85;69;58;58;74;59 04:49:56 INFO - PROCESS | 1654 | |4;audio-oscillator;91;71;88;68;69;68;83;69;68;84 04:49:56 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;87;84;88;84;92;131;123;85;87;84 04:49:56 INFO - PROCESS | 1654 | |6;imaging-darkroom;131;93;93;93;94;94;94;93;93;93 04:49:56 INFO - PROCESS | 1654 | |7;imaging-desaturate;86;84;85;88;86;87;85;103;136;142 04:49:56 INFO - PROCESS | 1654 | |8;json-parse-financial;56;56;54;54;56;57;57;57;55;55 04:49:56 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;66;80;66;77;74;70;72;69;95;99 04:49:56 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;87;86;85;87;87;89;87;90;88 04:49:56 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;155;149;140;144;142;138;138;141;184;150 04:49:56 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;175;181;172;169;172;171;168;170;171;173 04:49:56 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;67;69;65;67;68;67;66;66;65 04:49:56 INFO - PROCESS | 1654 | __end_tp_report 04:49:56 INFO - PROCESS | 1654 | __start_cc_report 04:49:56 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1987 04:49:56 INFO - PROCESS | 1654 | __end_cc_report 04:49:56 INFO - PROCESS | 1654 | __startTimestamp1483966196886__endTimestamp 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | ------- Summary: start ------- 04:49:56 INFO - PROCESS | 1654 | Number of tests: 14 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:122.50 Median:113.50 stddev:19.68 (17.3%) stddev-sans-first:20.57 04:49:56 INFO - PROCESS | 1654 | Values: 113.0 114.0 113.0 111.0 120.0 166.0 152.0 112.0 112.0 112.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:127.60 Median:137.00 stddev:16.39 (12.0%) stddev-sans-first:15.63 04:49:56 INFO - PROCESS | 1654 | Values: 148.0 110.0 109.0 146.0 136.0 138.0 140.0 112.0 130.0 107.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.25 (0.7%) stddev-sans-first:1.32 04:49:56 INFO - PROCESS | 1654 | Values: 183.0 183.0 184.0 185.0 182.0 181.0 182.0 181.0 183.0 183.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:72.70 Median:71.50 stddev:16.62 (23.2%) stddev-sans-first:17.47 04:49:56 INFO - PROCESS | 1654 | Values: 79.0 59.0 75.0 111.0 85.0 69.0 58.0 58.0 74.0 59.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.00 stddev:9.41 (12.2%) stddev-sans-first:8.24 04:49:56 INFO - PROCESS | 1654 | Values: 91.0 71.0 88.0 68.0 69.0 68.0 83.0 69.0 68.0 84.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:86.00 stddev:17.41 (20.2%) stddev-sans-first:18.25 04:49:56 INFO - PROCESS | 1654 | Values: 87.0 84.0 88.0 84.0 92.0 131.0 123.0 85.0 87.0 84.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:93.00 stddev:11.92 (12.8%) stddev-sans-first:0.50 04:49:56 INFO - PROCESS | 1654 | Values: 131.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 93.0 93.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:98.20 Median:85.50 stddev:22.23 (26.0%) stddev-sans-first:23.14 04:49:56 INFO - PROCESS | 1654 | Values: 86.0 84.0 85.0 88.0 86.0 87.0 85.0 103.0 136.0 142.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.16 (2.1%) stddev-sans-first:1.22 04:49:56 INFO - PROCESS | 1654 | Values: 56.0 56.0 54.0 54.0 56.0 57.0 57.0 57.0 55.0 55.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:76.80 Median:75.50 stddev:11.57 (15.3%) stddev-sans-first:11.60 04:49:56 INFO - PROCESS | 1654 | Values: 66.0 80.0 66.0 77.0 74.0 70.0 72.0 69.0 95.0 99.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.00 stddev:1.42 (1.6%) stddev-sans-first:1.50 04:49:56 INFO - PROCESS | 1654 | Values: 87.0 87.0 86.0 85.0 87.0 87.0 89.0 87.0 90.0 88.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:146.50 stddev:13.80 (9.4%) stddev-sans-first:14.41 04:49:56 INFO - PROCESS | 1654 | Values: 155.0 149.0 140.0 144.0 142.0 138.0 138.0 141.0 184.0 150.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:3.68 (2.1%) stddev-sans-first:3.76 04:49:56 INFO - PROCESS | 1654 | Values: 175.0 181.0 172.0 169.0 172.0 171.0 168.0 170.0 171.0 173.0 04:49:56 INFO - PROCESS | 1654 | 04:49:56 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 04:49:56 INFO - PROCESS | 1654 | Values: 66.0 67.0 69.0 65.0 67.0 68.0 67.0 66.0 66.0 65.0 04:49:56 INFO - PROCESS | 1654 | -------- Summary: end -------- 04:49:56 INFO - PROCESS | 1654 | 04:49:57 INFO - TEST-INFO | 1654: exit 0 04:49:57 INFO - TEST-OK | kraken | took 59782ms 04:49:57 INFO - SUITE-END | took 1180s 04:49:58 INFO - Completed test suite (00:19:40) 04:49:58 INFO - javascript benchmark 04:49:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.833408329583, 3342.782719123258, 3354.8993530194093, 3374.274530975843, 3409.267007593367, 4887.926681099786, 5551.028570000254, 5798.101294299385, 5849.766009359626, 5874.970625146873, 5749.195112684228, 5768.134779783035, 5834.154047663086, 5846.21076154719, 5896.941030589682, 5120.283160357553, 5135.204043373283, 5164.406093299281, 5225.111731005738, 5269.446708095652, 5308.203769434596, 5317.9468205318035, 5321.946780532203, 5343.625946183769, 5414.485623865727, 5154.30416893722, 5295.0468915595175, 5303.071962406563, 5344.866378340534, 5355.866103347428, 5172.043172013189, 5182.663126896739, 5223.138182199932, 5250.9737451312885, 5251.422343542207, 5229.163333866563, 5235.240890070919, 5251.054810134175, 5257.737113144328, 5299.33758280215, 4760.33355330254, 5797.536197104221, 5813.389594092644, 5815.098659707752, 5821.068629019336, 6120.508083830883, 6155.261368635747, 6171.352008039136, 6225.8132256032395, 6367.87264254717, 5626.409227031185, 5773.191753154561, 6343.238811342622, 6382.074599183139, 6399.520035997319, 5864.296284445893, 5910.231669882931, 5918.644881307136, 5929.911051334277, 5938.109283607407, 6137.754489820357, 6287.434130928238, 6357.4596159326975, 6377.74489020434, 6383.2978372379, 6141.416565426277, 6172.321044685081, 6178.66017369049, 6204.503639708812, 6253.937460625427, 6187.381261873777, 6266.0, 6342.23893132827, 6363.109164716943, 6369.172007638977, 6128.636540436954, 6248.187735594343, 6255.093011513396, 6257.530685198628, 6270.3416141305415, 6278.654673992883, 6292.716827742763, 6296.0, 6301.590396624205, 6302.621842689453, 4988.351514303117, 5011.198208286657, 5019.322391477177, 5020.221865610836, 5026.4973502649445, 4499.965008048167, 4518.367428560005, 4542.341360502709, 4567.497575266718, 4583.449986001701, 5752.539796816244, 5779.797707080232, 5803.332616749043, 5878.412158784087, 5965.522758179422, 6278.968605157036, 6322.114903913456, 6332.208473940757, 6365.809025729235, 6387.169667943138, 5910.527157827363, 5937.10943358491, 5941.31674857397, 5946.910796338058, 5994.610350327302, 5358.4373640767935, 5410.648307859977, 5537.058700021006, 5545.833624991256, 5549.417311182348, 6295.905561416674, 6318.462930650946, 6358.0, 6390.073439351175, 6451.129097571956, 6311.337309582428, 6330.8733825322315, 6346.048092785934, 6346.619202847844, 6355.5551111421755, 6396.296407395276, 6416.037594360883, 6440.355964403522, 6452.032195170575, 6479.028145778171, 5108.59131269505, 6110.40687992437, 6162.907556386568, 6189.071639253968, 6204.255489341127, 6324.747010119544, 6327.96836015817, 6331.90502142459, 6355.14205582259, 6361.61830290184, 6154.415330543591, 6182.072689096672, 6189.50483961273, 6195.473384762346, 6198.690065496617, 5938.881222375615, 5975.880482390415, 6101.450869421773, 6129.9387006131155, 6203.038529028008, 6072.332043475302, 6095.933211687849, 6096.817095487142, 6099.201365966966, 6113.354685976905, 6065.059915713072, 6123.4488895999575, 6125.724342404692, 6180.196574445294, 6234.064890266496, 5236.214567814858, 5985.581009329305, 6043.12374705674, 6056.9697151513965, 6060.939390606038], "value": 5751.6767846866305, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.83763426349384, 471.8678769944416, 519.8232600915685, 533.8798770276691, 580.7996241296754, 31170.84414577927, 32863.67136328636, 32868.342633147324, 33007.50488742668, 39770.60229397705, 39928.20143597126, 42004.15991680172, 42098.0, 42179.94550136248, 42304.0, 9154.992950775393, 9364.578593963253, 9374.578143983503, 9376.812463750755, 9396.389234699756, 8480.999999999969, 8699.173578510032, 9298.16316531512, 9407.294452916025, 9416.670416535422, 11101.500432480518, 11344.376059316734, 11387.145964052688, 11548.30710157389, 11571.826422603668, 11102.6114086007, 11149.0, 11255.155863310285, 11270.94364528172, 11279.774404511945, 8359.874601880976, 8585.969683638003, 8589.570521473965, 11157.0, 11212.383318917457, 31969.84015079933, 32282.515762263585, 39040.609593904264, 39725.60274397248, 39828.800855995825, 3902.4731661225655, 4033.4756481657196, 4044.2518134145275, 4062.7765472898977, 4172.958270417287, 29538.85230573855, 30240.803075268406, 30696.53955190674, 30700.2324941876, 30803.921862734922, 3200.647928727838, 3259.364423937333, 3353.9832300838584, 3359.6136444308727, 3368.8147151906637, 488271.0, 488526.0, 489618.0, 489652.0, 489655.99999999645, 61156.694216529526, 61368.38631613716, 61437.078443823375, 61598.69200653968, 61857.0, 64566.677166614805, 64717.0, 64737.35262647407, 64884.351156488774, 64887.0, 15727.68544629101, 16000.959937604019, 16003.839961600235, 16006.359745610278, 16015.839841601668, 12376.947959423369, 12483.188592741624, 12505.437255323532, 12506.937465312616, 12514.249145051326, 14793.964422490537, 15123.697526049418, 16381.0, 16804.831951680328, 16927.915360423118, 5933.406659334119, 6670.232923213795, 6683.264840867598, 6701.0, 6720.428763555153, 826.9586520673943, 849.227203245044, 876.333986170515, 898.2140626951417, 911.284641556375, 29798.0, 29966.400671986437, 30004.84997574998, 30520.236994075327, 30613.081607551365, 1061.4162210784039, 1079.6760971708457, 1100.834874768791, 1116.966491005255, 1172.46066809267, 15388.30752616135, 15455.53633391, 15470.303836327392, 15622.0, 15837.445689400816, 596145.0, 601335.0, 602327.0, 602368.0, 606475.0, 60716.785664286464, 61020.0, 61135.0, 61194.69402653047, 61203.69398152981, 1232.7657745028416, 1464.2385959301105, 1467.7945087687733, 1473.3738161281626, 1477.8891583131524, 28832.855835721526, 29383.412331753672, 29437.55843662304, 30312.0, 30501.84749076329, 50499.0, 50954.0, 51060.744696276284, 51072.0, 51293.48706512887, 507750.0, 509497.0, 514592.0, 514610.0, 515856.0, 39477.605223947394, 40055.39916901307, 40105.19789604133, 40166.39750403805, 40422.39366409565, 45825.770871146764, 46101.07797844092, 46938.06123877435, 46973.76513117413, 47002.29496557623, 38095.85712428632, 38290.42564361481, 38340.0, 39426.60573394229, 39774.60225397709, 37175.81412092922, 38247.80876095602, 38611.6138838608, 38785.80607096946, 39038.21923561456, 36503.72236971693, 36610.901672949854, 36999.44500832436, 37502.249955001294, 37966.81016594899, 25899.61150582705, 32398.676013240507, 32658.83670581632, 34133.0, 34148.82925585356, 24389.63415548804, 24782.752172478045, 24991.250262492154, 25317.87341063283, 25523.23430297094], "value": 19996.51498860525, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.65529385318575, 393.1566789237086, 395.501667898448, 400.4333867577379, 423.511357578113, 14849.554513364603, 20308.0, 20743.688844667322, 21796.8910155449, 21838.126474941022, 20831.27090551831, 21380.78619213811, 21801.236956706518, 21976.230831920886, 22242.332730018126, 4843.39457567804, 5482.91775623366, 5524.977879092361, 5879.723215674084, 6063.727132279058, 5976.252968378953, 6036.69816509175, 6093.603915745484, 6095.634261944298, 6118.082287656843, 6011.699415029253, 6020.096985452195, 6041.4562689357945, 7659.923400765977, 7989.0, 7922.0, 7995.120536740983, 8063.516189028677, 8153.9184608153755, 8156.184381561856, 7849.8037549061155, 7932.841343173162, 7937.603119844042, 7944.364450843918, 7959.721409750631, 29206.123816285544, 29279.560806587917, 29857.70142298571, 30299.364076310983, 30375.59455469889, 2914.986975168628, 2987.342784587387, 2993.640763108419, 3016.457037733207, 3144.984275078633, 13737.24445155516, 17684.3043995243, 17958.0, 18215.63568728618, 18554.628907421775, 1974.535984043763, 2039.0, 2061.4743240473586, 2069.658506346451, 2072.1400618743205, 102930.0, 107341.0, 119872.0, 121152.78847211592, 135393.32303338518, 18494.630107397777, 18644.0, 18907.3382431617, 23303.650445242994, 23669.881650591982, 41994.0, 42073.368899466535, 42266.0, 42271.0, 42384.57615423868, 11004.0, 11132.554697812158, 11261.324320540794, 11461.770764584662, 11498.99793589791, 8682.479051256945, 8926.642934282556, 8984.337897488986, 9163.49600771964, 9164.587593558306, 10948.4525773711, 10951.4524273786, 11014.339139651647, 11181.776364472666, 11204.182201653204, 3552.733544984085, 4061.3907913812573, 4134.586541345841, 4167.895802604899, 4178.749275043507, 1020.4132623741378, 1031.9638812641522, 1038.190211634926, 1051.517429668027, 1052.2922813716405, 19288.614227715367, 20498.590028199353, 21086.472838178863, 21184.576308473745, 21253.574928501654, 1023.3246057601947, 1028.4086650176177, 1058.1376178414569, 1060.6977011551671, 1064.1805809526777, 10280.22898282617, 11056.226064175431, 11098.44507774608, 11128.554857805597, 11134.49894754738, 698296.0, 737227.0, 743524.0, 761546.0, 765121.0, 144338.0, 147666.0, 148965.2551737256, 150935.0, 152851.0, 978.4667356290696, 989.391524212604, 1010.4240582867695, 1010.5957616953277, 1011.4133802394593, 17859.642807144046, 18477.630447391246, 18490.44528664142, 18626.0, 18677.71983420277, 56944.715276423354, 57150.7142464285, 57777.71111144417, 58447.0, 58832.0, 59899.0, 82187.5890620543, 83737.58131209548, 84388.0, 84463.0, 4811.374521312207, 5742.655440673573, 5837.0, 8141.185881411811, 8643.654253829776, 11030.669079927615, 12885.355732213164, 13208.0, 13458.0, 13468.461261549428, 11088.279261847954, 11548.769024619629, 11613.593524226611, 11900.702482438008, 11909.166358355178, 11605.0, 11669.88330116688, 11870.643880683594, 11934.28394296345, 12222.816657749963, 9732.805343893224, 11700.239484433485, 12041.397930103634, 12059.276443413422, 12073.939630301793, 7075.307870163561, 8351.123132071289, 8763.3427492937, 8877.691800132141, 8905.421147625384, 10158.339707918962, 11284.492197851281, 11458.484368203284, 11697.415129243675, 11855.0], "value": 11382.430890832375, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.6639956811011, 1328.8471825740044, 1454.7890555869399, 1479.2086233864882, 1482.2292407947862, 7420.183779784219, 7769.300762931335, 7915.643796029178, 8204.09754926959, 8212.548309842956, 7954.323882469983, 8207.876881846778, 8226.67093316268, 8227.012758468976, 8228.506289622626, 1942.601766637836, 2237.7090978172855, 2277.6583512473094, 2284.4288927768057, 2286.6112760830697, 2051.302557130575, 2117.6823476478576, 2201.856879302848, 2257.661350797377, 2263.7962583367494, 2253.9774602253933, 2277.1688333758143, 2277.2485079923667, 2278.111536500766, 2278.1912421090524, 1977.9307724229584, 2185.25701261571, 2214.6345852934246, 2216.5899308627954, 2219.755826859044, 2231.2190733243397, 2261.1068627892055, 2287.633978563422, 2303.8617682939075, 2321.0599707118645, 8130.430869839113, 8300.169983001711, 8305.626246818907, 8311.501309921363, 8338.916610833874, 5015.27278544609, 5142.640015198937, 5153.175491921274, 5162.2772811806, 5170.663906846043, 3279.475283954556, 3436.29555941034, 3468.0809585459874, 3493.3537295600395, 3536.1159710072484, 1148.9195756297063, 1204.151073493185, 1232.6117273058958, 1248.756492483966, 1249.3003917806059, 10509.474526273656, 10524.526396312185, 10594.046535811814, 10693.358398496037, 10694.144468442506, 4439.644828413784, 4528.02647430804, 4640.628749700016, 4645.814167433265, 4648.279516674921, 9384.483853387992, 9386.061393860697, 9395.57719902606, 9407.811843763086, 9480.099390558024, 5192.870178245498, 5203.583713302926, 5204.4646829185485, 5206.271122042917, 5221.216817477409, 4299.097189590158, 4578.313253012008, 4671.6262698984, 4768.3085952536685, 4774.928376074362, 3081.476149054621, 3551.538300020981, 3680.392735198716, 3819.866304679323, 3826.8660596878976, 1157.98097674048, 1185.2532904270254, 1224.8897599216114, 1262.3561983388354, 1273.7006803401193, 903.4353529044347, 924.9260059195249, 931.1107891963159, 932.911373419524, 970.995020154137, 5437.0, 5524.309461317335, 5538.806141785019, 5728.684922329312, 5750.7987220447085, 982.7150126463246, 1010.8685870836745, 1020.3775696824931, 1030.90206430389, 1031.0668844695563, 3539.9292014159573, 3603.2973570153567, 3819.1406933439753, 3831.59768224338, 3850.114473671071, 9579.90420095804, 9713.174380177623, 9805.0, 9807.31348805591, 9809.754756131011, 9729.0, 9740.464274464972, 9748.46383448903, 9755.756106097262, 9757.756056098655, 7274.599897005715, 7602.353799927068, 7625.199354067854, 7709.498882572615, 7734.5359278443475, 4712.81148754046, 5509.5041446270025, 5531.8893622126525, 5595.216669666169, 5715.31416230055, 8841.0, 9007.864882026644, 9030.683926062557, 9042.954785226031, 9130.452172869649, 9168.495732734496, 9188.678396256098, 9193.954030229806, 9241.353105282566, 9274.304427167854, 6983.755568555076, 7205.567665940061, 7206.603636800026, 7209.71161153548, 7221.350078492961, 6919.965400172967, 7118.074650295428, 7381.667824947783, 7703.2296770324765, 7824.217578242358, 6939.791806245821, 6972.58164510131, 6976.127984002, 7117.679704413417, 7168.8924666129005, 6135.969320153549, 6332.52506062038, 6922.896156557555, 6969.686364113729, 7156.0, 6333.524985626004, 6359.523035772243, 6376.107344971801, 6437.195350581177, 6450.645214513059, 4835.927461088157, 4944.851654450372, 4957.0, 5006.0, 5147.6911385317, 4938.111139994836, 4982.402111746614, 4999.400071991385, 5008.824691135793, 5173.870653233699], "value": 4363.059225470004, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.53794880244453, 352.58571178864844, 360.15902866806005, 360.624218606018, 362.4219370104685, 4267.359896015595, 4297.2694641910875, 5082.644214904958, 5167.586593072555, 5201.68789872607, 5117.206832940891, 5132.3327967364285, 5134.845954621358, 5146.551810287107, 5169.586433085354, 3426.6529785522057, 3439.970417453702, 3655.524781778365, 3795.582485926546, 3831.731778775487, 3318.548709342281, 3421.828908554575, 3539.8938031859084, 3578.4453409721536, 3677.0807298175455, 3187.7449804015737, 3945.9013524661827, 3998.6404622428367, 4053.945974046754, 4114.691398145136, 3201.5677883485664, 3826.770393776382, 3924.154987633971, 4075.714699971003, 4078.2667366369324, 3575.8390872410805, 3598.4962105305276, 3612.4039533476944, 3615.692666123383, 3622.275544891006, 5040.59675225981, 5056.924146137811, 5090.567301779316, 5094.79620815167, 5135.486451354872, 4172.561881002482, 4463.5982761551295, 4637.072585482883, 4645.767711614406, 4678.789454474523, 4257.106007738379, 5259.526642602184, 5417.160340147283, 5448.509634132947, 5463.125899856043, 3643.362411577963, 3730.962690373116, 3732.346839303111, 3759.6992240620957, 3794.2221844521805, 4895.657303988723, 5280.577553795687, 5576.275084239063, 5632.690991391589, 5856.76572937082, 3189.6650851660706, 3339.649336819599, 3562.28754249146, 3724.5344331958504, 3754.4931434256573, 3779.5842457330223, 3817.911895109936, 3822.503074600284, 3827.827747751358, 3844.5386553613744, 4071.3892916062237, 4149.398337241144, 4430.822767089345, 4457.618138377049, 4458.554144585581, 4806.8798280043275, 5435.939991701659, 5454.809081682122, 5467.6719396836315, 5477.315335583052, 1215.665691934725, 1231.7228623559627, 1234.3026190202506, 1235.5799028330412, 1247.9438425270885, 741.855338209044, 783.8745800671865, 805.697863301262, 849.6771226933726, 857.9313654907717, 4427.933580996288, 4440.157334706061, 4726.338312636233, 4786.869842919937, 4809.495003024702, 4504.815164645144, 5180.792768289301, 5321.733913304397, 5366.880082367521, 5404.91892621611, 4253.936190957138, 4326.673326673326, 4492.865214043584, 4751.833685821049, 4801.351817504662, 2370.5258948210426, 2489.962650560243, 2664.720204378551, 2789.595508651275, 2816.6760822505266, 5296.735163241822, 5352.518273355339, 5447.809326673548, 5472.589555783253, 5497.890042199214, 4447.644188464915, 4751.501092385319, 5049.183602137159, 5226.63413561047, 5441.667166177169, 4720.513038392895, 4990.725510096907, 5483.917741233805, 5492.395836457987, 5498.642588231752, 4795.112904112751, 5143.6399452037995, 5159.871003224949, 5293.24179832308, 5365.7048862311385, 4637.536246375335, 4644.8374306899095, 4752.334673145693, 4868.977514721948, 5210.270562121382, 5006.449290577961, 5422.593305502024, 5589.664620122643, 5598.964191624563, 5650.660960342392, 3455.51622772817, 3739.3082279778328, 3742.943855842219, 3745.756525825813, 3772.396416573335, 5100.591952643854, 5583.720813959367, 5600.495955363874, 5609.411011843697, 5611.80358687444, 4684.992726563805, 5061.620378471703, 5113.51421614946, 5217.895642087213, 5425.972870135624, 5345.973270133624, 5363.973180134074, 5366.436524164907, 5383.757730902041, 5389.326334208224, 4467.888302792456, 5033.370828646419, 5260.947390526046, 5279.47205279469, 5280.60395470349, 5350.705711185767, 5372.2747429098135, 5401.864953376197, 5406.135018397038, 5445.863853403696, 5265.657732247391, 5277.0, 5277.656952298088, 5309.309789727311, 5362.115250983544], "value": 3901.2753313959356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.3870479444871, 763.0271403959947, 776.5806464509162, 812.6464987730336, 816.7100679258862, 13483.325833708312, 15818.683626327467, 15841.524754257362, 15861.524154275361, 15899.125548094864, 14285.142891426522, 14438.422463101464, 14468.421263149488, 14468.638284042905, 14474.42102315906, 684.2370756606373, 705.7247673407376, 709.5600727548901, 859.7291853066264, 884.5002573545958, 839.345310657688, 872.0494660819705, 888.7022847346115, 890.4256754393413, 891.2246345679268, 893.8257039877227, 947.2374738335637, 949.1220620925616, 949.4920217683541, 949.6818565780471, 734.2217249715292, 752.7064444866506, 909.7407238936871, 941.9905800942006, 947.4457442396207, 702.9402500787439, 704.7075463682617, 707.6178863413751, 707.6567864585672, 709.1525626875834, 14624.561263162122, 15721.13533755643, 15806.525804225892, 15810.446634367741, 15811.446599368966, 13887.4445022199, 14605.92697036508, 14666.926665366711, 14806.407743690348, 14807.333669984877, 11366.829497557543, 11641.301521908712, 11948.342841143734, 11980.64058078259, 12445.751084978341, 3747.0, 3767.811609419518, 3787.4886890269736, 3834.3864981603087, 3938.5667576566557, 93824.13660380004, 101156.0, 102213.4889325556, 102296.0, 104133.0, 10480.947595261974, 11338.376389298668, 11340.886591133984, 11408.372539510407, 11691.649250522498, 23210.883945580164, 23957.880210599185, 24054.39864003414, 24064.51870962571, 24065.759342406353, 7761.0298712660915, 7786.4160187985, 7809.765707028798, 8015.318697910627, 8020.83958320842, 4302.483701955785, 4338.522762496123, 4356.244431503182, 4410.117976404732, 4447.155040542287, 3514.3498452786316, 3630.2557975615464, 3632.6730594246646, 3636.0182750657173, 3638.7089032877307, 603.6016229288648, 606.423897297569, 622.2688341199073, 634.876199141163, 645.1129696667083, 560.4563573333941, 660.2341284110408, 681.8056853796644, 693.6913073682163, 725.9782206533813, 9565.713028609152, 9859.605615775432, 9990.80018399628, 10315.84526232107, 10353.896461035294, 677.6510097299895, 702.9929700703029, 716.77421612192, 725.4450345485708, 726.2882375272263, 3934.626210509997, 4323.935140972888, 4896.4124305083615, 4909.2881532177635, 4928.507149285043, 67105.6644716783, 71049.64475177694, 71201.64399177971, 75360.0, 82549.58725206336, 61456.07815882765, 61782.0, 63148.0, 65175.67412162909, 73478.63260683662, 58307.42314421395, 60935.0, 61143.388566114656, 69007.30992690008, 69054.65472672705, 9924.900750992398, 10874.966878146563, 11023.050817138746, 11060.668179954615, 11081.224314298244, 41292.0, 44139.55860441354, 44311.55688443074, 45809.541904580525, 46592.301115482616, 48203.4976152626, 48721.53835384944, 57724.0, 58961.41038589559, 59255.70372148111, 11822.699503054486, 12483.941434348642, 12670.429830657828, 13074.411651475897, 13383.263920484467, 14349.426022958965, 14504.927475362556, 14533.709325813214, 14958.700825983637, 15002.624934376729, 13400.195988240737, 13626.727465450835, 13650.522231721841, 13696.65758356049, 14109.223992680501, 13135.73728525405, 13441.798373024218, 13457.865421346052, 13502.257375844423, 13777.517786877412, 12853.807192891927, 12967.935160324138, 13033.739325213632, 13512.864871351161, 13643.454261829416, 10823.350598964087, 11061.612843550438, 11381.43092845338, 11684.298942063504, 11689.473973671375, 7435.553866768011, 8894.73315800527, 9379.202767764817, 9414.0, 9420.0], "value": 7288.239231318197, "unit": "score"}], "name": "dromaeo_css", "value": 7386.570777448557, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 111.0, 120.0, 166.0, 152.0, 112.0, 112.0, 112.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 109.0, 146.0, 136.0, 138.0, 140.0, 112.0, 130.0, 107.0], "value": 127.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 184.0, 185.0, 182.0, 181.0, 182.0, 181.0, 183.0, 183.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 75.0, 111.0, 85.0, 69.0, 58.0, 58.0, 74.0, 59.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 88.0, 68.0, 69.0, 68.0, 83.0, 69.0, 68.0, 84.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 84.0, 92.0, 131.0, 123.0, 85.0, 87.0, 84.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 88.0, 86.0, 87.0, 85.0, 103.0, 136.0, 142.0], "value": 98.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 54.0, 56.0, 57.0, 57.0, 57.0, 55.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 66.0, 77.0, 74.0, 70.0, 72.0, 69.0, 95.0, 99.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 85.0, 87.0, 87.0, 89.0, 87.0, 90.0, 88.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 149.0, 140.0, 144.0, 142.0, 138.0, 138.0, 141.0, 184.0, 150.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 181.0, 172.0, 169.0, 172.0, 171.0, 168.0, 170.0, 171.0, 173.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 65.0, 67.0, 68.0, 67.0, 66.0, 66.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1477.8999999999999, "alertThreshold": 2.0}]} 04:49:58 INFO - Return code: 0 04:49:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:49:58 INFO - # TBPL SUCCESS # 04:49:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:58 INFO - 2017-01-09 04:49:58.195 screenresolution[1658:10282] starting screenresolution argv=screenresolution get 04:49:58 INFO - 2017-01-09 04:49:58.198 screenresolution[1658:10282] Display 0: 1600x1200x32@60 04:49:58 INFO - 2017-01-09 04:49:58.205 screenresolution[1659:10285] starting screenresolution argv=screenresolution list 04:49:58 INFO - Available Modes on Display 0 04:49:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:58 INFO - Intel Iris: 04:49:58 INFO - Chipset Model: Intel Iris 04:49:58 INFO - Type: GPU 04:49:58 INFO - Bus: Built-In 04:49:58 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:58 INFO - Vendor: Intel (0x8086) 04:49:58 INFO - Device ID: 0x0a2e 04:49:58 INFO - Revision ID: 0x0009 04:49:58 INFO - Displays: 04:49:58 INFO - Display: 04:49:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:58 INFO - Display Serial Number: 1600x1200 60 04:49:58 INFO - Main Display: Yes 04:49:58 INFO - Mirror: Off 04:49:58 INFO - Online: Yes 04:49:58 INFO - Rotation: Supported 04:49:58 INFO - Return code: 0 04:49:58 INFO - Running post-action listener: _resource_record_post_action 04:49:58 INFO - [mozharness: 2017-01-09 12:49:58.446720Z] Finished run-tests step (success) 04:49:58 INFO - Running post-run listener: _resource_record_post_run 04:49:58 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 32279552; Write bytes: 553115648; Read time: 194; Write time: 1688 04:49:58 INFO - TinderboxPrint: CPU usage
53.9% 04:49:58 INFO - TinderboxPrint: I/O read bytes / time
32,279,552 / 194 04:49:58 INFO - TinderboxPrint: I/O write bytes / time
553,115,648 / 1,688 04:49:58 INFO - TinderboxPrint: CPU idle
2,220.2 (46.1%) 04:49:58 INFO - TinderboxPrint: CPU system
965.6 (20.1%) 04:49:58 INFO - TinderboxPrint: CPU user
1,627.9 (33.8%) 04:49:58 INFO - TinderboxPrint: Swap in / out
277,483,520 / 0 04:49:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250868224; Write bytes: 253871616; Read time: 18141; Write time: 556 04:49:58 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 28904448; Write bytes: 297875968; Read time: 168; Write time: 1126 04:49:58 INFO - Running post-run listener: _upload_blobber_files 04:49:58 INFO - Blob upload gear active. 04:49:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:49:58 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 04:49:58 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'] 04:49:58 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 04:49:59 INFO - (blobuploader) - INFO - Open directory for files ... 04:49:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:49:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:00 INFO - (blobuploader) - INFO - Done attempting. 04:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:01 INFO - (blobuploader) - INFO - Done attempting. 04:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:02 INFO - (blobuploader) - INFO - Done attempting. 04:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:04 INFO - (blobuploader) - INFO - Done attempting. 04:50:04 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:04 INFO - Return code: 0 04:50:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a663d3469b643db1f634f052053ac5a44f4d441e6beaf9aa1f219c55945f6679003a51120d26b0537fc77a4671b3dfd7daae5ce15a598da9602a3a2e0ee7728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/689f4df485eec13e39088364a0e57a55f9da3446c9a488a316dc3b120b1c37d3504771e7c80f4cc6988e637e2ae447295deba4804d1c14744362a65c832fd78f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ebe67f9caf063192a95e5cbcec7501a83a23773be9e980de0a80bbb0e51495ee4a0455e58c54816ffc1ef09da04808f9ec5bd8f3fb9cd32554706833560b50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bbd3618cf39c0060fa6d356e7703e568a9be83d16ce2afdda9c5187e07c9e71df77a40df3472f2931abd337f4eb1ea05e142a6aa891379bf5659422da0a9351"} 04:50:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:04 INFO - Contents: 04:50:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a663d3469b643db1f634f052053ac5a44f4d441e6beaf9aa1f219c55945f6679003a51120d26b0537fc77a4671b3dfd7daae5ce15a598da9602a3a2e0ee7728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/689f4df485eec13e39088364a0e57a55f9da3446c9a488a316dc3b120b1c37d3504771e7c80f4cc6988e637e2ae447295deba4804d1c14744362a65c832fd78f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ebe67f9caf063192a95e5cbcec7501a83a23773be9e980de0a80bbb0e51495ee4a0455e58c54816ffc1ef09da04808f9ec5bd8f3fb9cd32554706833560b50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bbd3618cf39c0060fa6d356e7703e568a9be83d16ce2afdda9c5187e07c9e71df77a40df3472f2931abd337f4eb1ea05e142a6aa891379bf5659422da0a9351"} 04:50:04 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:04 INFO - Copying logs to upload dir... 04:50:04 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.733930 ========= master_lag: 0.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-09 04:50:05.215171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 04:50:05.218175) ========= 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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a663d3469b643db1f634f052053ac5a44f4d441e6beaf9aa1f219c55945f6679003a51120d26b0537fc77a4671b3dfd7daae5ce15a598da9602a3a2e0ee7728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/689f4df485eec13e39088364a0e57a55f9da3446c9a488a316dc3b120b1c37d3504771e7c80f4cc6988e637e2ae447295deba4804d1c14744362a65c832fd78f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ebe67f9caf063192a95e5cbcec7501a83a23773be9e980de0a80bbb0e51495ee4a0455e58c54816ffc1ef09da04808f9ec5bd8f3fb9cd32554706833560b50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bbd3618cf39c0060fa6d356e7703e568a9be83d16ce2afdda9c5187e07c9e71df77a40df3472f2931abd337f4eb1ea05e142a6aa891379bf5659422da0a9351"} build_url:https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/MEzSVWyKS-mAyH7xwLE5Uw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a663d3469b643db1f634f052053ac5a44f4d441e6beaf9aa1f219c55945f6679003a51120d26b0537fc77a4671b3dfd7daae5ce15a598da9602a3a2e0ee7728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/689f4df485eec13e39088364a0e57a55f9da3446c9a488a316dc3b120b1c37d3504771e7c80f4cc6988e637e2ae447295deba4804d1c14744362a65c832fd78f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ebe67f9caf063192a95e5cbcec7501a83a23773be9e980de0a80bbb0e51495ee4a0455e58c54816ffc1ef09da04808f9ec5bd8f3fb9cd32554706833560b50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bbd3618cf39c0060fa6d356e7703e568a9be83d16ce2afdda9c5187e07c9e71df77a40df3472f2931abd337f4eb1ea05e142a6aa891379bf5659422da0a9351"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 04:50:05.252498) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 04:50:05.252832) ========= 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.eczEgq9c0W/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.KtpvlYY9Gg/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.005146 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 04:50:05.301781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 04:50:05.302113) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 04:50:05.302467) ========= ========= Total master_lag: 0.54 =========