builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0028 starttime: 1495225797.65 results: success (0) buildid: 20170519115822 builduid: 0fb720275bd54dc9a76655a97ef3555b revision: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.652653) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.653127) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.653411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.678375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.678759) ========= 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.wALnJ8zn0H/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.ogwDHsemTB/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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-19 13:29:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.3M=0.001s 2017-05-19 13:29:58 (21.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111447 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.812706) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.813047) ========= 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.wALnJ8zn0H/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.ogwDHsemTB/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.032009 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:57.866268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 13:29:57.866635) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0241994e3aad3594cae0d42c7d80a2dff7b245e8 --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 0241994e3aad3594cae0d42c7d80a2dff7b245e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wALnJ8zn0H/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.ogwDHsemTB/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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-19 13:29:58,166 truncating revision to first 12 chars 2017-05-19 13:29:58,166 Setting DEBUG logging. 2017-05-19 13:29:58,166 attempt 1/10 2017-05-19 13:29:58,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0241994e3aad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 13:29:59,738 unpacking tar archive at: mozilla-beta-0241994e3aad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.839792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 13:29:59.728395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:59.728715) ========= script_repo_revision: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:59.729103) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:59.729413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 13:29:59.747743) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-19 13:29:59.748026) ========= /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.wALnJ8zn0H/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.ogwDHsemTB/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:00 INFO - MultiFileLogger online at 20170519 13:30:00 in /builds/slave/test 13:30:00 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 13:30:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:30:00 INFO - {'append_to_log': False, 13:30:00 INFO - 'base_work_dir': '/builds/slave/test', 13:30:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:30:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:30:00 INFO - 'branch': 'Mozilla-Beta', 13:30:00 INFO - 'buildbot_json_path': 'buildprops.json', 13:30:00 INFO - 'config_files': ('talos/mac_config.py',), 13:30:00 INFO - 'default_actions': ('clobber', 13:30:00 INFO - 'read-buildbot-config', 13:30:00 INFO - 'download-and-extract', 13:30:00 INFO - 'populate-webroot', 13:30:00 INFO - 'create-virtualenv', 13:30:00 INFO - 'install', 13:30:00 INFO - 'run-tests'), 13:30:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:30:00 INFO - 'download_minidump_stackwalk': True, 13:30:00 INFO - 'download_symbols': 'ondemand', 13:30:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:30:00 INFO - 'tooltool.py': '/tools/tooltool.py', 13:30:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:30:00 INFO - '/tools/misc-python/virtualenv.py')}, 13:30:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:30:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:30:00 INFO - 'gecko_profile': False, 13:30:00 INFO - 'gecko_profile_interval': 0, 13:30:00 INFO - 'installer_path': 'installer.exe', 13:30:00 INFO - 'log_level': 'info', 13:30:00 INFO - 'log_name': 'talos', 13:30:00 INFO - 'log_to_console': True, 13:30:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:30:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:30:00 INFO - 'opt_config_files': (), 13:30:00 INFO - 'pip_index': False, 13:30:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:00 INFO - 'cmd': ('bash', 13:30:00 INFO - '-c', 13:30:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:00 INFO - 'enabled': True, 13:30:00 INFO - 'halt_on_failure': False, 13:30:00 INFO - 'name': 'check_screen_resolution'},), 13:30:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:00 INFO - 'cmd': ('bash', 13:30:00 INFO - '-c', 13:30:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:00 INFO - 'enabled': True, 13:30:00 INFO - 'halt_on_failure': False, 13:30:00 INFO - 'name': 'check_screen_resolution'},), 13:30:00 INFO - 'run_cmd_checks_enabled': True, 13:30:00 INFO - 'suite': 'dromaeojs', 13:30:00 INFO - 'system_bits': '32', 13:30:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:30:00 INFO - 'title': 't-yosemite-r7-0028', 13:30:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:30:00 INFO - 'use_talos_json': True, 13:30:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:30:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:30:00 INFO - 'work_dir': 'build'} 13:30:00 INFO - [mozharness: 2017-05-19 20:30:00.161848Z] Running clobber step. 13:30:00 INFO - Running pre-action listener: _resource_record_pre_action 13:30:00 INFO - Running main action method: clobber 13:30:00 INFO - rmtree: /builds/slave/test/build 13:30:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:30:02 INFO - Running post-action listener: _resource_record_post_action 13:30:02 INFO - [mozharness: 2017-05-19 20:30:02.710416Z] Finished clobber step (success) 13:30:02 INFO - [mozharness: 2017-05-19 20:30:02.710556Z] Running read-buildbot-config step. 13:30:02 INFO - Running pre-action listener: _resource_record_pre_action 13:30:02 INFO - Running main action method: read_buildbot_config 13:30:02 INFO - Using buildbot properties: 13:30:02 INFO - { 13:30:02 INFO - "project": "", 13:30:02 INFO - "product": "firefox", 13:30:02 INFO - "script_repo_revision": "production", 13:30:02 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 13:30:02 INFO - "repository": "", 13:30:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 13:30:02 INFO - "buildid": "20170519115822", 13:30:02 INFO - "pgo_build": "False", 13:30:02 INFO - "basedir": "/builds/slave/test", 13:30:02 INFO - "buildnumber": 30, 13:30:02 INFO - "slavename": "t-yosemite-r7-0028", 13:30:02 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:30:02 INFO - "platform": "yosemite_r7", 13:30:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:30:02 INFO - "branch": "mozilla-beta", 13:30:02 INFO - "repo_path": "releases/mozilla-beta", 13:30:02 INFO - "revision": "0241994e3aad3594cae0d42c7d80a2dff7b245e8", 13:30:02 INFO - "stage_platform": "macosx64", 13:30:02 INFO - "builduid": "0fb720275bd54dc9a76655a97ef3555b", 13:30:02 INFO - "slavebuilddir": "test" 13:30:02 INFO - } 13:30:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 13:30:02 INFO - Running post-action listener: _resource_record_post_action 13:30:02 INFO - [mozharness: 2017-05-19 20:30:02.713939Z] Finished read-buildbot-config step (success) 13:30:02 INFO - [mozharness: 2017-05-19 20:30:02.714090Z] Running download-and-extract step. 13:30:02 INFO - Running pre-action listener: _resource_record_pre_action 13:30:02 INFO - Running main action method: download_and_extract 13:30:02 INFO - mkdir: /builds/slave/test/build/tests 13:30: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')]} 13:30:02 INFO - trying https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 13:30:02 INFO - Downloading https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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 13:30:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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 13:30:03 INFO - Downloaded 1287 bytes. 13:30:03 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:30:03 INFO - Using the following test package requirements: 13:30:03 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 13:30:03 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:30:03 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 13:30:03 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 13:30:03 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:30:03 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 13:30:03 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:30:03 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 13:30:03 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 13:30:03 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 13:30:03 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:03 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 13:30:03 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 13:30:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:30:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:30:03 INFO - Fetch https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:30:05 INFO - Expected file size: 51397337 13:30:05 INFO - Obtained file size: 51397337 13:30:20 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 13:30:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:30:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:30:20 INFO - Fetch https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:30:22 INFO - Expected file size: 51397337 13:30:22 INFO - Obtained file size: 51397337 13:30:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 13:30:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 13:30:38 INFO - Fetch https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 13:30:39 INFO - Expected file size: 14404449 13:30:39 INFO - Obtained file size: 14404449 13:30:39 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')]} 13:30:39 INFO - trying https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:30:39 INFO - Downloading https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 13:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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 13:30:41 INFO - Downloaded 59541826 bytes. 13:30:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:30:41 INFO - mkdir: /builds/slave/test/properties 13:30:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:30:41 INFO - Writing to file /builds/slave/test/properties/build_url 13:30:41 INFO - Contents: 13:30:41 INFO - build_url:https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:30:42 INFO - Running post-action listener: _resource_record_post_action 13:30:42 INFO - Running post-action listener: set_extra_try_arguments 13:30:42 INFO - [mozharness: 2017-05-19 20:30:42.318662Z] Finished download-and-extract step (success) 13:30:42 INFO - [mozharness: 2017-05-19 20:30:42.318804Z] Running populate-webroot step. 13:30:42 INFO - Running pre-action listener: _resource_record_pre_action 13:30:42 INFO - Running main action method: populate_webroot 13:30:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:30:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:30:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:30:42 INFO - '--tppagecycles', 13:30:42 INFO - '7'], 13:30:42 INFO - 'tests': ['tsvgm']}}, 13:30:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:30:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:30:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:30:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:30:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['damp', 'tps']}, 13:30:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'tests': ['damp', 'tps']}, 13:30:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['dromaeo_dom']}, 13:30:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:30:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['basic_compositor_video']}, 13:30:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:30:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['a11yr', 13:30:42 INFO - 'ts_paint', 13:30:42 INFO - 'tpaint', 13:30:42 INFO - 'sessionrestore', 13:30:42 INFO - 'sessionrestore_no_auto_restore', 13:30:42 INFO - 'tabpaint']}, 13:30:42 INFO - 'other-e10s': {'tests': ['a11yr', 13:30:42 INFO - 'ts_paint', 13:30:42 INFO - 'tpaint', 13:30:42 INFO - 'sessionrestore', 13:30:42 INFO - 'sessionrestore_no_auto_restore', 13:30:42 INFO - 'tabpaint']}, 13:30:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:30:42 INFO - 'ts_paint', 13:30:42 INFO - 'tpaint', 13:30:42 INFO - 'sessionrestore', 13:30:42 INFO - 'sessionrestore_no_auto_restore', 13:30:42 INFO - 'tabpaint']}, 13:30:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:30:42 INFO - 'ts_paint', 13:30:42 INFO - 'tpaint', 13:30:42 INFO - 'sessionrestore', 13:30:42 INFO - 'sessionrestore_no_auto_restore', 13:30:42 INFO - 'tabpaint']}, 13:30:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['a11yr', 13:30:42 INFO - 'ts_paint', 13:30:42 INFO - 'tpaint', 13:30:42 INFO - 'sessionrestore', 13:30:42 INFO - 'sessionrestore_no_auto_restore', 13:30:42 INFO - 'tabpaint']}, 13:30:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['a11yr', 13:30:42 INFO - 'ts_paint', 13:30:42 INFO - 'tpaint', 13:30:42 INFO - 'sessionrestore', 13:30:42 INFO - 'sessionrestore_no_auto_restore', 13:30:42 INFO - 'tabpaint']}, 13:30:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['tsvgx', 13:30:42 INFO - 'tsvgr_opacity', 13:30:42 INFO - 'tart', 13:30:42 INFO - 'tscrollx', 13:30:42 INFO - 'cart', 13:30:42 INFO - 'tsvg_static']}, 13:30:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:30:42 INFO - 'tsvgr_opacity', 13:30:42 INFO - 'tart', 13:30:42 INFO - 'tscrollx', 13:30:42 INFO - 'cart', 13:30:42 INFO - 'tsvg_static']}, 13:30:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'talos_options': ['--disable-e10s'], 13:30:42 INFO - 'tests': ['tp5o']}, 13:30:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:30:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'talos_options': ['--disable-e10s', 13:30:42 INFO - '--xperf_path', 13:30:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:42 INFO - 'tests': ['tp5n']}, 13:30:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:30:42 INFO - 'talos_options': ['--xperf_path', 13:30:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:42 INFO - 'tests': ['tp5n']}}, 13:30:42 INFO - 'talos.zip': {'path': '', 13:30:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:30:42 INFO - Running post-action listener: _resource_record_post_action 13:30:42 INFO - [mozharness: 2017-05-19 20:30:42.337316Z] Finished populate-webroot step (success) 13:30:42 INFO - [mozharness: 2017-05-19 20:30:42.337436Z] Running create-virtualenv step. 13:30:42 INFO - Running pre-action listener: _resource_record_pre_action 13:30:42 INFO - Running main action method: create_virtualenv 13:30:42 INFO - Creating virtualenv /builds/slave/test/build/venv 13:30:42 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 13:30:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:30:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:30:42 INFO - Using real prefix '/tools/python27' 13:30:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:30:43 INFO - Installing distribute.............................................................................................................................................................................................done. 13:30:46 INFO - Installing pip.................done. 13:30:46 INFO - Return code: 0 13:30:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:30:46 INFO - Reading from file tmpfile_stdout 13:30:46 INFO - Output received: 13:30:46 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) 13:30:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:30: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')]} 13:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30: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')]} 13:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:46 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:46 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 13:30:46 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 13:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:46 INFO - 'HOME': '/Users/cltbld', 13:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:46 INFO - 'LOGNAME': 'cltbld', 13:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:46 INFO - 'MOZ_NO_REMOTE': '1', 13:30:46 INFO - 'NO_EM_RESTART': '1', 13:30:46 INFO - 'PAGER': '/bin/cat', 13:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:46 INFO - 'PWD': '/builds/slave/test', 13:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:46 INFO - 'SHELL': '/bin/bash', 13:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:46 INFO - 'USER': 'cltbld', 13:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:46 INFO - 'XPC_FLAGS': '0x0', 13:30:46 INFO - 'XPC_SERVICE_NAME': '0', 13:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:46 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 13:30:46 INFO - Cleaning up... 13:30:46 INFO - Return code: 0 13:30:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:30: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')]} 13:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30: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')]} 13:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:46 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:46 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 13:30:46 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 13:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:46 INFO - 'HOME': '/Users/cltbld', 13:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:46 INFO - 'LOGNAME': 'cltbld', 13:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:46 INFO - 'MOZ_NO_REMOTE': '1', 13:30:46 INFO - 'NO_EM_RESTART': '1', 13:30:46 INFO - 'PAGER': '/bin/cat', 13:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:46 INFO - 'PWD': '/builds/slave/test', 13:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:46 INFO - 'SHELL': '/bin/bash', 13:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:46 INFO - 'USER': 'cltbld', 13:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:46 INFO - 'XPC_FLAGS': '0x0', 13:30:46 INFO - 'XPC_SERVICE_NAME': '0', 13:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:46 INFO - Downloading/unpacking psutil>=3.1.1 13:30:46 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 13:30:46 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 13:30:46 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 13:30:46 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 13:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:30:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:30:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:30:48 INFO - Installing collected packages: psutil 13:30:48 INFO - Running setup.py install for psutil 13:30:48 INFO - building 'psutil._psutil_osx' extension 13:30:48 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 13:30:49 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 13:30:49 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 13:30:49 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 13:30:49 INFO - building 'psutil._psutil_posix' extension 13:30:49 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 13:30:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:30:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:30:49 INFO - ^ 13:30:49 INFO - 1 warning generated. 13:30:49 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 13:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:30:49 INFO - Successfully installed psutil 13:30:49 INFO - Cleaning up... 13:30:49 INFO - Return code: 0 13:30:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:30:49 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')]} 13:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:49 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')]} 13:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:49 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:49 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 13:30:49 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 13:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:49 INFO - 'HOME': '/Users/cltbld', 13:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:49 INFO - 'LOGNAME': 'cltbld', 13:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:49 INFO - 'MOZ_NO_REMOTE': '1', 13:30:49 INFO - 'NO_EM_RESTART': '1', 13:30:49 INFO - 'PAGER': '/bin/cat', 13:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:49 INFO - 'PWD': '/builds/slave/test', 13:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:49 INFO - 'SHELL': '/bin/bash', 13:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:49 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:49 INFO - 'USER': 'cltbld', 13:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:49 INFO - 'XPC_FLAGS': '0x0', 13:30:49 INFO - 'XPC_SERVICE_NAME': '0', 13:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:30:51 INFO - Installing collected packages: mozsystemmonitor 13:30:51 INFO - Running setup.py install for mozsystemmonitor 13:30:51 INFO - Successfully installed mozsystemmonitor 13:30:51 INFO - Cleaning up... 13:30:51 INFO - Return code: 0 13:30:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:30:51 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')]} 13:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:51 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')]} 13:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:51 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:51 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 13:30:51 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 13:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:51 INFO - 'HOME': '/Users/cltbld', 13:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:51 INFO - 'LOGNAME': 'cltbld', 13:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:51 INFO - 'MOZ_NO_REMOTE': '1', 13:30:51 INFO - 'NO_EM_RESTART': '1', 13:30:51 INFO - 'PAGER': '/bin/cat', 13:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:51 INFO - 'PWD': '/builds/slave/test', 13:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:51 INFO - 'SHELL': '/bin/bash', 13:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:51 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:51 INFO - 'USER': 'cltbld', 13:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:51 INFO - 'XPC_FLAGS': '0x0', 13:30:51 INFO - 'XPC_SERVICE_NAME': '0', 13:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:52 INFO - Downloading/unpacking jsonschema==2.5.1 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:30:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:30:53 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 13:30:53 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 13:30:53 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 13:30:53 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 13:30:53 INFO - Downloading functools32-3.2.3-2.tar.gz 13:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:30:53 INFO - warning: no files found matching '*.txt' 13:30:53 INFO - no previously-included directories found matching 'build' 13:30:53 INFO - no previously-included directories found matching 'dist' 13:30:53 INFO - no previously-included directories found matching '.git*' 13:30:53 INFO - Installing collected packages: jsonschema, functools32 13:30:53 INFO - Running setup.py install for functools32 13:30:54 INFO - warning: no files found matching '*.txt' 13:30:54 INFO - no previously-included directories found matching 'build' 13:30:54 INFO - no previously-included directories found matching 'dist' 13:30:54 INFO - no previously-included directories found matching '.git*' 13:30:54 INFO - Successfully installed jsonschema functools32 13:30:54 INFO - Cleaning up... 13:30:54 INFO - Return code: 0 13:30:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:30:54 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')]} 13:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:54 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')]} 13:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:55 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 13:30:55 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 13:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:55 INFO - 'HOME': '/Users/cltbld', 13:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:55 INFO - 'LOGNAME': 'cltbld', 13:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:55 INFO - 'MOZ_NO_REMOTE': '1', 13:30:55 INFO - 'NO_EM_RESTART': '1', 13:30:55 INFO - 'PAGER': '/bin/cat', 13:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:55 INFO - 'PWD': '/builds/slave/test', 13:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:55 INFO - 'SHELL': '/bin/bash', 13:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:55 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:55 INFO - 'USER': 'cltbld', 13:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:55 INFO - 'XPC_FLAGS': '0x0', 13:30:55 INFO - 'XPC_SERVICE_NAME': '0', 13:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:30:55 INFO - Cleaning up... 13:30:55 INFO - Return code: 0 13:30:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:30:55 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')]} 13:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:55 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')]} 13:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:55 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 13:30:55 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 13:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:55 INFO - 'HOME': '/Users/cltbld', 13:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:55 INFO - 'LOGNAME': 'cltbld', 13:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:55 INFO - 'MOZ_NO_REMOTE': '1', 13:30:55 INFO - 'NO_EM_RESTART': '1', 13:30:55 INFO - 'PAGER': '/bin/cat', 13:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:55 INFO - 'PWD': '/builds/slave/test', 13:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:55 INFO - 'SHELL': '/bin/bash', 13:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:55 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:55 INFO - 'USER': 'cltbld', 13:30:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:56 INFO - 'XPC_FLAGS': '0x0', 13:30:56 INFO - 'XPC_SERVICE_NAME': '0', 13:30:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:56 INFO - Downloading/unpacking blobuploader==1.2.4 13:30:56 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 13:30:56 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 13:30:56 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 13:30:56 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 13:30:58 INFO - Downloading blobuploader-1.2.4.tar.gz 13:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:30:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:30:58 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 13:30:58 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 13:30:58 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 13:30:58 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 13:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:30:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:30:58 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 13:30:58 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 13:30:58 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 13:30:58 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 13:30:58 INFO - Downloading docopt-0.6.1.tar.gz 13:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:30:59 INFO - Installing collected packages: blobuploader, requests, docopt 13:30:59 INFO - Running setup.py install for blobuploader 13:30:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:30:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:30:59 INFO - Running setup.py install for requests 13:30:59 INFO - Running setup.py install for docopt 13:30:59 INFO - Successfully installed blobuploader requests docopt 13:30:59 INFO - Cleaning up... 13:30:59 INFO - Return code: 0 13:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30:59 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')]} 13:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:59 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')]} 13:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:59 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:30:59 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 13:30:59 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 13:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:59 INFO - 'HOME': '/Users/cltbld', 13:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:59 INFO - 'LOGNAME': 'cltbld', 13:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:59 INFO - 'MOZ_NO_REMOTE': '1', 13:30:59 INFO - 'NO_EM_RESTART': '1', 13:30:59 INFO - 'PAGER': '/bin/cat', 13:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:59 INFO - 'PWD': '/builds/slave/test', 13:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:59 INFO - 'SHELL': '/bin/bash', 13:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:30:59 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:59 INFO - 'USER': 'cltbld', 13:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:59 INFO - 'XPC_FLAGS': '0x0', 13:30:59 INFO - 'XPC_SERVICE_NAME': '0', 13:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-xSsr_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ItTodS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-BOzCpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-EMM704-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-_uuW9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-0hOpdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-aDR57i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-N1aM7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-U1PpaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-JtZFCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-l7BIIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-jX4yaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-2AEMVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ngWPBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-vSJwIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-6W7IHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-D6qigb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:31:01 INFO - Running setup.py install for manifestparser 13:31:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:31:02 INFO - Running setup.py install for mozcrash 13:31:02 INFO - Running setup.py install for mozdebug 13:31:02 INFO - Running setup.py install for mozdevice 13:31:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:31:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:31:02 INFO - Running setup.py install for mozfile 13:31:02 INFO - Running setup.py install for mozhttpd 13:31:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:31:02 INFO - Running setup.py install for mozinfo 13:31:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:31:02 INFO - Running setup.py install for mozInstall 13:31:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Running setup.py install for mozleak 13:31:03 INFO - Running setup.py install for mozlog 13:31:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Running setup.py install for moznetwork 13:31:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Running setup.py install for mozprocess 13:31:03 INFO - Running setup.py install for mozprofile 13:31:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:31:03 INFO - Running setup.py install for mozrunner 13:31:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:31:04 INFO - Running setup.py install for mozscreenshot 13:31:04 INFO - Running setup.py install for moztest 13:31:04 INFO - Running setup.py install for mozversion 13:31:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:31:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:31:04 INFO - Cleaning up... 13:31:04 INFO - Return code: 0 13:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:04 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')]} 13:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:04 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')]} 13:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:04 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:31:04 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 13:31:04 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 13:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:04 INFO - 'HOME': '/Users/cltbld', 13:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:04 INFO - 'LOGNAME': 'cltbld', 13:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:04 INFO - 'MOZ_NO_REMOTE': '1', 13:31:04 INFO - 'NO_EM_RESTART': '1', 13:31:04 INFO - 'PAGER': '/bin/cat', 13:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:04 INFO - 'PWD': '/builds/slave/test', 13:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:04 INFO - 'SHELL': '/bin/bash', 13:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:31:04 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:04 INFO - 'USER': 'cltbld', 13:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:04 INFO - 'XPC_FLAGS': '0x0', 13:31:04 INFO - 'XPC_SERVICE_NAME': '0', 13:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:04 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GgWsP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:04 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)) 13:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:04 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-9SxJkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-zGeqtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-cgPfR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-jkf4Y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-eSKSm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-SSEqCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-X6PJ_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-CQi03J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-UBZQYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-lCc791-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:05 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)) 13:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:05 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-sjHWGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:06 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-_6S75C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:06 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ki6iTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:06 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)) 13:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:06 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-KF5ToC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:06 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)) 13:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:06 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-tHqchT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:06 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)) 13:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:06 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-rvN__v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:06 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)) 13:31:06 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)) 13:31:06 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)) 13:31:06 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)) 13:31:06 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)) 13:31:06 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)) 13:31:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:07 INFO - Downloading blessings-1.6.tar.gz 13:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:31:08 INFO - Installing collected packages: blessings 13:31:08 INFO - Running setup.py install for blessings 13:31:08 INFO - Successfully installed blessings 13:31:08 INFO - Cleaning up... 13:31:08 INFO - Return code: 0 13:31:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:08 INFO - Reading from file tmpfile_stdout 13:31:08 INFO - Current package versions: 13:31:08 INFO - blessings == 1.6 13:31:08 INFO - blobuploader == 1.2.4 13:31:08 INFO - docopt == 0.6.1 13:31:08 INFO - functools32 == 3.2.3-2 13:31:08 INFO - jsonschema == 2.5.1 13:31:08 INFO - manifestparser == 1.1 13:31:08 INFO - mozInstall == 1.12 13:31:08 INFO - mozcrash == 1.0 13:31:08 INFO - mozdebug == 0.1 13:31:08 INFO - mozdevice == 0.49 13:31:08 INFO - mozfile == 1.2 13:31:08 INFO - mozhttpd == 0.7 13:31:08 INFO - mozinfo == 0.9 13:31:08 INFO - mozleak == 0.1 13:31:08 INFO - mozlog == 3.4 13:31:08 INFO - moznetwork == 0.27 13:31:08 INFO - mozprocess == 0.25 13:31:08 INFO - mozprofile == 0.28 13:31:08 INFO - mozrunner == 6.13 13:31:08 INFO - mozscreenshot == 0.1 13:31:08 INFO - mozsystemmonitor == 0.3 13:31:08 INFO - moztest == 0.8 13:31:08 INFO - mozversion == 1.4 13:31:08 INFO - psutil == 3.1.1 13:31:08 INFO - requests == 1.2.3 13:31:08 INFO - wsgiref == 0.1.2 13:31:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:08 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')]} 13:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:08 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')]} 13:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:08 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:31:08 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 13:31:08 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 13:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:08 INFO - 'HOME': '/Users/cltbld', 13:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:08 INFO - 'LOGNAME': 'cltbld', 13:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:08 INFO - 'MOZ_NO_REMOTE': '1', 13:31:08 INFO - 'NO_EM_RESTART': '1', 13:31:08 INFO - 'PAGER': '/bin/cat', 13:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:08 INFO - 'PWD': '/builds/slave/test', 13:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:08 INFO - 'SHELL': '/bin/bash', 13:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:31:08 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:08 INFO - 'USER': 'cltbld', 13:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:08 INFO - 'XPC_FLAGS': '0x0', 13:31:08 INFO - 'XPC_SERVICE_NAME': '0', 13:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 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)) 13:31:08 INFO - Cleaning up... 13:31:08 INFO - Return code: 0 13:31:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:31:08 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')]} 13:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:08 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')]} 13:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:08 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 0x108f27b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9863c953b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, '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.wALnJ8zn0H/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.ogwDHsemTB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:31:08 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 13:31:08 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 13:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:08 INFO - 'HOME': '/Users/cltbld', 13:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:08 INFO - 'LOGNAME': 'cltbld', 13:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:08 INFO - 'MOZ_NO_REMOTE': '1', 13:31:08 INFO - 'NO_EM_RESTART': '1', 13:31:08 INFO - 'PAGER': '/bin/cat', 13:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:08 INFO - 'PWD': '/builds/slave/test', 13:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:08 INFO - 'SHELL': '/bin/bash', 13:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:31:08 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:08 INFO - 'USER': 'cltbld', 13:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:08 INFO - 'XPC_FLAGS': '0x0', 13:31:08 INFO - 'XPC_SERVICE_NAME': '0', 13:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:31:09 INFO - Cleaning up... 13:31:09 INFO - Return code: 0 13:31:09 INFO - Running post-action listener: _resource_record_post_action 13:31:09 INFO - Running post-action listener: _start_resource_monitoring 13:31:09 INFO - Starting resource monitoring. 13:31:09 INFO - [mozharness: 2017-05-19 20:31:09.154773Z] Finished create-virtualenv step (success) 13:31:09 INFO - [mozharness: 2017-05-19 20:31:09.155721Z] Running install step. 13:31:09 INFO - Running pre-action listener: _resource_record_pre_action 13:31:09 INFO - Running main action method: install 13:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:09 INFO - Reading from file tmpfile_stdout 13:31:09 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:09 INFO - Reading from file tmpfile_stdout 13:31:09 INFO - Output received: 13:31:09 INFO - Usage: mozinstall [options] installer 13:31:09 INFO - Options: 13:31:09 INFO - -h, --help show this help message and exit 13:31:09 INFO - -d DEST, --destination=DEST 13:31:09 INFO - Directory to install application into. [default: 13:31:09 INFO - "/builds/slave/test"] 13:31:09 INFO - --app=APP Application being installed. [default: firefox] 13:31:09 INFO - mkdir: /builds/slave/test/build/application 13:31:09 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'] 13:31:09 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 13:31:24 INFO - Reading from file tmpfile_stdout 13:31:24 INFO - Output received: 13:31:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:31:24 INFO - Running post-action listener: _resource_record_post_action 13:31:24 INFO - [mozharness: 2017-05-19 20:31:24.012644Z] Finished install step (success) 13:31:24 INFO - [mozharness: 2017-05-19 20:31:24.012770Z] Running run-tests step. 13:31:24 INFO - Running pre-action listener: _resource_record_pre_action 13:31:24 INFO - Running main action method: run_tests 13:31:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:31:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:31:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:31:24 INFO - 2017-05-19 13:31:24.027 screenresolution[1623:7238] starting screenresolution argv=screenresolution get 13:31:24 INFO - 2017-05-19 13:31:24.029 screenresolution[1623:7238] Display 0: 1600x1200x32@60 13:31:24 INFO - 2017-05-19 13:31:24.035 screenresolution[1624:7240] starting screenresolution argv=screenresolution list 13:31:24 INFO - Available Modes on Display 0 13:31:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:31:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:31:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:31:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:31:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:31:24 INFO - Intel Iris: 13:31:24 INFO - Chipset Model: Intel Iris 13:31:24 INFO - Type: GPU 13:31:24 INFO - Bus: Built-In 13:31:24 INFO - VRAM (Dynamic, Max): 1536 MB 13:31:24 INFO - Vendor: Intel (0x8086) 13:31:24 INFO - Device ID: 0x0a2e 13:31:24 INFO - Revision ID: 0x0009 13:31:24 INFO - Displays: 13:31:24 INFO - Display: 13:31:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:31:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:31:24 INFO - Display Serial Number: 1600x1200 60 13:31:24 INFO - Main Display: Yes 13:31:24 INFO - Mirror: Off 13:31:24 INFO - Online: Yes 13:31:24 INFO - Rotation: Supported 13:31:24 INFO - Return code: 0 13:31:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:31:24 INFO - Python 2.7.3 13:31:24 INFO - Return code: 0 13:31:24 INFO - grabbing minidump binary from tooltool 13:31: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')]} 13:31:24 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 0x108d05d30>, '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 0x108c2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cfabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:31:24 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 13:31:24 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 13:31:24 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 13:31:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:31:24 INFO - Return code: 0 13:31:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:31:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:31:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:31:24 INFO - ENV: RUST_BACKTRACE is now 1 13:31:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:31:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:31:24 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/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 13:31:24 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/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0028 --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 13:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wALnJ8zn0H/Render', 13:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:24 INFO - 'HOME': '/Users/cltbld', 13:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:24 INFO - 'LOGNAME': 'cltbld', 13:31:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:31:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:24 INFO - 'MOZ_NO_REMOTE': '1', 13:31:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:31:24 INFO - 'NO_EM_RESTART': '1', 13:31:24 INFO - 'PAGER': '/bin/cat', 13:31:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:24 INFO - 'PWD': '/builds/slave/test', 13:31:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:24 INFO - 'RUST_BACKTRACE': '1', 13:31:24 INFO - 'SHELL': '/bin/bash', 13:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ogwDHsemTB/Listeners', 13:31:24 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:24 INFO - 'USER': 'cltbld', 13:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:24 INFO - 'XPC_FLAGS': '0x0', 13:31:24 INFO - 'XPC_SERVICE_NAME': '0', 13:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:24 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/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 13:31:24 INFO - mozversion application_buildid: 20170519115822 13:31:24 INFO - mozversion application_changeset: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 13:31:24 INFO - mozversion application_display_name: Firefox 13:31:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:31:24 INFO - mozversion application_name: Firefox 13:31:24 INFO - mozversion application_remotingname: firefox 13:31:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:31:24 INFO - mozversion application_vendor: Mozilla 13:31:24 INFO - mozversion application_version: 54.0 13:31:24 INFO - mozversion platform_buildid: 20170519115822 13:31:24 INFO - mozversion platform_changeset: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 13:31:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:31:24 INFO - mozversion platform_version: 54.0 13:31:24 INFO - using testdate: 1495225884 13:31:24 INFO - actual date: 1495225884 13:31:24 INFO - starting webserver on 'localhost:49220' 13:31:24 INFO - SUITE-START | Running 2 tests 13:31:24 INFO - TEST-START | dromaeo_css 13:31:24 INFO - operating with platform_type : mac_ 13:31:24 INFO - Initialising browser for dromaeo_css test... 13:31:24 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpVH5FF1/profile http://localhost:49220/getInfo.html) 13:31:27 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 13:31:27 INFO - PROCESS | 1634 | colorDepth:24 13:31:27 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 13:31:27 INFO - PROCESS | 1634 | __metrics 13:31:27 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" 13:31:27 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] 13:31:28 INFO - TEST-INFO | 1634: exit 0 13:31:28 INFO - Browser initialized. 13:31:28 INFO - Running cycle 1/1 for dromaeo_css test... 13:31:28 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpVH5FF1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:31:40 INFO - PROCESS | 1638 | RSS: Main: 165163008 13:31:40 INFO - PROCESS | 1638 | 13:34:43 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49220/tests/dromaeo/cssquery-ext.html) 13:34:45 INFO - PROCESS | 1638 | RSS: Main: 290607104 13:34:45 INFO - PROCESS | 1638 | 13:37:48 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-ext.html (next: http://localhost:49220/tests/dromaeo/cssquery-jquery.html) 13:37:48 INFO - PROCESS | 1638 | RSS: Main: 304107520 13:37:48 INFO - PROCESS | 1638 | 13:40:51 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49220/tests/dromaeo/cssquery-mootools.html) 13:40:51 INFO - PROCESS | 1638 | RSS: Main: 282603520 13:40:51 INFO - PROCESS | 1638 | 13:43:54 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49220/tests/dromaeo/cssquery-prototype.html) 13:43:54 INFO - PROCESS | 1638 | RSS: Main: 276426752 13:43:54 INFO - PROCESS | 1638 | 13:46:58 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49220/tests/dromaeo/cssquery-yui.html) 13:46:59 INFO - PROCESS | 1638 | RSS: Main: 273149952 13:46:59 INFO - PROCESS | 1638 | 13:50:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-yui.html (next: http://localhost:49220/tests/dromaeo/cssquery-dojo.html) 13:50:02 INFO - PROCESS | 1638 | RSS: Main: 462565376 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | __start_tp_report 13:50:02 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 13:50:02 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 13:50:02 INFO - PROCESS | 1638 | |i|pagename|runs| 13:50:02 INFO - PROCESS | 1638 | |0;dojo.html;3480.582330120385;3510.017195185348;3516.8241587920597;3535.6817886390254;3571.214332846773;5349.22436246744;5416.756245968931;6054.091886217069;6118.847028824276;6154.538409619274;6081.300650425192;6113.327533971271;6120.173776540166;6122.9387706122925;6154.538409619274;5442;5446.992306423314;5501.284832971708;5505.3393592768925;5662.745176467049;5343.893122137555;5360.3299587551555;5395.379531353905;5566.526845218162;5589.860253493654;5448.318960129984;5452.809151679692;5460.672359658413;5474.233607294982;5476.808311709091;5464;5466;5466.289382380305;5476.397596264408;5478.808241711541;5322.361316641989;5428.75570599324;5451.236826844245;5455.1544510600925;5509.283793106911;6110.832160913957;6116.092308610896;6117.449429551361;6123.069106197319;6131.019036954111;6339.587926784744;6352.650604216767;6361.522885783538;6374;6375.8406039848915;6474.935250647481;6479;6481.902771458432;6482.41658250755;6501.869962600769;5419.512243898029;5971.880562388728;5978.641281523122;5990.940090599125;6269.686515674198;6383.329750376143;6441.903371449432;6479.611223326616;6491.8701625967215;6527.8694426111215;5281.39263984639;6301.936980630227;6328.11406403104;6346.524010699124;6354.9682251589375;6439.710213040331;6440.6135631862235;6442.16251887261;6458.257300410513;6459.838504037437;6286.528510361742;6294.213223347082;6314.400131987454;6330.715117819709;6353.650549219745;6330.177076979963;6331.113644089831;6348.143000694847;6348.809535713936;6350.936490635127;5169.896602067937;5186.455422180692;5222.92165617516;5252.789888404497;5261.789528418821;4342.739435633872;4530.932036019462;4554.612857907049;4600.010997635524;4654.627629789608;6054.334023257438;6075.75696972125;6077.544184186204;6081.118237855486;6130.092410195535;6409.7436102555375;6423.550351475352;6472;6473.579217350952;6484.935150648527;5848.9707551462825;6063.029915213545;6116;6134.38656134383;6139.447449729546;4784.976075119672;5655.604107712503;5657.830265091971;5714.628549144375;5714.857128571736;6681.665916704048;6701.29636388182;6724.4620430364475;6730.562513436611;6733.393994540613;6376.107344971615;6431.228252609717;6436.774712885027;6452.935470645233;6456.806295811133;6334.90497642545;6386.776462823779;6491.058796474579;6496.610203387811;6676.399124078856;6301.495880329471;6321.273053598711;6327.272363678052;6342.985122380397;6379.075034120119;6375.776847810304;6430.324815894451;6436.871262574628;6451.161349024785;6456.483481321389;6336.303006669171;6340.4927605792345;6342.587731797418;6343.5876668016435;6350.269718982377;6225.501959843292;6284.528660350401;6291.96854015727;6303.936960630335;6321.367863213642;6144.907826382697;6197.163382943425;6278.529110316836;6303.2436107665535;6315.179026726496;6228.968855155696;6252.406021427957;6285.685715714288;6295.464885484785;6309.810705678837;6313.2424109107205;6326.462250708557;6333.429991300805;6337.271213810286;6347.68261586928 13:50:02 INFO - PROCESS | 1638 | |1;ext.html;816.2612835383977;895.4940458640867;909.8771665825116;915.1260546178396;988.8121256961173;39466.80266598667;40065.19869602606;42029.36955945663;42031.15937681248;42145;40504.18991620173;40743.59256407435;40800.79599602005;40911;40925.795371023174;7965.761027169166;8125.959370203171;8156.95921520393;8280.254777070062;8281.254687078163;7998.720044798433;8076.475029123118;8079.798005049892;11723.237989530697;11895.583654572092;11936.94031529841;11992.64022079339;12009.099317551176;12054.21647592908;12054.69863253417;11953.581624643139;12008.819867701992;12017.218880772722;12027.278363298143;12031.879681203163;11647.30116193031;11673.941630291794;11681.7734137916;11687;11702;41127.17745645071;41403.58596414027;41414;41414.1717165655;41445.37831932523;3987;4052.5947405259535;4059.3910913363234;4068.5524592294823;4089.284375234352;29108.417831643248;29280.853595732096;30412.8479357604;31361.686383136104;31619.52570711441;3292.3579901918897;3304.0913748719054;3316.6351701312838;3440.1571614954496;3453.5510383650217;350847;351266;352006;354268;362798;44288.228470860806;45050.87372815706;47264.7636761814;54517.45482545124;54579.454205457434;55310.72344638251;56232;56313.74745010154;56354.71822640861;56410.43589564134;15447;15512.767308490382;15517.301721422564;15523.301451434712;15525;13061.738765224833;13118.278494682692;13121.540746073842;13126.803097953538;13138.868611313765;12641.683957901127;12660.366981651065;12701.428435720414;12757.42591583381;12825.871741282654;5690.658560486384;5697.4587414195585;5714.799712161203;5727.3699893011735;5744.425557444306;962.6197651927449;966.4298064142049;968.2447690801187;979.3438396274513;993.5975929748463;26710.530920799152;29048.854755726086;29091.418171636873;29315.973940911965;29884.252893677833;918.2699753695828;1051.8790339110947;1066.983995240072;1081.453865797767;1104.4201794058183;15051.924740376448;15165.09009459414;15224.923875380553;15440.14357343895;15614.76577851333;375060;443033;448519;449226;452705;58207.8358432829;58250;58286.708566456895;58318;58354.416455835744;1465.369890946903;1468.9779653305209;1495.132822962679;1516.257034053306;1526.1990850795946;27188.864055679594;28438.715612843607;28845.855770721853;28875.5668664966;28953.276168095967;38388.61611383962;38520.422193666556;38588.6141138585;38621.80689096537;38662.646807361605;390045;391178;392300;392856;393916;29723.85138074296;30370;30422.239444014078;30433.391332173676;30490.695093048784;38545.421818673305;39003.41494877522;39687;39942.60057399505;40001.39997900092;28140.155795326173;28257.57613635756;28273.15180544587;28491.715082848907;29401.970931017313;28043.859780700965;28122.2969425766;28190.872365105166;29214;29309.56035659424;25595.744042559338;32026.83986580052;32820.179495512806;32910;33566.49650255199;28220.576691349237;28956.565651514822;29010.27474313159;29168.7083129166;29248.707512924597;22239.555208895406;22735.88632056829;22868.309802983356;23165.536689265784;23235.883820580788 13:50:02 INFO - PROCESS | 1638 | |2;jquery.html;429.54682809635824;433.1661551513338;436.06681701159516;441.9712718673286;449.04129683126513;19848.20607175713;20956;22051.11795528181;22260.666090008664;22316.21893233737;22863.31410057697;23381;23401;24303.51392972144;24639.014439422448;6681.131452911115;6770.763023294186;6812.250652428229;7052;7057.082579264714;6802.625855577941;6925.515213935001;6934.618595977219;6942.062821519081;6955.269696681851;6593;6862.656867156647;6927.099477067999;6942.34047765464;6960.65196740161;6732.562383445061;6818.147731533559;6833.453323734089;6905.033295338657;6909.896351554731;6704.363085506869;6720.361565651304;6724.798256052326;6726.966365168143;6733.696983635748;27747.306317342027;28037;28089.719102808915;28415.57376639352;28616.284592885135;3028.8939887103847;3061.3265081681993;3070.370574032318;3091.938161236763;3238.0609623209243;16450.98068550743;16674.58313542159;17188.398406055727;17331.826681733146;18200.63598728031;2084.8123668869875;2159.546495236002;2162.9351119466282;2183.9781602183934;2245.8091062259728;139223;140884;141131.29434352764;143905.28047359802;152419.2379038109;16344.918275408547;19164.90417547931;20490.692639610417;20701.964901755153;20725.378238652866;32431.51352729711;32919.83540082332;32966.50550241748;33005;33051;10411.219158563137;10573.788524229472;10793.568257269779;10812.891871081345;10980.286281391684;8960.641574337084;8987.640494380283;9012.684556040507;9106.81786364269;9136.49749263797;9509.429434233969;9573.521323933775;9659.46872921982;9678.322517423854;9722.805543889082;3752.549694036733;3870.4000879863665;4076.5515793262716;4127.33962565988;4141.275276826544;725.4232884856551;799.9480033797785;966.7432337960623;1016.1413605503337;1026.6560702164709;20961.37115886526;22634.773652263266;22654.980525876374;22746.976386062666;22754.544909101725;885.1016218538189;946.7502896176981;1003.5233264199593;1024.3751311699857;1037.4345981440133;10895.782084358269;11669.299842009337;11766.88233117675;11785.410729463663;11795;664694;767275;780477;794663;795835;125921.74078259284;125973.7402625962;138675;154623;163170;1049.192122066005;1217.7807994561065;1232.3961258983213;1234.0991076514194;1237.07219585311;19236.941968191884;19378.022669082442;20332.085056172804;20358.389248322575;20531.076101575167;63663.363366365746;63974;64818.02772958504;65446.672766635864;65555;90012;90523.54738226267;91132.63301050357;92302.53848730713;92948.53525732328;7060.54106483077;9724.513774311397;11063.778724425305;11110;11393.575803024622;12251.571195008488;12275;12469.688257793265;12579.93710031444;12685.873141268468;10805.837912431478;10849.45752712377;11030.889691102986;11164.274322169033;11205.04757095656;10107.696769096645;10215.080642742189;10219.540120694437;10350.22373322019;10440.53017614194;10974.725631859268;11013.724656883642;11145.721356965816;11253.549858005588;11475.885241147482;8759.562021899006;10824.350538967687;11057.336559806437;11327.546898123983;11397.886021139682;10737.355758654505;10758.332458468949;10937.343759374464;11007.889921100686;11161.33032018049 13:50:02 INFO - PROCESS | 1638 | |3;mootools.html;1619.1823129319691;1624.6913086513562;1638.2627817482132;1654.1481137214337;1663.7837081179446;10650.733731656712;11181.385023823686;11238.775224495515;11278;11288.322700637968;10827.296225745322;11030.338179709202;11309.434528273574;11322.716932076703;11359.545618175283;2352.7882490575844;2387.116766796279;2398.6641870138196;2399.5560821248123;2404.855708657486;2354.811615070799;2390.9641355379595;2465.5561998840203;2485.3289611804803;2490.0288887333954;2374.833761636686;2417.601095819188;2422.1159276863905;2430.562498750224;2462.802975761944;2386.3198988288254;2415.323709361373;2439.6706444629926;2445.254197469771;2446.2416650838118;2158.6546152615665;2277.237125562938;2423.575874222004;2425.357280320716;2448.326710154704;10974.286671366335;11103.72240693989;11158.888411115866;11159.051480624075;11183.105351571854;5766.336871259775;5850.795222167247;5870.941290587082;5925.140854576105;5947.910781338283;3376.0715803154085;3413.0955296846355;3426.012912510738;3579.588347340063;3622.3660859349507;1229.6369429610008;1235.7960936445477;1277.7222777222778;1285.530781264841;1285.736424033071;13604.251766152858;13859.930700346535;14134.222617756122;15291.770623440658;15322.463713769965;4643.512431194744;4765.285207218875;4770.9980904010545;4800.591949684316;4803.519648035169;10357.326773759833;11999.64001079969;12004.459799309052;12026.518939242507;12074.577389791491;5035.874103147377;5079.771410286472;5081.974590127025;5085.542301192911;5086.18621020635;4978.90042199154;5013.5989120870245;5086;5170.819021334235;5335.226392173113;3891.2412079644196;3928.1161738608585;3975.1453437511072;3982.8406863725704;3991.341428664296;934.3366209990983;1023.8208313545249;1048.5858086056119;1058.4601853054844;1072.0673014477454;832.854250506159;835.9498430094213;841.6002398860493;851.1403482482614;862.5644049754834;6265.8120256392385;6647.202335719745;6661.3005634408655;6743.52795304324;6919.135108111486;893.9865902011475;1021.8518314844306;1029.6499190275342;1033.2818691009677;1034.343192073024;4154.667626589866;4158.334666453353;4188.643965262987;4309.267424537836;4324.243257429937;13122.086116185992;13696.109752866032;13893.86106138946;13932.721345573033;14014.85985140156;12801.975841932623;13124.737505249841;13353.599392018255;13932.721345573033;13939.372728227254;9823.950880245697;9837.55730992107;10071;10077.193824494023;10103.898961010444;6749.257581665915;6859.794206173823;6883.862322753617;6957.686904089429;7082.18554866197;11720.88279117232;12164.635060948185;12165.45255463524;12241.755164896475;12288.692782680504;12292.201006934522;12446.37768111609;12462.065345098972;12542;12712.936435317764;9299;9415.199708024895;9417.717468475958;9469.66856160031;9483.952580237055;10225.488725563544;10350.585976561155;10375.16998640122;10432.895671043192;10466.895331046591;8860.822783544165;8864.955675221583;8923;9087.2730181584;9135.360524763468;8727.607257673293;8761;8787.209151176425;8841.292696584129;8920.330975176757;7933.761987140395;8029.5583742894705;8129.105798362057;8149.062857771432;8149.429539932385;5914.911276330772;5946;5950.404959504015;6074.726637301421;6129.877402452015;6005.279366476051;6177.1969643945995;6209.882221200227;6218.06728990655;6269.341719119427 13:50:02 INFO - PROCESS | 1638 | |4;prototype.html;342.57862828720675;379.65641094809206;389.4197645508192;395.4108378516011;397.3285148099711;4914.754262286885;5024.171011783056;5059.418166910807;5059.949400506003;5120.359955005629;4203.7477751334945;4824.565789078983;4939.876503087425;4963.900721985558;5006.324146240266;3079.8614062367137;3382.627910929802;3617.8733744318956;3673.659114423243;3828.6554210121085;3147.543606177103;3367.2592029753537;3957.4657421248194;3967.0280781208644;3984.262911361393;3490.4938783876323;3620.855165793365;3817.828197731109;3967.849323696139;3979.671891943217;3455.7580969332157;3792.2225943681615;3937.015746063484;3953.822078006482;4031.5687930784734;3429.382711111999;3436.6906978371817;3478.3217272631846;3495.143689796016;3525.365434221839;4450.421445212101;4529.935465165683;4648.604868586174;4870.391201099863;4915.361003069614;4219.2405367033925;4442.8222871085445;4524.217606172213;4554.544545545452;4591.403117594691;4216.03031302799;4581.496035436099;5099.69401835891;5247.10799164143;5372.462753724596;4299.312110062407;4316.892077698083;4337.392765012901;4338.674599405026;4358.782060896974;4865.635077369141;5404.972975135099;5520.66875987442;5555.555555555587;5765.509931655814;3755.7370984031404;4157.023099571598;4213.789310534461;4263.850765223202;4397.31841564558;4466.754328511963;4472.463304403493;4514.6614003949835;4526.4115664964;4550.590446859798;3986.880393588197;4399.956000440018;4437.3597709362875;4457.77711114443;4915.755601303123;4594.448666160082;4678.625709943196;4878.390201224847;4992.975035124801;5104.498795590208;1394.3446580107334;1449.4419648435428;1515.7347464193722;1536.6004838741785;1541.9306131224123;1070.0690399352613;1092.6175838456636;1108.955641774336;1127.6278827986744;1158.8725240223569;4211.220594407738;4683.8360657376825;4749.26386410107;4887.682300650446;5035.971223021568;4957.380327459067;5051.444341122474;5608;5616.427400327915;5618.213450116987;3853.7109716771356;4420.9336859947125;4673.579377856009;4989.702677303927;5130.358705161855;2713.986430067837;2740.410811675499;2893.9710602894124;2896.5227733855463;2926.912192634182;5322.121849894801;5385.353757549119;5417.756200970965;5781.537477001913;5837.29952405714;5253.343332083489;5310.2831117798605;5370.624056316098;5412.431694672082;5708.20085188074;5039.748012599429;5469.781208751685;5631.80288689902;5715.885682286331;5775.653460792366;5067.49325067505;5188.766505507186;5369.060414427538;5561.636304570622;5577.581681373832;4742.774913059153;5158.148905430562;5248.958302107432;5411.702356370437;5482.533984611193;4812.614990800657;5190.688558686491;5368.221607866915;5798.304203495608;5811.56413268998;3515.54297941266;4329.116834177143;4343.782810859381;4420.3590479380955;4429.490608580055;5216.66091704038;5558.861028474321;5559.27729395176;5589.301337332833;5627.943720562742;4837.323634038086;5402.891942161214;5437.40188579248;5437.918431223456;5459.344878614592;4995.950850321327;5191.779931716119;5393.379761327497;5435.510804027656;5440.972795135999;4488.483824360241;5018.573421259234;5107.744612769272;5267.894642107213;5346.304980352529;4945.9968712671425;5217.921731174111;5395.649282796606;5407.810281738011;5449.754761035683;5037;5144.048351055067;5297.8344764151825;5315.335583052119;5339.0389729849 13:50:02 INFO - PROCESS | 1638 | |5;yui.html;688.8312363470948;765.2653452685422;772.6098320348224;895.7984453497962;911.2072496927672;16295.511134665965;17753.64492710145;17762.911185444085;17908.28366865324;18186.90906545467;12558.748825023495;16273.267702953366;16428.342866285333;16438;16484.505464836046;995.9701208963743;1005.2812239248935;1007.6070332570304;1009.2380252909044;1012.5899010900561;1004.829179039565;1007.6725064354114;1010.5806090472482;1010.6664800615816;1010.9090181883629;1074.6131392698621;1074.9570017199303;1075.9946200268987;1076.9192310576739;1078.0782431021482;1066.6640008397408;1067.8611780468489;1070.2294348069418;1070.304302203566;1075.1828610256184;793.1632128104847;794.8092457810142;795.129333379948;797.0714118052462;797.7606717984581;17987.920724756557;18116.003619800907;18130.365437209766;18131.456056318333;18170.818291817046;15394.076355418598;15786;15847.52457426279;16612.916935415367;16658.0838053907;11992.22050566719;12218.694532636755;12257.661658010567;12421.31682757448;12619.495220191182;3863.710221733353;3916.4125381192766;4061;4156.833726650931;4157.293260145752;116407;119473.40263298714;122427.55144897141;123356.38321808423;124127;10879.401632910265;10932.289401188897;11933.462994165284;11941.104417168746;11954.701132471584;26570.335741606614;26628;27176.45647087047;27181.728182718314;27215.319617009733;8264.297534709616;8315.542645154575;8467.969889017788;8563.400561960727;8728.170823771732;4543.454785425704;4583.702059366131;4653.813847446132;4757.9048419032115;4863.124637565202;3304.735621150302;3350.0787283496747;3373.578302712161;3404.795712257223;3418.6581341865613;647.4237928243866;653.9247986481521;662.4799532367068;669.8928171492538;672.025562933748;712.7469748239404;712.8004158835535;725.2022774947516;741.2846603028104;774.3108633316353;10706.250562460553;10809.35143891369;10964.725881853017;11054.668359949215;11232.887671123348;700.8072779985442;703.802935178151;716.5378330976491;728.8906664000442;732.2348146187247;5272.664119485887;5295.311609490818;5303.28405665238;5326.760295786699;5338.193802901332;109201;110017.44991274993;111952;112881;115430;69769;100536.98926021585;101010.49494752478;103952.48023759833;105461;48972.755136224805;60864.69567652133;64835.351646483876;72111;81992.18007820084;11166.665000050012;11186;11229.831552526883;11448.82826757616;11530.769384612093;50998.49001509937;51149.23276150935;52593.7370313146;52775;53452;66518.66740666266;67739.66130169318;68289.65855170891;68539;69018;13951.232682202577;14046;14065.789013164605;14559.490417835324;14754.188519631523;12825.486980520674;15178.165200914056;15366.84633153654;15437.768433473731;15488.967286403575;14119.505817296345;14139.505117321256;14255;14814;15068.171250580885;13842.653933651336;14333.498327558485;14542.200178990257;14574.781378279122;14984.026038307475;13792.042861395059;14283.42866285337;14303.284835758379;14308.213048282445;14321.28393580296;9918.801623967625;10385.324953877975;10651.520681569193;10699.89300106989;10804.837927431252;8254.83490330178;10662.827089020357;11314.934335644466;11591.942040289745;11670.183087184156 13:50:02 INFO - PROCESS | 1638 | __end_tp_report 13:50:02 INFO - PROCESS | 1638 | __start_cc_report 13:50:02 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2224 13:50:02 INFO - PROCESS | 1638 | __end_cc_report 13:50:02 INFO - PROCESS | 1638 | __startTimestamp1495227002213__endTimestamp 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | ------- Summary: start ------- 13:50:02 INFO - PROCESS | 1638 | Number of tests: 6 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5972.72 Median:6284.53 stddev:655.35 (10.4%) stddev-sans-first:627.52 13:50:02 INFO - PROCESS | 1638 | Values: 3480.6 3510.0 3516.8 3535.7 3571.2 5349.2 5416.8 6054.1 6118.8 6154.5 6081.3 6113.3 6120.2 6122.9 6154.5 5442.0 5447.0 5501.3 5505.3 5662.7 5343.9 5360.3 5395.4 5566.5 5589.9 5448.3 5452.8 5460.7 5474.2 5476.8 5464.0 5466.0 5466.3 5476.4 5478.8 5322.4 5428.8 5451.2 5455.2 5509.3 6110.8 6116.1 6117.4 6123.1 6131.0 6339.6 6352.7 6361.5 6374.0 6375.8 6474.9 6479.0 6481.9 6482.4 6501.9 5419.5 5971.9 5978.6 5990.9 6269.7 6383.3 6441.9 6479.6 6491.9 6527.9 5281.4 6301.9 6328.1 6346.5 6355.0 6439.7 6440.6 6442.2 6458.3 6459.8 6286.5 6294.2 6314.4 6330.7 6353.7 6330.2 6331.1 6348.1 6348.8 6350.9 5169.9 5186.5 5222.9 5252.8 5261.8 4342.7 4530.9 4554.6 4600.0 4654.6 6054.3 6075.8 6077.5 6081.1 6130.1 6409.7 6423.6 6472.0 6473.6 6484.9 5849.0 6063.0 6116.0 6134.4 6139.4 4785.0 5655.6 5657.8 5714.6 5714.9 6681.7 6701.3 6724.5 6730.6 6733.4 6376.1 6431.2 6436.8 6452.9 6456.8 6334.9 6386.8 6491.1 6496.6 6676.4 6301.5 6321.3 6327.3 6343.0 6379.1 6375.8 6430.3 6436.9 6451.2 6456.5 6336.3 6340.5 6342.6 6343.6 6350.3 6225.5 6284.5 6292.0 6303.9 6321.4 6144.9 6197.2 6278.5 6303.2 6315.2 6229.0 6252.4 6285.7 6295.5 6309.8 6313.2 6326.5 6333.4 6337.3 6347.7 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:53627.28 Median:3298.22 stddev:104463.61 (3167.3%) stddev-sans-first:104681.41 13:50:02 INFO - PROCESS | 1638 | Values: 816.3 895.5 909.9 915.1 988.8 39466.8 40065.2 42029.4 42031.2 42145.0 40504.2 40743.6 40800.8 40911.0 40925.8 7965.8 8126.0 8157.0 8280.3 8281.3 7998.7 8076.5 8079.8 11723.2 11895.6 11936.9 11992.6 12009.1 12054.2 12054.7 11953.6 12008.8 12017.2 12027.3 12031.9 11647.3 11673.9 11681.8 11687.0 11702.0 41127.2 41403.6 41414.0 41414.2 41445.4 3987.0 4052.6 4059.4 4068.6 4089.3 29108.4 29280.9 30412.8 31361.7 31619.5 3292.4 3304.1 3316.6 3440.2 3453.6 350847.0 351266.0 352006.0 354268.0 362798.0 44288.2 45050.9 47264.8 54517.5 54579.5 55310.7 56232.0 56313.7 56354.7 56410.4 15447.0 15512.8 15517.3 15523.3 15525.0 13061.7 13118.3 13121.5 13126.8 13138.9 12641.7 12660.4 12701.4 12757.4 12825.9 5690.7 5697.5 5714.8 5727.4 5744.4 962.6 966.4 968.2 979.3 993.6 26710.5 29048.9 29091.4 29316.0 29884.3 918.3 1051.9 1067.0 1081.5 1104.4 15051.9 15165.1 15224.9 15440.1 15614.8 375060.0 443033.0 448519.0 449226.0 452705.0 58207.8 58250.0 58286.7 58318.0 58354.4 1465.4 1469.0 1495.1 1516.3 1526.2 27188.9 28438.7 28845.9 28875.6 28953.3 38388.6 38520.4 38588.6 38621.8 38662.6 390045.0 391178.0 392300.0 392856.0 393916.0 29723.9 30370.0 30422.2 30433.4 30490.7 38545.4 39003.4 39687.0 39942.6 40001.4 28140.2 28257.6 28273.2 28491.7 29402.0 28043.9 28122.3 28190.9 29214.0 29309.6 25595.7 32026.8 32820.2 32910.0 33566.5 28220.6 28956.6 29010.3 29168.7 29248.7 22239.6 22735.9 22868.3 23165.5 23235.9 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43099.97 Median:22750.76 stddev:126567.97 (556.3%) stddev-sans-first:126882.47 13:50:02 INFO - PROCESS | 1638 | Values: 429.5 433.2 436.1 442.0 449.0 19848.2 20956.0 22051.1 22260.7 22316.2 22863.3 23381.0 23401.0 24303.5 24639.0 6681.1 6770.8 6812.3 7052.0 7057.1 6802.6 6925.5 6934.6 6942.1 6955.3 6593.0 6862.7 6927.1 6942.3 6960.7 6732.6 6818.1 6833.5 6905.0 6909.9 6704.4 6720.4 6724.8 6727.0 6733.7 27747.3 28037.0 28089.7 28415.6 28616.3 3028.9 3061.3 3070.4 3091.9 3238.1 16451.0 16674.6 17188.4 17331.8 18200.6 2084.8 2159.5 2162.9 2184.0 2245.8 139223.0 140884.0 141131.3 143905.3 152419.2 16344.9 19164.9 20490.7 20702.0 20725.4 32431.5 32919.8 32966.5 33005.0 33051.0 10411.2 10573.8 10793.6 10812.9 10980.3 8960.6 8987.6 9012.7 9106.8 9136.5 9509.4 9573.5 9659.5 9678.3 9722.8 3752.5 3870.4 4076.6 4127.3 4141.3 725.4 799.9 966.7 1016.1 1026.7 20961.4 22634.8 22655.0 22747.0 22754.5 885.1 946.8 1003.5 1024.4 1037.4 10895.8 11669.3 11766.9 11785.4 11795.0 664694.0 767275.0 780477.0 794663.0 795835.0 125921.7 125973.7 138675.0 154623.0 163170.0 1049.2 1217.8 1232.4 1234.1 1237.1 19236.9 19378.0 20332.1 20358.4 20531.1 63663.4 63974.0 64818.0 65446.7 65555.0 90012.0 90523.5 91132.6 92302.5 92948.5 7060.5 9724.5 11063.8 11110.0 11393.6 12251.6 12275.0 12469.7 12579.9 12685.9 10805.8 10849.5 11030.9 11164.3 11205.0 10107.7 10215.1 10219.5 10350.2 10440.5 10974.7 11013.7 11145.7 11253.5 11475.9 8759.6 10824.4 11057.3 11327.5 11397.9 10737.4 10758.3 10937.3 11007.9 11161.3 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6748.99 Median:2447.28 stddev:4185.13 (171.0%) stddev-sans-first:4179.13 13:50:02 INFO - PROCESS | 1638 | Values: 1619.2 1624.7 1638.3 1654.1 1663.8 10650.7 11181.4 11238.8 11278.0 11288.3 10827.3 11030.3 11309.4 11322.7 11359.5 2352.8 2387.1 2398.7 2399.6 2404.9 2354.8 2391.0 2465.6 2485.3 2490.0 2374.8 2417.6 2422.1 2430.6 2462.8 2386.3 2415.3 2439.7 2445.3 2446.2 2158.7 2277.2 2423.6 2425.4 2448.3 10974.3 11103.7 11158.9 11159.1 11183.1 5766.3 5850.8 5870.9 5925.1 5947.9 3376.1 3413.1 3426.0 3579.6 3622.4 1229.6 1235.8 1277.7 1285.5 1285.7 13604.3 13859.9 14134.2 15291.8 15322.5 4643.5 4765.3 4771.0 4800.6 4803.5 10357.3 11999.6 12004.5 12026.5 12074.6 5035.9 5079.8 5082.0 5085.5 5086.2 4978.9 5013.6 5086.0 5170.8 5335.2 3891.2 3928.1 3975.1 3982.8 3991.3 934.3 1023.8 1048.6 1058.5 1072.1 832.9 835.9 841.6 851.1 862.6 6265.8 6647.2 6661.3 6743.5 6919.1 894.0 1021.9 1029.6 1033.3 1034.3 4154.7 4158.3 4188.6 4309.3 4324.2 13122.1 13696.1 13893.9 13932.7 14014.9 12802.0 13124.7 13353.6 13932.7 13939.4 9824.0 9837.6 10071.0 10077.2 10103.9 6749.3 6859.8 6883.9 6957.7 7082.2 11720.9 12164.6 12165.5 12241.8 12288.7 12292.2 12446.4 12462.1 12542.0 12712.9 9299.0 9415.2 9417.7 9469.7 9484.0 10225.5 10350.6 10375.2 10432.9 10466.9 8860.8 8865.0 8923.0 9087.3 9135.4 8727.6 8761.0 8787.2 8841.3 8920.3 7933.8 8029.6 8129.1 8149.1 8149.4 5914.9 5946.0 5950.4 6074.7 6129.9 6005.3 6177.2 6209.9 6218.1 6269.3 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4408.40 Median:4818.59 stddev:1262.16 (26.2%) stddev-sans-first:1228.26 13:50:02 INFO - PROCESS | 1638 | Values: 342.6 379.7 389.4 395.4 397.3 4914.8 5024.2 5059.4 5059.9 5120.4 4203.7 4824.6 4939.9 4963.9 5006.3 3079.9 3382.6 3617.9 3673.7 3828.7 3147.5 3367.3 3957.5 3967.0 3984.3 3490.5 3620.9 3817.8 3967.8 3979.7 3455.8 3792.2 3937.0 3953.8 4031.6 3429.4 3436.7 3478.3 3495.1 3525.4 4450.4 4529.9 4648.6 4870.4 4915.4 4219.2 4442.8 4524.2 4554.5 4591.4 4216.0 4581.5 5099.7 5247.1 5372.5 4299.3 4316.9 4337.4 4338.7 4358.8 4865.6 5405.0 5520.7 5555.6 5765.5 3755.7 4157.0 4213.8 4263.9 4397.3 4466.8 4472.5 4514.7 4526.4 4550.6 3986.9 4400.0 4437.4 4457.8 4915.8 4594.4 4678.6 4878.4 4993.0 5104.5 1394.3 1449.4 1515.7 1536.6 1541.9 1070.1 1092.6 1109.0 1127.6 1158.9 4211.2 4683.8 4749.3 4887.7 5036.0 4957.4 5051.4 5608.0 5616.4 5618.2 3853.7 4420.9 4673.6 4989.7 5130.4 2714.0 2740.4 2894.0 2896.5 2926.9 5322.1 5385.4 5417.8 5781.5 5837.3 5253.3 5310.3 5370.6 5412.4 5708.2 5039.7 5469.8 5631.8 5715.9 5775.7 5067.5 5188.8 5369.1 5561.6 5577.6 4742.8 5158.1 5249.0 5411.7 5482.5 4812.6 5190.7 5368.2 5798.3 5811.6 3515.5 4329.1 4343.8 4420.4 4429.5 5216.7 5558.9 5559.3 5589.3 5627.9 4837.3 5402.9 5437.4 5437.9 5459.3 4996.0 5191.8 5393.4 5435.5 5441.0 4488.5 5018.6 5107.7 5267.9 5346.3 4946.0 5217.9 5395.6 5407.8 5449.8 5037.0 5144.0 5297.8 5315.3 5339.0 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21744.43 Median:16284.39 stddev:31375.47 (192.7%) stddev-sans-first:31423.66 13:50:02 INFO - PROCESS | 1638 | Values: 688.8 765.3 772.6 895.8 911.2 16295.5 17753.6 17762.9 17908.3 18186.9 12558.7 16273.3 16428.3 16438.0 16484.5 996.0 1005.3 1007.6 1009.2 1012.6 1004.8 1007.7 1010.6 1010.7 1010.9 1074.6 1075.0 1076.0 1076.9 1078.1 1066.7 1067.9 1070.2 1070.3 1075.2 793.2 794.8 795.1 797.1 797.8 17987.9 18116.0 18130.4 18131.5 18170.8 15394.1 15786.0 15847.5 16612.9 16658.1 11992.2 12218.7 12257.7 12421.3 12619.5 3863.7 3916.4 4061.0 4156.8 4157.3 116407.0 119473.4 122427.6 123356.4 124127.0 10879.4 10932.3 11933.5 11941.1 11954.7 26570.3 26628.0 27176.5 27181.7 27215.3 8264.3 8315.5 8468.0 8563.4 8728.2 4543.5 4583.7 4653.8 4757.9 4863.1 3304.7 3350.1 3373.6 3404.8 3418.7 647.4 653.9 662.5 669.9 672.0 712.7 712.8 725.2 741.3 774.3 10706.3 10809.4 10964.7 11054.7 11232.9 700.8 703.8 716.5 728.9 732.2 5272.7 5295.3 5303.3 5326.8 5338.2 109201.0 110017.4 111952.0 112881.0 115430.0 69769.0 100537.0 101010.5 103952.5 105461.0 48972.8 60864.7 64835.4 72111.0 81992.2 11166.7 11186.0 11229.8 11448.8 11530.8 50998.5 51149.2 52593.7 52775.0 53452.0 66518.7 67739.7 68289.7 68539.0 69018.0 13951.2 14046.0 14065.8 14559.5 14754.2 12825.5 15178.2 15366.8 15437.8 15489.0 14119.5 14139.5 14255.0 14814.0 15068.2 13842.7 14333.5 14542.2 14574.8 14984.0 13792.0 14283.4 14303.3 14308.2 14321.3 9918.8 10385.3 10651.5 10699.9 10804.8 8254.8 10662.8 11314.9 11591.9 11670.2 13:50:02 INFO - PROCESS | 1638 | -------- Summary: end -------- 13:50:02 INFO - PROCESS | 1638 | 13:50:02 INFO - TEST-INFO | 1638: exit 0 13:50:02 INFO - TEST-OK | dromaeo_css | took 1118171ms 13:50:02 INFO - TEST-START | kraken 13:50:02 INFO - operating with platform_type : mac_ 13:50:02 INFO - Initialising browser for kraken test... 13:50:02 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp4fxm2s/profile http://localhost:49220/getInfo.html) 13:50:05 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 13:50:05 INFO - PROCESS | 1647 | colorDepth:24 13:50:05 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 13:50:05 INFO - PROCESS | 1647 | __metrics 13:50:05 INFO - PROCESS | 1647 | 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" 13:50:05 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:50:05 INFO - TEST-INFO | 1647: exit 0 13:50:05 INFO - Browser initialized. 13:50:05 INFO - Running cycle 1/1 for kraken test... 13:50:05 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp4fxm2s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:50:17 INFO - PROCESS | 1649 | RSS: Main: 165048320 13:50:17 INFO - PROCESS | 1649 | 13:50:20 INFO - PROCESS | 1649 | 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) 13:50:21 INFO - PROCESS | 1649 | RSS: Main: 215912448 13:50:21 INFO - PROCESS | 1649 | 13:50:23 INFO - PROCESS | 1649 | 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) 13:50:24 INFO - PROCESS | 1649 | RSS: Main: 231448576 13:50:24 INFO - PROCESS | 1649 | 13:50:27 INFO - PROCESS | 1649 | 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) 13:50:28 INFO - PROCESS | 1649 | RSS: Main: 327847936 13:50:28 INFO - PROCESS | 1649 | 13:50:30 INFO - PROCESS | 1649 | 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) 13:50:30 INFO - PROCESS | 1649 | RSS: Main: 229728256 13:50:30 INFO - PROCESS | 1649 | 13:50:32 INFO - PROCESS | 1649 | 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) 13:50:33 INFO - PROCESS | 1649 | RSS: Main: 232259584 13:50:33 INFO - PROCESS | 1649 | 13:50:36 INFO - PROCESS | 1649 | 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) 13:50:37 INFO - PROCESS | 1649 | RSS: Main: 273014784 13:50:37 INFO - PROCESS | 1649 | 13:50:40 INFO - PROCESS | 1649 | 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) 13:50:40 INFO - PROCESS | 1649 | RSS: Main: 273874944 13:50:40 INFO - PROCESS | 1649 | 13:50:43 INFO - PROCESS | 1649 | 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) 13:50:44 INFO - PROCESS | 1649 | RSS: Main: 268808192 13:50:44 INFO - PROCESS | 1649 | 13:50:45 INFO - PROCESS | 1649 | 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) 13:50:46 INFO - PROCESS | 1649 | RSS: Main: 248029184 13:50:46 INFO - PROCESS | 1649 | 13:50:48 INFO - PROCESS | 1649 | 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) 13:50:48 INFO - PROCESS | 1649 | RSS: Main: 251727872 13:50:48 INFO - PROCESS | 1649 | 13:50:50 INFO - PROCESS | 1649 | 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) 13:50:51 INFO - PROCESS | 1649 | RSS: Main: 260861952 13:50:51 INFO - PROCESS | 1649 | 13:50:54 INFO - PROCESS | 1649 | 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) 13:50:55 INFO - PROCESS | 1649 | RSS: Main: 273756160 13:50:55 INFO - PROCESS | 1649 | 13:50:58 INFO - PROCESS | 1649 | 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) 13:50:58 INFO - PROCESS | 1649 | RSS: Main: 267419648 13:50:58 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | 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) 13:51:00 INFO - PROCESS | 1649 | RSS: Main: 312029184 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | __start_tp_report 13:51:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 13:51:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 13:51:00 INFO - PROCESS | 1649 | |i|pagename|runs| 13:51:00 INFO - PROCESS | 1649 | |0;ai-astar;104;115;115;114;114;115;114;114;114;115 13:51:00 INFO - PROCESS | 1649 | |1;audio-beat-detection;133;106;107;153;135;135;128;107;126;109 13:51:00 INFO - PROCESS | 1649 | |2;audio-dft;188;184;184;183;185;185;181;184;190;263 13:51:00 INFO - PROCESS | 1649 | |3;audio-fft;80;59;59;90;63;63;59;59;74;58 13:51:00 INFO - PROCESS | 1649 | |4;audio-oscillator;92;71;86;69;69;69;81;69;68;81 13:51:00 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;87;86;86;86;87;87;87;86;87;85 13:51:00 INFO - PROCESS | 1649 | |6;imaging-darkroom;93;93;93;93;104;152;115;94;93;93 13:51:00 INFO - PROCESS | 1649 | |7;imaging-desaturate;83;85;84;83;86;87;84;86;83;83 13:51:00 INFO - PROCESS | 1649 | |8;json-parse-financial;61;60;60;61;64;63;65;64;64;67 13:51:00 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;60;58;62;65;62;59;60;58;58;60 13:51:00 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;85;85;78;84;82;80;83;83;86;82 13:51:00 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;137;133;138;128;133;126;148;167;181;144 13:51:00 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;165;162;159;155;160;162;162;161;160;161 13:51:00 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;64;62;66;63;64;63;61;64;62;65 13:51:00 INFO - PROCESS | 1649 | __end_tp_report 13:51:00 INFO - PROCESS | 1649 | __start_cc_report 13:51:00 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2064 13:51:00 INFO - PROCESS | 1649 | __end_cc_report 13:51:00 INFO - PROCESS | 1649 | __startTimestamp1495227060577__endTimestamp 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | ------- Summary: start ------- 13:51:00 INFO - PROCESS | 1649 | Number of tests: 14 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.50 stddev:3.34 (2.9%) stddev-sans-first:0.53 13:51:00 INFO - PROCESS | 1649 | Values: 104.0 115.0 115.0 114.0 114.0 115.0 114.0 114.0 114.0 115.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:130.50 stddev:16.02 (12.3%) stddev-sans-first:16.65 13:51:00 INFO - PROCESS | 1649 | Values: 133.0 106.0 107.0 153.0 135.0 135.0 128.0 107.0 126.0 109.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:192.70 Median:185.00 stddev:24.83 (13.4%) stddev-sans-first:26.28 13:51:00 INFO - PROCESS | 1649 | Values: 188.0 184.0 184.0 183.0 185.0 185.0 181.0 184.0 190.0 263.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.40 Median:63.00 stddev:11.12 (17.6%) stddev-sans-first:10.65 13:51:00 INFO - PROCESS | 1649 | Values: 80.0 59.0 59.0 90.0 63.0 63.0 59.0 59.0 74.0 58.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:8.75 (11.5%) stddev-sans-first:6.95 13:51:00 INFO - PROCESS | 1649 | Values: 92.0 71.0 86.0 69.0 69.0 69.0 81.0 69.0 68.0 81.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 13:51:00 INFO - PROCESS | 1649 | Values: 87.0 86.0 86.0 86.0 87.0 87.0 87.0 86.0 87.0 85.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:93.00 stddev:18.92 (20.3%) stddev-sans-first:19.77 13:51:00 INFO - PROCESS | 1649 | Values: 93.0 93.0 93.0 93.0 104.0 152.0 115.0 94.0 93.0 93.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.51 (1.8%) stddev-sans-first:1.51 13:51:00 INFO - PROCESS | 1649 | Values: 83.0 85.0 84.0 83.0 86.0 87.0 84.0 86.0 83.0 83.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:64.00 stddev:2.33 (3.6%) stddev-sans-first:2.37 13:51:00 INFO - PROCESS | 1649 | Values: 61.0 60.0 60.0 61.0 64.0 63.0 65.0 64.0 64.0 67.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:2.25 (3.8%) stddev-sans-first:2.39 13:51:00 INFO - PROCESS | 1649 | Values: 60.0 58.0 62.0 65.0 62.0 59.0 60.0 58.0 58.0 60.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.50 stddev:2.44 (2.9%) stddev-sans-first:2.46 13:51:00 INFO - PROCESS | 1649 | Values: 85.0 85.0 78.0 84.0 82.0 80.0 83.0 83.0 86.0 82.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:141.00 stddev:17.70 (12.6%) stddev-sans-first:18.61 13:51:00 INFO - PROCESS | 1649 | Values: 137.0 133.0 138.0 128.0 133.0 126.0 148.0 167.0 181.0 144.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:161.50 stddev:2.58 (1.6%) stddev-sans-first:2.22 13:51:00 INFO - PROCESS | 1649 | Values: 165.0 162.0 159.0 155.0 160.0 162.0 162.0 161.0 160.0 161.0 13:51:00 INFO - PROCESS | 1649 | 13:51:00 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:1.51 (2.4%) stddev-sans-first:1.58 13:51:00 INFO - PROCESS | 1649 | Values: 64.0 62.0 66.0 63.0 64.0 63.0 61.0 64.0 62.0 65.0 13:51:00 INFO - PROCESS | 1649 | -------- Summary: end -------- 13:51:00 INFO - PROCESS | 1649 | 13:51:01 INFO - TEST-INFO | 1649: exit 0 13:51:01 INFO - TEST-OK | kraken | took 58788ms 13:51:01 INFO - SUITE-END | took 1176s 13:51:01 INFO - Completed test suite (00:19:37) 13:51:01 INFO - javascript benchmark 13:51:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7771.7614753214975, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.582330120385, 3510.017195185348, 3516.8241587920597, 3535.6817886390254, 3571.214332846773, 5349.22436246744, 5416.756245968931, 6054.091886217069, 6118.847028824276, 6154.538409619274, 6081.300650425192, 6113.327533971271, 6120.173776540166, 6122.9387706122925, 6154.538409619274, 5442.0, 5446.992306423314, 5501.284832971708, 5505.3393592768925, 5662.745176467049, 5343.893122137555, 5360.3299587551555, 5395.379531353905, 5566.526845218162, 5589.860253493654, 5448.318960129984, 5452.809151679692, 5460.672359658413, 5474.233607294982, 5476.808311709091, 5464.0, 5466.0, 5466.289382380305, 5476.397596264408, 5478.808241711541, 5322.361316641989, 5428.75570599324, 5451.236826844245, 5455.1544510600925, 5509.283793106911, 6110.832160913957, 6116.092308610896, 6117.449429551361, 6123.069106197319, 6131.019036954111, 6339.587926784744, 6352.650604216767, 6361.522885783538, 6374.0, 6375.8406039848915, 6474.935250647481, 6479.0, 6481.902771458432, 6482.41658250755, 6501.869962600769, 5419.512243898029, 5971.880562388728, 5978.641281523122, 5990.940090599125, 6269.686515674198, 6383.329750376143, 6441.903371449432, 6479.611223326616, 6491.8701625967215, 6527.8694426111215, 5281.39263984639, 6301.936980630227, 6328.11406403104, 6346.524010699124, 6354.9682251589375, 6439.710213040331, 6440.6135631862235, 6442.16251887261, 6458.257300410513, 6459.838504037437, 6286.528510361742, 6294.213223347082, 6314.400131987454, 6330.715117819709, 6353.650549219745, 6330.177076979963, 6331.113644089831, 6348.143000694847, 6348.809535713936, 6350.936490635127, 5169.896602067937, 5186.455422180692, 5222.92165617516, 5252.789888404497, 5261.789528418821, 4342.739435633872, 4530.932036019462, 4554.612857907049, 4600.010997635524, 4654.627629789608, 6054.334023257438, 6075.75696972125, 6077.544184186204, 6081.118237855486, 6130.092410195535, 6409.7436102555375, 6423.550351475352, 6472.0, 6473.579217350952, 6484.935150648527, 5848.9707551462825, 6063.029915213545, 6116.0, 6134.38656134383, 6139.447449729546, 4784.976075119672, 5655.604107712503, 5657.830265091971, 5714.628549144375, 5714.857128571736, 6681.665916704048, 6701.29636388182, 6724.4620430364475, 6730.562513436611, 6733.393994540613, 6376.107344971615, 6431.228252609717, 6436.774712885027, 6452.935470645233, 6456.806295811133, 6334.90497642545, 6386.776462823779, 6491.058796474579, 6496.610203387811, 6676.399124078856, 6301.495880329471, 6321.273053598711, 6327.272363678052, 6342.985122380397, 6379.075034120119, 6375.776847810304, 6430.324815894451, 6436.871262574628, 6451.161349024785, 6456.483481321389, 6336.303006669171, 6340.4927605792345, 6342.587731797418, 6343.5876668016435, 6350.269718982377, 6225.501959843292, 6284.528660350401, 6291.96854015727, 6303.936960630335, 6321.367863213642, 6144.907826382697, 6197.163382943425, 6278.529110316836, 6303.2436107665535, 6315.179026726496, 6228.968855155696, 6252.406021427957, 6285.685715714288, 6295.464885484785, 6309.810705678837, 6313.2424109107205, 6326.462250708557, 6333.429991300805, 6337.271213810286, 6347.68261586928], "value": 5931.564288739608, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.2612835383977, 895.4940458640867, 909.8771665825116, 915.1260546178396, 988.8121256961173, 39466.80266598667, 40065.19869602606, 42029.36955945663, 42031.15937681248, 42145.0, 40504.18991620173, 40743.59256407435, 40800.79599602005, 40911.0, 40925.795371023174, 7965.761027169166, 8125.959370203171, 8156.95921520393, 8280.254777070062, 8281.254687078163, 7998.720044798433, 8076.475029123118, 8079.798005049892, 11723.237989530697, 11895.583654572092, 11936.94031529841, 11992.64022079339, 12009.099317551176, 12054.21647592908, 12054.69863253417, 11953.581624643139, 12008.819867701992, 12017.218880772722, 12027.278363298143, 12031.879681203163, 11647.30116193031, 11673.941630291794, 11681.7734137916, 11687.0, 11702.0, 41127.17745645071, 41403.58596414027, 41414.0, 41414.1717165655, 41445.37831932523, 3987.0, 4052.5947405259535, 4059.3910913363234, 4068.5524592294823, 4089.284375234352, 29108.417831643248, 29280.853595732096, 30412.8479357604, 31361.686383136104, 31619.52570711441, 3292.3579901918897, 3304.0913748719054, 3316.6351701312838, 3440.1571614954496, 3453.5510383650217, 350847.0, 351266.0, 352006.0, 354268.0, 362798.0, 44288.228470860806, 45050.87372815706, 47264.7636761814, 54517.45482545124, 54579.454205457434, 55310.72344638251, 56232.0, 56313.74745010154, 56354.71822640861, 56410.43589564134, 15447.0, 15512.767308490382, 15517.301721422564, 15523.301451434712, 15525.0, 13061.738765224833, 13118.278494682692, 13121.540746073842, 13126.803097953538, 13138.868611313765, 12641.683957901127, 12660.366981651065, 12701.428435720414, 12757.42591583381, 12825.871741282654, 5690.658560486384, 5697.4587414195585, 5714.799712161203, 5727.3699893011735, 5744.425557444306, 962.6197651927449, 966.4298064142049, 968.2447690801187, 979.3438396274513, 993.5975929748463, 26710.530920799152, 29048.854755726086, 29091.418171636873, 29315.973940911965, 29884.252893677833, 918.2699753695828, 1051.8790339110947, 1066.983995240072, 1081.453865797767, 1104.4201794058183, 15051.924740376448, 15165.09009459414, 15224.923875380553, 15440.14357343895, 15614.76577851333, 375060.0, 443033.0, 448519.0, 449226.0, 452705.0, 58207.8358432829, 58250.0, 58286.708566456895, 58318.0, 58354.416455835744, 1465.369890946903, 1468.9779653305209, 1495.132822962679, 1516.257034053306, 1526.1990850795946, 27188.864055679594, 28438.715612843607, 28845.855770721853, 28875.5668664966, 28953.276168095967, 38388.61611383962, 38520.422193666556, 38588.6141138585, 38621.80689096537, 38662.646807361605, 390045.0, 391178.0, 392300.0, 392856.0, 393916.0, 29723.85138074296, 30370.0, 30422.239444014078, 30433.391332173676, 30490.695093048784, 38545.421818673305, 39003.41494877522, 39687.0, 39942.60057399505, 40001.39997900092, 28140.155795326173, 28257.57613635756, 28273.15180544587, 28491.715082848907, 29401.970931017313, 28043.859780700965, 28122.2969425766, 28190.872365105166, 29214.0, 29309.56035659424, 25595.744042559338, 32026.83986580052, 32820.179495512806, 32910.0, 33566.49650255199, 28220.576691349237, 28956.565651514822, 29010.27474313159, 29168.7083129166, 29248.707512924597, 22239.555208895406, 22735.88632056829, 22868.309802983356, 23165.536689265784, 23235.883820580788], "value": 18790.279945400365, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.54682809635824, 433.1661551513338, 436.06681701159516, 441.9712718673286, 449.04129683126513, 19848.20607175713, 20956.0, 22051.11795528181, 22260.666090008664, 22316.21893233737, 22863.31410057697, 23381.0, 23401.0, 24303.51392972144, 24639.014439422448, 6681.131452911115, 6770.763023294186, 6812.250652428229, 7052.0, 7057.082579264714, 6802.625855577941, 6925.515213935001, 6934.618595977219, 6942.062821519081, 6955.269696681851, 6593.0, 6862.656867156647, 6927.099477067999, 6942.34047765464, 6960.65196740161, 6732.562383445061, 6818.147731533559, 6833.453323734089, 6905.033295338657, 6909.896351554731, 6704.363085506869, 6720.361565651304, 6724.798256052326, 6726.966365168143, 6733.696983635748, 27747.306317342027, 28037.0, 28089.719102808915, 28415.57376639352, 28616.284592885135, 3028.8939887103847, 3061.3265081681993, 3070.370574032318, 3091.938161236763, 3238.0609623209243, 16450.98068550743, 16674.58313542159, 17188.398406055727, 17331.826681733146, 18200.63598728031, 2084.8123668869875, 2159.546495236002, 2162.9351119466282, 2183.9781602183934, 2245.8091062259728, 139223.0, 140884.0, 141131.29434352764, 143905.28047359802, 152419.2379038109, 16344.918275408547, 19164.90417547931, 20490.692639610417, 20701.964901755153, 20725.378238652866, 32431.51352729711, 32919.83540082332, 32966.50550241748, 33005.0, 33051.0, 10411.219158563137, 10573.788524229472, 10793.568257269779, 10812.891871081345, 10980.286281391684, 8960.641574337084, 8987.640494380283, 9012.684556040507, 9106.81786364269, 9136.49749263797, 9509.429434233969, 9573.521323933775, 9659.46872921982, 9678.322517423854, 9722.805543889082, 3752.549694036733, 3870.4000879863665, 4076.5515793262716, 4127.33962565988, 4141.275276826544, 725.4232884856551, 799.9480033797785, 966.7432337960623, 1016.1413605503337, 1026.6560702164709, 20961.37115886526, 22634.773652263266, 22654.980525876374, 22746.976386062666, 22754.544909101725, 885.1016218538189, 946.7502896176981, 1003.5233264199593, 1024.3751311699857, 1037.4345981440133, 10895.782084358269, 11669.299842009337, 11766.88233117675, 11785.410729463663, 11795.0, 664694.0, 767275.0, 780477.0, 794663.0, 795835.0, 125921.74078259284, 125973.7402625962, 138675.0, 154623.0, 163170.0, 1049.192122066005, 1217.7807994561065, 1232.3961258983213, 1234.0991076514194, 1237.07219585311, 19236.941968191884, 19378.022669082442, 20332.085056172804, 20358.389248322575, 20531.076101575167, 63663.363366365746, 63974.0, 64818.02772958504, 65446.672766635864, 65555.0, 90012.0, 90523.54738226267, 91132.63301050357, 92302.53848730713, 92948.53525732328, 7060.54106483077, 9724.513774311397, 11063.778724425305, 11110.0, 11393.575803024622, 12251.571195008488, 12275.0, 12469.688257793265, 12579.93710031444, 12685.873141268468, 10805.837912431478, 10849.45752712377, 11030.889691102986, 11164.274322169033, 11205.04757095656, 10107.696769096645, 10215.080642742189, 10219.540120694437, 10350.22373322019, 10440.53017614194, 10974.725631859268, 11013.724656883642, 11145.721356965816, 11253.549858005588, 11475.885241147482, 8759.562021899006, 10824.350538967687, 11057.336559806437, 11327.546898123983, 11397.886021139682, 10737.355758654505, 10758.332458468949, 10937.343759374464, 11007.889921100686, 11161.33032018049], "value": 11645.95622526921, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1619.1823129319691, 1624.6913086513562, 1638.2627817482132, 1654.1481137214337, 1663.7837081179446, 10650.733731656712, 11181.385023823686, 11238.775224495515, 11278.0, 11288.322700637968, 10827.296225745322, 11030.338179709202, 11309.434528273574, 11322.716932076703, 11359.545618175283, 2352.7882490575844, 2387.116766796279, 2398.6641870138196, 2399.5560821248123, 2404.855708657486, 2354.811615070799, 2390.9641355379595, 2465.5561998840203, 2485.3289611804803, 2490.0288887333954, 2374.833761636686, 2417.601095819188, 2422.1159276863905, 2430.562498750224, 2462.802975761944, 2386.3198988288254, 2415.323709361373, 2439.6706444629926, 2445.254197469771, 2446.2416650838118, 2158.6546152615665, 2277.237125562938, 2423.575874222004, 2425.357280320716, 2448.326710154704, 10974.286671366335, 11103.72240693989, 11158.888411115866, 11159.051480624075, 11183.105351571854, 5766.336871259775, 5850.795222167247, 5870.941290587082, 5925.140854576105, 5947.910781338283, 3376.0715803154085, 3413.0955296846355, 3426.012912510738, 3579.588347340063, 3622.3660859349507, 1229.6369429610008, 1235.7960936445477, 1277.7222777222778, 1285.530781264841, 1285.736424033071, 13604.251766152858, 13859.930700346535, 14134.222617756122, 15291.770623440658, 15322.463713769965, 4643.512431194744, 4765.285207218875, 4770.9980904010545, 4800.591949684316, 4803.519648035169, 10357.326773759833, 11999.64001079969, 12004.459799309052, 12026.518939242507, 12074.577389791491, 5035.874103147377, 5079.771410286472, 5081.974590127025, 5085.542301192911, 5086.18621020635, 4978.90042199154, 5013.5989120870245, 5086.0, 5170.819021334235, 5335.226392173113, 3891.2412079644196, 3928.1161738608585, 3975.1453437511072, 3982.8406863725704, 3991.341428664296, 934.3366209990983, 1023.8208313545249, 1048.5858086056119, 1058.4601853054844, 1072.0673014477454, 832.854250506159, 835.9498430094213, 841.6002398860493, 851.1403482482614, 862.5644049754834, 6265.8120256392385, 6647.202335719745, 6661.3005634408655, 6743.52795304324, 6919.135108111486, 893.9865902011475, 1021.8518314844306, 1029.6499190275342, 1033.2818691009677, 1034.343192073024, 4154.667626589866, 4158.334666453353, 4188.643965262987, 4309.267424537836, 4324.243257429937, 13122.086116185992, 13696.109752866032, 13893.86106138946, 13932.721345573033, 14014.85985140156, 12801.975841932623, 13124.737505249841, 13353.599392018255, 13932.721345573033, 13939.372728227254, 9823.950880245697, 9837.55730992107, 10071.0, 10077.193824494023, 10103.898961010444, 6749.257581665915, 6859.794206173823, 6883.862322753617, 6957.686904089429, 7082.18554866197, 11720.88279117232, 12164.635060948185, 12165.45255463524, 12241.755164896475, 12288.692782680504, 12292.201006934522, 12446.37768111609, 12462.065345098972, 12542.0, 12712.936435317764, 9299.0, 9415.199708024895, 9417.717468475958, 9469.66856160031, 9483.952580237055, 10225.488725563544, 10350.585976561155, 10375.16998640122, 10432.895671043192, 10466.895331046591, 8860.822783544165, 8864.955675221583, 8923.0, 9087.2730181584, 9135.360524763468, 8727.607257673293, 8761.0, 8787.209151176425, 8841.292696584129, 8920.330975176757, 7933.761987140395, 8029.5583742894705, 8129.105798362057, 8149.062857771432, 8149.429539932385, 5914.911276330772, 5946.0, 5950.404959504015, 6074.726637301421, 6129.877402452015, 6005.279366476051, 6177.1969643945995, 6209.882221200227, 6218.06728990655, 6269.341719119427], "value": 5150.3824529378335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.57862828720675, 379.65641094809206, 389.4197645508192, 395.4108378516011, 397.3285148099711, 4914.754262286885, 5024.171011783056, 5059.418166910807, 5059.949400506003, 5120.359955005629, 4203.7477751334945, 4824.565789078983, 4939.876503087425, 4963.900721985558, 5006.324146240266, 3079.8614062367137, 3382.627910929802, 3617.8733744318956, 3673.659114423243, 3828.6554210121085, 3147.543606177103, 3367.2592029753537, 3957.4657421248194, 3967.0280781208644, 3984.262911361393, 3490.4938783876323, 3620.855165793365, 3817.828197731109, 3967.849323696139, 3979.671891943217, 3455.7580969332157, 3792.2225943681615, 3937.015746063484, 3953.822078006482, 4031.5687930784734, 3429.382711111999, 3436.6906978371817, 3478.3217272631846, 3495.143689796016, 3525.365434221839, 4450.421445212101, 4529.935465165683, 4648.604868586174, 4870.391201099863, 4915.361003069614, 4219.2405367033925, 4442.8222871085445, 4524.217606172213, 4554.544545545452, 4591.403117594691, 4216.03031302799, 4581.496035436099, 5099.69401835891, 5247.10799164143, 5372.462753724596, 4299.312110062407, 4316.892077698083, 4337.392765012901, 4338.674599405026, 4358.782060896974, 4865.635077369141, 5404.972975135099, 5520.66875987442, 5555.555555555587, 5765.509931655814, 3755.7370984031404, 4157.023099571598, 4213.789310534461, 4263.850765223202, 4397.31841564558, 4466.754328511963, 4472.463304403493, 4514.6614003949835, 4526.4115664964, 4550.590446859798, 3986.880393588197, 4399.956000440018, 4437.3597709362875, 4457.77711114443, 4915.755601303123, 4594.448666160082, 4678.625709943196, 4878.390201224847, 4992.975035124801, 5104.498795590208, 1394.3446580107334, 1449.4419648435428, 1515.7347464193722, 1536.6004838741785, 1541.9306131224123, 1070.0690399352613, 1092.6175838456636, 1108.955641774336, 1127.6278827986744, 1158.8725240223569, 4211.220594407738, 4683.8360657376825, 4749.26386410107, 4887.682300650446, 5035.971223021568, 4957.380327459067, 5051.444341122474, 5608.0, 5616.427400327915, 5618.213450116987, 3853.7109716771356, 4420.9336859947125, 4673.579377856009, 4989.702677303927, 5130.358705161855, 2713.986430067837, 2740.410811675499, 2893.9710602894124, 2896.5227733855463, 2926.912192634182, 5322.121849894801, 5385.353757549119, 5417.756200970965, 5781.537477001913, 5837.29952405714, 5253.343332083489, 5310.2831117798605, 5370.624056316098, 5412.431694672082, 5708.20085188074, 5039.748012599429, 5469.781208751685, 5631.80288689902, 5715.885682286331, 5775.653460792366, 5067.49325067505, 5188.766505507186, 5369.060414427538, 5561.636304570622, 5577.581681373832, 4742.774913059153, 5158.148905430562, 5248.958302107432, 5411.702356370437, 5482.533984611193, 4812.614990800657, 5190.688558686491, 5368.221607866915, 5798.304203495608, 5811.56413268998, 3515.54297941266, 4329.116834177143, 4343.782810859381, 4420.3590479380955, 4429.490608580055, 5216.66091704038, 5558.861028474321, 5559.27729395176, 5589.301337332833, 5627.943720562742, 4837.323634038086, 5402.891942161214, 5437.40188579248, 5437.918431223456, 5459.344878614592, 4995.950850321327, 5191.779931716119, 5393.379761327497, 5435.510804027656, 5440.972795135999, 4488.483824360241, 5018.573421259234, 5107.744612769272, 5267.894642107213, 5346.304980352529, 4945.9968712671425, 5217.921731174111, 5395.649282796606, 5407.810281738011, 5449.754761035683, 5037.0, 5144.048351055067, 5297.8344764151825, 5315.335583052119, 5339.0389729849], "value": 4047.650153742311, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.8312363470948, 765.2653452685422, 772.6098320348224, 895.7984453497962, 911.2072496927672, 16295.511134665965, 17753.64492710145, 17762.911185444085, 17908.28366865324, 18186.90906545467, 12558.748825023495, 16273.267702953366, 16428.342866285333, 16438.0, 16484.505464836046, 995.9701208963743, 1005.2812239248935, 1007.6070332570304, 1009.2380252909044, 1012.5899010900561, 1004.829179039565, 1007.6725064354114, 1010.5806090472482, 1010.6664800615816, 1010.9090181883629, 1074.6131392698621, 1074.9570017199303, 1075.9946200268987, 1076.9192310576739, 1078.0782431021482, 1066.6640008397408, 1067.8611780468489, 1070.2294348069418, 1070.304302203566, 1075.1828610256184, 793.1632128104847, 794.8092457810142, 795.129333379948, 797.0714118052462, 797.7606717984581, 17987.920724756557, 18116.003619800907, 18130.365437209766, 18131.456056318333, 18170.818291817046, 15394.076355418598, 15786.0, 15847.52457426279, 16612.916935415367, 16658.0838053907, 11992.22050566719, 12218.694532636755, 12257.661658010567, 12421.31682757448, 12619.495220191182, 3863.710221733353, 3916.4125381192766, 4061.0, 4156.833726650931, 4157.293260145752, 116407.0, 119473.40263298714, 122427.55144897141, 123356.38321808423, 124127.0, 10879.401632910265, 10932.289401188897, 11933.462994165284, 11941.104417168746, 11954.701132471584, 26570.335741606614, 26628.0, 27176.45647087047, 27181.728182718314, 27215.319617009733, 8264.297534709616, 8315.542645154575, 8467.969889017788, 8563.400561960727, 8728.170823771732, 4543.454785425704, 4583.702059366131, 4653.813847446132, 4757.9048419032115, 4863.124637565202, 3304.735621150302, 3350.0787283496747, 3373.578302712161, 3404.795712257223, 3418.6581341865613, 647.4237928243866, 653.9247986481521, 662.4799532367068, 669.8928171492538, 672.025562933748, 712.7469748239404, 712.8004158835535, 725.2022774947516, 741.2846603028104, 774.3108633316353, 10706.250562460553, 10809.35143891369, 10964.725881853017, 11054.668359949215, 11232.887671123348, 700.8072779985442, 703.802935178151, 716.5378330976491, 728.8906664000442, 732.2348146187247, 5272.664119485887, 5295.311609490818, 5303.28405665238, 5326.760295786699, 5338.193802901332, 109201.0, 110017.44991274993, 111952.0, 112881.0, 115430.0, 69769.0, 100536.98926021585, 101010.49494752478, 103952.48023759833, 105461.0, 48972.755136224805, 60864.69567652133, 64835.351646483876, 72111.0, 81992.18007820084, 11166.665000050012, 11186.0, 11229.831552526883, 11448.82826757616, 11530.769384612093, 50998.49001509937, 51149.23276150935, 52593.7370313146, 52775.0, 53452.0, 66518.66740666266, 67739.66130169318, 68289.65855170891, 68539.0, 69018.0, 13951.232682202577, 14046.0, 14065.789013164605, 14559.490417835324, 14754.188519631523, 12825.486980520674, 15178.165200914056, 15366.84633153654, 15437.768433473731, 15488.967286403575, 14119.505817296345, 14139.505117321256, 14255.0, 14814.0, 15068.171250580885, 13842.653933651336, 14333.498327558485, 14542.200178990257, 14574.781378279122, 14984.026038307475, 13792.042861395059, 14283.42866285337, 14303.284835758379, 14308.213048282445, 14321.28393580296, 9918.801623967625, 10385.324953877975, 10651.520681569193, 10699.89300106989, 10804.837927431252, 8254.83490330178, 10662.827089020357, 11314.934335644466, 11591.942040289745, 11670.183087184156], "value": 8142.418225835065, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1418.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 106.0, 107.0, 153.0, 135.0, 135.0, 128.0, 107.0, 126.0, 109.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 184.0, 183.0, 185.0, 185.0, 181.0, 184.0, 190.0, 263.0], "value": 192.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 90.0, 63.0, 63.0, 59.0, 59.0, 74.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 86.0, 69.0, 69.0, 69.0, 81.0, 69.0, 68.0, 81.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 87.0, 87.0, 87.0, 86.0, 87.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 104.0, 152.0, 115.0, 94.0, 93.0, 93.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 83.0, 86.0, 87.0, 84.0, 86.0, 83.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 61.0, 64.0, 63.0, 65.0, 64.0, 64.0, 67.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 62.0, 65.0, 62.0, 59.0, 60.0, 58.0, 58.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 78.0, 84.0, 82.0, 80.0, 83.0, 83.0, 86.0, 82.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 133.0, 138.0, 128.0, 133.0, 126.0, 148.0, 167.0, 181.0, 144.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 159.0, 155.0, 160.0, 162.0, 162.0, 161.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 66.0, 63.0, 64.0, 63.0, 61.0, 64.0, 62.0, 65.0], "value": 63.4, "unit": "score"}]}]} 13:51:01 INFO - Return code: 0 13:51:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:51:01 INFO - # TBPL SUCCESS # 13:51:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:51:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:51:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:51:01 INFO - 2017-05-19 13:51:01.883 screenresolution[1653:9566] starting screenresolution argv=screenresolution get 13:51:01 INFO - 2017-05-19 13:51:01.887 screenresolution[1653:9566] Display 0: 1600x1200x32@60 13:51:01 INFO - 2017-05-19 13:51:01.893 screenresolution[1654:9569] starting screenresolution argv=screenresolution list 13:51:01 INFO - Available Modes on Display 0 13:51:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:51:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:51:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:51:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:51:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:51:02 INFO - Intel Iris: 13:51:02 INFO - Chipset Model: Intel Iris 13:51:02 INFO - Type: GPU 13:51:02 INFO - Bus: Built-In 13:51:02 INFO - VRAM (Dynamic, Max): 1536 MB 13:51:02 INFO - Vendor: Intel (0x8086) 13:51:02 INFO - Device ID: 0x0a2e 13:51:02 INFO - Revision ID: 0x0009 13:51:02 INFO - Displays: 13:51:02 INFO - Display: 13:51:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:51:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:51:02 INFO - Display Serial Number: 1600x1200 60 13:51:02 INFO - Main Display: Yes 13:51:02 INFO - Mirror: Off 13:51:02 INFO - Online: Yes 13:51:02 INFO - Rotation: Supported 13:51:02 INFO - Return code: 0 13:51:02 INFO - Running post-action listener: _resource_record_post_action 13:51:02 INFO - [mozharness: 2017-05-19 20:51:02.137807Z] Finished run-tests step (success) 13:51:02 INFO - Running post-run listener: _resource_record_post_run 13:51:02 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 49804800; Write bytes: 423539712; Read time: 490; Write time: 1478 13:51:02 INFO - TinderboxPrint: CPU usage
53.8% 13:51:02 INFO - TinderboxPrint: I/O read bytes / time
49,804,800 / 490 13:51:02 INFO - TinderboxPrint: I/O write bytes / time
423,539,712 / 1,478 13:51:02 INFO - TinderboxPrint: CPU idle
2,203.9 (46.2%) 13:51:02 INFO - TinderboxPrint: CPU system
951.2 (19.9%) 13:51:02 INFO - TinderboxPrint: CPU user
1,614.4 (33.8%) 13:51:02 INFO - TinderboxPrint: Swap in / out
176,095,232 / 0 13:51:02 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 113390080; Write bytes: 112979968; Read time: 10076; Write time: 211 13:51:02 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 48805376; Write bytes: 297507840; Read time: 475; Write time: 1135 13:51:02 INFO - Running post-run listener: _upload_blobber_files 13:51:02 INFO - Blob upload gear active. 13:51:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:02 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 13:51:02 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'] 13:51:02 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 13:51:03 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:51:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:51:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:03 INFO - (blobuploader) - INFO - Done attempting. 13:51:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:51:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:51:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:04 INFO - (blobuploader) - INFO - Done attempting. 13:51:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:51:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:51:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:05 INFO - (blobuploader) - INFO - Done attempting. 13:51:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:51:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:08 INFO - (blobuploader) - INFO - Done attempting. 13:51:08 INFO - (blobuploader) - INFO - Iteration through files over. 13:51:08 INFO - Return code: 0 13:51:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:51:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:51:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d40fc5718f087b626ce5f01ee527559641c045086f4f787a36b57947b22099c87007edb395bf4a93c0db5faa6a31a348cc5592de8dd84e288209bcabec0df926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/110676dafe9b4ed92cec33e4f2afa25376839c6ce31acedc7a252c2685e51044b896b59446059f87e4d84290f982670ab0e2d7aa7803006178e28306d3f50f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4fb6cf892ebaa2a9cbeabb1000b159a0073e91bce486d979e85f4022d9c3abe3a5d11f634db31842dab06e34c64f11087483264e55e753fb49ab9177a216efc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b0feea0721cd1142ecd0d0a14f0265d594f9980fd64d9f0391a2d29c0c1693b6dc115f4cc01a100c07c1513fb370289690d5ff14bfd67338caeb3de4e91aac5"} 13:51:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:51:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:51:08 INFO - Contents: 13:51:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d40fc5718f087b626ce5f01ee527559641c045086f4f787a36b57947b22099c87007edb395bf4a93c0db5faa6a31a348cc5592de8dd84e288209bcabec0df926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/110676dafe9b4ed92cec33e4f2afa25376839c6ce31acedc7a252c2685e51044b896b59446059f87e4d84290f982670ab0e2d7aa7803006178e28306d3f50f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4fb6cf892ebaa2a9cbeabb1000b159a0073e91bce486d979e85f4022d9c3abe3a5d11f634db31842dab06e34c64f11087483264e55e753fb49ab9177a216efc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b0feea0721cd1142ecd0d0a14f0265d594f9980fd64d9f0391a2d29c0c1693b6dc115f4cc01a100c07c1513fb370289690d5ff14bfd67338caeb3de4e91aac5"} 13:51:08 INFO - Running post-run listener: copy_logs_to_upload_dir 13:51:08 INFO - Copying logs to upload dir... 13:51:08 INFO - mkdir: /builds/slave/test/build/upload/logs 13:51:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.145781 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-19 13:51:07.960684) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:07.968489) ========= 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.wALnJ8zn0H/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.ogwDHsemTB/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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/d40fc5718f087b626ce5f01ee527559641c045086f4f787a36b57947b22099c87007edb395bf4a93c0db5faa6a31a348cc5592de8dd84e288209bcabec0df926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/110676dafe9b4ed92cec33e4f2afa25376839c6ce31acedc7a252c2685e51044b896b59446059f87e4d84290f982670ab0e2d7aa7803006178e28306d3f50f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4fb6cf892ebaa2a9cbeabb1000b159a0073e91bce486d979e85f4022d9c3abe3a5d11f634db31842dab06e34c64f11087483264e55e753fb49ab9177a216efc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b0feea0721cd1142ecd0d0a14f0265d594f9980fd64d9f0391a2d29c0c1693b6dc115f4cc01a100c07c1513fb370289690d5ff14bfd67338caeb3de4e91aac5"} build_url:https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011012 build_url: 'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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/d40fc5718f087b626ce5f01ee527559641c045086f4f787a36b57947b22099c87007edb395bf4a93c0db5faa6a31a348cc5592de8dd84e288209bcabec0df926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/110676dafe9b4ed92cec33e4f2afa25376839c6ce31acedc7a252c2685e51044b896b59446059f87e4d84290f982670ab0e2d7aa7803006178e28306d3f50f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4fb6cf892ebaa2a9cbeabb1000b159a0073e91bce486d979e85f4022d9c3abe3a5d11f634db31842dab06e34c64f11087483264e55e753fb49ab9177a216efc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b0feea0721cd1142ecd0d0a14f0265d594f9980fd64d9f0391a2d29c0c1693b6dc115f4cc01a100c07c1513fb370289690d5ff14bfd67338caeb3de4e91aac5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:08.014105) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:08.014724) ========= 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.wALnJ8zn0H/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.ogwDHsemTB/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005091 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:08.063867) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 13:51:08.064190) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 13:51:08.064524) ========= ========= Total master_lag: 0.21 =========