builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0379 starttime: 1495757736.03 results: success (0) buildid: 20170525155044 builduid: a4f8c8479bb5416591901e9c8e6543a0 revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.025960) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.026421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.026709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.052610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.053168) ========= 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.hxEhgaCABu/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.fNleFZnoAV/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 17:15:36-- 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% 164M=0s 2017-05-25 17:15:36 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144184 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.220679) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.221012) ========= 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.hxEhgaCABu/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.fNleFZnoAV/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.026072 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:36.269042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 17:15:36.269401) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 51c849c595a0b5428d1d56e4ae668653437283e2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 51c849c595a0b5428d1d56e4ae668653437283e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hxEhgaCABu/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.fNleFZnoAV/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 17:15:36,390 truncating revision to first 12 chars 2017-05-25 17:15:36,391 Setting DEBUG logging. 2017-05-25 17:15:36,391 attempt 1/10 2017-05-25 17:15:36,391 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/51c849c595a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 17:15:37,839 unpacking tar archive at: mozilla-beta-51c849c595a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.730487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 17:15:38.022462) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:38.022820) ========= script_repo_revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:38.023221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:38.023553) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:15:38.042700) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-25 17:15:38.043118) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hxEhgaCABu/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.fNleFZnoAV/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:15:38 INFO - MultiFileLogger online at 20170525 17:15:38 in /builds/slave/test 17:15:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:15:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:15:38 INFO - {'append_to_log': False, 17:15:38 INFO - 'base_work_dir': '/builds/slave/test', 17:15:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:15:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:15:38 INFO - 'branch': 'Mozilla-Beta', 17:15:38 INFO - 'buildbot_json_path': 'buildprops.json', 17:15:38 INFO - 'config_files': ('talos/mac_config.py',), 17:15:38 INFO - 'default_actions': ('clobber', 17:15:38 INFO - 'read-buildbot-config', 17:15:38 INFO - 'download-and-extract', 17:15:38 INFO - 'populate-webroot', 17:15:38 INFO - 'create-virtualenv', 17:15:38 INFO - 'install', 17:15:38 INFO - 'run-tests'), 17:15:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:15:38 INFO - 'download_minidump_stackwalk': True, 17:15:38 INFO - 'download_symbols': 'ondemand', 17:15:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:15:38 INFO - 'tooltool.py': '/tools/tooltool.py', 17:15:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:15:38 INFO - '/tools/misc-python/virtualenv.py')}, 17:15:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:15:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:15:38 INFO - 'gecko_profile': False, 17:15:38 INFO - 'gecko_profile_interval': 0, 17:15:38 INFO - 'installer_path': 'installer.exe', 17:15:38 INFO - 'log_level': 'info', 17:15:38 INFO - 'log_name': 'talos', 17:15:38 INFO - 'log_to_console': True, 17:15:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:15:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:15:38 INFO - 'opt_config_files': (), 17:15:38 INFO - 'pip_index': False, 17:15:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:38 INFO - 'cmd': ('bash', 17:15:38 INFO - '-c', 17:15:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:38 INFO - 'enabled': True, 17:15:38 INFO - 'halt_on_failure': False, 17:15:38 INFO - 'name': 'check_screen_resolution'},), 17:15:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:38 INFO - 'cmd': ('bash', 17:15:38 INFO - '-c', 17:15:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:38 INFO - 'enabled': True, 17:15:38 INFO - 'halt_on_failure': False, 17:15:38 INFO - 'name': 'check_screen_resolution'},), 17:15:38 INFO - 'run_cmd_checks_enabled': True, 17:15:38 INFO - 'suite': 'dromaeojs', 17:15:38 INFO - 'system_bits': '32', 17:15:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:15:38 INFO - 'title': 't-yosemite-r7-0379', 17:15:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:15:38 INFO - 'use_talos_json': True, 17:15:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:15:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:15:38 INFO - 'work_dir': 'build'} 17:15:38 INFO - [mozharness: 2017-05-26 00:15:38.269646Z] Running clobber step. 17:15:38 INFO - Running pre-action listener: _resource_record_pre_action 17:15:38 INFO - Running main action method: clobber 17:15:38 INFO - rmtree: /builds/slave/test/build 17:15:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:15:40 INFO - Running post-action listener: _resource_record_post_action 17:15:40 INFO - [mozharness: 2017-05-26 00:15:40.721842Z] Finished clobber step (success) 17:15:40 INFO - [mozharness: 2017-05-26 00:15:40.721976Z] Running read-buildbot-config step. 17:15:40 INFO - Running pre-action listener: _resource_record_pre_action 17:15:40 INFO - Running main action method: read_buildbot_config 17:15:40 INFO - Using buildbot properties: 17:15:40 INFO - { 17:15:40 INFO - "project": "", 17:15:40 INFO - "product": "firefox", 17:15:40 INFO - "script_repo_revision": "production", 17:15:40 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 17:15:40 INFO - "repository": "", 17:15:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 17:15:40 INFO - "buildid": "20170525155044", 17:15:40 INFO - "pgo_build": "False", 17:15:40 INFO - "basedir": "/builds/slave/test", 17:15:40 INFO - "buildnumber": 16, 17:15:40 INFO - "slavename": "t-yosemite-r7-0379", 17:15:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:15:40 INFO - "platform": "yosemite_r7", 17:15:40 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:15:40 INFO - "branch": "mozilla-beta", 17:15:40 INFO - "repo_path": "releases/mozilla-beta", 17:15:40 INFO - "revision": "51c849c595a0b5428d1d56e4ae668653437283e2", 17:15:40 INFO - "stage_platform": "macosx64", 17:15:40 INFO - "builduid": "a4f8c8479bb5416591901e9c8e6543a0", 17:15:40 INFO - "slavebuilddir": "test" 17:15:40 INFO - } 17:15:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:15:40 INFO - Running post-action listener: _resource_record_post_action 17:15:40 INFO - [mozharness: 2017-05-26 00:15:40.725153Z] Finished read-buildbot-config step (success) 17:15:40 INFO - [mozharness: 2017-05-26 00:15:40.725258Z] Running download-and-extract step. 17:15:40 INFO - Running pre-action listener: _resource_record_pre_action 17:15:40 INFO - Running main action method: download_and_extract 17:15:40 INFO - mkdir: /builds/slave/test/build/tests 17:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:15:40 INFO - trying https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:15:40 INFO - Downloading https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:15:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 17:15:41 INFO - Downloaded 1287 bytes. 17:15:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:15:41 INFO - Using the following test package requirements: 17:15:41 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:15:41 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:15:41 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:15:41 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:15:41 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:15:41 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:15:41 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:15:41 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:15:41 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:15:41 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:15:41 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:15:41 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:15:41 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:15:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:15:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:15:41 INFO - Fetch https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:15:43 INFO - Expected file size: 51393282 17:15:43 INFO - Obtained file size: 51393282 17:15:59 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:15:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:15:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:15:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:16:01 INFO - Expected file size: 51393282 17:16:01 INFO - Obtained file size: 51393282 17:16:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:16:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:16:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:16:18 INFO - Expected file size: 14404449 17:16:18 INFO - Obtained file size: 14404449 17:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:18 INFO - trying https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:18 INFO - Downloading https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 17:16:20 INFO - Downloaded 59648164 bytes. 17:16:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:20 INFO - mkdir: /builds/slave/test/properties 17:16:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:20 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:20 INFO - Contents: 17:16:20 INFO - build_url:https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:20 INFO - Running post-action listener: _resource_record_post_action 17:16:20 INFO - Running post-action listener: set_extra_try_arguments 17:16:20 INFO - [mozharness: 2017-05-26 00:16:20.795613Z] Finished download-and-extract step (success) 17:16:20 INFO - [mozharness: 2017-05-26 00:16:20.795789Z] Running populate-webroot step. 17:16:20 INFO - Running pre-action listener: _resource_record_pre_action 17:16:20 INFO - Running main action method: populate_webroot 17:16:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:20 INFO - '--tppagecycles', 17:16:20 INFO - '7'], 17:16:20 INFO - 'tests': ['tsvgm']}}, 17:16:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['damp', 'tps']}, 17:16:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'tests': ['damp', 'tps']}, 17:16:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['dromaeo_dom']}, 17:16:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['basic_compositor_video']}, 17:16:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:16:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['a11yr', 17:16:20 INFO - 'ts_paint', 17:16:20 INFO - 'tpaint', 17:16:20 INFO - 'sessionrestore', 17:16:20 INFO - 'sessionrestore_no_auto_restore', 17:16:20 INFO - 'tabpaint']}, 17:16:20 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:20 INFO - 'ts_paint', 17:16:20 INFO - 'tpaint', 17:16:20 INFO - 'sessionrestore', 17:16:20 INFO - 'sessionrestore_no_auto_restore', 17:16:20 INFO - 'tabpaint']}, 17:16:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:20 INFO - 'ts_paint', 17:16:20 INFO - 'tpaint', 17:16:20 INFO - 'sessionrestore', 17:16:20 INFO - 'sessionrestore_no_auto_restore', 17:16:20 INFO - 'tabpaint']}, 17:16:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:20 INFO - 'ts_paint', 17:16:20 INFO - 'tpaint', 17:16:20 INFO - 'sessionrestore', 17:16:20 INFO - 'sessionrestore_no_auto_restore', 17:16:20 INFO - 'tabpaint']}, 17:16:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['a11yr', 17:16:20 INFO - 'ts_paint', 17:16:20 INFO - 'tpaint', 17:16:20 INFO - 'sessionrestore', 17:16:20 INFO - 'sessionrestore_no_auto_restore', 17:16:20 INFO - 'tabpaint']}, 17:16:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['a11yr', 17:16:20 INFO - 'ts_paint', 17:16:20 INFO - 'tpaint', 17:16:20 INFO - 'sessionrestore', 17:16:20 INFO - 'sessionrestore_no_auto_restore', 17:16:20 INFO - 'tabpaint']}, 17:16:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['tsvgx', 17:16:20 INFO - 'tsvgr_opacity', 17:16:20 INFO - 'tart', 17:16:20 INFO - 'tscrollx', 17:16:20 INFO - 'cart', 17:16:20 INFO - 'tsvg_static']}, 17:16:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:20 INFO - 'tsvgr_opacity', 17:16:20 INFO - 'tart', 17:16:20 INFO - 'tscrollx', 17:16:20 INFO - 'cart', 17:16:20 INFO - 'tsvg_static']}, 17:16:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'talos_options': ['--disable-e10s'], 17:16:20 INFO - 'tests': ['tp5o']}, 17:16:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:16:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'talos_options': ['--disable-e10s', 17:16:20 INFO - '--xperf_path', 17:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:20 INFO - 'tests': ['tp5n']}, 17:16:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:16:20 INFO - 'talos_options': ['--xperf_path', 17:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:20 INFO - 'tests': ['tp5n']}}, 17:16:20 INFO - 'talos.zip': {'path': '', 17:16:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:20 INFO - Running post-action listener: _resource_record_post_action 17:16:20 INFO - [mozharness: 2017-05-26 00:16:20.815889Z] Finished populate-webroot step (success) 17:16:20 INFO - [mozharness: 2017-05-26 00:16:20.816024Z] Running create-virtualenv step. 17:16:20 INFO - Running pre-action listener: _resource_record_pre_action 17:16:20 INFO - Running main action method: create_virtualenv 17:16:20 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:20 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 17:16:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:20 INFO - Using real prefix '/tools/python27' 17:16:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:24 INFO - Installing pip.................done. 17:16:24 INFO - Return code: 0 17:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:16:24 INFO - Reading from file tmpfile_stdout 17:16:24 INFO - Output received: 17:16:24 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) 17:16:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:24 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:24 INFO - 'HOME': '/Users/cltbld', 17:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:24 INFO - 'LOGNAME': 'cltbld', 17:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:24 INFO - 'MOZ_NO_REMOTE': '1', 17:16:24 INFO - 'NO_EM_RESTART': '1', 17:16:24 INFO - 'PAGER': '/bin/cat', 17:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:24 INFO - 'PWD': '/builds/slave/test', 17:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:24 INFO - 'SHELL': '/bin/bash', 17:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:24 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:24 INFO - 'USER': 'cltbld', 17:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:24 INFO - 'XPC_FLAGS': '0x0', 17:16:24 INFO - 'XPC_SERVICE_NAME': '0', 17:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 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 17:16:25 INFO - Cleaning up... 17:16:25 INFO - Return code: 0 17:16:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:25 INFO - 'HOME': '/Users/cltbld', 17:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:25 INFO - 'LOGNAME': 'cltbld', 17:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:25 INFO - 'MOZ_NO_REMOTE': '1', 17:16:25 INFO - 'NO_EM_RESTART': '1', 17:16:25 INFO - 'PAGER': '/bin/cat', 17:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:25 INFO - 'PWD': '/builds/slave/test', 17:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:25 INFO - 'SHELL': '/bin/bash', 17:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:25 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:25 INFO - 'USER': 'cltbld', 17:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:25 INFO - 'XPC_FLAGS': '0x0', 17:16:25 INFO - 'XPC_SERVICE_NAME': '0', 17:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 INFO - Downloading/unpacking psutil>=3.1.1 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:27 INFO - Installing collected packages: psutil 17:16:27 INFO - Running setup.py install for psutil 17:16:27 INFO - building 'psutil._psutil_osx' extension 17:16:27 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 17:16:27 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 17:16:27 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 17:16:28 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 17:16:28 INFO - building 'psutil._psutil_posix' extension 17:16:28 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 17:16:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:16:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:16:28 INFO - ^ 17:16:28 INFO - 1 warning generated. 17:16:28 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 17:16:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:28 INFO - Successfully installed psutil 17:16:28 INFO - Cleaning up... 17:16:28 INFO - Return code: 0 17:16:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:28 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:28 INFO - 'HOME': '/Users/cltbld', 17:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:28 INFO - 'LOGNAME': 'cltbld', 17:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:28 INFO - 'MOZ_NO_REMOTE': '1', 17:16:28 INFO - 'NO_EM_RESTART': '1', 17:16:28 INFO - 'PAGER': '/bin/cat', 17:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:28 INFO - 'PWD': '/builds/slave/test', 17:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:28 INFO - 'SHELL': '/bin/bash', 17:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:28 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:28 INFO - 'USER': 'cltbld', 17:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:28 INFO - 'XPC_FLAGS': '0x0', 17:16:28 INFO - 'XPC_SERVICE_NAME': '0', 17:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:30 INFO - Installing collected packages: mozsystemmonitor 17:16:30 INFO - Running setup.py install for mozsystemmonitor 17:16:30 INFO - Successfully installed mozsystemmonitor 17:16:30 INFO - Cleaning up... 17:16:30 INFO - Return code: 0 17:16:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:30 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:16:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:30 INFO - 'HOME': '/Users/cltbld', 17:16:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:30 INFO - 'LOGNAME': 'cltbld', 17:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:30 INFO - 'MOZ_NO_REMOTE': '1', 17:16:30 INFO - 'NO_EM_RESTART': '1', 17:16:30 INFO - 'PAGER': '/bin/cat', 17:16:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:30 INFO - 'PWD': '/builds/slave/test', 17:16:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:30 INFO - 'SHELL': '/bin/bash', 17:16:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:30 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:30 INFO - 'USER': 'cltbld', 17:16:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:30 INFO - 'XPC_FLAGS': '0x0', 17:16:30 INFO - 'XPC_SERVICE_NAME': '0', 17:16:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:30 INFO - Downloading/unpacking jsonschema==2.5.1 17:16:30 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 17:16:30 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 17:16:30 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 17:16:30 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 17:16:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:31 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 17:16:31 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 17:16:31 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 17:16:31 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 17:16:32 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:32 INFO - warning: no files found matching '*.txt' 17:16:32 INFO - no previously-included directories found matching 'build' 17:16:32 INFO - no previously-included directories found matching 'dist' 17:16:32 INFO - no previously-included directories found matching '.git*' 17:16:32 INFO - Installing collected packages: jsonschema, functools32 17:16:32 INFO - Running setup.py install for functools32 17:16:32 INFO - warning: no files found matching '*.txt' 17:16:32 INFO - no previously-included directories found matching 'build' 17:16:32 INFO - no previously-included directories found matching 'dist' 17:16:32 INFO - no previously-included directories found matching '.git*' 17:16:32 INFO - Successfully installed jsonschema functools32 17:16:32 INFO - Cleaning up... 17:16:32 INFO - Return code: 0 17:16:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:33 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:33 INFO - 'HOME': '/Users/cltbld', 17:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:33 INFO - 'LOGNAME': 'cltbld', 17:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:33 INFO - 'MOZ_NO_REMOTE': '1', 17:16:33 INFO - 'NO_EM_RESTART': '1', 17:16:33 INFO - 'PAGER': '/bin/cat', 17:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:33 INFO - 'PWD': '/builds/slave/test', 17:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:33 INFO - 'SHELL': '/bin/bash', 17:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:33 INFO - 'USER': 'cltbld', 17:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:33 INFO - 'XPC_FLAGS': '0x0', 17:16:33 INFO - 'XPC_SERVICE_NAME': '0', 17:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:33 INFO - Cleaning up... 17:16:33 INFO - Return code: 0 17:16:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:34 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:34 INFO - 'HOME': '/Users/cltbld', 17:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:34 INFO - 'LOGNAME': 'cltbld', 17:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:34 INFO - 'MOZ_NO_REMOTE': '1', 17:16:34 INFO - 'NO_EM_RESTART': '1', 17:16:34 INFO - 'PAGER': '/bin/cat', 17:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:34 INFO - 'PWD': '/builds/slave/test', 17:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:34 INFO - 'SHELL': '/bin/bash', 17:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:34 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:34 INFO - 'USER': 'cltbld', 17:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:34 INFO - 'XPC_FLAGS': '0x0', 17:16:34 INFO - 'XPC_SERVICE_NAME': '0', 17:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:34 INFO - Downloading/unpacking blobuploader==1.2.4 17:16:34 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 17:16:34 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 17:16:34 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 17:16:34 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 17:16:36 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:37 INFO - Downloading docopt-0.6.1.tar.gz 17:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:37 INFO - Running setup.py install for blobuploader 17:16:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:37 INFO - Running setup.py install for requests 17:16:37 INFO - Running setup.py install for docopt 17:16:38 INFO - Successfully installed blobuploader requests docopt 17:16:38 INFO - Cleaning up... 17:16:38 INFO - Return code: 0 17:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:38 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:38 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:16:38 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:16:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:38 INFO - 'HOME': '/Users/cltbld', 17:16:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:38 INFO - 'LOGNAME': 'cltbld', 17:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:38 INFO - 'MOZ_NO_REMOTE': '1', 17:16:38 INFO - 'NO_EM_RESTART': '1', 17:16:38 INFO - 'PAGER': '/bin/cat', 17:16:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:38 INFO - 'PWD': '/builds/slave/test', 17:16:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:38 INFO - 'SHELL': '/bin/bash', 17:16:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:38 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:38 INFO - 'USER': 'cltbld', 17:16:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:38 INFO - 'XPC_FLAGS': '0x0', 17:16:38 INFO - 'XPC_SERVICE_NAME': '0', 17:16:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-aiFN8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-IRBmf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-y0G6eK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-K5MZvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-PU5RTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-GRT06z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:38 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-gykRXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-wH_gak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-sHHub7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-OtxkGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-z3a4Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-pD36CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vUkN9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-iKiQhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-io0y5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:39 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-r2WlT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:40 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-HebXWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:40 INFO - Running setup.py install for manifestparser 17:16:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for mozcrash 17:16:40 INFO - Running setup.py install for mozdebug 17:16:40 INFO - Running setup.py install for mozdevice 17:16:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:40 INFO - Running setup.py install for mozfile 17:16:40 INFO - Running setup.py install for mozhttpd 17:16:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for mozinfo 17:16:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for mozInstall 17:16:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for mozleak 17:16:41 INFO - Running setup.py install for mozlog 17:16:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for moznetwork 17:16:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:41 INFO - Running setup.py install for mozprocess 17:16:41 INFO - Running setup.py install for mozprofile 17:16:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:42 INFO - Running setup.py install for mozrunner 17:16:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:42 INFO - Running setup.py install for mozscreenshot 17:16:42 INFO - Running setup.py install for moztest 17:16:42 INFO - Running setup.py install for mozversion 17:16:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:42 INFO - Cleaning up... 17:16:42 INFO - Return code: 0 17:16:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:42 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:42 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:16:42 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:42 INFO - 'HOME': '/Users/cltbld', 17:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:42 INFO - 'LOGNAME': 'cltbld', 17:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:42 INFO - 'MOZ_NO_REMOTE': '1', 17:16:42 INFO - 'NO_EM_RESTART': '1', 17:16:42 INFO - 'PAGER': '/bin/cat', 17:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:42 INFO - 'PWD': '/builds/slave/test', 17:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:42 INFO - 'SHELL': '/bin/bash', 17:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:42 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:42 INFO - 'USER': 'cltbld', 17:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:42 INFO - 'XPC_FLAGS': '0x0', 17:16:42 INFO - 'XPC_SERVICE_NAME': '0', 17:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:42 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-f9SUxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-PFt9fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-DCB4I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-eypiDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0KJgSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-NVRFe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-YZWm8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Ek_5yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-s_YqcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:43 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)) 17:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:43 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Ttw7eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:44 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)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-yqCC6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:44 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)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-SCLqiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-XEuH7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:44 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)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ZfaNC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:44 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)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-fo50DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:44 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)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-rLxnrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:44 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)) 17:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:44 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5ApS6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 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)) 17:16:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:44 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 17:16:44 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 17:16:44 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 17:16:44 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 17:16:46 INFO - Downloading blessings-1.6.tar.gz 17:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:16:46 INFO - Installing collected packages: blessings 17:16:46 INFO - Running setup.py install for blessings 17:16:46 INFO - Successfully installed blessings 17:16:46 INFO - Cleaning up... 17:16:46 INFO - Return code: 0 17:16:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:16:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:46 INFO - Reading from file tmpfile_stdout 17:16:46 INFO - Current package versions: 17:16:46 INFO - blessings == 1.6 17:16:46 INFO - blobuploader == 1.2.4 17:16:46 INFO - docopt == 0.6.1 17:16:46 INFO - functools32 == 3.2.3-2 17:16:46 INFO - jsonschema == 2.5.1 17:16:46 INFO - manifestparser == 1.1 17:16:46 INFO - mozInstall == 1.12 17:16:46 INFO - mozcrash == 1.0 17:16:46 INFO - mozdebug == 0.1 17:16:46 INFO - mozdevice == 0.49 17:16:46 INFO - mozfile == 1.2 17:16:46 INFO - mozhttpd == 0.7 17:16:46 INFO - mozinfo == 0.9 17:16:46 INFO - mozleak == 0.1 17:16:46 INFO - mozlog == 3.4 17:16:46 INFO - moznetwork == 0.27 17:16:46 INFO - mozprocess == 0.25 17:16:46 INFO - mozprofile == 0.28 17:16:46 INFO - mozrunner == 6.13 17:16:46 INFO - mozscreenshot == 0.1 17:16:46 INFO - mozsystemmonitor == 0.3 17:16:46 INFO - moztest == 0.8 17:16:46 INFO - mozversion == 1.4 17:16:46 INFO - psutil == 3.1.1 17:16:46 INFO - requests == 1.2.3 17:16:46 INFO - wsgiref == 0.1.2 17:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:46 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:46 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:16:46 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:46 INFO - 'HOME': '/Users/cltbld', 17:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:46 INFO - 'LOGNAME': 'cltbld', 17:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:46 INFO - 'MOZ_NO_REMOTE': '1', 17:16:46 INFO - 'NO_EM_RESTART': '1', 17:16:46 INFO - 'PAGER': '/bin/cat', 17:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:46 INFO - 'PWD': '/builds/slave/test', 17:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:46 INFO - 'SHELL': '/bin/bash', 17:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:46 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:46 INFO - 'USER': 'cltbld', 17:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:46 INFO - 'XPC_FLAGS': '0x0', 17:16:46 INFO - 'XPC_SERVICE_NAME': '0', 17:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 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)) 17:16:47 INFO - Cleaning up... 17:16:47 INFO - Return code: 0 17:16:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:47 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/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 0x1050d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d88510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, '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.hxEhgaCABu/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.fNleFZnoAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:47 INFO - 'HOME': '/Users/cltbld', 17:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:47 INFO - 'LOGNAME': 'cltbld', 17:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:47 INFO - 'MOZ_NO_REMOTE': '1', 17:16:47 INFO - 'NO_EM_RESTART': '1', 17:16:47 INFO - 'PAGER': '/bin/cat', 17:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:47 INFO - 'PWD': '/builds/slave/test', 17:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:47 INFO - 'SHELL': '/bin/bash', 17:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:16:47 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:47 INFO - 'USER': 'cltbld', 17:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:47 INFO - 'XPC_FLAGS': '0x0', 17:16:47 INFO - 'XPC_SERVICE_NAME': '0', 17:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:16:47 INFO - Cleaning up... 17:16:47 INFO - Return code: 0 17:16:47 INFO - Running post-action listener: _resource_record_post_action 17:16:47 INFO - Running post-action listener: _start_resource_monitoring 17:16:47 INFO - Starting resource monitoring. 17:16:47 INFO - [mozharness: 2017-05-26 00:16:47.351999Z] Finished create-virtualenv step (success) 17:16:47 INFO - [mozharness: 2017-05-26 00:16:47.352932Z] Running install step. 17:16:47 INFO - Running pre-action listener: _resource_record_pre_action 17:16:47 INFO - Running main action method: install 17:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:47 INFO - Reading from file tmpfile_stdout 17:16:47 INFO - Detecting whether we're running mozinstall >=1.0... 17:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:16:47 INFO - Reading from file tmpfile_stdout 17:16:47 INFO - Output received: 17:16:47 INFO - Usage: mozinstall [options] installer 17:16:47 INFO - Options: 17:16:47 INFO - -h, --help show this help message and exit 17:16:47 INFO - -d DEST, --destination=DEST 17:16:47 INFO - Directory to install application into. [default: 17:16:47 INFO - "/builds/slave/test"] 17:16:47 INFO - --app=APP Application being installed. [default: firefox] 17:16:47 INFO - mkdir: /builds/slave/test/build/application 17:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:17:02 INFO - Reading from file tmpfile_stdout 17:17:02 INFO - Output received: 17:17:02 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:17:02 INFO - Running post-action listener: _resource_record_post_action 17:17:02 INFO - [mozharness: 2017-05-26 00:17:02.411672Z] Finished install step (success) 17:17:02 INFO - [mozharness: 2017-05-26 00:17:02.411792Z] Running run-tests step. 17:17:02 INFO - Running pre-action listener: _resource_record_pre_action 17:17:02 INFO - Running main action method: run_tests 17:17:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:02 INFO - 2017-05-25 17:17:02.425 screenresolution[1623:7223] starting screenresolution argv=screenresolution get 17:17:02 INFO - 2017-05-25 17:17:02.427 screenresolution[1623:7223] Display 0: 1600x1200x32@60 17:17:02 INFO - 2017-05-25 17:17:02.432 screenresolution[1624:7225] starting screenresolution argv=screenresolution list 17:17:02 INFO - Available Modes on Display 0 17:17:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:02 INFO - Intel Iris: 17:17:02 INFO - Chipset Model: Intel Iris 17:17:02 INFO - Type: GPU 17:17:02 INFO - Bus: Built-In 17:17:02 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:02 INFO - Vendor: Intel (0x8086) 17:17:02 INFO - Device ID: 0x0a2e 17:17:02 INFO - Revision ID: 0x0009 17:17:02 INFO - Displays: 17:17:02 INFO - Display: 17:17:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:02 INFO - Display Serial Number: 1600x1200 60 17:17:02 INFO - Main Display: Yes 17:17:02 INFO - Mirror: Off 17:17:02 INFO - Online: Yes 17:17:02 INFO - Rotation: Supported 17:17:02 INFO - Return code: 0 17:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:02 INFO - Python 2.7.3 17:17:02 INFO - Return code: 0 17:17:02 INFO - grabbing minidump binary from tooltool 17:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:02 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 0x104eaed30>, '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 0x104dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ea3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:02 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 17:17:02 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 17:17:02 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 17:17:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:02 INFO - Return code: 0 17:17:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:17:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:02 INFO - ENV: RUST_BACKTRACE is now 1 17:17:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:17:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 17:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --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 17:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hxEhgaCABu/Render', 17:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:02 INFO - 'HOME': '/Users/cltbld', 17:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:02 INFO - 'LOGNAME': 'cltbld', 17:17:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:02 INFO - 'MOZ_NO_REMOTE': '1', 17:17:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:02 INFO - 'NO_EM_RESTART': '1', 17:17:02 INFO - 'PAGER': '/bin/cat', 17:17:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:02 INFO - 'PWD': '/builds/slave/test', 17:17:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:02 INFO - 'RUST_BACKTRACE': '1', 17:17:02 INFO - 'SHELL': '/bin/bash', 17:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNleFZnoAV/Listeners', 17:17:02 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:02 INFO - 'USER': 'cltbld', 17:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:02 INFO - 'XPC_FLAGS': '0x0', 17:17:02 INFO - 'XPC_SERVICE_NAME': '0', 17:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 17:17:03 INFO - mozversion application_buildid: 20170525155044 17:17:03 INFO - mozversion application_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:17:03 INFO - mozversion application_display_name: Firefox 17:17:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:03 INFO - mozversion application_name: Firefox 17:17:03 INFO - mozversion application_remotingname: firefox 17:17:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:03 INFO - mozversion application_vendor: Mozilla 17:17:03 INFO - mozversion application_version: 54.0 17:17:03 INFO - mozversion platform_buildid: 20170525155044 17:17:03 INFO - mozversion platform_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:17:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:03 INFO - mozversion platform_version: 54.0 17:17:03 INFO - using testdate: 1495757823 17:17:03 INFO - actual date: 1495757823 17:17:03 INFO - starting webserver on 'localhost:49220' 17:17:03 INFO - SUITE-START | Running 2 tests 17:17:03 INFO - TEST-START | dromaeo_css 17:17:03 INFO - operating with platform_type : mac_ 17:17:03 INFO - Initialising browser for dromaeo_css test... 17:17:03 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpSDt2HY/profile http://localhost:49220/getInfo.html) 17:17:05 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 17:17:05 INFO - PROCESS | 1634 | colorDepth:24 17:17:05 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 17:17:05 INFO - PROCESS | 1634 | __metrics 17:17:05 INFO - PROCESS | 1634 | 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" 17:17:05 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:17:06 INFO - TEST-INFO | 1634: exit 0 17:17:06 INFO - Browser initialized. 17:17:06 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:06 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpSDt2HY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:18 INFO - PROCESS | 1637 | RSS: Main: 165486592 17:17:18 INFO - PROCESS | 1637 | 17:20:22 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49220/tests/dromaeo/cssquery-ext.html) 17:20:24 INFO - PROCESS | 1637 | RSS: Main: 299610112 17:20:24 INFO - PROCESS | 1637 | 17:23:27 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-ext.html (next: http://localhost:49220/tests/dromaeo/cssquery-jquery.html) 17:23:27 INFO - PROCESS | 1637 | RSS: Main: 302567424 17:23:27 INFO - PROCESS | 1637 | 17:26:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49220/tests/dromaeo/cssquery-mootools.html) 17:26:30 INFO - PROCESS | 1637 | RSS: Main: 283508736 17:26:30 INFO - PROCESS | 1637 | 17:29:33 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49220/tests/dromaeo/cssquery-prototype.html) 17:29:33 INFO - PROCESS | 1637 | RSS: Main: 271867904 17:29:33 INFO - PROCESS | 1637 | 17:32:37 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49220/tests/dromaeo/cssquery-yui.html) 17:32:37 INFO - PROCESS | 1637 | RSS: Main: 276774912 17:32:37 INFO - PROCESS | 1637 | 17:35:40 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-yui.html (next: http://localhost:49220/tests/dromaeo/cssquery-dojo.html) 17:35:41 INFO - PROCESS | 1637 | RSS: Main: 448729088 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | __start_tp_report 17:35:41 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 17:35:41 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 17:35:41 INFO - PROCESS | 1637 | |i|pagename|runs| 17:35:41 INFO - PROCESS | 1637 | |0;dojo.html;3522.1546828761097;3527.0124365177758;3534.611192768795;3534.8055856927876;3586.426171812509;4628.861134165972;5828.921649494847;6094.17728606638;6119.4186552277515;6148.139260503527;6030.66831324277;6111.877762444748;6116.6024208426425;6117.969410152943;6123.4182752638535;5397.028534863733;5406.486383793534;5438.15708565173;5470.890582188354;5538.169274608821;5341.946580534183;5364.249005139283;5420.159875219348;5548.4174161713045;5551.750171242284;5440.374356948962;5469.316335458067;5469.699166545838;5474.562035037207;5489.615726899118;5455.918161227585;5461.726913654302;5481.725913704338;5531.751071201806;5533.6403133796175;5360.437154098841;5484.040292948718;5484.7531861065945;5486.725663716798;5507.917381239285;6262.091996660459;6272.6236425814595;6285.182926219608;6292.402221788922;6296.401841825019;6322.557420980534;6343.333949935282;6346.174997250374;6396.26442959061;6407.359264073602;6339.175907132089;6369.585976911532;6417.647029413381;6433.002884552901;6461.6123032617725;6152.661603611799;6215.440610345091;6220.657863817533;6226.875462490725;6227.283862355796;6290.874182516415;6578.3092775258865;6595.901061484082;6597.47220222381;6643.269240383554;6265.028920517327;6292.150559674477;6296.937030629727;6307.621542707452;6340.1440805490665;6447.516436267205;6451.612903225821;6460.870782584322;6472.9029064564065;6479.092926990131;6317.305096439388;6341.6195028297525;6377;6391.392817682313;6403.775867844603;5558.777648893999;6273.341299163613;6292.779752708725;6339.429451349401;6350.7459701611415;5097.490250974873;5106.66806657566;5187.740612969336;5213.191955246943;5282;4683.929741053887;4695.943412732108;4703.60019398355;4704.200285951397;4705.5059218781535;6083.939160608337;6115.1744514490865;6139.416755408229;6194.628322300677;6210.658413787195;5421.159720243369;5449.373322067933;6269.122322874801;6297.4962003039645;6489;6174.938250617436;6212.658303793335;6212.9378706213265;6213.751449941951;6225.657588832658;5725.570582206351;5728.914066289009;5741.770329186786;5758.452946970031;5778.797742079007;5491.7254137292175;6386.361363863576;6432.614043157426;6766.763163289262;6771.695273712793;6417.358264173546;6452;6453.290138084901;6457.935420645921;6476.935230647633;6708.228553716385;6756.29058948823;6770.221424536241;6771.119754431892;6775.661216939035;6306.527010474141;6359.332270111571;6435.646039467874;6444.420002199825;6454.451371633464;6247.375262473716;6394.776182833579;6429.517786165963;6456;6468.126802881551;6327.746890124344;6345.302016778243;6356.936430635819;6358.618482891041;6362.236531616236;6132.080177995836;6305.90541141874;6312.495000400048;6346.904796427965;6372.904406433814;6109.84725381869;6267.122602835698;6314.210723659543;6321.589096708699;6343.777967771106;6331.810045698637;6339.5562310637815;6343.238811342669;6346.492280617447;6349.904751428639;6139.386061393825;6145.463634091477;6154.169187159676;6378.681065946591;6406.199225096863 17:35:41 INFO - PROCESS | 1637 | |1;ext.html;813.5078277642026;817.6933649881295;831.7089018843405;904.2178515584022;911.4941207629771;28158.718412815866;39340;39578.406323905096;39589.40615890764;39633.60366396339;40417.59582404175;40673;40775.572854950085;40806.59193408065;41130;7843.607819609026;9435.150836424738;9598.42409455435;9600.279979001569;9611.231101511898;7858.057033156029;11296.717582060432;11512;11661.125415593822;11704.53181872724;11514.42427878607;11524.942375288112;11540.538378464851;11568.363739994296;11579.826302605468;11457.656270311903;11496.252743571642;11506.654800356002;11525.250858694159;11542.826857597143;11188.832167517494;11238;11264.66206013821;11265.549378024869;11271.041961433288;38669;38969.61030389688;39038;39054;39082.413763793564;3931.154801717616;3976.7216294859372;4007.7595344279434;4010.9197816043807;4072.8370865165357;27663.446731065265;29177.56233656497;29770.255743606365;29945.401091978256;29947.25131871699;3063.3720087382035;3265.7713960022807;3268.7221586165206;3364.7812892162174;3393.1347506385714;358155.2092239548;358159;358468;358532;359016;42041;45375;51660.48339516632;51811;51853.481465184865;51800.74099629553;53502.19746703802;53543.92912141735;54668.80646338671;54723;15441;15537.45618903333;15553;15558.066516009076;15569.844301557067;11672.124590655565;11806.52773889052;13234.205947643171;13235.07354485196;13251.73496530064;10427.530761115768;11529.538818447336;11568.24806387582;11914.642560723192;11923.463444145034;4922.286268491121;4980.576650984634;5373.11343628297;5390;5396.379416367089;1147.0708725931854;1157.9710507237219;1164.7612239490884;1164.9242799218023;1225.3628113380992;25449.87275063613;26521.886124535424;26598.867005664848;26695.065672701752;26833.463330733277;1252.7557126360273;1258.1444617659897;1261.829652996852;1294.825198598196;1308.4177540994167;13301.73396532064;13668.52160174389;13829.446822127204;13866.584002479942;13957.441702331793;388986;390245;390854;391233;391493;53359.73320133452;54517;54517.454825452034;54552.636184094925;54686.45313546814;1458.3801884199386;1462.8829693624484;1514.712204681107;1515.1969456188237;1524.1083965879864;24652.753472465047;25681.22956311314;25848.8707556461;25992.480150396506;26007.60988585135;43797.34303985379;43810.34284485799;43986.68039958806;44121.77939110284;44126.33810492909;367618;368484;368771;368995;369440.30559694057;25720.871395642902;31943;32367.028989130366;32507.187320316236;32519.512207316435;32270.19324516906;32456.67543324631;37128.07179820418;42567.57432425636;45660.315095274265;30218.24454388658;30328.331941743418;30509;31057.378852423277;31292.687073129888;31056.378872421974;31088.067357979297;31287.84356078205;31450.52824207593;31603;32562.674373256912;33480.33039339151;34557.03277837649;34944.65055349516;34992.65007349894;26632.467350653267;27815.30461738473;28250.43499130047;29305;29439.822407103475;21461.89269053707;22844.885775571016;23080.884595576914;23447.17934872271;23879.283621491384 17:35:41 INFO - PROCESS | 1637 | |2;jquery.html;429.6047636174719;437.5995963693219;438.12812503118784;447.7425480348799;452.44801342362325;20084.096215670274;21140.365789026317;22307.888460557693;22330.441738956535;22534.88732556339;21968.560628787454;23596.528069438646;24108.75891241087;24201.39496512588;24374.75625243747;6734.3602357775935;6887.173539175294;6946.47901407394;7096.85806283874;7163.64181790911;6769.932300677004;6889.586624802503;6892.104026476576;6901.44788416928;6903.0680858084015;6886.414654754327;7525.209852965442;7814.648340824648;7982.960085199566;7988.680452781882;7692.923070769305;7836.647350869168;7926.6036698165435;7941.00737407824;7997.320227780617;7196.712131514733;7636.160022397532;7671.961640191819;7679.308862202372;7684.462087653867;25846.483070338676;26300.73699263002;26595.46809063827;27471.725282747117;27548.724512754816;2943.072932026405;2943.632045994251;3006.28831009107;3084.4447999360104;3178.141901686555;16263.024218546925;16282.75575866363;16362.100084495349;16435.342586296654;17624.295028198856;1880.7649043869517;1939.854510911673;1949.7855235924037;1965.2040923426036;1979.6832506798987;154727.45272547242;172896;173544.99999999875;175543;180034;20152;20343.593128137356;20353.185872564933;20434.591308173753;20480.487987800425;31918.840405797822;32392;32420.513692294633;32502;32569.348613027607;10464.529096190538;10534.894651053544;10743.301685390581;10766.900454411314;10921.726956825984;8997.730068097968;8999.550022498848;9035.232005279493;9179.127982841619;9189.862152067724;9354.391964522285;9464.763380915394;9484.766980292517;9613.134817866425;9656.37233579815;3031.2267323615492;3109.3314937288137;3285.408626447215;3917.7061720371084;3948.499570163377;817.3910436724678;827.1934863508031;844.8563744163507;862.008690006485;875.2472873328932;18142.378425818668;22641.320760377213;22665.773342266697;22773.202937897095;22913.770862291498;879.6437442835662;894.1103601916005;911.3939230411835;916.2624087609405;923.736735030513;10975;11847.442527162293;11925.70185745346;11958.342291173894;11982.460789264504;664044;685037;694900.9233840778;716146;729801;140215;144415;146718;151472;155815.44184558236;1239.1759479945915;1283.794592865137;1336.0981337597084;1339.8526162121964;1342.3422522963679;20261.898690506452;20475;20973.58052838965;21023.789762102184;21046.68429973582;65989;67820.66089669717;68489.3151068483;68811.3118868825;70600.64699676468;98153.50923245338;98822;100054;101490;101773.49113254386;11386.259893106922;11759.882401175879;11854.644360669194;12022.939885300517;12145.514179432723;12827.807582886451;13262.27057511846;13283.734325313633;13287.933560332136;13507.986900982662;9353.906460935576;9577;11888.643340699793;11979.580714674945;12058.51765929353;9561.808763824823;9581.185599223865;9587.18508926749;9677.951610241904;9800.65697700577;11691.181617286707;11774.764504709687;11800.70498237551;11975.161738678209;12149.817752733892;11731;11802.52789888395;12105.69735756613;12106.878931210575;12113.091518136;11391.544338226378;11400.600978965696;11489.310641361544;11783.528658853893;11841.703957401134 17:35:41 INFO - PROCESS | 1637 | |3;mootools.html;1604.2219523531094;1615.200475764496;1653.6031352475409;1661.5762980439986;1678.5719641491419;9627.759306017344;10876.075533579646;11581;11586.826197607032;11592;10327.535260913259;10781.353118812856;10849.945750271258;10867.673969780899;10890.564377424913;2456.275398757362;2496.2261698873317;2497.213377786001;2500.0249902538026;2503.5869081601513;2441.8779061046966;2461.0155937625004;2503.662005629244;2511.221521328385;2513.1329691256487;2460.2250291158316;2464.2853572464055;2482.453860150764;2502.8373155744907;2506.7618576235227;2410.9035638574437;2419.7822196002267;2441.6093425051995;2447.2046584859813;2474.344298760812;2037.3174986379581;2418.3712234819072;2446.082718980382;2453.8405003674884;2455.0057226823164;11381.829272560919;11432.599859004895;11448.96959273661;11470.369129697863;11470.369129697863;5618.101103823409;5784.566157538202;5786.363500014995;5808.215890854723;5812;3656.597774244831;3665.5234819473562;3742.5134732484876;3804.9809750951067;3855.0169706724605;1247.3700781105574;1248.6191711527981;1288.6585054960442;1305.1386085183733;1308.6335825968745;13802.65493362664;14155.221462819542;14595.927020364936;15518.689626207526;15726.449574264845;4080.3267460868624;4706.058788242365;4843.200871856175;4894.339264199359;4901.681390709592;10396.428196449117;12077.939610301892;12085;12089.03287736979;12124.757504849853;5059.595232381401;5846.269216347957;5985.880282394415;6061.151438798572;6068.787592434243;4423.756693381895;5461.972690136524;5462.289902312754;5559.054960656698;5573.359063707644;3253;3385.7291416686608;3943.191645712622;4015.0966032643005;4050.1697152083752;946.8117512521741;1024.206240163864;1061.1535927486118;1064.8136576099153;1079.1906070447164;801.7550857598817;818.5170749257967;839.8236370362171;855.1234984141305;866.994286627509;6250.687465626791;6494.188226471691;6719.160104986877;6769.93230067693;6821.942598897179;880.3441436130124;1047.7328281288223;1083.9566417343376;1090.9236353455183;1109.4064675398622;4228.302330115496;4294.806733696991;4297.720648157922;4427.977860110679;4429.645628349725;10916.617918372818;13978.86021139796;14015.719685606231;14016.509422170175;14016.71966560663;13540.119892206974;13703.314834258248;14070.2964851757;14114.012019158765;14124;9919.752006199902;10195.286329956833;10362.222833287533;10546.736331591772;10569.630062947914;6674.966625166843;6674.966625166843;6750.831229219309;6778.0510728497065;6886.931130688629;10319.174466042548;11272.605458808903;12389.380530973596;12562.120651554304;12574.748505030031;12391.318477483464;12568.748625027265;12689.87310126887;12780.936095319465;12831.294278814756;9381.718548443558;9382.95308523453;9591.184749296388;9602.279829012712;9607.759806004626;10421.895781042396;10440.477976101012;10538.683839484827;10562.366258024544;10562.630307939186;8917;9049.502277374544;9101.772455688395;9117.589708463267;9143.405678630868;8805.427647203167;8858.468491890508;8907.777305567413;8908.955455222942;8943.23982461498;8013.398995075509;8086.474379165335;8158.102608713156;8169.754907352789;8177.018757749108;5660.801871934627;5749;5825.0388685866355;5875.177475153397;5883.058710606282;5851.391956699116;5905.940940590539;5906.763729450946;6164.7842325518395;6219.004959206505 17:35:41 INFO - PROCESS | 1637 | |4;prototype.html;325.9185203699075;385.9594742552032;394.5009562902927;397.7514053716427;400.1429791290188;4345.826166953322;5383.258635248504;5388.763663079822;5424.538914192299;5462.562994960404;4160.131078097368;4758.738269395182;5004.624653151019;5193.610479214065;5359.7320133993335;3866.67133293669;3896.0065932419284;3899.493065901429;4061.451704019964;4119.794010299488;3727.3477141500266;3778.433235014756;4341.004706752331;4382.802773875183;4445.222086134913;3987.9721481303945;4208.957910420902;4387.868363949086;4397.406350142722;4411.911761764763;4087.754734715897;4192.295969099929;4267.423897773808;4309.82760689572;4345.391645169679;3685.7836913818373;3760.849566017356;4023.879283621496;4028.8589899353674;4125.1749650070105;5237.842864714026;5309.33633295838;5313.814016509404;5338.359396872362;5344.675066346152;4567.931481027787;4767.880802979918;4886.535779101016;5156.871078223037;5160.78086422838;4596.678232523725;5585.8603534911545;5612.66324020556;5647.181158732002;5666.54667626593;4208.579142085798;4462.687611867171;4495.314257153567;4525.171767645222;4547.15877562652;5484;5598.500936914428;6028.849278768066;6084.566173530579;6143.692815359214;3997.7801220932547;4615.9076818463445;4619.307103934437;4638.628909687284;4646.024334889644;3931.8794143668915;4247.5540068293;4405.757683327448;4548.632613516017;4749.430068391815;4227.936580951288;4546.954530454719;4802.543758343022;4872.293517439952;5023.4129423347;4576.391456869619;5011.448740638527;5146.639735218574;5463.590230732711;5573.832785016456;1441.373002743789;1604.663020765629;1609.8148712897932;1618.3007841766098;1626.300690703009;942.3874481586886;978.5792109392887;1145.4136021609963;1180.0441642269789;1180.8110702287593;4812.398450193726;4871.856383191384;5268.023566684811;5298.311219541512;5307.9734601326745;4974.32846565709;4990.925136122961;5307.681539107666;5521.668699878021;5808.854778630568;4240.85157019509;4826.541478559531;4919.557239848431;4998.850034498971;5269.394019687785;2560.5647040003246;2584.8755791230874;2876.7123287671484;3010.4129694709322;3113.532970054465;5262.657927234717;5494.423085576037;5726.942730572724;5936.37668044849;6064.878702425926;5273.525382715574;5613.295225151381;5743.195952566644;5773.653580785166;5920.809300558821;4958.677685950402;5906.696388408524;5962.284525856839;5995.430434108753;6045.879082418327;5211.9478805211465;5331.6014288213155;5450.890982180414;5697.515711164598;5802.854928626818;4834.131718636471;5120.003198752415;5234.555062819703;5554.972225138848;5626.803061892814;5148.716820574904;5223;5227.18978558324;5406.972965135149;5424.098107512462;4302.9672878509555;4474.910501790011;4564.292534657134;4570.702904311209;4574.634029277717;5162.406323272871;5384.919226211531;5394.56843452515;5777.826665200051;5904.822855314347;4816.8554943351755;5165.767540460613;5426.891462170655;5430.945690543202;5547.195656629847;5100.744962751922;5321.308229930084;5379.515843574097;5554.139618096764;5682.488576028178;4896.975515122402;4912.287718280758;5458.235846981346;5467.523768582381;5486.9670791968665;5259.579233661374;5345.118055520951;5402.72986350673;5598.972005140111;5717.485426311652;4384.846530371422;5033.471485494117;5040.84877453677;5129.050631014024;5489.643173193889 17:35:41 INFO - PROCESS | 1637 | |5;yui.html;764.7094104240384;767.2174382130231;879.7888506758378;893.4505279253261;922.2852289475653;15565.766513502293;17016.489505314847;17229.396971105998;17557.82442175578;18114.5471363216;15965.600859978505;16144.677106457864;16164.353425862952;16192.271347789349;16192.75710864338;994.5176589354157;1029.809485245232;1031.865857438532;1032.9690109296732;1034.7826956339177;1017.8015287019034;1019.0879163148977;1019.4138370436991;1024.4775164666;1024.677226673599;1031.8091153136656;1057.2070946789909;1057.2546354819872;1057.6879820452946;1071.314358810362;1071.4589132488084;1072.3351522056337;1072.5709716113618;1073.5437439088435;1073.5544748929185;791.3629528229773;791.8139237279235;792.1761368177089;792.7502836606508;793.111714879333;17261.223244954006;17559.20983555743;17647.647047059116;18041.097945102825;18339.724904126448;13371.064025518217;13472.593716316032;13713.314334283346;13752;13754.105983111165;12169.72303526274;12299.508019679202;12333;12340.465792579373;12576.182548134342;3824.770513769155;3907.6873850091924;3915.5888631693547;3945.4673619061346;4002.5397079335958;117787.41106294414;119365.40317298444;120513;120689.39655301755;121766;11329;11612;11720.70698232551;11809.645710628694;11814.527418903148;21608.891955540435;22219;24213;24532.754672453048;24573.631395529083;7494.962525187339;8330.875036874451;8379.036410812712;8381.916180838114;8450.830983380421;4634.652401069866;4635.930461043087;4693.0613877224005;4702.811887524461;4727.550882666142;3121.7034381733697;3597.172650290448;3789.488419063391;3790.639889210521;3951.05174758056;565.4628103301798;661.5336187987457;663.290279401046;668.6723505482279;675.1290834823023;591.4676790888149;601.7953895675491;608.4797498139116;614.8063360041573;616.3713012726994;10352.223583231287;10515.239767015086;11054.502547385226;11278.323300601991;11308.604198853001;514.6511973821225;595.7884950842475;644.0853987337991;671.7279501801778;728.6356821589205;4574.4739354973935;4744.098621261949;4968.116115040607;5378.650387724785;5402.945970540323;80394;102034;104826;105614;109726;78836.6058169717;79334;85643.57178214194;86294.56852715697;95973.56039659411;74515.6661683763;77382.61308693534;77734.83397749151;78517.42965140665;80298.39403211903;10516.421596812248;11264.718382040186;11359.375234362189;11373.829392558953;11606.70983225426;47585.52414475811;48604;48793.756031219615;50397;50995;53450.46549534611;67582.66208668925;68716;70396.240093996;72618;12822.166559173624;13282.335883205995;13337.399817008452;13757.931210343884;13817.930910345383;15065.322060507084;15579.610509737347;15845.207739612742;16127.596810079842;16334.509964701077;14287;14662.78005829892;14687.870258812198;14867.628309292355;15328.310226039632;13885.375158118111;14763.261836907897;14768.483103091341;14850.257487125817;15105.773413398589;13475.528356507475;14139.505117320845;14150.858491414954;14674.339654715777;14686.265686715407;10129.594816207564;10421.478926053818;10511.94744026275;10687.198460115367;10745.194110441591;9190.954045229731;10000.449975251431;10730.83903741429;10976.305103402014;11791.410429478321 17:35:41 INFO - PROCESS | 1637 | __end_tp_report 17:35:41 INFO - PROCESS | 1637 | __start_cc_report 17:35:41 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1480 17:35:41 INFO - PROCESS | 1637 | __end_cc_report 17:35:41 INFO - PROCESS | 1637 | __startTimestamp1495758941062__endTimestamp 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | ------- Summary: start ------- 17:35:41 INFO - PROCESS | 1637 | Number of tests: 6 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5989.75 Median:6267.12 stddev:649.85 (10.4%) stddev-sans-first:622.35 17:35:41 INFO - PROCESS | 1637 | Values: 3522.2 3527.0 3534.6 3534.8 3586.4 4628.9 5828.9 6094.2 6119.4 6148.1 6030.7 6111.9 6116.6 6118.0 6123.4 5397.0 5406.5 5438.2 5470.9 5538.2 5341.9 5364.2 5420.2 5548.4 5551.8 5440.4 5469.3 5469.7 5474.6 5489.6 5455.9 5461.7 5481.7 5531.8 5533.6 5360.4 5484.0 5484.8 5486.7 5507.9 6262.1 6272.6 6285.2 6292.4 6296.4 6322.6 6343.3 6346.2 6396.3 6407.4 6339.2 6369.6 6417.6 6433.0 6461.6 6152.7 6215.4 6220.7 6226.9 6227.3 6290.9 6578.3 6595.9 6597.5 6643.3 6265.0 6292.2 6296.9 6307.6 6340.1 6447.5 6451.6 6460.9 6472.9 6479.1 6317.3 6341.6 6377.0 6391.4 6403.8 5558.8 6273.3 6292.8 6339.4 6350.7 5097.5 5106.7 5187.7 5213.2 5282.0 4683.9 4695.9 4703.6 4704.2 4705.5 6083.9 6115.2 6139.4 6194.6 6210.7 5421.2 5449.4 6269.1 6297.5 6489.0 6174.9 6212.7 6212.9 6213.8 6225.7 5725.6 5728.9 5741.8 5758.5 5778.8 5491.7 6386.4 6432.6 6766.8 6771.7 6417.4 6452.0 6453.3 6457.9 6476.9 6708.2 6756.3 6770.2 6771.1 6775.7 6306.5 6359.3 6435.6 6444.4 6454.5 6247.4 6394.8 6429.5 6456.0 6468.1 6327.7 6345.3 6356.9 6358.6 6362.2 6132.1 6305.9 6312.5 6346.9 6372.9 6109.8 6267.1 6314.2 6321.6 6343.8 6331.8 6339.6 6343.2 6346.5 6349.9 6139.4 6145.5 6154.2 6378.7 6406.2 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:51606.63 Median:31526.76 stddev:98345.08 (311.9%) stddev-sans-first:98547.02 17:35:41 INFO - PROCESS | 1637 | Values: 813.5 817.7 831.7 904.2 911.5 28158.7 39340.0 39578.4 39589.4 39633.6 40417.6 40673.0 40775.6 40806.6 41130.0 7843.6 9435.2 9598.4 9600.3 9611.2 7858.1 11296.7 11512.0 11661.1 11704.5 11514.4 11524.9 11540.5 11568.4 11579.8 11457.7 11496.3 11506.7 11525.3 11542.8 11188.8 11238.0 11264.7 11265.5 11271.0 38669.0 38969.6 39038.0 39054.0 39082.4 3931.2 3976.7 4007.8 4010.9 4072.8 27663.4 29177.6 29770.3 29945.4 29947.3 3063.4 3265.8 3268.7 3364.8 3393.1 358155.2 358159.0 358468.0 358532.0 359016.0 42041.0 45375.0 51660.5 51811.0 51853.5 51800.7 53502.2 53543.9 54668.8 54723.0 15441.0 15537.5 15553.0 15558.1 15569.8 11672.1 11806.5 13234.2 13235.1 13251.7 10427.5 11529.5 11568.2 11914.6 11923.5 4922.3 4980.6 5373.1 5390.0 5396.4 1147.1 1158.0 1164.8 1164.9 1225.4 25449.9 26521.9 26598.9 26695.1 26833.5 1252.8 1258.1 1261.8 1294.8 1308.4 13301.7 13668.5 13829.4 13866.6 13957.4 388986.0 390245.0 390854.0 391233.0 391493.0 53359.7 54517.0 54517.5 54552.6 54686.5 1458.4 1462.9 1514.7 1515.2 1524.1 24652.8 25681.2 25848.9 25992.5 26007.6 43797.3 43810.3 43986.7 44121.8 44126.3 367618.0 368484.0 368771.0 368995.0 369440.3 25720.9 31943.0 32367.0 32507.2 32519.5 32270.2 32456.7 37128.1 42567.6 45660.3 30218.2 30328.3 30509.0 31057.4 31292.7 31056.4 31088.1 31287.8 31450.5 31603.0 32562.7 33480.3 34557.0 34944.7 34992.7 26632.5 27815.3 28250.4 29305.0 29439.8 21461.9 22844.9 23080.9 23447.2 23879.3 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:42789.68 Median:25110.62 stddev:117450.88 (467.7%) stddev-sans-first:117737.29 17:35:41 INFO - PROCESS | 1637 | Values: 429.6 437.6 438.1 447.7 452.4 20084.1 21140.4 22307.9 22330.4 22534.9 21968.6 23596.5 24108.8 24201.4 24374.8 6734.4 6887.2 6946.5 7096.9 7163.6 6769.9 6889.6 6892.1 6901.4 6903.1 6886.4 7525.2 7814.6 7983.0 7988.7 7692.9 7836.6 7926.6 7941.0 7997.3 7196.7 7636.2 7672.0 7679.3 7684.5 25846.5 26300.7 26595.5 27471.7 27548.7 2943.1 2943.6 3006.3 3084.4 3178.1 16263.0 16282.8 16362.1 16435.3 17624.3 1880.8 1939.9 1949.8 1965.2 1979.7 154727.5 172896.0 173545.0 175543.0 180034.0 20152.0 20343.6 20353.2 20434.6 20480.5 31918.8 32392.0 32420.5 32502.0 32569.3 10464.5 10534.9 10743.3 10766.9 10921.7 8997.7 8999.6 9035.2 9179.1 9189.9 9354.4 9464.8 9484.8 9613.1 9656.4 3031.2 3109.3 3285.4 3917.7 3948.5 817.4 827.2 844.9 862.0 875.2 18142.4 22641.3 22665.8 22773.2 22913.8 879.6 894.1 911.4 916.3 923.7 10975.0 11847.4 11925.7 11958.3 11982.5 664044.0 685037.0 694900.9 716146.0 729801.0 140215.0 144415.0 146718.0 151472.0 155815.4 1239.2 1283.8 1336.1 1339.9 1342.3 20261.9 20475.0 20973.6 21023.8 21046.7 65989.0 67820.7 68489.3 68811.3 70600.6 98153.5 98822.0 100054.0 101490.0 101773.5 11386.3 11759.9 11854.6 12022.9 12145.5 12827.8 13262.3 13283.7 13287.9 13508.0 9353.9 9577.0 11888.6 11979.6 12058.5 9561.8 9581.2 9587.2 9678.0 9800.7 11691.2 11774.8 11800.7 11975.2 12149.8 11731.0 11802.5 12105.7 12106.9 12113.1 11391.5 11400.6 11489.3 11783.5 11841.7 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6793.41 Median:2503.21 stddev:4211.32 (168.2%) stddev-sans-first:4205.09 17:35:41 INFO - PROCESS | 1637 | Values: 1604.2 1615.2 1653.6 1661.6 1678.6 9627.8 10876.1 11581.0 11586.8 11592.0 10327.5 10781.4 10849.9 10867.7 10890.6 2456.3 2496.2 2497.2 2500.0 2503.6 2441.9 2461.0 2503.7 2511.2 2513.1 2460.2 2464.3 2482.5 2502.8 2506.8 2410.9 2419.8 2441.6 2447.2 2474.3 2037.3 2418.4 2446.1 2453.8 2455.0 11381.8 11432.6 11449.0 11470.4 11470.4 5618.1 5784.6 5786.4 5808.2 5812.0 3656.6 3665.5 3742.5 3805.0 3855.0 1247.4 1248.6 1288.7 1305.1 1308.6 13802.7 14155.2 14595.9 15518.7 15726.4 4080.3 4706.1 4843.2 4894.3 4901.7 10396.4 12077.9 12085.0 12089.0 12124.8 5059.6 5846.3 5985.9 6061.2 6068.8 4423.8 5462.0 5462.3 5559.1 5573.4 3253.0 3385.7 3943.2 4015.1 4050.2 946.8 1024.2 1061.2 1064.8 1079.2 801.8 818.5 839.8 855.1 867.0 6250.7 6494.2 6719.2 6769.9 6821.9 880.3 1047.7 1084.0 1090.9 1109.4 4228.3 4294.8 4297.7 4428.0 4429.6 10916.6 13978.9 14015.7 14016.5 14016.7 13540.1 13703.3 14070.3 14114.0 14124.0 9919.8 10195.3 10362.2 10546.7 10569.6 6675.0 6675.0 6750.8 6778.1 6886.9 10319.2 11272.6 12389.4 12562.1 12574.7 12391.3 12568.7 12689.9 12780.9 12831.3 9381.7 9383.0 9591.2 9602.3 9607.8 10421.9 10440.5 10538.7 10562.4 10562.6 8917.0 9049.5 9101.8 9117.6 9143.4 8805.4 8858.5 8907.8 8909.0 8943.2 8013.4 8086.5 8158.1 8169.8 8177.0 5660.8 5749.0 5825.0 5875.2 5883.1 5851.4 5905.9 5906.8 6164.8 6219.0 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4614.28 Median:5017.43 stddev:1288.45 (25.7%) stddev-sans-first:1251.22 17:35:41 INFO - PROCESS | 1637 | Values: 325.9 386.0 394.5 397.8 400.1 4345.8 5383.3 5388.8 5424.5 5462.6 4160.1 4758.7 5004.6 5193.6 5359.7 3866.7 3896.0 3899.5 4061.5 4119.8 3727.3 3778.4 4341.0 4382.8 4445.2 3988.0 4209.0 4387.9 4397.4 4411.9 4087.8 4192.3 4267.4 4309.8 4345.4 3685.8 3760.8 4023.9 4028.9 4125.2 5237.8 5309.3 5313.8 5338.4 5344.7 4567.9 4767.9 4886.5 5156.9 5160.8 4596.7 5585.9 5612.7 5647.2 5666.5 4208.6 4462.7 4495.3 4525.2 4547.2 5484.0 5598.5 6028.8 6084.6 6143.7 3997.8 4615.9 4619.3 4638.6 4646.0 3931.9 4247.6 4405.8 4548.6 4749.4 4227.9 4547.0 4802.5 4872.3 5023.4 4576.4 5011.4 5146.6 5463.6 5573.8 1441.4 1604.7 1609.8 1618.3 1626.3 942.4 978.6 1145.4 1180.0 1180.8 4812.4 4871.9 5268.0 5298.3 5308.0 4974.3 4990.9 5307.7 5521.7 5808.9 4240.9 4826.5 4919.6 4998.9 5269.4 2560.6 2584.9 2876.7 3010.4 3113.5 5262.7 5494.4 5726.9 5936.4 6064.9 5273.5 5613.3 5743.2 5773.7 5920.8 4958.7 5906.7 5962.3 5995.4 6045.9 5211.9 5331.6 5450.9 5697.5 5802.9 4834.1 5120.0 5234.6 5555.0 5626.8 5148.7 5223.0 5227.2 5407.0 5424.1 4303.0 4474.9 4564.3 4570.7 4574.6 5162.4 5384.9 5394.6 5777.8 5904.8 4816.9 5165.8 5426.9 5430.9 5547.2 5100.7 5321.3 5379.5 5554.1 5682.5 4897.0 4912.3 5458.2 5467.5 5487.0 5259.6 5345.1 5402.7 5599.0 5717.5 4384.8 5033.5 5040.8 5129.1 5489.6 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:21125.20 Median:16192.51 stddev:30071.49 (185.7%) stddev-sans-first:30116.99 17:35:41 INFO - PROCESS | 1637 | Values: 764.7 767.2 879.8 893.5 922.3 15565.8 17016.5 17229.4 17557.8 18114.5 15965.6 16144.7 16164.4 16192.3 16192.8 994.5 1029.8 1031.9 1033.0 1034.8 1017.8 1019.1 1019.4 1024.5 1024.7 1031.8 1057.2 1057.3 1057.7 1071.3 1071.5 1072.3 1072.6 1073.5 1073.6 791.4 791.8 792.2 792.8 793.1 17261.2 17559.2 17647.6 18041.1 18339.7 13371.1 13472.6 13713.3 13752.0 13754.1 12169.7 12299.5 12333.0 12340.5 12576.2 3824.8 3907.7 3915.6 3945.5 4002.5 117787.4 119365.4 120513.0 120689.4 121766.0 11329.0 11612.0 11720.7 11809.6 11814.5 21608.9 22219.0 24213.0 24532.8 24573.6 7495.0 8330.9 8379.0 8381.9 8450.8 4634.7 4635.9 4693.1 4702.8 4727.6 3121.7 3597.2 3789.5 3790.6 3951.1 565.5 661.5 663.3 668.7 675.1 591.5 601.8 608.5 614.8 616.4 10352.2 10515.2 11054.5 11278.3 11308.6 514.7 595.8 644.1 671.7 728.6 4574.5 4744.1 4968.1 5378.7 5402.9 80394.0 102034.0 104826.0 105614.0 109726.0 78836.6 79334.0 85643.6 86294.6 95973.6 74515.7 77382.6 77734.8 78517.4 80298.4 10516.4 11264.7 11359.4 11373.8 11606.7 47585.5 48604.0 48793.8 50397.0 50995.0 53450.5 67582.7 68716.0 70396.2 72618.0 12822.2 13282.3 13337.4 13757.9 13817.9 15065.3 15579.6 15845.2 16127.6 16334.5 14287.0 14662.8 14687.9 14867.6 15328.3 13885.4 14763.3 14768.5 14850.3 15105.8 13475.5 14139.5 14150.9 14674.3 14686.3 10129.6 10421.5 10511.9 10687.2 10745.2 9191.0 10000.4 10730.8 10976.3 11791.4 17:35:41 INFO - PROCESS | 1637 | -------- Summary: end -------- 17:35:41 INFO - PROCESS | 1637 | 17:35:41 INFO - TEST-INFO | 1637: exit 0 17:35:41 INFO - TEST-OK | dromaeo_css | took 1118626ms 17:35:41 INFO - TEST-START | kraken 17:35:41 INFO - operating with platform_type : mac_ 17:35:41 INFO - Initialising browser for kraken test... 17:35:41 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp939laN/profile http://localhost:49220/getInfo.html) 17:35:43 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 17:35:43 INFO - PROCESS | 1648 | colorDepth:24 17:35:43 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 17:35:43 INFO - PROCESS | 1648 | __metrics 17:35:43 INFO - PROCESS | 1648 | 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" 17:35:43 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:44 INFO - TEST-INFO | 1648: exit 0 17:35:44 INFO - Browser initialized. 17:35:44 INFO - Running cycle 1/1 for kraken test... 17:35:44 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp939laN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:56 INFO - PROCESS | 1650 | RSS: Main: 165343232 17:35:56 INFO - PROCESS | 1650 | 17:35:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:00 INFO - PROCESS | 1650 | RSS: Main: 216666112 17:36:00 INFO - PROCESS | 1650 | 17:36:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-dft) 17:36:03 INFO - PROCESS | 1650 | RSS: Main: 231649280 17:36:03 INFO - PROCESS | 1650 | 17:36:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-fft) 17:36:06 INFO - PROCESS | 1650 | RSS: Main: 328130560 17:36:06 INFO - PROCESS | 1650 | 17:36:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator) 17:36:09 INFO - PROCESS | 1650 | RSS: Main: 229691392 17:36:09 INFO - PROCESS | 1650 | 17:36:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:12 INFO - PROCESS | 1650 | RSS: Main: 230998016 17:36:12 INFO - PROCESS | 1650 | 17:36:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom) 17:36:15 INFO - PROCESS | 1650 | RSS: Main: 273104896 17:36:15 INFO - PROCESS | 1650 | 17:36:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:19 INFO - PROCESS | 1650 | RSS: Main: 273670144 17:36:19 INFO - PROCESS | 1650 | 17:36:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial) 17:36:22 INFO - PROCESS | 1650 | RSS: Main: 272121856 17:36:22 INFO - PROCESS | 1650 | 17:36:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:24 INFO - PROCESS | 1650 | RSS: Main: 247103488 17:36:24 INFO - PROCESS | 1650 | 17:36:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:27 INFO - PROCESS | 1650 | RSS: Main: 251072512 17:36:27 INFO - PROCESS | 1650 | 17:36:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:30 INFO - PROCESS | 1650 | RSS: Main: 257814528 17:36:30 INFO - PROCESS | 1650 | 17:36:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:33 INFO - PROCESS | 1650 | RSS: Main: 273776640 17:36:33 INFO - PROCESS | 1650 | 17:36:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:37 INFO - PROCESS | 1650 | RSS: Main: 267415552 17:36:37 INFO - PROCESS | 1650 | 17:36:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49220/tests/kraken/driver.html?testName=ai-astar) 17:36:39 INFO - PROCESS | 1650 | RSS: Main: 311656448 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | __start_tp_report 17:36:39 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 17:36:39 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 17:36:39 INFO - PROCESS | 1650 | |i|pagename|runs| 17:36:39 INFO - PROCESS | 1650 | |0;ai-astar;103;116;115;115;115;115;115;115;115;116 17:36:39 INFO - PROCESS | 1650 | |1;audio-beat-detection;129;106;110;169;116;107;107;105;125;107 17:36:39 INFO - PROCESS | 1650 | |2;audio-dft;184;182;185;183;182;184;183;185;182;184 17:36:39 INFO - PROCESS | 1650 | |3;audio-fft;83;50;60;105;90;86;74;69;74;60 17:36:39 INFO - PROCESS | 1650 | |4;audio-oscillator;84;69;86;69;68;68;80;70;90;68 17:36:39 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;86;86;85;85;86;86;85;86;86;85 17:36:39 INFO - PROCESS | 1650 | |6;imaging-darkroom;93;93;94;93;92;93;93;93;92;92 17:36:39 INFO - PROCESS | 1650 | |7;imaging-desaturate;84;85;85;88;83;83;83;86;84;86 17:36:39 INFO - PROCESS | 1650 | |8;json-parse-financial;63;60;59;59;62;63;63;64;63;63 17:36:39 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;56;60;65;66;63;57;79;87;87;61 17:36:39 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;83;83;81;84;79;80;80;78;88;83 17:36:39 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;135;133;134;133;135;133;134;136;173;143 17:36:39 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;161;162;162;161;158;161;159;159;218;217 17:36:39 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;63;63;66;67;77;81;80;78;63;63 17:36:39 INFO - PROCESS | 1650 | __end_tp_report 17:36:39 INFO - PROCESS | 1650 | __start_cc_report 17:36:39 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2050 17:36:39 INFO - PROCESS | 1650 | __end_cc_report 17:36:39 INFO - PROCESS | 1650 | __startTimestamp1495758999241__endTimestamp 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | ------- Summary: start ------- 17:36:39 INFO - PROCESS | 1650 | Number of tests: 14 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:3.89 (3.4%) stddev-sans-first:0.44 17:36:39 INFO - PROCESS | 1650 | Values: 103.0 116.0 115.0 115.0 115.0 115.0 115.0 115.0 115.0 116.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:113.00 stddev:19.76 (17.5%) stddev-sans-first:20.56 17:36:39 INFO - PROCESS | 1650 | Values: 129.0 106.0 110.0 169.0 116.0 107.0 107.0 105.0 125.0 107.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.17 (0.6%) stddev-sans-first:1.22 17:36:39 INFO - PROCESS | 1650 | Values: 184.0 182.0 185.0 183.0 182.0 184.0 183.0 185.0 182.0 184.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:75.10 Median:74.00 stddev:16.41 (22.2%) stddev-sans-first:17.15 17:36:39 INFO - PROCESS | 1650 | Values: 83.0 50.0 60.0 105.0 90.0 86.0 74.0 69.0 74.0 60.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:75.00 stddev:8.79 (11.7%) stddev-sans-first:8.73 17:36:39 INFO - PROCESS | 1650 | Values: 84.0 69.0 86.0 69.0 68.0 68.0 80.0 70.0 90.0 68.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 17:36:39 INFO - PROCESS | 1650 | Values: 86.0 86.0 85.0 85.0 86.0 86.0 85.0 86.0 86.0 85.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 17:36:39 INFO - PROCESS | 1650 | Values: 93.0 93.0 94.0 93.0 92.0 93.0 93.0 93.0 92.0 92.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.64 (1.9%) stddev-sans-first:1.72 17:36:39 INFO - PROCESS | 1650 | Values: 84.0 85.0 85.0 88.0 83.0 83.0 83.0 86.0 84.0 86.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:63.00 stddev:1.85 (2.9%) stddev-sans-first:1.92 17:36:39 INFO - PROCESS | 1650 | Values: 63.0 60.0 59.0 59.0 62.0 63.0 63.0 64.0 63.0 63.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:65.50 stddev:11.83 (18.1%) stddev-sans-first:11.71 17:36:39 INFO - PROCESS | 1650 | Values: 56.0 60.0 65.0 66.0 63.0 57.0 79.0 87.0 87.0 61.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:83.00 stddev:2.92 (3.5%) stddev-sans-first:3.07 17:36:39 INFO - PROCESS | 1650 | Values: 83.0 83.0 81.0 84.0 79.0 80.0 80.0 78.0 88.0 83.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:135.00 stddev:12.34 (9.1%) stddev-sans-first:13.01 17:36:39 INFO - PROCESS | 1650 | Values: 135.0 133.0 134.0 133.0 135.0 133.0 134.0 136.0 173.0 143.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:161.50 stddev:24.12 (14.9%) stddev-sans-first:25.27 17:36:39 INFO - PROCESS | 1650 | Values: 161.0 162.0 162.0 161.0 158.0 161.0 159.0 159.0 218.0 217.0 17:36:39 INFO - PROCESS | 1650 | 17:36:39 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:72.00 stddev:7.85 (10.9%) stddev-sans-first:7.90 17:36:39 INFO - PROCESS | 1650 | Values: 63.0 63.0 66.0 67.0 77.0 81.0 80.0 78.0 63.0 63.0 17:36:39 INFO - PROCESS | 1650 | -------- Summary: end -------- 17:36:39 INFO - PROCESS | 1650 | 17:36:40 INFO - TEST-INFO | 1650: exit 0 17:36:40 INFO - TEST-OK | kraken | took 58444ms 17:36:40 INFO - SUITE-END | took 1177s 17:36:40 INFO - Completed test suite (00:19:37) 17:36:40 INFO - javascript benchmark 17:36:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7834.885430482246, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3522.1546828761097, 3527.0124365177758, 3534.611192768795, 3534.8055856927876, 3586.426171812509, 4628.861134165972, 5828.921649494847, 6094.17728606638, 6119.4186552277515, 6148.139260503527, 6030.66831324277, 6111.877762444748, 6116.6024208426425, 6117.969410152943, 6123.4182752638535, 5397.028534863733, 5406.486383793534, 5438.15708565173, 5470.890582188354, 5538.169274608821, 5341.946580534183, 5364.249005139283, 5420.159875219348, 5548.4174161713045, 5551.750171242284, 5440.374356948962, 5469.316335458067, 5469.699166545838, 5474.562035037207, 5489.615726899118, 5455.918161227585, 5461.726913654302, 5481.725913704338, 5531.751071201806, 5533.6403133796175, 5360.437154098841, 5484.040292948718, 5484.7531861065945, 5486.725663716798, 5507.917381239285, 6262.091996660459, 6272.6236425814595, 6285.182926219608, 6292.402221788922, 6296.401841825019, 6322.557420980534, 6343.333949935282, 6346.174997250374, 6396.26442959061, 6407.359264073602, 6339.175907132089, 6369.585976911532, 6417.647029413381, 6433.002884552901, 6461.6123032617725, 6152.661603611799, 6215.440610345091, 6220.657863817533, 6226.875462490725, 6227.283862355796, 6290.874182516415, 6578.3092775258865, 6595.901061484082, 6597.47220222381, 6643.269240383554, 6265.028920517327, 6292.150559674477, 6296.937030629727, 6307.621542707452, 6340.1440805490665, 6447.516436267205, 6451.612903225821, 6460.870782584322, 6472.9029064564065, 6479.092926990131, 6317.305096439388, 6341.6195028297525, 6377.0, 6391.392817682313, 6403.775867844603, 5558.777648893999, 6273.341299163613, 6292.779752708725, 6339.429451349401, 6350.7459701611415, 5097.490250974873, 5106.66806657566, 5187.740612969336, 5213.191955246943, 5282.0, 4683.929741053887, 4695.943412732108, 4703.60019398355, 4704.200285951397, 4705.5059218781535, 6083.939160608337, 6115.1744514490865, 6139.416755408229, 6194.628322300677, 6210.658413787195, 5421.159720243369, 5449.373322067933, 6269.122322874801, 6297.4962003039645, 6489.0, 6174.938250617436, 6212.658303793335, 6212.9378706213265, 6213.751449941951, 6225.657588832658, 5725.570582206351, 5728.914066289009, 5741.770329186786, 5758.452946970031, 5778.797742079007, 5491.7254137292175, 6386.361363863576, 6432.614043157426, 6766.763163289262, 6771.695273712793, 6417.358264173546, 6452.0, 6453.290138084901, 6457.935420645921, 6476.935230647633, 6708.228553716385, 6756.29058948823, 6770.221424536241, 6771.119754431892, 6775.661216939035, 6306.527010474141, 6359.332270111571, 6435.646039467874, 6444.420002199825, 6454.451371633464, 6247.375262473716, 6394.776182833579, 6429.517786165963, 6456.0, 6468.126802881551, 6327.746890124344, 6345.302016778243, 6356.936430635819, 6358.618482891041, 6362.236531616236, 6132.080177995836, 6305.90541141874, 6312.495000400048, 6346.904796427965, 6372.904406433814, 6109.84725381869, 6267.122602835698, 6314.210723659543, 6321.589096708699, 6343.777967771106, 6331.810045698637, 6339.5562310637815, 6343.238811342669, 6346.492280617447, 6349.904751428639, 6139.386061393825, 6145.463634091477, 6154.169187159676, 6378.681065946591, 6406.199225096863], "value": 5950.454055478411, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.5078277642026, 817.6933649881295, 831.7089018843405, 904.2178515584022, 911.4941207629771, 28158.718412815866, 39340.0, 39578.406323905096, 39589.40615890764, 39633.60366396339, 40417.59582404175, 40673.0, 40775.572854950085, 40806.59193408065, 41130.0, 7843.607819609026, 9435.150836424738, 9598.42409455435, 9600.279979001569, 9611.231101511898, 7858.057033156029, 11296.717582060432, 11512.0, 11661.125415593822, 11704.53181872724, 11514.42427878607, 11524.942375288112, 11540.538378464851, 11568.363739994296, 11579.826302605468, 11457.656270311903, 11496.252743571642, 11506.654800356002, 11525.250858694159, 11542.826857597143, 11188.832167517494, 11238.0, 11264.66206013821, 11265.549378024869, 11271.041961433288, 38669.0, 38969.61030389688, 39038.0, 39054.0, 39082.413763793564, 3931.154801717616, 3976.7216294859372, 4007.7595344279434, 4010.9197816043807, 4072.8370865165357, 27663.446731065265, 29177.56233656497, 29770.255743606365, 29945.401091978256, 29947.25131871699, 3063.3720087382035, 3265.7713960022807, 3268.7221586165206, 3364.7812892162174, 3393.1347506385714, 358155.2092239548, 358159.0, 358468.0, 358532.0, 359016.0, 42041.0, 45375.0, 51660.48339516632, 51811.0, 51853.481465184865, 51800.74099629553, 53502.19746703802, 53543.92912141735, 54668.80646338671, 54723.0, 15441.0, 15537.45618903333, 15553.0, 15558.066516009076, 15569.844301557067, 11672.124590655565, 11806.52773889052, 13234.205947643171, 13235.07354485196, 13251.73496530064, 10427.530761115768, 11529.538818447336, 11568.24806387582, 11914.642560723192, 11923.463444145034, 4922.286268491121, 4980.576650984634, 5373.11343628297, 5390.0, 5396.379416367089, 1147.0708725931854, 1157.9710507237219, 1164.7612239490884, 1164.9242799218023, 1225.3628113380992, 25449.87275063613, 26521.886124535424, 26598.867005664848, 26695.065672701752, 26833.463330733277, 1252.7557126360273, 1258.1444617659897, 1261.829652996852, 1294.825198598196, 1308.4177540994167, 13301.73396532064, 13668.52160174389, 13829.446822127204, 13866.584002479942, 13957.441702331793, 388986.0, 390245.0, 390854.0, 391233.0, 391493.0, 53359.73320133452, 54517.0, 54517.454825452034, 54552.636184094925, 54686.45313546814, 1458.3801884199386, 1462.8829693624484, 1514.712204681107, 1515.1969456188237, 1524.1083965879864, 24652.753472465047, 25681.22956311314, 25848.8707556461, 25992.480150396506, 26007.60988585135, 43797.34303985379, 43810.34284485799, 43986.68039958806, 44121.77939110284, 44126.33810492909, 367618.0, 368484.0, 368771.0, 368995.0, 369440.30559694057, 25720.871395642902, 31943.0, 32367.028989130366, 32507.187320316236, 32519.512207316435, 32270.19324516906, 32456.67543324631, 37128.07179820418, 42567.57432425636, 45660.315095274265, 30218.24454388658, 30328.331941743418, 30509.0, 31057.378852423277, 31292.687073129888, 31056.378872421974, 31088.067357979297, 31287.84356078205, 31450.52824207593, 31603.0, 32562.674373256912, 33480.33039339151, 34557.03277837649, 34944.65055349516, 34992.65007349894, 26632.467350653267, 27815.30461738473, 28250.43499130047, 29305.0, 29439.822407103475, 21461.89269053707, 22844.885775571016, 23080.884595576914, 23447.17934872271, 23879.283621491384], "value": 18662.639672983263, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.6047636174719, 437.5995963693219, 438.12812503118784, 447.7425480348799, 452.44801342362325, 20084.096215670274, 21140.365789026317, 22307.888460557693, 22330.441738956535, 22534.88732556339, 21968.560628787454, 23596.528069438646, 24108.75891241087, 24201.39496512588, 24374.75625243747, 6734.3602357775935, 6887.173539175294, 6946.47901407394, 7096.85806283874, 7163.64181790911, 6769.932300677004, 6889.586624802503, 6892.104026476576, 6901.44788416928, 6903.0680858084015, 6886.414654754327, 7525.209852965442, 7814.648340824648, 7982.960085199566, 7988.680452781882, 7692.923070769305, 7836.647350869168, 7926.6036698165435, 7941.00737407824, 7997.320227780617, 7196.712131514733, 7636.160022397532, 7671.961640191819, 7679.308862202372, 7684.462087653867, 25846.483070338676, 26300.73699263002, 26595.46809063827, 27471.725282747117, 27548.724512754816, 2943.072932026405, 2943.632045994251, 3006.28831009107, 3084.4447999360104, 3178.141901686555, 16263.024218546925, 16282.75575866363, 16362.100084495349, 16435.342586296654, 17624.295028198856, 1880.7649043869517, 1939.854510911673, 1949.7855235924037, 1965.2040923426036, 1979.6832506798987, 154727.45272547242, 172896.0, 173544.99999999875, 175543.0, 180034.0, 20152.0, 20343.593128137356, 20353.185872564933, 20434.591308173753, 20480.487987800425, 31918.840405797822, 32392.0, 32420.513692294633, 32502.0, 32569.348613027607, 10464.529096190538, 10534.894651053544, 10743.301685390581, 10766.900454411314, 10921.726956825984, 8997.730068097968, 8999.550022498848, 9035.232005279493, 9179.127982841619, 9189.862152067724, 9354.391964522285, 9464.763380915394, 9484.766980292517, 9613.134817866425, 9656.37233579815, 3031.2267323615492, 3109.3314937288137, 3285.408626447215, 3917.7061720371084, 3948.499570163377, 817.3910436724678, 827.1934863508031, 844.8563744163507, 862.008690006485, 875.2472873328932, 18142.378425818668, 22641.320760377213, 22665.773342266697, 22773.202937897095, 22913.770862291498, 879.6437442835662, 894.1103601916005, 911.3939230411835, 916.2624087609405, 923.736735030513, 10975.0, 11847.442527162293, 11925.70185745346, 11958.342291173894, 11982.460789264504, 664044.0, 685037.0, 694900.9233840778, 716146.0, 729801.0, 140215.0, 144415.0, 146718.0, 151472.0, 155815.44184558236, 1239.1759479945915, 1283.794592865137, 1336.0981337597084, 1339.8526162121964, 1342.3422522963679, 20261.898690506452, 20475.0, 20973.58052838965, 21023.789762102184, 21046.68429973582, 65989.0, 67820.66089669717, 68489.3151068483, 68811.3118868825, 70600.64699676468, 98153.50923245338, 98822.0, 100054.0, 101490.0, 101773.49113254386, 11386.259893106922, 11759.882401175879, 11854.644360669194, 12022.939885300517, 12145.514179432723, 12827.807582886451, 13262.27057511846, 13283.734325313633, 13287.933560332136, 13507.986900982662, 9353.906460935576, 9577.0, 11888.643340699793, 11979.580714674945, 12058.51765929353, 9561.808763824823, 9581.185599223865, 9587.18508926749, 9677.951610241904, 9800.65697700577, 11691.181617286707, 11774.764504709687, 11800.70498237551, 11975.161738678209, 12149.817752733892, 11731.0, 11802.52789888395, 12105.69735756613, 12106.878931210575, 12113.091518136, 11391.544338226378, 11400.600978965696, 11489.310641361544, 11783.528658853893, 11841.703957401134], "value": 11869.8671709945, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1604.2219523531094, 1615.200475764496, 1653.6031352475409, 1661.5762980439986, 1678.5719641491419, 9627.759306017344, 10876.075533579646, 11581.0, 11586.826197607032, 11592.0, 10327.535260913259, 10781.353118812856, 10849.945750271258, 10867.673969780899, 10890.564377424913, 2456.275398757362, 2496.2261698873317, 2497.213377786001, 2500.0249902538026, 2503.5869081601513, 2441.8779061046966, 2461.0155937625004, 2503.662005629244, 2511.221521328385, 2513.1329691256487, 2460.2250291158316, 2464.2853572464055, 2482.453860150764, 2502.8373155744907, 2506.7618576235227, 2410.9035638574437, 2419.7822196002267, 2441.6093425051995, 2447.2046584859813, 2474.344298760812, 2037.3174986379581, 2418.3712234819072, 2446.082718980382, 2453.8405003674884, 2455.0057226823164, 11381.829272560919, 11432.599859004895, 11448.96959273661, 11470.369129697863, 11470.369129697863, 5618.101103823409, 5784.566157538202, 5786.363500014995, 5808.215890854723, 5812.0, 3656.597774244831, 3665.5234819473562, 3742.5134732484876, 3804.9809750951067, 3855.0169706724605, 1247.3700781105574, 1248.6191711527981, 1288.6585054960442, 1305.1386085183733, 1308.6335825968745, 13802.65493362664, 14155.221462819542, 14595.927020364936, 15518.689626207526, 15726.449574264845, 4080.3267460868624, 4706.058788242365, 4843.200871856175, 4894.339264199359, 4901.681390709592, 10396.428196449117, 12077.939610301892, 12085.0, 12089.03287736979, 12124.757504849853, 5059.595232381401, 5846.269216347957, 5985.880282394415, 6061.151438798572, 6068.787592434243, 4423.756693381895, 5461.972690136524, 5462.289902312754, 5559.054960656698, 5573.359063707644, 3253.0, 3385.7291416686608, 3943.191645712622, 4015.0966032643005, 4050.1697152083752, 946.8117512521741, 1024.206240163864, 1061.1535927486118, 1064.8136576099153, 1079.1906070447164, 801.7550857598817, 818.5170749257967, 839.8236370362171, 855.1234984141305, 866.994286627509, 6250.687465626791, 6494.188226471691, 6719.160104986877, 6769.93230067693, 6821.942598897179, 880.3441436130124, 1047.7328281288223, 1083.9566417343376, 1090.9236353455183, 1109.4064675398622, 4228.302330115496, 4294.806733696991, 4297.720648157922, 4427.977860110679, 4429.645628349725, 10916.617918372818, 13978.86021139796, 14015.719685606231, 14016.509422170175, 14016.71966560663, 13540.119892206974, 13703.314834258248, 14070.2964851757, 14114.012019158765, 14124.0, 9919.752006199902, 10195.286329956833, 10362.222833287533, 10546.736331591772, 10569.630062947914, 6674.966625166843, 6674.966625166843, 6750.831229219309, 6778.0510728497065, 6886.931130688629, 10319.174466042548, 11272.605458808903, 12389.380530973596, 12562.120651554304, 12574.748505030031, 12391.318477483464, 12568.748625027265, 12689.87310126887, 12780.936095319465, 12831.294278814756, 9381.718548443558, 9382.95308523453, 9591.184749296388, 9602.279829012712, 9607.759806004626, 10421.895781042396, 10440.477976101012, 10538.683839484827, 10562.366258024544, 10562.630307939186, 8917.0, 9049.502277374544, 9101.772455688395, 9117.589708463267, 9143.405678630868, 8805.427647203167, 8858.468491890508, 8907.777305567413, 8908.955455222942, 8943.23982461498, 8013.398995075509, 8086.474379165335, 8158.102608713156, 8169.754907352789, 8177.018757749108, 5660.801871934627, 5749.0, 5825.0388685866355, 5875.177475153397, 5883.058710606282, 5851.391956699116, 5905.940940590539, 5906.763729450946, 6164.7842325518395, 6219.004959206505], "value": 5196.76053879845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.9185203699075, 385.9594742552032, 394.5009562902927, 397.7514053716427, 400.1429791290188, 4345.826166953322, 5383.258635248504, 5388.763663079822, 5424.538914192299, 5462.562994960404, 4160.131078097368, 4758.738269395182, 5004.624653151019, 5193.610479214065, 5359.7320133993335, 3866.67133293669, 3896.0065932419284, 3899.493065901429, 4061.451704019964, 4119.794010299488, 3727.3477141500266, 3778.433235014756, 4341.004706752331, 4382.802773875183, 4445.222086134913, 3987.9721481303945, 4208.957910420902, 4387.868363949086, 4397.406350142722, 4411.911761764763, 4087.754734715897, 4192.295969099929, 4267.423897773808, 4309.82760689572, 4345.391645169679, 3685.7836913818373, 3760.849566017356, 4023.879283621496, 4028.8589899353674, 4125.1749650070105, 5237.842864714026, 5309.33633295838, 5313.814016509404, 5338.359396872362, 5344.675066346152, 4567.931481027787, 4767.880802979918, 4886.535779101016, 5156.871078223037, 5160.78086422838, 4596.678232523725, 5585.8603534911545, 5612.66324020556, 5647.181158732002, 5666.54667626593, 4208.579142085798, 4462.687611867171, 4495.314257153567, 4525.171767645222, 4547.15877562652, 5484.0, 5598.500936914428, 6028.849278768066, 6084.566173530579, 6143.692815359214, 3997.7801220932547, 4615.9076818463445, 4619.307103934437, 4638.628909687284, 4646.024334889644, 3931.8794143668915, 4247.5540068293, 4405.757683327448, 4548.632613516017, 4749.430068391815, 4227.936580951288, 4546.954530454719, 4802.543758343022, 4872.293517439952, 5023.4129423347, 4576.391456869619, 5011.448740638527, 5146.639735218574, 5463.590230732711, 5573.832785016456, 1441.373002743789, 1604.663020765629, 1609.8148712897932, 1618.3007841766098, 1626.300690703009, 942.3874481586886, 978.5792109392887, 1145.4136021609963, 1180.0441642269789, 1180.8110702287593, 4812.398450193726, 4871.856383191384, 5268.023566684811, 5298.311219541512, 5307.9734601326745, 4974.32846565709, 4990.925136122961, 5307.681539107666, 5521.668699878021, 5808.854778630568, 4240.85157019509, 4826.541478559531, 4919.557239848431, 4998.850034498971, 5269.394019687785, 2560.5647040003246, 2584.8755791230874, 2876.7123287671484, 3010.4129694709322, 3113.532970054465, 5262.657927234717, 5494.423085576037, 5726.942730572724, 5936.37668044849, 6064.878702425926, 5273.525382715574, 5613.295225151381, 5743.195952566644, 5773.653580785166, 5920.809300558821, 4958.677685950402, 5906.696388408524, 5962.284525856839, 5995.430434108753, 6045.879082418327, 5211.9478805211465, 5331.6014288213155, 5450.890982180414, 5697.515711164598, 5802.854928626818, 4834.131718636471, 5120.003198752415, 5234.555062819703, 5554.972225138848, 5626.803061892814, 5148.716820574904, 5223.0, 5227.18978558324, 5406.972965135149, 5424.098107512462, 4302.9672878509555, 4474.910501790011, 4564.292534657134, 4570.702904311209, 4574.634029277717, 5162.406323272871, 5384.919226211531, 5394.56843452515, 5777.826665200051, 5904.822855314347, 4816.8554943351755, 5165.767540460613, 5426.891462170655, 5430.945690543202, 5547.195656629847, 5100.744962751922, 5321.308229930084, 5379.515843574097, 5554.139618096764, 5682.488576028178, 4896.975515122402, 4912.287718280758, 5458.235846981346, 5467.523768582381, 5486.9670791968665, 5259.579233661374, 5345.118055520951, 5402.72986350673, 5598.972005140111, 5717.485426311652, 4384.846530371422, 5033.471485494117, 5040.84877453677, 5129.050631014024, 5489.643173193889], "value": 4236.175858689166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.7094104240384, 767.2174382130231, 879.7888506758378, 893.4505279253261, 922.2852289475653, 15565.766513502293, 17016.489505314847, 17229.396971105998, 17557.82442175578, 18114.5471363216, 15965.600859978505, 16144.677106457864, 16164.353425862952, 16192.271347789349, 16192.75710864338, 994.5176589354157, 1029.809485245232, 1031.865857438532, 1032.9690109296732, 1034.7826956339177, 1017.8015287019034, 1019.0879163148977, 1019.4138370436991, 1024.4775164666, 1024.677226673599, 1031.8091153136656, 1057.2070946789909, 1057.2546354819872, 1057.6879820452946, 1071.314358810362, 1071.4589132488084, 1072.3351522056337, 1072.5709716113618, 1073.5437439088435, 1073.5544748929185, 791.3629528229773, 791.8139237279235, 792.1761368177089, 792.7502836606508, 793.111714879333, 17261.223244954006, 17559.20983555743, 17647.647047059116, 18041.097945102825, 18339.724904126448, 13371.064025518217, 13472.593716316032, 13713.314334283346, 13752.0, 13754.105983111165, 12169.72303526274, 12299.508019679202, 12333.0, 12340.465792579373, 12576.182548134342, 3824.770513769155, 3907.6873850091924, 3915.5888631693547, 3945.4673619061346, 4002.5397079335958, 117787.41106294414, 119365.40317298444, 120513.0, 120689.39655301755, 121766.0, 11329.0, 11612.0, 11720.70698232551, 11809.645710628694, 11814.527418903148, 21608.891955540435, 22219.0, 24213.0, 24532.754672453048, 24573.631395529083, 7494.962525187339, 8330.875036874451, 8379.036410812712, 8381.916180838114, 8450.830983380421, 4634.652401069866, 4635.930461043087, 4693.0613877224005, 4702.811887524461, 4727.550882666142, 3121.7034381733697, 3597.172650290448, 3789.488419063391, 3790.639889210521, 3951.05174758056, 565.4628103301798, 661.5336187987457, 663.290279401046, 668.6723505482279, 675.1290834823023, 591.4676790888149, 601.7953895675491, 608.4797498139116, 614.8063360041573, 616.3713012726994, 10352.223583231287, 10515.239767015086, 11054.502547385226, 11278.323300601991, 11308.604198853001, 514.6511973821225, 595.7884950842475, 644.0853987337991, 671.7279501801778, 728.6356821589205, 4574.4739354973935, 4744.098621261949, 4968.116115040607, 5378.650387724785, 5402.945970540323, 80394.0, 102034.0, 104826.0, 105614.0, 109726.0, 78836.6058169717, 79334.0, 85643.57178214194, 86294.56852715697, 95973.56039659411, 74515.6661683763, 77382.61308693534, 77734.83397749151, 78517.42965140665, 80298.39403211903, 10516.421596812248, 11264.718382040186, 11359.375234362189, 11373.829392558953, 11606.70983225426, 47585.52414475811, 48604.0, 48793.756031219615, 50397.0, 50995.0, 53450.46549534611, 67582.66208668925, 68716.0, 70396.240093996, 72618.0, 12822.166559173624, 13282.335883205995, 13337.399817008452, 13757.931210343884, 13817.930910345383, 15065.322060507084, 15579.610509737347, 15845.207739612742, 16127.596810079842, 16334.509964701077, 14287.0, 14662.78005829892, 14687.870258812198, 14867.628309292355, 15328.310226039632, 13885.375158118111, 14763.261836907897, 14768.483103091341, 14850.257487125817, 15105.773413398589, 13475.528356507475, 14139.505117320845, 14150.858491414954, 14674.339654715777, 14686.265686715407, 10129.594816207564, 10421.478926053818, 10511.94744026275, 10687.198460115367, 10745.194110441591, 9190.954045229731, 10000.449975251431, 10730.83903741429, 10976.305103402014, 11791.410429478321], "value": 7970.38593707119, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 115.0, 115.0, 115.0, 115.0, 115.0, 115.0, 115.0, 116.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 106.0, 110.0, 169.0, 116.0, 107.0, 107.0, 105.0, 125.0, 107.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 185.0, 183.0, 182.0, 184.0, 183.0, 185.0, 182.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 50.0, 60.0, 105.0, 90.0, 86.0, 74.0, 69.0, 74.0, 60.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 86.0, 69.0, 68.0, 68.0, 80.0, 70.0, 90.0, 68.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 86.0, 86.0, 85.0, 86.0, 86.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 92.0, 93.0, 93.0, 93.0, 92.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 88.0, 83.0, 83.0, 83.0, 86.0, 84.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 59.0, 59.0, 62.0, 63.0, 63.0, 64.0, 63.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 65.0, 66.0, 63.0, 57.0, 79.0, 87.0, 87.0, 61.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 81.0, 84.0, 79.0, 80.0, 80.0, 78.0, 88.0, 83.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 133.0, 134.0, 133.0, 135.0, 133.0, 134.0, 136.0, 173.0, 143.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 158.0, 161.0, 159.0, 159.0, 218.0, 217.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 66.0, 67.0, 77.0, 81.0, 80.0, 78.0, 63.0, 63.0], "value": 70.1, "unit": "score"}]}]} 17:36:40 INFO - Return code: 0 17:36:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:40 INFO - # TBPL SUCCESS # 17:36:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:36:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:36:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:36:40 INFO - 2017-05-25 17:36:40.561 screenresolution[1654:9548] starting screenresolution argv=screenresolution get 17:36:40 INFO - 2017-05-25 17:36:40.564 screenresolution[1654:9548] Display 0: 1600x1200x32@60 17:36:40 INFO - 2017-05-25 17:36:40.570 screenresolution[1655:9551] starting screenresolution argv=screenresolution list 17:36:40 INFO - Available Modes on Display 0 17:36:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:36:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:36:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:36:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:36:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:36:40 INFO - Intel Iris: 17:36:40 INFO - Chipset Model: Intel Iris 17:36:40 INFO - Type: GPU 17:36:40 INFO - Bus: Built-In 17:36:40 INFO - VRAM (Dynamic, Max): 1536 MB 17:36:40 INFO - Vendor: Intel (0x8086) 17:36:40 INFO - Device ID: 0x0a2e 17:36:40 INFO - Revision ID: 0x0009 17:36:40 INFO - Displays: 17:36:40 INFO - Display: 17:36:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:36:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:36:40 INFO - Display Serial Number: 1600x1200 60 17:36:40 INFO - Main Display: Yes 17:36:40 INFO - Mirror: Off 17:36:40 INFO - Online: Yes 17:36:40 INFO - Rotation: Supported 17:36:40 INFO - Return code: 0 17:36:40 INFO - Running post-action listener: _resource_record_post_action 17:36:40 INFO - [mozharness: 2017-05-26 00:36:40.806970Z] Finished run-tests step (success) 17:36:40 INFO - Running post-run listener: _resource_record_post_run 17:36:40 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 38896640; Write bytes: 420203520; Read time: 248; Write time: 1357 17:36:40 INFO - TinderboxPrint: CPU usage
54.0% 17:36:40 INFO - TinderboxPrint: I/O read bytes / time
38,896,640 / 248 17:36:40 INFO - TinderboxPrint: I/O write bytes / time
420,203,520 / 1,357 17:36:40 INFO - TinderboxPrint: CPU idle
2,196.4 (46.0%) 17:36:40 INFO - TinderboxPrint: CPU system
953.4 (20.0%) 17:36:40 INFO - TinderboxPrint: CPU user
1,619.8 (34.0%) 17:36:40 INFO - TinderboxPrint: Swap in / out
161,103,872 / 0 17:36:40 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123324928; Write bytes: 123113472; Read time: 11034; Write time: 288 17:36:40 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34673664; Write bytes: 295697408; Read time: 223; Write time: 1065 17:36:41 INFO - Running post-run listener: _upload_blobber_files 17:36:41 INFO - Blob upload gear active. 17:36:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:41 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 17:36:41 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:36:41 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:36:41 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:36:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:36:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:42 INFO - (blobuploader) - INFO - Done attempting. 17:36:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:36:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:36:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:43 INFO - (blobuploader) - INFO - Done attempting. 17:36:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:36:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:36:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:44 INFO - (blobuploader) - INFO - Done attempting. 17:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:36:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:46 INFO - (blobuploader) - INFO - Done attempting. 17:36:46 INFO - (blobuploader) - INFO - Iteration through files over. 17:36:46 INFO - Return code: 0 17:36:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:36:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:36:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54b5f8791cb93595c015e1b76949643bbb93d1a2d4e7248d2b7f9885ce849003489cb0647d70f32af4a67ac5dd3cf3d0f9af1a45ddee14a3e8f16d4b36a25e04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/915ba9d014f672fe4c21a71f8fe6e544ce757c8ebe41cee0bd81509ffbfdb0d4da1bb8f14e3e43a9f200bac50676c9774fa28dbc226350fabda98184617d5441", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc9761ed32848db66921db0d44435366e0925831060eb81c82a1de14fc3d19c5f058684526821c80192b01fe3760682c2be7eaf7d9859499539637d7fce8c138", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7252407c4e7dff847d0c38dd4f5cd5e7457d4344faae10c33b7d236782b34d001cfef6635480cf5ea4b8661b3ff253bb5267c8e6c97a28d44b1439a66fc823b5"} 17:36:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:36:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:36:46 INFO - Contents: 17:36:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54b5f8791cb93595c015e1b76949643bbb93d1a2d4e7248d2b7f9885ce849003489cb0647d70f32af4a67ac5dd3cf3d0f9af1a45ddee14a3e8f16d4b36a25e04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/915ba9d014f672fe4c21a71f8fe6e544ce757c8ebe41cee0bd81509ffbfdb0d4da1bb8f14e3e43a9f200bac50676c9774fa28dbc226350fabda98184617d5441", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc9761ed32848db66921db0d44435366e0925831060eb81c82a1de14fc3d19c5f058684526821c80192b01fe3760682c2be7eaf7d9859499539637d7fce8c138", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7252407c4e7dff847d0c38dd4f5cd5e7457d4344faae10c33b7d236782b34d001cfef6635480cf5ea4b8661b3ff253bb5267c8e6c97a28d44b1439a66fc823b5"} 17:36:46 INFO - Running post-run listener: copy_logs_to_upload_dir 17:36:46 INFO - Copying logs to upload dir... 17:36:46 INFO - mkdir: /builds/slave/test/build/upload/logs 17:36:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.519232 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-25 17:36:46.609731) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:36:46.613234) ========= 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.hxEhgaCABu/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.fNleFZnoAV/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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/Mozilla-Beta/sha512/54b5f8791cb93595c015e1b76949643bbb93d1a2d4e7248d2b7f9885ce849003489cb0647d70f32af4a67ac5dd3cf3d0f9af1a45ddee14a3e8f16d4b36a25e04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/915ba9d014f672fe4c21a71f8fe6e544ce757c8ebe41cee0bd81509ffbfdb0d4da1bb8f14e3e43a9f200bac50676c9774fa28dbc226350fabda98184617d5441", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc9761ed32848db66921db0d44435366e0925831060eb81c82a1de14fc3d19c5f058684526821c80192b01fe3760682c2be7eaf7d9859499539637d7fce8c138", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7252407c4e7dff847d0c38dd4f5cd5e7457d4344faae10c33b7d236782b34d001cfef6635480cf5ea4b8661b3ff253bb5267c8e6c97a28d44b1439a66fc823b5"} build_url:https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010876 build_url: 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54b5f8791cb93595c015e1b76949643bbb93d1a2d4e7248d2b7f9885ce849003489cb0647d70f32af4a67ac5dd3cf3d0f9af1a45ddee14a3e8f16d4b36a25e04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/915ba9d014f672fe4c21a71f8fe6e544ce757c8ebe41cee0bd81509ffbfdb0d4da1bb8f14e3e43a9f200bac50676c9774fa28dbc226350fabda98184617d5441", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc9761ed32848db66921db0d44435366e0925831060eb81c82a1de14fc3d19c5f058684526821c80192b01fe3760682c2be7eaf7d9859499539637d7fce8c138", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7252407c4e7dff847d0c38dd4f5cd5e7457d4344faae10c33b7d236782b34d001cfef6635480cf5ea4b8661b3ff253bb5267c8e6c97a28d44b1439a66fc823b5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:36:46.646055) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:36:46.646447) ========= 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.hxEhgaCABu/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.fNleFZnoAV/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.005168 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:36:46.696857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:36:46.697191) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:36:46.697531) ========= ========= Total master_lag: 0.18 =========