builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0160 starttime: 1496343864.58 results: success (0) buildid: 20170601104724 builduid: 92e3c85109984813a53af0d289c80762 revision: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.577232) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.577649) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.577941) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.603433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.603774) ========= 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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-06-01 12:04:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2017-06-01 12:04:24 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146010 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.773438) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.773845) ========= 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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.029307 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:24.824572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 12:04:24.824932) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e --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 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-06-01 12:04:25,016 truncating revision to first 12 chars 2017-06-01 12:04:25,016 Setting DEBUG logging. 2017-06-01 12:04:25,016 attempt 1/10 2017-06-01 12:04:25,016 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4cc48c061ec5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 12:04:26,061 unpacking tar archive at: mozilla-beta-4cc48c061ec5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.313014 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 12:04:26.160577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:26.160927) ========= script_repo_revision: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:26.161320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:26.161583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 12:04:26.180374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-06-01 12:04:26.180701) ========= /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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:04:26 INFO - MultiFileLogger online at 20170601 12:04:26 in /builds/slave/test 12:04:26 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 12:04:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:04:26 INFO - {'append_to_log': False, 12:04:26 INFO - 'base_work_dir': '/builds/slave/test', 12:04:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:04:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:04:26 INFO - 'branch': 'Mozilla-Beta', 12:04:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:04:26 INFO - 'config_files': ('talos/mac_config.py',), 12:04:26 INFO - 'default_actions': ('clobber', 12:04:26 INFO - 'read-buildbot-config', 12:04:26 INFO - 'download-and-extract', 12:04:26 INFO - 'populate-webroot', 12:04:26 INFO - 'create-virtualenv', 12:04:26 INFO - 'install', 12:04:26 INFO - 'run-tests'), 12:04:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:04:26 INFO - 'download_minidump_stackwalk': True, 12:04:26 INFO - 'download_symbols': 'ondemand', 12:04:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:04:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:04:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:04:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:04:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:04:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:04:26 INFO - 'gecko_profile': False, 12:04:26 INFO - 'gecko_profile_interval': 0, 12:04:26 INFO - 'installer_path': 'installer.exe', 12:04:26 INFO - 'log_level': 'info', 12:04:26 INFO - 'log_name': 'talos', 12:04:26 INFO - 'log_to_console': True, 12:04:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:04:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:04:26 INFO - 'opt_config_files': (), 12:04:26 INFO - 'pip_index': False, 12:04:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:04:26 INFO - 'cmd': ('bash', 12:04:26 INFO - '-c', 12:04:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:04:26 INFO - 'enabled': True, 12:04:26 INFO - 'halt_on_failure': False, 12:04:26 INFO - 'name': 'check_screen_resolution'},), 12:04:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:04:26 INFO - 'cmd': ('bash', 12:04:26 INFO - '-c', 12:04:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:04:26 INFO - 'enabled': True, 12:04:26 INFO - 'halt_on_failure': False, 12:04:26 INFO - 'name': 'check_screen_resolution'},), 12:04:26 INFO - 'run_cmd_checks_enabled': True, 12:04:26 INFO - 'suite': 'dromaeojs', 12:04:26 INFO - 'system_bits': '32', 12:04:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:04:26 INFO - 'title': 't-yosemite-r7-0160', 12:04:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:04:26 INFO - 'use_talos_json': True, 12:04:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:04:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:04:26 INFO - 'work_dir': 'build'} 12:04:26 INFO - [mozharness: 2017-06-01 19:04:26.480713Z] Running clobber step. 12:04:26 INFO - Running pre-action listener: _resource_record_pre_action 12:04:26 INFO - Running main action method: clobber 12:04:26 INFO - rmtree: /builds/slave/test/build 12:04:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:04:28 INFO - Running post-action listener: _resource_record_post_action 12:04:28 INFO - [mozharness: 2017-06-01 19:04:28.973028Z] Finished clobber step (success) 12:04:28 INFO - [mozharness: 2017-06-01 19:04:28.973229Z] Running read-buildbot-config step. 12:04:28 INFO - Running pre-action listener: _resource_record_pre_action 12:04:28 INFO - Running main action method: read_buildbot_config 12:04:28 INFO - Using buildbot properties: 12:04:28 INFO - { 12:04:28 INFO - "project": "", 12:04:28 INFO - "product": "firefox", 12:04:28 INFO - "script_repo_revision": "production", 12:04:28 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:04:28 INFO - "repository": "", 12:04:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:04:28 INFO - "buildid": "20170601104724", 12:04:28 INFO - "pgo_build": "False", 12:04:28 INFO - "basedir": "/builds/slave/test", 12:04:28 INFO - "buildnumber": 6, 12:04:28 INFO - "slavename": "t-yosemite-r7-0160", 12:04:28 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:04:28 INFO - "platform": "yosemite_r7", 12:04:28 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:04:28 INFO - "branch": "mozilla-beta", 12:04:28 INFO - "repo_path": "releases/mozilla-beta", 12:04:28 INFO - "revision": "4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e", 12:04:28 INFO - "stage_platform": "macosx64", 12:04:28 INFO - "builduid": "92e3c85109984813a53af0d289c80762", 12:04:28 INFO - "slavebuilddir": "test" 12:04:28 INFO - } 12:04:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:04:28 INFO - Running post-action listener: _resource_record_post_action 12:04:28 INFO - [mozharness: 2017-06-01 19:04:28.976701Z] Finished read-buildbot-config step (success) 12:04:28 INFO - [mozharness: 2017-06-01 19:04:28.976819Z] Running download-and-extract step. 12:04:28 INFO - Running pre-action listener: _resource_record_pre_action 12:04:28 INFO - Running main action method: download_and_extract 12:04:28 INFO - mkdir: /builds/slave/test/build/tests 12:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:28 INFO - trying https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:04:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/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 12:04:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/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 12:04:30 INFO - Downloaded 1287 bytes. 12:04:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:04:30 INFO - Using the following test package requirements: 12:04:30 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:04:30 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:04:30 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:04:30 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:04:30 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:04:30 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:04:30 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:04:30 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:04:30 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:04:30 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:04:30 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:04:30 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:04:30 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:04:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:04:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:04:30 INFO - Fetch https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:04:31 INFO - Expected file size: 51396881 12:04:31 INFO - Obtained file size: 51396881 12:04:47 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 12:04:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:04:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:04:47 INFO - Fetch https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:04:49 INFO - Expected file size: 51396881 12:04:49 INFO - Obtained file size: 51396881 12:05:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:05:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:05:04 INFO - Fetch https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:05:06 INFO - Expected file size: 14404461 12:05:06 INFO - Obtained file size: 14404461 12:05:06 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')]} 12:05:06 INFO - trying https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:05:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/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 12:05:08 INFO - Downloaded 59647761 bytes. 12:05:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:05:08 INFO - mkdir: /builds/slave/test/properties 12:05:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:05:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:05:08 INFO - Contents: 12:05:08 INFO - build_url:https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:05:08 INFO - Running post-action listener: _resource_record_post_action 12:05:08 INFO - Running post-action listener: set_extra_try_arguments 12:05:08 INFO - [mozharness: 2017-06-01 19:05:08.904079Z] Finished download-and-extract step (success) 12:05:08 INFO - [mozharness: 2017-06-01 19:05:08.904232Z] Running populate-webroot step. 12:05:08 INFO - Running pre-action listener: _resource_record_pre_action 12:05:08 INFO - Running main action method: populate_webroot 12:05:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:05:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:05:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:05:08 INFO - '--tppagecycles', 12:05:08 INFO - '7'], 12:05:08 INFO - 'tests': ['tsvgm']}}, 12:05:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:05:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:05:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:05:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:05:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['damp', 'tps']}, 12:05:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'tests': ['damp', 'tps']}, 12:05:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['dromaeo_dom']}, 12:05:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:05:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['basic_compositor_video']}, 12:05:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:05:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['a11yr', 12:05:08 INFO - 'ts_paint', 12:05:08 INFO - 'tpaint', 12:05:08 INFO - 'sessionrestore', 12:05:08 INFO - 'sessionrestore_no_auto_restore', 12:05:08 INFO - 'tabpaint']}, 12:05:08 INFO - 'other-e10s': {'tests': ['a11yr', 12:05:08 INFO - 'ts_paint', 12:05:08 INFO - 'tpaint', 12:05:08 INFO - 'sessionrestore', 12:05:08 INFO - 'sessionrestore_no_auto_restore', 12:05:08 INFO - 'tabpaint']}, 12:05:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:05:08 INFO - 'ts_paint', 12:05:08 INFO - 'tpaint', 12:05:08 INFO - 'sessionrestore', 12:05:08 INFO - 'sessionrestore_no_auto_restore', 12:05:08 INFO - 'tabpaint']}, 12:05:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:05:08 INFO - 'ts_paint', 12:05:08 INFO - 'tpaint', 12:05:08 INFO - 'sessionrestore', 12:05:08 INFO - 'sessionrestore_no_auto_restore', 12:05:08 INFO - 'tabpaint']}, 12:05:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['a11yr', 12:05:08 INFO - 'ts_paint', 12:05:08 INFO - 'tpaint', 12:05:08 INFO - 'sessionrestore', 12:05:08 INFO - 'sessionrestore_no_auto_restore', 12:05:08 INFO - 'tabpaint']}, 12:05:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['a11yr', 12:05:08 INFO - 'ts_paint', 12:05:08 INFO - 'tpaint', 12:05:08 INFO - 'sessionrestore', 12:05:08 INFO - 'sessionrestore_no_auto_restore', 12:05:08 INFO - 'tabpaint']}, 12:05:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['tsvgx', 12:05:08 INFO - 'tsvgr_opacity', 12:05:08 INFO - 'tart', 12:05:08 INFO - 'tscrollx', 12:05:08 INFO - 'cart', 12:05:08 INFO - 'tsvg_static']}, 12:05:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:05:08 INFO - 'tsvgr_opacity', 12:05:08 INFO - 'tart', 12:05:08 INFO - 'tscrollx', 12:05:08 INFO - 'cart', 12:05:08 INFO - 'tsvg_static']}, 12:05:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'talos_options': ['--disable-e10s'], 12:05:08 INFO - 'tests': ['tp5o']}, 12:05:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:05:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'talos_options': ['--disable-e10s', 12:05:08 INFO - '--xperf_path', 12:05:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:08 INFO - 'tests': ['tp5n']}, 12:05:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:05:08 INFO - 'talos_options': ['--xperf_path', 12:05:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:08 INFO - 'tests': ['tp5n']}}, 12:05:08 INFO - 'talos.zip': {'path': '', 12:05:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:05:08 INFO - Running post-action listener: _resource_record_post_action 12:05:08 INFO - [mozharness: 2017-06-01 19:05:08.923026Z] Finished populate-webroot step (success) 12:05:08 INFO - [mozharness: 2017-06-01 19:05:08.923113Z] Running create-virtualenv step. 12:05:08 INFO - Running pre-action listener: _resource_record_pre_action 12:05:08 INFO - Running main action method: create_virtualenv 12:05:08 INFO - Creating virtualenv /builds/slave/test/build/venv 12:05:08 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 12:05:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:05:08 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:05:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:05:09 INFO - Using real prefix '/tools/python27' 12:05:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:05:09 INFO - Installing distribute.............................................................................................................................................................................................done. 12:05:12 INFO - Installing pip.................done. 12:05:12 INFO - Return code: 0 12:05:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:05:12 INFO - Reading from file tmpfile_stdout 12:05:12 INFO - Output received: 12:05:12 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) 12:05:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:05:12 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')]} 12:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:12 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')]} 12:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:12 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:12 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 12:05:12 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 12:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:12 INFO - 'HOME': '/Users/cltbld', 12:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:12 INFO - 'LOGNAME': 'cltbld', 12:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:12 INFO - 'MOZ_NO_REMOTE': '1', 12:05:12 INFO - 'NO_EM_RESTART': '1', 12:05:12 INFO - 'PAGER': '/bin/cat', 12:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:12 INFO - 'PWD': '/builds/slave/test', 12:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:12 INFO - 'SHELL': '/bin/bash', 12:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:12 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:12 INFO - 'USER': 'cltbld', 12:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:12 INFO - 'XPC_FLAGS': '0x0', 12:05:12 INFO - 'XPC_SERVICE_NAME': '0', 12:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:13 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 12:05:13 INFO - Cleaning up... 12:05:13 INFO - Return code: 0 12:05:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:05:13 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')]} 12:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:13 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')]} 12:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:13 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:13 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 12:05:13 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 12:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:13 INFO - 'HOME': '/Users/cltbld', 12:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:13 INFO - 'LOGNAME': 'cltbld', 12:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:13 INFO - 'MOZ_NO_REMOTE': '1', 12:05:13 INFO - 'NO_EM_RESTART': '1', 12:05:13 INFO - 'PAGER': '/bin/cat', 12:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:13 INFO - 'PWD': '/builds/slave/test', 12:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:13 INFO - 'SHELL': '/bin/bash', 12:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:13 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:13 INFO - 'USER': 'cltbld', 12:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:13 INFO - 'XPC_FLAGS': '0x0', 12:05:13 INFO - 'XPC_SERVICE_NAME': '0', 12:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:13 INFO - Downloading/unpacking psutil>=3.1.1 12:05:13 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 12:05:13 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 12:05:13 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 12:05:13 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 12:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:05:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:15 INFO - Installing collected packages: psutil 12:05:15 INFO - Running setup.py install for psutil 12:05:15 INFO - building 'psutil._psutil_osx' extension 12:05:15 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 12:05:16 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 12:05:16 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 12:05:16 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 12:05:16 INFO - building 'psutil._psutil_posix' extension 12:05:16 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 12:05:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:05:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:05:16 INFO - ^ 12:05:16 INFO - 1 warning generated. 12:05:16 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 12:05:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:16 INFO - Successfully installed psutil 12:05:16 INFO - Cleaning up... 12:05:16 INFO - Return code: 0 12:05:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:05:16 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')]} 12:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:16 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')]} 12:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:16 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:16 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 12:05:16 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 12:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:16 INFO - 'HOME': '/Users/cltbld', 12:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:16 INFO - 'LOGNAME': 'cltbld', 12:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:16 INFO - 'MOZ_NO_REMOTE': '1', 12:05:16 INFO - 'NO_EM_RESTART': '1', 12:05:16 INFO - 'PAGER': '/bin/cat', 12:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:16 INFO - 'PWD': '/builds/slave/test', 12:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:16 INFO - 'SHELL': '/bin/bash', 12:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:16 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:16 INFO - 'USER': 'cltbld', 12:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:16 INFO - 'XPC_FLAGS': '0x0', 12:05:16 INFO - 'XPC_SERVICE_NAME': '0', 12:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:05:16 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 12:05:16 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 12:05:16 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 12:05:16 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 12:05:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:05:18 INFO - Installing collected packages: mozsystemmonitor 12:05:18 INFO - Running setup.py install for mozsystemmonitor 12:05:18 INFO - Successfully installed mozsystemmonitor 12:05:18 INFO - Cleaning up... 12:05:18 INFO - Return code: 0 12:05:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:18 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:18 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 12:05:18 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 12:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:18 INFO - 'HOME': '/Users/cltbld', 12:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:18 INFO - 'LOGNAME': 'cltbld', 12:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:18 INFO - 'MOZ_NO_REMOTE': '1', 12:05:18 INFO - 'NO_EM_RESTART': '1', 12:05:18 INFO - 'PAGER': '/bin/cat', 12:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:18 INFO - 'PWD': '/builds/slave/test', 12:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:18 INFO - 'SHELL': '/bin/bash', 12:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:18 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:18 INFO - 'USER': 'cltbld', 12:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:18 INFO - 'XPC_FLAGS': '0x0', 12:05:18 INFO - 'XPC_SERVICE_NAME': '0', 12:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:18 INFO - Downloading/unpacking jsonschema==2.5.1 12:05:18 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 12:05:18 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 12:05:18 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 12:05:18 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 12:05:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:05:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:05:20 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 12:05:20 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 12:05:20 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 12:05:20 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 12:05:20 INFO - Downloading functools32-3.2.3-2.tar.gz 12:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:05:20 INFO - warning: no files found matching '*.txt' 12:05:20 INFO - no previously-included directories found matching 'build' 12:05:20 INFO - no previously-included directories found matching 'dist' 12:05:20 INFO - no previously-included directories found matching '.git*' 12:05:20 INFO - Installing collected packages: jsonschema, functools32 12:05:20 INFO - Running setup.py install for functools32 12:05:20 INFO - warning: no files found matching '*.txt' 12:05:20 INFO - no previously-included directories found matching 'build' 12:05:20 INFO - no previously-included directories found matching 'dist' 12:05:20 INFO - no previously-included directories found matching '.git*' 12:05:20 INFO - Successfully installed jsonschema functools32 12:05:20 INFO - Cleaning up... 12:05:20 INFO - Return code: 0 12:05:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:05:20 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')]} 12:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:20 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')]} 12:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:21 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:21 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 12:05:21 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 12:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:21 INFO - 'HOME': '/Users/cltbld', 12:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:21 INFO - 'LOGNAME': 'cltbld', 12:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:21 INFO - 'MOZ_NO_REMOTE': '1', 12:05:21 INFO - 'NO_EM_RESTART': '1', 12:05:21 INFO - 'PAGER': '/bin/cat', 12:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:21 INFO - 'PWD': '/builds/slave/test', 12:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:21 INFO - 'SHELL': '/bin/bash', 12:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:21 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:21 INFO - 'USER': 'cltbld', 12:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:21 INFO - 'XPC_FLAGS': '0x0', 12:05:21 INFO - 'XPC_SERVICE_NAME': '0', 12:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:05:21 INFO - Cleaning up... 12:05:21 INFO - Return code: 0 12:05:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:05:21 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')]} 12:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:21 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')]} 12:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:21 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:21 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 12:05:21 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 12:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:21 INFO - 'HOME': '/Users/cltbld', 12:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:21 INFO - 'LOGNAME': 'cltbld', 12:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:21 INFO - 'MOZ_NO_REMOTE': '1', 12:05:21 INFO - 'NO_EM_RESTART': '1', 12:05:21 INFO - 'PAGER': '/bin/cat', 12:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:21 INFO - 'PWD': '/builds/slave/test', 12:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:21 INFO - 'SHELL': '/bin/bash', 12:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:21 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:21 INFO - 'USER': 'cltbld', 12:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:21 INFO - 'XPC_FLAGS': '0x0', 12:05:21 INFO - 'XPC_SERVICE_NAME': '0', 12:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:22 INFO - Downloading/unpacking blobuploader==1.2.4 12:05:22 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 12:05:22 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 12:05:22 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 12:05:22 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 12:05:23 INFO - Downloading blobuploader-1.2.4.tar.gz 12:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:05:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:05:23 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 12:05:23 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 12:05:23 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 12:05:23 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 12:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:05:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:05:24 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 12:05:24 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 12:05:24 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 12:05:24 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 12:05:24 INFO - Downloading docopt-0.6.1.tar.gz 12:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:05:24 INFO - Installing collected packages: blobuploader, requests, docopt 12:05:24 INFO - Running setup.py install for blobuploader 12:05:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:05:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:05:24 INFO - Running setup.py install for requests 12:05:25 INFO - Running setup.py install for docopt 12:05:25 INFO - Successfully installed blobuploader requests docopt 12:05:25 INFO - Cleaning up... 12:05:25 INFO - Return code: 0 12:05:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:25 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:25 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 12:05:25 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 12:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:25 INFO - 'HOME': '/Users/cltbld', 12:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:25 INFO - 'LOGNAME': 'cltbld', 12:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:25 INFO - 'MOZ_NO_REMOTE': '1', 12:05:25 INFO - 'NO_EM_RESTART': '1', 12:05:25 INFO - 'PAGER': '/bin/cat', 12:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:25 INFO - 'PWD': '/builds/slave/test', 12:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:25 INFO - 'SHELL': '/bin/bash', 12:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:25 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:25 INFO - 'USER': 'cltbld', 12:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:25 INFO - 'XPC_FLAGS': '0x0', 12:05:25 INFO - 'XPC_SERVICE_NAME': '0', 12:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-wSfApq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-mkPAbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-DUu7dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-TrDuAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MK5H4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-hLSl9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-LvX2Il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-IUO4Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-3nVeCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-dHXk5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Hf6lGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:27 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-GD2BuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:27 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-tQtYa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:27 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-H0ayq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:27 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-dnU8Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:27 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_fF8Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:27 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_u2H15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:05:27 INFO - Running setup.py install for manifestparser 12:05:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Running setup.py install for mozcrash 12:05:27 INFO - Running setup.py install for mozdebug 12:05:28 INFO - Running setup.py install for mozdevice 12:05:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozfile 12:05:28 INFO - Running setup.py install for mozhttpd 12:05:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozinfo 12:05:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozInstall 12:05:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozleak 12:05:28 INFO - Running setup.py install for mozlog 12:05:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Running setup.py install for moznetwork 12:05:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Running setup.py install for mozprocess 12:05:29 INFO - Running setup.py install for mozprofile 12:05:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Running setup.py install for mozrunner 12:05:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Running setup.py install for mozscreenshot 12:05:29 INFO - Running setup.py install for moztest 12:05:30 INFO - Running setup.py install for mozversion 12:05:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:05:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:05:30 INFO - Cleaning up... 12:05:30 INFO - Return code: 0 12:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:30 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:30 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 12:05:30 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 12:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:30 INFO - 'HOME': '/Users/cltbld', 12:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:30 INFO - 'LOGNAME': 'cltbld', 12:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:30 INFO - 'MOZ_NO_REMOTE': '1', 12:05:30 INFO - 'NO_EM_RESTART': '1', 12:05:30 INFO - 'PAGER': '/bin/cat', 12:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:30 INFO - 'PWD': '/builds/slave/test', 12:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:30 INFO - 'SHELL': '/bin/bash', 12:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:30 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:30 INFO - 'USER': 'cltbld', 12:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:30 INFO - 'XPC_FLAGS': '0x0', 12:05:30 INFO - 'XPC_SERVICE_NAME': '0', 12:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-HA5cDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:30 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)) 12:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-BxvHpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:30 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)) 12:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-tR0Xbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:30 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)) 12:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ifWZFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:30 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)) 12:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-062cvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-J2ns9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-xjlHYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-PVtwbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-iuAjzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-EmlXDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-6X4cfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-11whuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-GirByp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-wmJNLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:31 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)) 12:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:31 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-SLafOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:32 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)) 12:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:32 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-JVF6uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:32 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)) 12:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:32 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_8nykD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:32 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)) 12:05:32 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)) 12:05:32 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)) 12:05:32 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)) 12:05:32 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)) 12:05:32 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)) 12:05:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:33 INFO - Downloading blessings-1.6.tar.gz 12:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:05:33 INFO - Installing collected packages: blessings 12:05:33 INFO - Running setup.py install for blessings 12:05:33 INFO - Successfully installed blessings 12:05:33 INFO - Cleaning up... 12:05:33 INFO - Return code: 0 12:05:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:05:34 INFO - Reading from file tmpfile_stdout 12:05:34 INFO - Current package versions: 12:05:34 INFO - blessings == 1.6 12:05:34 INFO - blobuploader == 1.2.4 12:05:34 INFO - docopt == 0.6.1 12:05:34 INFO - functools32 == 3.2.3-2 12:05:34 INFO - jsonschema == 2.5.1 12:05:34 INFO - manifestparser == 1.1 12:05:34 INFO - mozInstall == 1.12 12:05:34 INFO - mozcrash == 1.0 12:05:34 INFO - mozdebug == 0.1 12:05:34 INFO - mozdevice == 0.49 12:05:34 INFO - mozfile == 1.2 12:05:34 INFO - mozhttpd == 0.7 12:05:34 INFO - mozinfo == 0.9 12:05:34 INFO - mozleak == 0.1 12:05:34 INFO - mozlog == 3.4 12:05:34 INFO - moznetwork == 0.27 12:05:34 INFO - mozprocess == 0.25 12:05:34 INFO - mozprofile == 0.28 12:05:34 INFO - mozrunner == 6.13 12:05:34 INFO - mozscreenshot == 0.1 12:05:34 INFO - mozsystemmonitor == 0.3 12:05:34 INFO - moztest == 0.8 12:05:34 INFO - mozversion == 1.4 12:05:34 INFO - psutil == 3.1.1 12:05:34 INFO - requests == 1.2.3 12:05:34 INFO - wsgiref == 0.1.2 12:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:34 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')]} 12:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:34 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')]} 12:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:34 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 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:34 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 12:05:34 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 12:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:34 INFO - 'HOME': '/Users/cltbld', 12:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:34 INFO - 'LOGNAME': 'cltbld', 12:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:34 INFO - 'MOZ_NO_REMOTE': '1', 12:05:34 INFO - 'NO_EM_RESTART': '1', 12:05:34 INFO - 'PAGER': '/bin/cat', 12:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:34 INFO - 'PWD': '/builds/slave/test', 12:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:34 INFO - 'SHELL': '/bin/bash', 12:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:34 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:34 INFO - 'USER': 'cltbld', 12:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:34 INFO - 'XPC_FLAGS': '0x0', 12:05:34 INFO - 'XPC_SERVICE_NAME': '0', 12:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 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)) 12:05:34 INFO - Cleaning up... 12:05:34 INFO - Return code: 0 12:05:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:05:34 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')]} 12:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:34 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')]} 12:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ed67b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed6a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0d1c95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, '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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 12:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:34 INFO - 'HOME': '/Users/cltbld', 12:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:34 INFO - 'LOGNAME': 'cltbld', 12:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:34 INFO - 'MOZ_NO_REMOTE': '1', 12:05:34 INFO - 'NO_EM_RESTART': '1', 12:05:34 INFO - 'PAGER': '/bin/cat', 12:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:34 INFO - 'PWD': '/builds/slave/test', 12:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:34 INFO - 'SHELL': '/bin/bash', 12:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:34 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:34 INFO - 'USER': 'cltbld', 12:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:34 INFO - 'XPC_FLAGS': '0x0', 12:05:34 INFO - 'XPC_SERVICE_NAME': '0', 12:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:05:34 INFO - Cleaning up... 12:05:34 INFO - Return code: 0 12:05:34 INFO - Running post-action listener: _resource_record_post_action 12:05:34 INFO - Running post-action listener: _start_resource_monitoring 12:05:34 INFO - Starting resource monitoring. 12:05:34 INFO - [mozharness: 2017-06-01 19:05:34.827156Z] Finished create-virtualenv step (success) 12:05:34 INFO - [mozharness: 2017-06-01 19:05:34.828093Z] Running install step. 12:05:34 INFO - Running pre-action listener: _resource_record_pre_action 12:05:34 INFO - Running main action method: install 12:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:05:35 INFO - Reading from file tmpfile_stdout 12:05:35 INFO - Detecting whether we're running mozinstall >=1.0... 12:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:05:35 INFO - Reading from file tmpfile_stdout 12:05:35 INFO - Output received: 12:05:35 INFO - Usage: mozinstall [options] installer 12:05:35 INFO - Options: 12:05:35 INFO - -h, --help show this help message and exit 12:05:35 INFO - -d DEST, --destination=DEST 12:05:35 INFO - Directory to install application into. [default: 12:05:35 INFO - "/builds/slave/test"] 12:05:35 INFO - --app=APP Application being installed. [default: firefox] 12:05:35 INFO - mkdir: /builds/slave/test/build/application 12:05:35 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'] 12:05:35 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 12:05:49 INFO - Reading from file tmpfile_stdout 12:05:49 INFO - Output received: 12:05:49 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:05:49 INFO - Running post-action listener: _resource_record_post_action 12:05:49 INFO - [mozharness: 2017-06-01 19:05:49.991836Z] Finished install step (success) 12:05:49 INFO - [mozharness: 2017-06-01 19:05:49.991992Z] Running run-tests step. 12:05:49 INFO - Running pre-action listener: _resource_record_pre_action 12:05:49 INFO - Running main action method: run_tests 12:05:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:05:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:05:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:05:50 INFO - 2017-06-01 12:05:50.005 screenresolution[1623:7299] starting screenresolution argv=screenresolution get 12:05:50 INFO - 2017-06-01 12:05:50.008 screenresolution[1623:7299] Display 0: 1600x1200x32@60 12:05:50 INFO - 2017-06-01 12:05:50.014 screenresolution[1624:7301] starting screenresolution argv=screenresolution list 12:05:50 INFO - Available Modes on Display 0 12:05:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:05:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:05:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:05:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:05:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:05:50 INFO - Intel Iris: 12:05:50 INFO - Chipset Model: Intel Iris 12:05:50 INFO - Type: GPU 12:05:50 INFO - Bus: Built-In 12:05:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:05:50 INFO - Vendor: Intel (0x8086) 12:05:50 INFO - Device ID: 0x0a2e 12:05:50 INFO - Revision ID: 0x0009 12:05:50 INFO - Displays: 12:05:50 INFO - Display: 12:05:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:05:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:05:50 INFO - Display Serial Number: 1600x1200 60 12:05:50 INFO - Main Display: Yes 12:05:50 INFO - Mirror: Off 12:05:50 INFO - Online: Yes 12:05:50 INFO - Rotation: Supported 12:05:50 INFO - Return code: 0 12:05:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:05:50 INFO - Python 2.7.3 12:05:50 INFO - Return code: 0 12:05:50 INFO - grabbing minidump binary from tooltool 12:05:50 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')]} 12:05:50 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 0x10eb45d30>, '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 0x10ea6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb3abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:05:50 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 12:05:50 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 12:05:50 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 12:05:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:05:50 INFO - Return code: 0 12:05:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:05:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:05:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:05:50 INFO - ENV: RUST_BACKTRACE is now 1 12:05:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:05:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:05:50 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/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 12:05:50 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/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --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 12:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7FZmJQsXIF/Render', 12:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:50 INFO - 'HOME': '/Users/cltbld', 12:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:50 INFO - 'LOGNAME': 'cltbld', 12:05:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:05:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:50 INFO - 'MOZ_NO_REMOTE': '1', 12:05:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:05:50 INFO - 'NO_EM_RESTART': '1', 12:05:50 INFO - 'PAGER': '/bin/cat', 12:05:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:50 INFO - 'PWD': '/builds/slave/test', 12:05:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:50 INFO - 'RUST_BACKTRACE': '1', 12:05:50 INFO - 'SHELL': '/bin/bash', 12:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h7xZ6k5Sus/Listeners', 12:05:50 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 12:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:50 INFO - 'USER': 'cltbld', 12:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:50 INFO - 'XPC_FLAGS': '0x0', 12:05:50 INFO - 'XPC_SERVICE_NAME': '0', 12:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:50 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/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 12:05:50 INFO - mozversion application_buildid: 20170601104724 12:05:50 INFO - mozversion application_changeset: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e 12:05:50 INFO - mozversion application_display_name: Firefox 12:05:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:05:50 INFO - mozversion application_name: Firefox 12:05:50 INFO - mozversion application_remotingname: firefox 12:05:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:05:50 INFO - mozversion application_vendor: Mozilla 12:05:50 INFO - mozversion application_version: 54.0 12:05:50 INFO - mozversion platform_buildid: 20170601104724 12:05:50 INFO - mozversion platform_changeset: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e 12:05:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:05:50 INFO - mozversion platform_version: 54.0 12:05:50 INFO - using testdate: 1496343950 12:05:50 INFO - actual date: 1496343950 12:05:50 INFO - starting webserver on 'localhost:49226' 12:05:50 INFO - SUITE-START | Running 2 tests 12:05:50 INFO - TEST-START | dromaeo_css 12:05:50 INFO - operating with platform_type : mac_ 12:05:50 INFO - Initialising browser for dromaeo_css test... 12:05:50 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmp67BlIr/profile http://localhost:49226/getInfo.html) 12:05:53 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 12:05:53 INFO - PROCESS | 1634 | colorDepth:24 12:05:53 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 12:05:53 INFO - PROCESS | 1634 | __metrics 12:05:53 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" 12:05:53 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] 12:05:53 INFO - TEST-INFO | 1634: exit 0 12:05:53 INFO - Browser initialized. 12:05:53 INFO - Running cycle 1/1 for dromaeo_css test... 12:05:53 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmp67BlIr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:05 INFO - PROCESS | 1637 | RSS: Main: 165044224 12:06:05 INFO - PROCESS | 1637 | 12:09:09 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 12:09:11 INFO - PROCESS | 1637 | RSS: Main: 231870464 12:09:11 INFO - PROCESS | 1637 | 12:12:14 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 12:12:15 INFO - PROCESS | 1637 | RSS: Main: 301797376 12:12:15 INFO - PROCESS | 1637 | 12:15:17 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 12:15:18 INFO - PROCESS | 1637 | RSS: Main: 276865024 12:15:18 INFO - PROCESS | 1637 | 12:18:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 12:18:21 INFO - PROCESS | 1637 | RSS: Main: 291344384 12:18:21 INFO - PROCESS | 1637 | 12:21:25 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 12:21:25 INFO - PROCESS | 1637 | RSS: Main: 264396800 12:21:25 INFO - PROCESS | 1637 | 12:24:28 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 12:24:29 INFO - PROCESS | 1637 | RSS: Main: 343605248 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | __start_tp_report 12:24:29 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 12:24:29 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 12:24:29 INFO - PROCESS | 1637 | |i|pagename|runs| 12:24:29 INFO - PROCESS | 1637 | |0;dojo.html;3573.9635505703345;3580.820958952052;3605.7295702822294;3617.847380808418;3623.2572322673836;6192.876142477147;6202;6210.813675589734;6216.254049514053;6247.812565623033;4525.841595544157;6067.998780201261;6210.161628180194;6219.346968568303;6228.1591985082005;5498.1752737089555;5507.889842203153;5544.972275138619;5570.303712035996;5676.148577713335;5426.104692725696;5439.374471935718;5488.121900495921;5648.802291919763;5666.206731057655;5511.255980442649;5563.304586926634;5569.331680198382;5585.1063829787245;5585.776568937237;5582.553395728332;5584.6090773645865;5586.804461843816;5595.328560572757;5623.353314368859;5472.50747432729;5546.251256080418;5559.110542313251;5564.054110801133;5579.38626751057;6079.878402431971;6089.6041757286075;6122.337166850092;6246.219222597176;6301.968490157566;6446.548741588091;6487.902681459782;6552.8361790955605;6565.048068030157;6570.605763654196;6546.18172728409;6627.304133066007;6682.966585167043;6689.063531105649;6700.229473610549;6125.459783291287;6370.426661600478;6373.776917807854;6385.904211436832;6400.967995160041;6582.868342633216;6668.566543174678;6699.631520266432;6711.4966377520905;6724.764633237911;6430.260520040255;6451.903221451682;6454.612723236527;6456.483481321483;6470.029495575702;6562.081308616797;6598.307177746364;6639.203295604559;6650.866982660416;6650.933490665128;6043.667598282049;6332;6404;6414.775482858077;6516;6311.684415779193;6372.490200783926;6374.362563743682;6374.649394283359;6522.739090436425;5225.451327610622;5226.294450249244;5229.6600720953775;5236.450172731885;5258.789648414021;4761.666683332202;4797.352357431771;4814.277858321209;4817.807287708522;4837.540433658797;6156.07658851167;6301.6219026858535;6303.9684801575695;6304.18045654062;6315.842103947346;6366.745330186741;6389.520785941073;6537.313582073909;6659;6689.063531105649;5205.6356055075785;6250.1874756281395;6266.404691554295;6282.748690052438;6293.370662933761;5758.203249442521;5804.564657650693;5808.448197421239;5820.330661973842;5864.501517371071;6537.836554085996;6568.573042752206;6785.219699734396;6830.692618832164;6837.829054273683;6502;6544.509161812788;6654.73381064752;6656.167979002625;6662.6335551545135;6827.761028363983;6831.146106736658;6844.2129155147795;6853.211880633791;6854.9657251713425;5409;6482.092507048923;6553.705083271359;6579.769708060387;6588.571742836701;5876.882462350814;5892.263467066617;6422.967885160731;6501.9674901625185;6574.572652777554;6436.485081193587;6454.903176452263;6464.547481676237;6476.190476190476;6478.70845811949;6412.551121421456;6433.260175079926;6445.581037232565;6451.548391612542;6483.189601299838;6206.627602343874;6207.534434917489;6215.098810672337;6345.904811427925;6362.936370636235;6379;6415.486761059197;6421.165248517663;6425.4217120457515;6443.7422503101225;6197.938020619736;6259.311475737756;6363.395477429637;6380.553361264667;6380.968095159495 12:24:29 INFO - PROCESS | 1637 | |1;ext.html;829.1791126784483;843.7173546861801;908;924.5562130177519;926.9026752191015;40542;40964.77105686827;44035.11929761403;44111.779441102786;44224.3366349505;42164.94587635311;42565.57434425655;42643.3603495947;42703.572964270345;42772.358414623806;8134.267915887569;8283.917160828374;9674.322797404167;9848.015198480167;9858.950705246463;11910.46402911871;12007.459664315127;12101.394930253497;12108.212966157216;12116.939415302912;12232.938835305811;12286.508539658447;12292.508299668003;12302.384880755972;12335.814962775565;12189.20770149942;12224.938875305656;12233.816492752616;12236.143469957107;12278.631641050782;11697.824532631932;11744.588939387166;11771.823422648667;11780.234284771463;11784.351860647661;41548.1690366194;41567.792161039004;41593.1681366371;41610.583894160976;41659.37510937338;4055.7972101395108;4063.8374465021366;4076.714629975903;4092.242935056994;4099.877003689894;28808.42383152346;29605.407891842042;30819.383612327852;31071.84464077688;31173;3300.47192449207;3310.933781324384;3319.5850518685165;3441.0881116504147;3452.2750222453315;364637;369716;370016;370108;370233;42131.368029479585;42748.358774618406;45422.318665220046;53049.67375815636;53300.66748331245;53179;54082.7295863526;54096.72951635216;54107;54225.3732388029;15608.219589020504;15660.37358505647;15676.764848527282;15690;15726.29231684577;13097.869021309856;13112.344382781013;13123.409446574928;13125.803112953314;13148.342582870819;12406.81389779154;12416;12425.254484730945;12505.749885002431;12528.686782830502;5689.345725241567;5756.539476841843;5759.65442073477;5771.682557459296;5800.926828536734;957.2916042128913;962.6149540183842;982.4645568165367;983.5967253426061;990.3265779270165;28587.999420020198;28597.57103643447;29098.854505727337;29319.120426387242;29354.706452935625;1049.574922156528;1058.6030238660503;1066.6586692258402;1089.8801131875487;1091.5633746501462;13296.33795775528;13459.730805383837;14660.853391466162;14783.926080369529;15090.24548772557;479218;480155;480694;480851;480984;56362.154567681515;56402.43597564054;56443;56498.15252771212;56578.7171064142;1430.27056201336;1460.466929570699;1486.0712054965863;1496.2593516209547;1526.023345059163;26228.344291392867;27368;27887.44225115527;27889.581656274768;27946;38894;38970;38983.805080974416;39103.608963909996;39205.607943921335;445190;445269;446149.5385046108;446484;446536;25027.249182523825;25338.98644054291;28328.85835570809;28784.42431151407;29765.66054527508;37976.43035354527;38027.80986095052;38139.23721525499;38718.225635487695;38849.41725874058;27502.44995100127;28041;28042.71957280401;28130.578041329805;29053.418931621673;27806.582901256093;27867.44265114727;28083.578746318413;28663.570046448902;28862.278443038253;31267.37465250637;32082.679173208904;32529.186770330933;32542.67457325491;32591.859284924914;25802.741972580785;25814.483710325312;25848.09531666383;27966.44067118687;28564.0002599908;22014;22784.27926668507;23011;23184;23276.301710948697 12:24:29 INFO - PROCESS | 1637 | |2;jquery.html;422.03985932004684;426.2668210677633;427.6108741045647;434.56543456543454;452.55875521366664;20148.395548133543;21216.57566848662;21232.681509777347;22333.664995025047;22463.550728985432;19830.801691983077;22913.656295155546;23375.766242337573;23619.64570531439;24244.03023879047;6799.966000170017;6856.6571671416605;7129.287071292855;7137.10786151731;7138.428925685933;6726.529142959995;6922.203946546136;6925.238223795379;6932.757353492629;6943.132108486439;6921.550099243558;6928.688209030605;7464.664090115931;7763.844723105534;7920.881186782203;7660.693572257103;7840.255175758294;7866.842663146762;7911.762647120596;7919.089304729943;7702.191269916634;7703.1526532081425;7703.3452156566755;7715.845683086363;7725.420593455513;20817.271395501237;27521.036763713375;27732.584011239847;27880.442391152064;27988.300292492648;2959.940801183986;2996.235959830229;3011.728944395015;3037.817730936129;3124.4844600640868;15728.640703894393;16387.344506219855;16616.836821422505;16647.583810404714;17456.82543174565;1992.621401933628;2144.238795227703;2150.290404166621;2152.9892350538303;2158.589867925089;107972.4601376996;115509;124155.3792231042;130298.99999999905;166612;17564.912175439298;21066.157353705985;21260.78739212588;21301.68047479289;21341.359759207247;33361.332773344395;34057.807976720695;34151.82924085413;34219.82890085533;34374.31251374959;10806.135509159405;11025.83461248082;11065.871601644563;11066.833997490045;11294.152938529478;9281.535923203948;9300.627974880928;9302.488363140092;9453.763655908657;9504.714858554255;8391.580420978926;9215.677451289306;9699.757506062404;9726.75683107928;9834;3104.580881581003;3928.744631598937;3929.6070392961046;3938.547067087264;3945.704072194597;723.4105036464176;816.2612835383868;819.7824883689606;857.7855536115972;962.20617990157;21619.24332648381;23392.298231052755;23527.529449410915;23615.7638423617;23657;910.3581974707815;921.9492927888896;946.8485042393322;952.538019060755;983.8229118758692;11228.270162439416;11871.46578403974;12214.57248996281;12254.693632659255;12267.263964162179;729892;755960;774181;786473;800795;115573.4221328888;124769.37615311865;158283;168482;171423;1217.4582310871576;1244.3964677131764;1252.943617537213;1269.695273134441;1295.1193188631821;18975.14611842443;20059.796412215313;20815.271465498634;21153.25963591267;21201.787982120597;56091.15863262136;61054.38945610487;62097.44756381126;63410.682946586814;64848.40606375692;84168.57915710627;84994;85533;86347.56826215828;86636;11962.282263064244;12040.578579749666;12309.322987235788;12345.259284442962;12606.684832879251;13810.240436776074;13919.164850109026;14234.786478202628;14286.499972500913;14308.069975451563;11693.532258709556;11775.52897884075;12135.150539462153;12151.39243037827;12176.208546444454;10533.789324213627;11218.775624487302;11294;11774.646760597196;11812.527498899948;11779.52881884715;11841.171118021655;12198.390080496118;12202.450889709808;12202.938985305016;12273.324967126893;12347.75304493923;12403.62789116328;12568;12620.116591838483;11693.76612467763;11702.890610993001;11936.164468487123;12124.27254364741;12157.574484892986 12:24:29 INFO - PROCESS | 1637 | |3;mootools.html;1530.7524367640372;1632.1512813337065;1636.124673299785;1654.4788391656634;1676.0614056128566;10840.67477975661;11603.361815100177;11616.24494407863;11621.709457263561;11631.6510504685;10956.34261944284;11244.325340479578;11277.887221127787;11509.597164099257;11520.193586448931;2458.0782206672498;2502.0867383405107;2509.8870550825163;2510.259473455335;2511.7739403453775;2073.6889466579983;2431.63525471179;2470.4441500662297;2517.8615176165304;2519.6220566914926;2477.5168842075805;2487.987560062197;2507.3731567108225;2519.1812660885194;2523.129520315488;2384.523095380931;2396.8442051266697;2398.5802484565133;2409.27721683496;2444.7310795812446;2470.654108424822;2484.465839844442;2497.687789026372;2498.0632262901413;2498.2879879234333;11206.775864482746;11414;11421.486033128529;11427.257228280136;11429.02853257474;5992.161097446361;6148.354422785588;6152.323244443108;6154.5384096192965;6160;3666.358387282215;3696.3346597612417;3776.150366167618;3787.223619158066;3866.4780254665543;1301.1932601786953;1930.4787707318944;1938.2731475696614;1967.5572996075912;2001.599680063993;14037.438502459992;14379.85620143806;14775.187364694937;15864.682706345808;16041.919790400972;4389.1660584488845;4433.7561434121435;4851.611871050308;4870.878228044327;4880.41435027799;12053.457594408274;12055.336956467303;12070.637880863587;12107.757844843054;12129.211601246066;5094.592432605383;5240.9213861792105;5847.386024467455;5964.940350596526;6075.027995520695;5456.48163424474;5620.494155526022;5624.184493248538;5625.128105143709;5685.715714214272;3683.9079023024638;3975.9662487753394;4057.107436363995;4062.2281766464866;4065.7560546367313;1051.059301924773;1068.540527573151;1075.7579544602559;1084.0568705226503;1084.3764835219622;821.1829229916272;824.6783754335814;853.9402241843011;867.2454964181077;887.0105655686762;6560.212774467094;6645.302243264484;6693.631850248185;6710.664466776641;6740.224874139439;979.2264111352094;991.3704797453669;996.1234113979651;1034.6895931220604;1036.6164519127972;4139.441175441337;4167.541570427251;4188.371744238328;4212.115455754265;4332.826686932551;13802.65493362674;14324.713505729827;14325.355359008661;14329.713405731827;14330.140191588538;14218.360173792204;14220.004599677923;14255.572332830032;14259.42962281497;14267.785983210053;8976.730698079067;10193.133583645325;10288.742781430525;10553.736156596147;10621.5220315086;6168.537359697951;6506.577072490273;6633.568818026813;6667.83330416724;6670.533062685566;12375.071869609996;12734.235945843278;12759.808602870778;12792.488300468249;12806.295653739135;12715.427805748579;12797.872021279669;12905.548305809252;12973;13000.869991299965;8536.189062039097;8565.95717021411;8676.175763302475;8689.435186713097;8699;10327.793444131226;10441.425721585385;10470.476476176313;10532.157427405942;10598.364098154136;8910;8976.910230897607;9044.321675874467;9057.36598438103;9207.355485115977;8739.43193692408;8812.779680508038;8838.938651343718;8917.063708310794;8961.193492585699;6254.874902502015;8041.678332866853;8133.430659853753;8144.83710325802;8148.837023259383;5885.146653735099;5889.411058894077;5897.321807992136;6046.123312119806;6117.632942023492;5411.565935027143;5923.111533270044;6125.938740612537;6143;6201.441870231701 12:24:29 INFO - PROCESS | 1637 | |4;prototype.html;373.73091374210577;391.24099247459924;394.1742050404402;398.57949862894634;401.71879684221045;5372.307723067231;5394.460553944608;5400.378956420013;5475.616706830513;5543.727071900522;4796.3764710587575;5058.772355244014;5124.9487505124935;5167.534921857032;5176.171812509999;3661.7253705972093;3832.616738326173;4132.772697501636;4339.7396156230725;4361.277295468288;3703.703703703697;4146.3158578834455;4171.582841715819;4296.066769953533;4389.639211844338;3421.7946923184563;4041.818118184689;4070.9389359159636;4342.782860856961;4425.601695847373;4313.28830742927;4346.391505189276;4353.151135528573;4377.25586650267;4447.688661793676;3447.8620855165764;3477.7653932853727;3701.407774756026;4069.7354671946227;4117.402976568381;4583.9083218335445;4670.416197975253;5115.258287548322;5217.947820521822;5270.894582108374;5039.218921067204;5049.748261807716;5071.619628527838;5095.949040509585;5277.894442111136;4949.4060712714345;5179.974100129475;5345.679259244458;5595.21666966625;5671.291088613923;4421.801018954155;4477.820887164509;4518.209313370147;4531;4596.471405788344;5068.452083541536;5587.8987162125;5749.108888122306;5802.97098514509;5848.759649254299;4459.435824400917;4551.52209018055;4565.840195593205;4601.585857272861;4612.008418190106;4403.798100949525;4690.577847993697;4741.620670346364;4749.501302363271;4784.976075119602;4137.358709400048;4659.673822832437;4785.138675038526;4990.878876477483;5044.4436672146785;4950.009998000414;5582.5764430070885;5594.608377413623;5600.5799565032785;5662.5367964935385;1359.4901911783081;1403.8768984812314;1405.3324670781296;1473.5284708893266;1544.8764098872064;1052.85786418834;1110.5724296146043;1199.064729510983;1213.5631172777794;1226.7485165541043;4195.475800327678;4514.855443664299;5008.949910500848;5086.262491938648;5193;5233.450487698813;5483;5672.829815105553;5701.885962280731;5748.5688573357165;4169.478140478764;4909.3126962225315;4990.629451010814;5132.3327967364;5155.484451554815;2647.9338016549355;2744.519709050908;2840.2899275181203;2927.502324604822;2938.4279410515273;4864.297495876485;5178.197379406198;5274.789008439696;5785.537157027427;5906.793262235887;4844.273358996108;4884.4613946606705;5521.3926468088475;5808.186853840466;5864.475236438471;5545.528630066489;5839.386864379264;5850.5027072699295;5857.502112320501;5858.564651660286;4892.461829198714;5216.843494695166;5347.459956520622;5415.702136382537;5537.723113844372;4661.20759470884;5012;5046.798128074836;5390.245365648734;5610;4948.076663719622;4968;5207;5469.316335458067;6000.669963152069;3923.489946306962;4097.8975525612095;4155.418241446139;4478.596926276651;4576.794044267949;5006.165351225321;5359.847632758975;5474.029349434121;5676.602637815313;5800.622959507618;5143.8456846294675;5456.890862182813;5550.333959924835;5561.721913904369;5596.720163991866;5266.920996184984;5404.810941592843;5626.746796394254;5632.2678051853;5648.293963254593;4916.336294600326;5169.06956747789;5281.989266118276;5465.114535485411;5540.45139235956;4813.614910807057;4910.790144434947;5128.10260203727;5393.757280922447;5405.486478784509;4797.856064318076;4883.926741098958;5386.437932999386;5431.239626452379;5443.401225865231 12:24:29 INFO - PROCESS | 1637 | |5;yui.html;794.6622685358722;812.3582369927761;838.5555655502586;888.3204348673264;893.075666684981;15786.368545258176;17458.516026137793;17472.737908931344;17480.3007879685;17507.12464376781;14419.855801441983;16111.033337999728;16291.51125466235;16297.83702162981;16332.75500867488;988.7231575158949;1009.5608410341488;1029.4698230411343;1029.9691009269734;1030.814453398388;1009.4801177393648;1011.6560369474378;1012.4735137728377;1012.6759436980169;1012.9392236465799;1050.6375300521347;1050.6480329089763;1051.610903965534;1052.337027672569;1052.899974502421;1041.6041904076478;1042.5569133118395;1043.5304113149114;1043.6660268714015;1044.4046893270802;764.6673696941792;765.7434759355623;766.2796970847386;766.3830616353833;769.0387016229713;14755.11469311834;15126.243687815675;15366.462173823975;15551.222438878123;15566.9221653891;12372.81440778389;12563.18339307942;12722.809157862639;12849.871501285055;12899.161554499022;11255.943720281428;11305.886941130648;11441.272114323223;11563.364014979174;11842.993345565637;3316.8673253069846;3737.1820730802497;3766.190269092131;3810.2760475509563;3848.7299191267093;87606;87979.56010219971;108946;110480;111956.99999999919;9458.668946586837;9655.468949207861;9733.805323893625;9829.705108846745;10126.430642834675;21669.34991950244;21837.344879653636;21930.122795088017;21943;22632.321030369116;8354.415190936697;8406.831863362699;8468.618912148844;8545.102764209792;8663.566821658891;4509.436320459942;4571.679982401267;4649.372334734835;4687.20317546018;4714.127886341038;3166.224275052627;3592.263585964923;3700.925981480409;3705.7591256568235;3723.702103831687;652.2531701202097;667.5460686733068;672.8561445542597;673.2157037051779;690.975815846443;653.3335997282745;659.370301362198;701.5019336271203;716.992830071703;726.8400951790597;9665;9914.55384507699;9995.900040999642;10093.495325233709;10100.343477673927;633.6071635585874;652.1326635574675;676.8696277217059;695.6556504530189;712.4798896805361;4345.869623911288;4924.804014419427;4968.155413579628;5344.625876188629;5676.460736230051;89821;93186.60220096704;93612;96618;109451;60314.09528857071;61844.69077654583;65432;86582.56708716416;87281.56359218163;71975.64012179905;77886.83169752458;79110.81333780113;81410;82791;9141.360104792866;10052.396856188652;10606.946965265124;11391;11546.59586914454;39788.403173953;49712.25431618456;51685.7415712919;52045.438636840954;53010.67473313198;64067.35932640614;64766.67616662075;65100;65844;66967.66516167388;12854.228746275254;13193.802092968805;13276.601701948957;13545.322733863464;13549.119307244997;15223.619409514407;15459;15574.922125389301;15590.220488975732;15909.68180636404;13933.860661393663;14031.801717431452;14116.929415352857;14655.047421917541;14826.96211265201;14098.154110753389;14305.57083287503;14449;14525.92737036308;14958.700825983637;13879.53146162735;13918;13929.651758705708;14656.304023697094;15028.323725432163;9559.18746906493;9844.556994935101;10207.642732504326;10271.150809267872;10938.945305273422;11301.604443844426;11594.420278986187;11683.429645477981;11701.473433695333;11717 12:24:29 INFO - PROCESS | 1637 | __end_tp_report 12:24:29 INFO - PROCESS | 1637 | __start_cc_report 12:24:29 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1931 12:24:29 INFO - PROCESS | 1637 | __end_cc_report 12:24:29 INFO - PROCESS | 1637 | __startTimestamp1496345069084__endTimestamp 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | ------- Summary: start ------- 12:24:29 INFO - PROCESS | 1637 | Number of tests: 6 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:6090.18 Median:6345.90 stddev:668.56 (10.5%) stddev-sans-first:640.80 12:24:29 INFO - PROCESS | 1637 | Values: 3574.0 3580.8 3605.7 3617.8 3623.3 6192.9 6202.0 6210.8 6216.3 6247.8 4525.8 6068.0 6210.2 6219.3 6228.2 5498.2 5507.9 5545.0 5570.3 5676.1 5426.1 5439.4 5488.1 5648.8 5666.2 5511.3 5563.3 5569.3 5585.1 5585.8 5582.6 5584.6 5586.8 5595.3 5623.4 5472.5 5546.3 5559.1 5564.1 5579.4 6079.9 6089.6 6122.3 6246.2 6302.0 6446.5 6487.9 6552.8 6565.0 6570.6 6546.2 6627.3 6683.0 6689.1 6700.2 6125.5 6370.4 6373.8 6385.9 6401.0 6582.9 6668.6 6699.6 6711.5 6724.8 6430.3 6451.9 6454.6 6456.5 6470.0 6562.1 6598.3 6639.2 6650.9 6650.9 6043.7 6332.0 6404.0 6414.8 6516.0 6311.7 6372.5 6374.4 6374.6 6522.7 5225.5 5226.3 5229.7 5236.5 5258.8 4761.7 4797.4 4814.3 4817.8 4837.5 6156.1 6301.6 6304.0 6304.2 6315.8 6366.7 6389.5 6537.3 6659.0 6689.1 5205.6 6250.2 6266.4 6282.7 6293.4 5758.2 5804.6 5808.4 5820.3 5864.5 6537.8 6568.6 6785.2 6830.7 6837.8 6502.0 6544.5 6654.7 6656.2 6662.6 6827.8 6831.1 6844.2 6853.2 6855.0 5409.0 6482.1 6553.7 6579.8 6588.6 5876.9 5892.3 6423.0 6502.0 6574.6 6436.5 6454.9 6464.5 6476.2 6478.7 6412.6 6433.3 6445.6 6451.5 6483.2 6206.6 6207.5 6215.1 6345.9 6362.9 6379.0 6415.5 6421.2 6425.4 6443.7 6197.9 6259.3 6363.4 6380.6 6381.0 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:56676.73 Median:31675.03 stddev:115245.79 (363.8%) stddev-sans-first:115492.80 12:24:29 INFO - PROCESS | 1637 | Values: 829.2 843.7 908.0 924.6 926.9 40542.0 40964.8 44035.1 44111.8 44224.3 42164.9 42565.6 42643.4 42703.6 42772.4 8134.3 8283.9 9674.3 9848.0 9859.0 11910.5 12007.5 12101.4 12108.2 12116.9 12232.9 12286.5 12292.5 12302.4 12335.8 12189.2 12224.9 12233.8 12236.1 12278.6 11697.8 11744.6 11771.8 11780.2 11784.4 41548.2 41567.8 41593.2 41610.6 41659.4 4055.8 4063.8 4076.7 4092.2 4099.9 28808.4 29605.4 30819.4 31071.8 31173.0 3300.5 3310.9 3319.6 3441.1 3452.3 364637.0 369716.0 370016.0 370108.0 370233.0 42131.4 42748.4 45422.3 53049.7 53300.7 53179.0 54082.7 54096.7 54107.0 54225.4 15608.2 15660.4 15676.8 15690.0 15726.3 13097.9 13112.3 13123.4 13125.8 13148.3 12406.8 12416.0 12425.3 12505.7 12528.7 5689.3 5756.5 5759.7 5771.7 5800.9 957.3 962.6 982.5 983.6 990.3 28588.0 28597.6 29098.9 29319.1 29354.7 1049.6 1058.6 1066.7 1089.9 1091.6 13296.3 13459.7 14660.9 14783.9 15090.2 479218.0 480155.0 480694.0 480851.0 480984.0 56362.2 56402.4 56443.0 56498.2 56578.7 1430.3 1460.5 1486.1 1496.3 1526.0 26228.3 27368.0 27887.4 27889.6 27946.0 38894.0 38970.0 38983.8 39103.6 39205.6 445190.0 445269.0 446149.5 446484.0 446536.0 25027.2 25339.0 28328.9 28784.4 29765.7 37976.4 38027.8 38139.2 38718.2 38849.4 27502.4 28041.0 28042.7 28130.6 29053.4 27806.6 27867.4 28083.6 28663.6 28862.3 31267.4 32082.7 32529.2 32542.7 32591.9 25802.7 25814.5 25848.1 27966.4 28564.0 22014.0 22784.3 23011.0 23184.0 23276.3 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:43210.30 Median:23459.91 stddev:127531.17 (543.6%) stddev-sans-first:127848.46 12:24:29 INFO - PROCESS | 1637 | Values: 422.0 426.3 427.6 434.6 452.6 20148.4 21216.6 21232.7 22333.7 22463.6 19830.8 22913.7 23375.8 23619.6 24244.0 6800.0 6856.7 7129.3 7137.1 7138.4 6726.5 6922.2 6925.2 6932.8 6943.1 6921.6 6928.7 7464.7 7763.8 7920.9 7660.7 7840.3 7866.8 7911.8 7919.1 7702.2 7703.2 7703.3 7715.8 7725.4 20817.3 27521.0 27732.6 27880.4 27988.3 2959.9 2996.2 3011.7 3037.8 3124.5 15728.6 16387.3 16616.8 16647.6 17456.8 1992.6 2144.2 2150.3 2153.0 2158.6 107972.5 115509.0 124155.4 130299.0 166612.0 17564.9 21066.2 21260.8 21301.7 21341.4 33361.3 34057.8 34151.8 34219.8 34374.3 10806.1 11025.8 11065.9 11066.8 11294.2 9281.5 9300.6 9302.5 9453.8 9504.7 8391.6 9215.7 9699.8 9726.8 9834.0 3104.6 3928.7 3929.6 3938.5 3945.7 723.4 816.3 819.8 857.8 962.2 21619.2 23392.3 23527.5 23615.8 23657.0 910.4 921.9 946.8 952.5 983.8 11228.3 11871.5 12214.6 12254.7 12267.3 729892.0 755960.0 774181.0 786473.0 800795.0 115573.4 124769.4 158283.0 168482.0 171423.0 1217.5 1244.4 1252.9 1269.7 1295.1 18975.1 20059.8 20815.3 21153.3 21201.8 56091.2 61054.4 62097.4 63410.7 64848.4 84168.6 84994.0 85533.0 86347.6 86636.0 11962.3 12040.6 12309.3 12345.3 12606.7 13810.2 13919.2 14234.8 14286.5 14308.1 11693.5 11775.5 12135.2 12151.4 12176.2 10533.8 11218.8 11294.0 11774.6 11812.5 11779.5 11841.2 12198.4 12202.5 12202.9 12273.3 12347.8 12403.6 12568.0 12620.1 11693.8 11702.9 11936.2 12124.3 12157.6 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6887.79 Median:2511.02 stddev:4297.97 (171.2%) stddev-sans-first:4291.18 12:24:29 INFO - PROCESS | 1637 | Values: 1530.8 1632.2 1636.1 1654.5 1676.1 10840.7 11603.4 11616.2 11621.7 11631.7 10956.3 11244.3 11277.9 11509.6 11520.2 2458.1 2502.1 2509.9 2510.3 2511.8 2073.7 2431.6 2470.4 2517.9 2519.6 2477.5 2488.0 2507.4 2519.2 2523.1 2384.5 2396.8 2398.6 2409.3 2444.7 2470.7 2484.5 2497.7 2498.1 2498.3 11206.8 11414.0 11421.5 11427.3 11429.0 5992.2 6148.4 6152.3 6154.5 6160.0 3666.4 3696.3 3776.2 3787.2 3866.5 1301.2 1930.5 1938.3 1967.6 2001.6 14037.4 14379.9 14775.2 15864.7 16041.9 4389.2 4433.8 4851.6 4870.9 4880.4 12053.5 12055.3 12070.6 12107.8 12129.2 5094.6 5240.9 5847.4 5964.9 6075.0 5456.5 5620.5 5624.2 5625.1 5685.7 3683.9 3976.0 4057.1 4062.2 4065.8 1051.1 1068.5 1075.8 1084.1 1084.4 821.2 824.7 853.9 867.2 887.0 6560.2 6645.3 6693.6 6710.7 6740.2 979.2 991.4 996.1 1034.7 1036.6 4139.4 4167.5 4188.4 4212.1 4332.8 13802.7 14324.7 14325.4 14329.7 14330.1 14218.4 14220.0 14255.6 14259.4 14267.8 8976.7 10193.1 10288.7 10553.7 10621.5 6168.5 6506.6 6633.6 6667.8 6670.5 12375.1 12734.2 12759.8 12792.5 12806.3 12715.4 12797.9 12905.5 12973.0 13000.9 8536.2 8566.0 8676.2 8689.4 8699.0 10327.8 10441.4 10470.5 10532.2 10598.4 8910.0 8976.9 9044.3 9057.4 9207.4 8739.4 8812.8 8838.9 8917.1 8961.2 6254.9 8041.7 8133.4 8144.8 8148.8 5885.1 5889.4 5897.3 6046.1 6117.6 5411.6 5923.1 6125.9 6143.0 6201.4 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4598.13 Median:4998.52 stddev:1280.18 (25.6%) stddev-sans-first:1243.89 12:24:29 INFO - PROCESS | 1637 | Values: 373.7 391.2 394.2 398.6 401.7 5372.3 5394.5 5400.4 5475.6 5543.7 4796.4 5058.8 5124.9 5167.5 5176.2 3661.7 3832.6 4132.8 4339.7 4361.3 3703.7 4146.3 4171.6 4296.1 4389.6 3421.8 4041.8 4070.9 4342.8 4425.6 4313.3 4346.4 4353.2 4377.3 4447.7 3447.9 3477.8 3701.4 4069.7 4117.4 4583.9 4670.4 5115.3 5217.9 5270.9 5039.2 5049.7 5071.6 5095.9 5277.9 4949.4 5180.0 5345.7 5595.2 5671.3 4421.8 4477.8 4518.2 4531.0 4596.5 5068.5 5587.9 5749.1 5803.0 5848.8 4459.4 4551.5 4565.8 4601.6 4612.0 4403.8 4690.6 4741.6 4749.5 4785.0 4137.4 4659.7 4785.1 4990.9 5044.4 4950.0 5582.6 5594.6 5600.6 5662.5 1359.5 1403.9 1405.3 1473.5 1544.9 1052.9 1110.6 1199.1 1213.6 1226.7 4195.5 4514.9 5008.9 5086.3 5193.0 5233.5 5483.0 5672.8 5701.9 5748.6 4169.5 4909.3 4990.6 5132.3 5155.5 2647.9 2744.5 2840.3 2927.5 2938.4 4864.3 5178.2 5274.8 5785.5 5906.8 4844.3 4884.5 5521.4 5808.2 5864.5 5545.5 5839.4 5850.5 5857.5 5858.6 4892.5 5216.8 5347.5 5415.7 5537.7 4661.2 5012.0 5046.8 5390.2 5610.0 4948.1 4968.0 5207.0 5469.3 6000.7 3923.5 4097.9 4155.4 4478.6 4576.8 5006.2 5359.8 5474.0 5676.6 5800.6 5143.8 5456.9 5550.3 5561.7 5596.7 5266.9 5404.8 5626.7 5632.3 5648.3 4916.3 5169.1 5282.0 5465.1 5540.5 4813.6 4910.8 5128.1 5393.8 5405.5 4797.9 4883.9 5386.4 5431.2 5443.4 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19862.86 Median:21936.56 stddev:27538.24 (125.5%) stddev-sans-first:27578.27 12:24:29 INFO - PROCESS | 1637 | Values: 794.7 812.4 838.6 888.3 893.1 15786.4 17458.5 17472.7 17480.3 17507.1 14419.9 16111.0 16291.5 16297.8 16332.8 988.7 1009.6 1029.5 1030.0 1030.8 1009.5 1011.7 1012.5 1012.7 1012.9 1050.6 1050.6 1051.6 1052.3 1052.9 1041.6 1042.6 1043.5 1043.7 1044.4 764.7 765.7 766.3 766.4 769.0 14755.1 15126.2 15366.5 15551.2 15566.9 12372.8 12563.2 12722.8 12849.9 12899.2 11255.9 11305.9 11441.3 11563.4 11843.0 3316.9 3737.2 3766.2 3810.3 3848.7 87606.0 87979.6 108946.0 110480.0 111957.0 9458.7 9655.5 9733.8 9829.7 10126.4 21669.3 21837.3 21930.1 21943.0 22632.3 8354.4 8406.8 8468.6 8545.1 8663.6 4509.4 4571.7 4649.4 4687.2 4714.1 3166.2 3592.3 3700.9 3705.8 3723.7 652.3 667.5 672.9 673.2 691.0 653.3 659.4 701.5 717.0 726.8 9665.0 9914.6 9995.9 10093.5 10100.3 633.6 652.1 676.9 695.7 712.5 4345.9 4924.8 4968.2 5344.6 5676.5 89821.0 93186.6 93612.0 96618.0 109451.0 60314.1 61844.7 65432.0 86582.6 87281.6 71975.6 77886.8 79110.8 81410.0 82791.0 9141.4 10052.4 10606.9 11391.0 11546.6 39788.4 49712.3 51685.7 52045.4 53010.7 64067.4 64766.7 65100.0 65844.0 66967.7 12854.2 13193.8 13276.6 13545.3 13549.1 15223.6 15459.0 15574.9 15590.2 15909.7 13933.9 14031.8 14116.9 14655.0 14827.0 14098.2 14305.6 14449.0 14525.9 14958.7 13879.5 13918.0 13929.7 14656.3 15028.3 9559.2 9844.6 10207.6 10271.2 10938.9 11301.6 11594.4 11683.4 11701.5 11717.0 12:24:29 INFO - PROCESS | 1637 | -------- Summary: end -------- 12:24:29 INFO - PROCESS | 1637 | 12:24:29 INFO - TEST-INFO | 1637: exit 0 12:24:29 INFO - TEST-OK | dromaeo_css | took 1119228ms 12:24:29 INFO - TEST-START | kraken 12:24:29 INFO - operating with platform_type : mac_ 12:24:29 INFO - Initialising browser for kraken test... 12:24:29 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpVayzqv/profile http://localhost:49226/getInfo.html) 12:24:31 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:24:31 INFO - PROCESS | 1647 | colorDepth:24 12:24:31 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:24:31 INFO - PROCESS | 1647 | __metrics 12:24:31 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" 12:24:31 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] 12:24:32 INFO - TEST-INFO | 1647: exit 0 12:24:32 INFO - Browser initialized. 12:24:32 INFO - Running cycle 1/1 for kraken test... 12:24:32 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpVayzqv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:44 INFO - PROCESS | 1649 | RSS: Main: 165240832 12:24:44 INFO - PROCESS | 1649 | 12:24:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 12:24:48 INFO - PROCESS | 1649 | RSS: Main: 216174592 12:24:48 INFO - PROCESS | 1649 | 12:24:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 12:24:51 INFO - PROCESS | 1649 | RSS: Main: 232480768 12:24:51 INFO - PROCESS | 1649 | 12:24:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 12:24:55 INFO - PROCESS | 1649 | RSS: Main: 330104832 12:24:55 INFO - PROCESS | 1649 | 12:24:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 12:24:57 INFO - PROCESS | 1649 | RSS: Main: 230031360 12:24:57 INFO - PROCESS | 1649 | 12:24:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:25:00 INFO - PROCESS | 1649 | RSS: Main: 231153664 12:25:00 INFO - PROCESS | 1649 | 12:25:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 12:25:04 INFO - PROCESS | 1649 | RSS: Main: 271867904 12:25:04 INFO - PROCESS | 1649 | 12:25:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 12:25:07 INFO - PROCESS | 1649 | RSS: Main: 273379328 12:25:07 INFO - PROCESS | 1649 | 12:25:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 12:25:11 INFO - PROCESS | 1649 | RSS: Main: 272781312 12:25:11 INFO - PROCESS | 1649 | 12:25:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:25:13 INFO - PROCESS | 1649 | RSS: Main: 246972416 12:25:13 INFO - PROCESS | 1649 | 12:25:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:25:15 INFO - PROCESS | 1649 | RSS: Main: 251891712 12:25:15 INFO - PROCESS | 1649 | 12:25:18 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:25:18 INFO - PROCESS | 1649 | RSS: Main: 256319488 12:25:18 INFO - PROCESS | 1649 | 12:25:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:25:22 INFO - PROCESS | 1649 | RSS: Main: 271241216 12:25:22 INFO - PROCESS | 1649 | 12:25:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:25:25 INFO - PROCESS | 1649 | RSS: Main: 264855552 12:25:25 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 12:25:27 INFO - PROCESS | 1649 | RSS: Main: 309108736 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | __start_tp_report 12:25:27 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 12:25:27 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 12:25:27 INFO - PROCESS | 1649 | |i|pagename|runs| 12:25:27 INFO - PROCESS | 1649 | |0;ai-astar;105;114;115;114;113;115;114;114;115;116 12:25:27 INFO - PROCESS | 1649 | |1;audio-beat-detection;140;108;108;144;111;109;109;113;147;118 12:25:27 INFO - PROCESS | 1649 | |2;audio-dft;183;186;183;190;220;184;182;184;184;180 12:25:27 INFO - PROCESS | 1649 | |3;audio-fft;82;59;60;91;63;61;72;83;99;82 12:25:27 INFO - PROCESS | 1649 | |4;audio-oscillator;93;69;87;68;70;69;81;68;69;100 12:25:27 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;87;87;87;87;88;88;87;86;87;86 12:25:27 INFO - PROCESS | 1649 | |6;imaging-darkroom;94;104;121;93;93;106;129;94;123;98 12:25:27 INFO - PROCESS | 1649 | |7;imaging-desaturate;83;87;85;83;85;83;84;86;85;83 12:25:27 INFO - PROCESS | 1649 | |8;json-parse-financial;63;61;62;60;61;63;62;62;61;62 12:25:27 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;56;64;65;61;59;57;57;57;61;84 12:25:27 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;81;81;85;82;78;81;91;99;83;88 12:25:27 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;136;134;134;132;124;123;137;149;178;152 12:25:27 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;160;160;161;159;161;160;160;159;160;160 12:25:27 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;64;64;66;68;77;84;85;81;63;63 12:25:27 INFO - PROCESS | 1649 | __end_tp_report 12:25:27 INFO - PROCESS | 1649 | __start_cc_report 12:25:27 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2089 12:25:27 INFO - PROCESS | 1649 | __end_cc_report 12:25:27 INFO - PROCESS | 1649 | __startTimestamp1496345127777__endTimestamp 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | ------- Summary: start ------- 12:25:27 INFO - PROCESS | 1649 | Number of tests: 14 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.50 stddev:3.10 (2.7%) stddev-sans-first:0.88 12:25:27 INFO - PROCESS | 1649 | Values: 105.0 114.0 115.0 114.0 113.0 115.0 114.0 114.0 115.0 116.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:115.50 stddev:16.21 (14.0%) stddev-sans-first:15.61 12:25:27 INFO - PROCESS | 1649 | Values: 140.0 108.0 108.0 144.0 111.0 109.0 109.0 113.0 147.0 118.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:187.60 Median:184.00 stddev:11.68 (6.3%) stddev-sans-first:12.27 12:25:27 INFO - PROCESS | 1649 | Values: 183.0 186.0 183.0 190.0 220.0 184.0 182.0 184.0 184.0 180.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:75.20 Median:82.00 stddev:14.23 (17.4%) stddev-sans-first:14.88 12:25:27 INFO - PROCESS | 1649 | Values: 82.0 59.0 60.0 91.0 63.0 61.0 72.0 83.0 99.0 82.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:69.50 stddev:12.03 (17.3%) stddev-sans-first:11.36 12:25:27 INFO - PROCESS | 1649 | Values: 93.0 69.0 87.0 68.0 70.0 69.0 81.0 68.0 69.0 100.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 12:25:27 INFO - PROCESS | 1649 | Values: 87.0 87.0 87.0 87.0 88.0 88.0 87.0 86.0 87.0 86.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:93.00 stddev:13.88 (14.9%) stddev-sans-first:14.09 12:25:27 INFO - PROCESS | 1649 | Values: 94.0 104.0 121.0 93.0 93.0 106.0 129.0 94.0 123.0 98.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:85.00 stddev:1.43 (1.7%) stddev-sans-first:1.42 12:25:27 INFO - PROCESS | 1649 | Values: 83.0 87.0 85.0 83.0 85.0 83.0 84.0 86.0 85.0 83.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:62.00 stddev:0.95 (1.5%) stddev-sans-first:0.88 12:25:27 INFO - PROCESS | 1649 | Values: 63.0 61.0 62.0 60.0 61.0 63.0 62.0 62.0 61.0 62.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:61.00 stddev:8.29 (13.6%) stddev-sans-first:8.50 12:25:27 INFO - PROCESS | 1649 | Values: 56.0 64.0 65.0 61.0 59.0 57.0 57.0 57.0 61.0 84.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:84.00 stddev:6.24 (7.4%) stddev-sans-first:6.46 12:25:27 INFO - PROCESS | 1649 | Values: 81.0 81.0 85.0 82.0 78.0 81.0 91.0 99.0 83.0 88.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:136.50 stddev:16.24 (11.9%) stddev-sans-first:17.17 12:25:27 INFO - PROCESS | 1649 | Values: 136.0 134.0 134.0 132.0 124.0 123.0 137.0 149.0 178.0 152.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 12:25:27 INFO - PROCESS | 1649 | Values: 160.0 160.0 161.0 159.0 161.0 160.0 160.0 159.0 160.0 160.0 12:25:27 INFO - PROCESS | 1649 | 12:25:27 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:72.50 stddev:9.18 (12.7%) stddev-sans-first:9.33 12:25:27 INFO - PROCESS | 1649 | Values: 64.0 64.0 66.0 68.0 77.0 84.0 85.0 81.0 63.0 63.0 12:25:27 INFO - PROCESS | 1649 | -------- Summary: end -------- 12:25:27 INFO - PROCESS | 1649 | 12:25:28 INFO - TEST-INFO | 1649: exit 0 12:25:28 INFO - TEST-OK | kraken | took 58811ms 12:25:28 INFO - SUITE-END | took 1178s 12:25:28 INFO - Completed test suite (00:19:38) 12:25:28 INFO - javascript benchmark 12:25:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7870.117892300967, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3573.9635505703345, 3580.820958952052, 3605.7295702822294, 3617.847380808418, 3623.2572322673836, 6192.876142477147, 6202.0, 6210.813675589734, 6216.254049514053, 6247.812565623033, 4525.841595544157, 6067.998780201261, 6210.161628180194, 6219.346968568303, 6228.1591985082005, 5498.1752737089555, 5507.889842203153, 5544.972275138619, 5570.303712035996, 5676.148577713335, 5426.104692725696, 5439.374471935718, 5488.121900495921, 5648.802291919763, 5666.206731057655, 5511.255980442649, 5563.304586926634, 5569.331680198382, 5585.1063829787245, 5585.776568937237, 5582.553395728332, 5584.6090773645865, 5586.804461843816, 5595.328560572757, 5623.353314368859, 5472.50747432729, 5546.251256080418, 5559.110542313251, 5564.054110801133, 5579.38626751057, 6079.878402431971, 6089.6041757286075, 6122.337166850092, 6246.219222597176, 6301.968490157566, 6446.548741588091, 6487.902681459782, 6552.8361790955605, 6565.048068030157, 6570.605763654196, 6546.18172728409, 6627.304133066007, 6682.966585167043, 6689.063531105649, 6700.229473610549, 6125.459783291287, 6370.426661600478, 6373.776917807854, 6385.904211436832, 6400.967995160041, 6582.868342633216, 6668.566543174678, 6699.631520266432, 6711.4966377520905, 6724.764633237911, 6430.260520040255, 6451.903221451682, 6454.612723236527, 6456.483481321483, 6470.029495575702, 6562.081308616797, 6598.307177746364, 6639.203295604559, 6650.866982660416, 6650.933490665128, 6043.667598282049, 6332.0, 6404.0, 6414.775482858077, 6516.0, 6311.684415779193, 6372.490200783926, 6374.362563743682, 6374.649394283359, 6522.739090436425, 5225.451327610622, 5226.294450249244, 5229.6600720953775, 5236.450172731885, 5258.789648414021, 4761.666683332202, 4797.352357431771, 4814.277858321209, 4817.807287708522, 4837.540433658797, 6156.07658851167, 6301.6219026858535, 6303.9684801575695, 6304.18045654062, 6315.842103947346, 6366.745330186741, 6389.520785941073, 6537.313582073909, 6659.0, 6689.063531105649, 5205.6356055075785, 6250.1874756281395, 6266.404691554295, 6282.748690052438, 6293.370662933761, 5758.203249442521, 5804.564657650693, 5808.448197421239, 5820.330661973842, 5864.501517371071, 6537.836554085996, 6568.573042752206, 6785.219699734396, 6830.692618832164, 6837.829054273683, 6502.0, 6544.509161812788, 6654.73381064752, 6656.167979002625, 6662.6335551545135, 6827.761028363983, 6831.146106736658, 6844.2129155147795, 6853.211880633791, 6854.9657251713425, 5409.0, 6482.092507048923, 6553.705083271359, 6579.769708060387, 6588.571742836701, 5876.882462350814, 5892.263467066617, 6422.967885160731, 6501.9674901625185, 6574.572652777554, 6436.485081193587, 6454.903176452263, 6464.547481676237, 6476.190476190476, 6478.70845811949, 6412.551121421456, 6433.260175079926, 6445.581037232565, 6451.548391612542, 6483.189601299838, 6206.627602343874, 6207.534434917489, 6215.098810672337, 6345.904811427925, 6362.936370636235, 6379.0, 6415.486761059197, 6421.165248517663, 6425.4217120457515, 6443.7422503101225, 6197.938020619736, 6259.311475737756, 6363.395477429637, 6380.553361264667, 6380.968095159495], "value": 6049.69956003456, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.1791126784483, 843.7173546861801, 908.0, 924.5562130177519, 926.9026752191015, 40542.0, 40964.77105686827, 44035.11929761403, 44111.779441102786, 44224.3366349505, 42164.94587635311, 42565.57434425655, 42643.3603495947, 42703.572964270345, 42772.358414623806, 8134.267915887569, 8283.917160828374, 9674.322797404167, 9848.015198480167, 9858.950705246463, 11910.46402911871, 12007.459664315127, 12101.394930253497, 12108.212966157216, 12116.939415302912, 12232.938835305811, 12286.508539658447, 12292.508299668003, 12302.384880755972, 12335.814962775565, 12189.20770149942, 12224.938875305656, 12233.816492752616, 12236.143469957107, 12278.631641050782, 11697.824532631932, 11744.588939387166, 11771.823422648667, 11780.234284771463, 11784.351860647661, 41548.1690366194, 41567.792161039004, 41593.1681366371, 41610.583894160976, 41659.37510937338, 4055.7972101395108, 4063.8374465021366, 4076.714629975903, 4092.242935056994, 4099.877003689894, 28808.42383152346, 29605.407891842042, 30819.383612327852, 31071.84464077688, 31173.0, 3300.47192449207, 3310.933781324384, 3319.5850518685165, 3441.0881116504147, 3452.2750222453315, 364637.0, 369716.0, 370016.0, 370108.0, 370233.0, 42131.368029479585, 42748.358774618406, 45422.318665220046, 53049.67375815636, 53300.66748331245, 53179.0, 54082.7295863526, 54096.72951635216, 54107.0, 54225.3732388029, 15608.219589020504, 15660.37358505647, 15676.764848527282, 15690.0, 15726.29231684577, 13097.869021309856, 13112.344382781013, 13123.409446574928, 13125.803112953314, 13148.342582870819, 12406.81389779154, 12416.0, 12425.254484730945, 12505.749885002431, 12528.686782830502, 5689.345725241567, 5756.539476841843, 5759.65442073477, 5771.682557459296, 5800.926828536734, 957.2916042128913, 962.6149540183842, 982.4645568165367, 983.5967253426061, 990.3265779270165, 28587.999420020198, 28597.57103643447, 29098.854505727337, 29319.120426387242, 29354.706452935625, 1049.574922156528, 1058.6030238660503, 1066.6586692258402, 1089.8801131875487, 1091.5633746501462, 13296.33795775528, 13459.730805383837, 14660.853391466162, 14783.926080369529, 15090.24548772557, 479218.0, 480155.0, 480694.0, 480851.0, 480984.0, 56362.154567681515, 56402.43597564054, 56443.0, 56498.15252771212, 56578.7171064142, 1430.27056201336, 1460.466929570699, 1486.0712054965863, 1496.2593516209547, 1526.023345059163, 26228.344291392867, 27368.0, 27887.44225115527, 27889.581656274768, 27946.0, 38894.0, 38970.0, 38983.805080974416, 39103.608963909996, 39205.607943921335, 445190.0, 445269.0, 446149.5385046108, 446484.0, 446536.0, 25027.249182523825, 25338.98644054291, 28328.85835570809, 28784.42431151407, 29765.66054527508, 37976.43035354527, 38027.80986095052, 38139.23721525499, 38718.225635487695, 38849.41725874058, 27502.44995100127, 28041.0, 28042.71957280401, 28130.578041329805, 29053.418931621673, 27806.582901256093, 27867.44265114727, 28083.578746318413, 28663.570046448902, 28862.278443038253, 31267.37465250637, 32082.679173208904, 32529.186770330933, 32542.67457325491, 32591.859284924914, 25802.741972580785, 25814.483710325312, 25848.09531666383, 27966.44067118687, 28564.0002599908, 22014.0, 22784.27926668507, 23011.0, 23184.0, 23276.301710948697], "value": 18977.178039956227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.03985932004684, 426.2668210677633, 427.6108741045647, 434.56543456543454, 452.55875521366664, 20148.395548133543, 21216.57566848662, 21232.681509777347, 22333.664995025047, 22463.550728985432, 19830.801691983077, 22913.656295155546, 23375.766242337573, 23619.64570531439, 24244.03023879047, 6799.966000170017, 6856.6571671416605, 7129.287071292855, 7137.10786151731, 7138.428925685933, 6726.529142959995, 6922.203946546136, 6925.238223795379, 6932.757353492629, 6943.132108486439, 6921.550099243558, 6928.688209030605, 7464.664090115931, 7763.844723105534, 7920.881186782203, 7660.693572257103, 7840.255175758294, 7866.842663146762, 7911.762647120596, 7919.089304729943, 7702.191269916634, 7703.1526532081425, 7703.3452156566755, 7715.845683086363, 7725.420593455513, 20817.271395501237, 27521.036763713375, 27732.584011239847, 27880.442391152064, 27988.300292492648, 2959.940801183986, 2996.235959830229, 3011.728944395015, 3037.817730936129, 3124.4844600640868, 15728.640703894393, 16387.344506219855, 16616.836821422505, 16647.583810404714, 17456.82543174565, 1992.621401933628, 2144.238795227703, 2150.290404166621, 2152.9892350538303, 2158.589867925089, 107972.4601376996, 115509.0, 124155.3792231042, 130298.99999999905, 166612.0, 17564.912175439298, 21066.157353705985, 21260.78739212588, 21301.68047479289, 21341.359759207247, 33361.332773344395, 34057.807976720695, 34151.82924085413, 34219.82890085533, 34374.31251374959, 10806.135509159405, 11025.83461248082, 11065.871601644563, 11066.833997490045, 11294.152938529478, 9281.535923203948, 9300.627974880928, 9302.488363140092, 9453.763655908657, 9504.714858554255, 8391.580420978926, 9215.677451289306, 9699.757506062404, 9726.75683107928, 9834.0, 3104.580881581003, 3928.744631598937, 3929.6070392961046, 3938.547067087264, 3945.704072194597, 723.4105036464176, 816.2612835383868, 819.7824883689606, 857.7855536115972, 962.20617990157, 21619.24332648381, 23392.298231052755, 23527.529449410915, 23615.7638423617, 23657.0, 910.3581974707815, 921.9492927888896, 946.8485042393322, 952.538019060755, 983.8229118758692, 11228.270162439416, 11871.46578403974, 12214.57248996281, 12254.693632659255, 12267.263964162179, 729892.0, 755960.0, 774181.0, 786473.0, 800795.0, 115573.4221328888, 124769.37615311865, 158283.0, 168482.0, 171423.0, 1217.4582310871576, 1244.3964677131764, 1252.943617537213, 1269.695273134441, 1295.1193188631821, 18975.14611842443, 20059.796412215313, 20815.271465498634, 21153.25963591267, 21201.787982120597, 56091.15863262136, 61054.38945610487, 62097.44756381126, 63410.682946586814, 64848.40606375692, 84168.57915710627, 84994.0, 85533.0, 86347.56826215828, 86636.0, 11962.282263064244, 12040.578579749666, 12309.322987235788, 12345.259284442962, 12606.684832879251, 13810.240436776074, 13919.164850109026, 14234.786478202628, 14286.499972500913, 14308.069975451563, 11693.532258709556, 11775.52897884075, 12135.150539462153, 12151.39243037827, 12176.208546444454, 10533.789324213627, 11218.775624487302, 11294.0, 11774.646760597196, 11812.527498899948, 11779.52881884715, 11841.171118021655, 12198.390080496118, 12202.450889709808, 12202.938985305016, 12273.324967126893, 12347.75304493923, 12403.62789116328, 12568.0, 12620.116591838483, 11693.76612467763, 11702.890610993001, 11936.164468487123, 12124.27254364741, 12157.574484892986], "value": 11943.298422889431, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.7524367640372, 1632.1512813337065, 1636.124673299785, 1654.4788391656634, 1676.0614056128566, 10840.67477975661, 11603.361815100177, 11616.24494407863, 11621.709457263561, 11631.6510504685, 10956.34261944284, 11244.325340479578, 11277.887221127787, 11509.597164099257, 11520.193586448931, 2458.0782206672498, 2502.0867383405107, 2509.8870550825163, 2510.259473455335, 2511.7739403453775, 2073.6889466579983, 2431.63525471179, 2470.4441500662297, 2517.8615176165304, 2519.6220566914926, 2477.5168842075805, 2487.987560062197, 2507.3731567108225, 2519.1812660885194, 2523.129520315488, 2384.523095380931, 2396.8442051266697, 2398.5802484565133, 2409.27721683496, 2444.7310795812446, 2470.654108424822, 2484.465839844442, 2497.687789026372, 2498.0632262901413, 2498.2879879234333, 11206.775864482746, 11414.0, 11421.486033128529, 11427.257228280136, 11429.02853257474, 5992.161097446361, 6148.354422785588, 6152.323244443108, 6154.5384096192965, 6160.0, 3666.358387282215, 3696.3346597612417, 3776.150366167618, 3787.223619158066, 3866.4780254665543, 1301.1932601786953, 1930.4787707318944, 1938.2731475696614, 1967.5572996075912, 2001.599680063993, 14037.438502459992, 14379.85620143806, 14775.187364694937, 15864.682706345808, 16041.919790400972, 4389.1660584488845, 4433.7561434121435, 4851.611871050308, 4870.878228044327, 4880.41435027799, 12053.457594408274, 12055.336956467303, 12070.637880863587, 12107.757844843054, 12129.211601246066, 5094.592432605383, 5240.9213861792105, 5847.386024467455, 5964.940350596526, 6075.027995520695, 5456.48163424474, 5620.494155526022, 5624.184493248538, 5625.128105143709, 5685.715714214272, 3683.9079023024638, 3975.9662487753394, 4057.107436363995, 4062.2281766464866, 4065.7560546367313, 1051.059301924773, 1068.540527573151, 1075.7579544602559, 1084.0568705226503, 1084.3764835219622, 821.1829229916272, 824.6783754335814, 853.9402241843011, 867.2454964181077, 887.0105655686762, 6560.212774467094, 6645.302243264484, 6693.631850248185, 6710.664466776641, 6740.224874139439, 979.2264111352094, 991.3704797453669, 996.1234113979651, 1034.6895931220604, 1036.6164519127972, 4139.441175441337, 4167.541570427251, 4188.371744238328, 4212.115455754265, 4332.826686932551, 13802.65493362674, 14324.713505729827, 14325.355359008661, 14329.713405731827, 14330.140191588538, 14218.360173792204, 14220.004599677923, 14255.572332830032, 14259.42962281497, 14267.785983210053, 8976.730698079067, 10193.133583645325, 10288.742781430525, 10553.736156596147, 10621.5220315086, 6168.537359697951, 6506.577072490273, 6633.568818026813, 6667.83330416724, 6670.533062685566, 12375.071869609996, 12734.235945843278, 12759.808602870778, 12792.488300468249, 12806.295653739135, 12715.427805748579, 12797.872021279669, 12905.548305809252, 12973.0, 13000.869991299965, 8536.189062039097, 8565.95717021411, 8676.175763302475, 8689.435186713097, 8699.0, 10327.793444131226, 10441.425721585385, 10470.476476176313, 10532.157427405942, 10598.364098154136, 8910.0, 8976.910230897607, 9044.321675874467, 9057.36598438103, 9207.355485115977, 8739.43193692408, 8812.779680508038, 8838.938651343718, 8917.063708310794, 8961.193492585699, 6254.874902502015, 8041.678332866853, 8133.430659853753, 8144.83710325802, 8148.837023259383, 5885.146653735099, 5889.411058894077, 5897.321807992136, 6046.123312119806, 6117.632942023492, 5411.565935027143, 5923.111533270044, 6125.938740612537, 6143.0, 6201.441870231701], "value": 5288.288828212136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.73091374210577, 391.24099247459924, 394.1742050404402, 398.57949862894634, 401.71879684221045, 5372.307723067231, 5394.460553944608, 5400.378956420013, 5475.616706830513, 5543.727071900522, 4796.3764710587575, 5058.772355244014, 5124.9487505124935, 5167.534921857032, 5176.171812509999, 3661.7253705972093, 3832.616738326173, 4132.772697501636, 4339.7396156230725, 4361.277295468288, 3703.703703703697, 4146.3158578834455, 4171.582841715819, 4296.066769953533, 4389.639211844338, 3421.7946923184563, 4041.818118184689, 4070.9389359159636, 4342.782860856961, 4425.601695847373, 4313.28830742927, 4346.391505189276, 4353.151135528573, 4377.25586650267, 4447.688661793676, 3447.8620855165764, 3477.7653932853727, 3701.407774756026, 4069.7354671946227, 4117.402976568381, 4583.9083218335445, 4670.416197975253, 5115.258287548322, 5217.947820521822, 5270.894582108374, 5039.218921067204, 5049.748261807716, 5071.619628527838, 5095.949040509585, 5277.894442111136, 4949.4060712714345, 5179.974100129475, 5345.679259244458, 5595.21666966625, 5671.291088613923, 4421.801018954155, 4477.820887164509, 4518.209313370147, 4531.0, 4596.471405788344, 5068.452083541536, 5587.8987162125, 5749.108888122306, 5802.97098514509, 5848.759649254299, 4459.435824400917, 4551.52209018055, 4565.840195593205, 4601.585857272861, 4612.008418190106, 4403.798100949525, 4690.577847993697, 4741.620670346364, 4749.501302363271, 4784.976075119602, 4137.358709400048, 4659.673822832437, 4785.138675038526, 4990.878876477483, 5044.4436672146785, 4950.009998000414, 5582.5764430070885, 5594.608377413623, 5600.5799565032785, 5662.5367964935385, 1359.4901911783081, 1403.8768984812314, 1405.3324670781296, 1473.5284708893266, 1544.8764098872064, 1052.85786418834, 1110.5724296146043, 1199.064729510983, 1213.5631172777794, 1226.7485165541043, 4195.475800327678, 4514.855443664299, 5008.949910500848, 5086.262491938648, 5193.0, 5233.450487698813, 5483.0, 5672.829815105553, 5701.885962280731, 5748.5688573357165, 4169.478140478764, 4909.3126962225315, 4990.629451010814, 5132.3327967364, 5155.484451554815, 2647.9338016549355, 2744.519709050908, 2840.2899275181203, 2927.502324604822, 2938.4279410515273, 4864.297495876485, 5178.197379406198, 5274.789008439696, 5785.537157027427, 5906.793262235887, 4844.273358996108, 4884.4613946606705, 5521.3926468088475, 5808.186853840466, 5864.475236438471, 5545.528630066489, 5839.386864379264, 5850.5027072699295, 5857.502112320501, 5858.564651660286, 4892.461829198714, 5216.843494695166, 5347.459956520622, 5415.702136382537, 5537.723113844372, 4661.20759470884, 5012.0, 5046.798128074836, 5390.245365648734, 5610.0, 4948.076663719622, 4968.0, 5207.0, 5469.316335458067, 6000.669963152069, 3923.489946306962, 4097.8975525612095, 4155.418241446139, 4478.596926276651, 4576.794044267949, 5006.165351225321, 5359.847632758975, 5474.029349434121, 5676.602637815313, 5800.622959507618, 5143.8456846294675, 5456.890862182813, 5550.333959924835, 5561.721913904369, 5596.720163991866, 5266.920996184984, 5404.810941592843, 5626.746796394254, 5632.2678051853, 5648.293963254593, 4916.336294600326, 5169.06956747789, 5281.989266118276, 5465.114535485411, 5540.45139235956, 4813.614910807057, 4910.790144434947, 5128.10260203727, 5393.757280922447, 5405.486478784509, 4797.856064318076, 4883.926741098958, 5386.437932999386, 5431.239626452379, 5443.401225865231], "value": 4224.348790066341, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.6622685358722, 812.3582369927761, 838.5555655502586, 888.3204348673264, 893.075666684981, 15786.368545258176, 17458.516026137793, 17472.737908931344, 17480.3007879685, 17507.12464376781, 14419.855801441983, 16111.033337999728, 16291.51125466235, 16297.83702162981, 16332.75500867488, 988.7231575158949, 1009.5608410341488, 1029.4698230411343, 1029.9691009269734, 1030.814453398388, 1009.4801177393648, 1011.6560369474378, 1012.4735137728377, 1012.6759436980169, 1012.9392236465799, 1050.6375300521347, 1050.6480329089763, 1051.610903965534, 1052.337027672569, 1052.899974502421, 1041.6041904076478, 1042.5569133118395, 1043.5304113149114, 1043.6660268714015, 1044.4046893270802, 764.6673696941792, 765.7434759355623, 766.2796970847386, 766.3830616353833, 769.0387016229713, 14755.11469311834, 15126.243687815675, 15366.462173823975, 15551.222438878123, 15566.9221653891, 12372.81440778389, 12563.18339307942, 12722.809157862639, 12849.871501285055, 12899.161554499022, 11255.943720281428, 11305.886941130648, 11441.272114323223, 11563.364014979174, 11842.993345565637, 3316.8673253069846, 3737.1820730802497, 3766.190269092131, 3810.2760475509563, 3848.7299191267093, 87606.0, 87979.56010219971, 108946.0, 110480.0, 111956.99999999919, 9458.668946586837, 9655.468949207861, 9733.805323893625, 9829.705108846745, 10126.430642834675, 21669.34991950244, 21837.344879653636, 21930.122795088017, 21943.0, 22632.321030369116, 8354.415190936697, 8406.831863362699, 8468.618912148844, 8545.102764209792, 8663.566821658891, 4509.436320459942, 4571.679982401267, 4649.372334734835, 4687.20317546018, 4714.127886341038, 3166.224275052627, 3592.263585964923, 3700.925981480409, 3705.7591256568235, 3723.702103831687, 652.2531701202097, 667.5460686733068, 672.8561445542597, 673.2157037051779, 690.975815846443, 653.3335997282745, 659.370301362198, 701.5019336271203, 716.992830071703, 726.8400951790597, 9665.0, 9914.55384507699, 9995.900040999642, 10093.495325233709, 10100.343477673927, 633.6071635585874, 652.1326635574675, 676.8696277217059, 695.6556504530189, 712.4798896805361, 4345.869623911288, 4924.804014419427, 4968.155413579628, 5344.625876188629, 5676.460736230051, 89821.0, 93186.60220096704, 93612.0, 96618.0, 109451.0, 60314.09528857071, 61844.69077654583, 65432.0, 86582.56708716416, 87281.56359218163, 71975.64012179905, 77886.83169752458, 79110.81333780113, 81410.0, 82791.0, 9141.360104792866, 10052.396856188652, 10606.946965265124, 11391.0, 11546.59586914454, 39788.403173953, 49712.25431618456, 51685.7415712919, 52045.438636840954, 53010.67473313198, 64067.35932640614, 64766.67616662075, 65100.0, 65844.0, 66967.66516167388, 12854.228746275254, 13193.802092968805, 13276.601701948957, 13545.322733863464, 13549.119307244997, 15223.619409514407, 15459.0, 15574.922125389301, 15590.220488975732, 15909.68180636404, 13933.860661393663, 14031.801717431452, 14116.929415352857, 14655.047421917541, 14826.96211265201, 14098.154110753389, 14305.57083287503, 14449.0, 14525.92737036308, 14958.700825983637, 13879.53146162735, 13918.0, 13929.651758705708, 14656.304023697094, 15028.323725432163, 9559.18746906493, 9844.556994935101, 10207.642732504326, 10271.150809267872, 10938.945305273422, 11301.604443844426, 11594.420278986187, 11683.429645477981, 11701.473433695333, 11717.0], "value": 7756.787956835934, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 115.0, 114.0, 113.0, 115.0, 114.0, 114.0, 115.0, 116.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 108.0, 144.0, 111.0, 109.0, 109.0, 113.0, 147.0, 118.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 183.0, 190.0, 220.0, 184.0, 182.0, 184.0, 184.0, 180.0], "value": 187.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 60.0, 91.0, 63.0, 61.0, 72.0, 83.0, 99.0, 82.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 69.0, 87.0, 68.0, 70.0, 69.0, 81.0, 68.0, 69.0, 100.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 87.0, 88.0, 88.0, 87.0, 86.0, 87.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 104.0, 121.0, 93.0, 93.0, 106.0, 129.0, 94.0, 123.0, 98.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 85.0, 83.0, 85.0, 83.0, 84.0, 86.0, 85.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 60.0, 61.0, 63.0, 62.0, 62.0, 61.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 65.0, 61.0, 59.0, 57.0, 57.0, 57.0, 61.0, 84.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 85.0, 82.0, 78.0, 81.0, 91.0, 99.0, 83.0, 88.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 134.0, 132.0, 124.0, 123.0, 137.0, 149.0, 178.0, 152.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 159.0, 161.0, 160.0, 160.0, 159.0, 160.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 68.0, 77.0, 84.0, 85.0, 81.0, 63.0, 63.0], "value": 71.5, "unit": "score"}]}]} 12:25:29 INFO - Return code: 0 12:25:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:25:29 INFO - # TBPL SUCCESS # 12:25:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:25:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:25:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:25:29 INFO - 2017-06-01 12:25:29.099 screenresolution[1653:9710] starting screenresolution argv=screenresolution get 12:25:29 INFO - 2017-06-01 12:25:29.102 screenresolution[1653:9710] Display 0: 1600x1200x32@60 12:25:29 INFO - 2017-06-01 12:25:29.108 screenresolution[1654:9714] starting screenresolution argv=screenresolution list 12:25:29 INFO - Available Modes on Display 0 12:25:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:25:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:25:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:25:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:25:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:25:29 INFO - Intel Iris: 12:25:29 INFO - Chipset Model: Intel Iris 12:25:29 INFO - Type: GPU 12:25:29 INFO - Bus: Built-In 12:25:29 INFO - VRAM (Dynamic, Max): 1536 MB 12:25:29 INFO - Vendor: Intel (0x8086) 12:25:29 INFO - Device ID: 0x0a2e 12:25:29 INFO - Revision ID: 0x0009 12:25:29 INFO - Displays: 12:25:29 INFO - Display: 12:25:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:25:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:25:29 INFO - Display Serial Number: 1600x1200 60 12:25:29 INFO - Main Display: Yes 12:25:29 INFO - Mirror: Off 12:25:29 INFO - Online: Yes 12:25:29 INFO - Rotation: Supported 12:25:29 INFO - Return code: 0 12:25:29 INFO - Running post-action listener: _resource_record_post_action 12:25:29 INFO - [mozharness: 2017-06-01 19:25:29.344782Z] Finished run-tests step (success) 12:25:29 INFO - Running post-run listener: _resource_record_post_run 12:25:29 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 39326720; Write bytes: 423573504; Read time: 382; Write time: 1570 12:25:29 INFO - TinderboxPrint: CPU usage
53.8% 12:25:29 INFO - TinderboxPrint: I/O read bytes / time
39,326,720 / 382 12:25:29 INFO - TinderboxPrint: I/O write bytes / time
423,573,504 / 1,570 12:25:29 INFO - TinderboxPrint: CPU idle
2,208.7 (46.2%) 12:25:29 INFO - TinderboxPrint: CPU system
951.8 (19.9%) 12:25:29 INFO - TinderboxPrint: CPU user
1,617.1 (33.8%) 12:25:29 INFO - TinderboxPrint: Swap in / out
161,468,416 / 0 12:25:29 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121891328; Write bytes: 114184192; Read time: 11033; Write time: 222 12:25:29 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34501632; Write bytes: 299169792; Read time: 348; Write time: 1314 12:25:29 INFO - Running post-run listener: _upload_blobber_files 12:25:29 INFO - Blob upload gear active. 12:25:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:25:29 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 12:25:29 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'] 12:25:29 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 12:25:30 INFO - (blobuploader) - INFO - Open directory for files ... 12:25:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:25:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:25:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:31 INFO - (blobuploader) - INFO - Done attempting. 12:25:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:25:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:25:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:33 INFO - (blobuploader) - INFO - Done attempting. 12:25:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:25:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:25:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:33 INFO - (blobuploader) - INFO - Done attempting. 12:25:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:25:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:25:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:36 INFO - (blobuploader) - INFO - Done attempting. 12:25:36 INFO - (blobuploader) - INFO - Iteration through files over. 12:25:36 INFO - Return code: 0 12:25:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:25:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:25:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd0de25cc381306d54f031d42f88151eb3cbff73c05e27fd7a3363efb82c8c56c7cea4cabf8d5fc9405cfe77199b3df0985d01792cd49ad988896f4ba942d36d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7ba27de6ec5ad08d2b7112384e6a1680d8a36bdd085cb57068bf9e63a1404b163d985697efeaa0d3b139d49c8a571df59f1b1db157fc0d6e86fcfc77d74bc4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6004eecc2c516cfd3a5fe4e3799def76b546911c68a309e6fef83fd116478872220391261a4f8db9441ca5d6c9aa023fe82ff6f0a9f28221e72aa9117e8e8113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f9ada975b1324ac75a28cb2549a031c0c9564cde92360531df4575ee0a55f136a432746900a6a5f9e04bfd85797fb072fe9d197f59da7cab0e2c8607705b793"} 12:25:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:25:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:25:36 INFO - Contents: 12:25:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd0de25cc381306d54f031d42f88151eb3cbff73c05e27fd7a3363efb82c8c56c7cea4cabf8d5fc9405cfe77199b3df0985d01792cd49ad988896f4ba942d36d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7ba27de6ec5ad08d2b7112384e6a1680d8a36bdd085cb57068bf9e63a1404b163d985697efeaa0d3b139d49c8a571df59f1b1db157fc0d6e86fcfc77d74bc4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6004eecc2c516cfd3a5fe4e3799def76b546911c68a309e6fef83fd116478872220391261a4f8db9441ca5d6c9aa023fe82ff6f0a9f28221e72aa9117e8e8113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f9ada975b1324ac75a28cb2549a031c0c9564cde92360531df4575ee0a55f136a432746900a6a5f9e04bfd85797fb072fe9d197f59da7cab0e2c8607705b793"} 12:25:36 INFO - Running post-run listener: copy_logs_to_upload_dir 12:25:36 INFO - Copying logs to upload dir... 12:25:36 INFO - mkdir: /builds/slave/test/build/upload/logs 12:25:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.827952 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-06-01 12:25:36.117096) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 12:25:36.120261) ========= 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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/dd0de25cc381306d54f031d42f88151eb3cbff73c05e27fd7a3363efb82c8c56c7cea4cabf8d5fc9405cfe77199b3df0985d01792cd49ad988896f4ba942d36d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7ba27de6ec5ad08d2b7112384e6a1680d8a36bdd085cb57068bf9e63a1404b163d985697efeaa0d3b139d49c8a571df59f1b1db157fc0d6e86fcfc77d74bc4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6004eecc2c516cfd3a5fe4e3799def76b546911c68a309e6fef83fd116478872220391261a4f8db9441ca5d6c9aa023fe82ff6f0a9f28221e72aa9117e8e8113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f9ada975b1324ac75a28cb2549a031c0c9564cde92360531df4575ee0a55f136a432746900a6a5f9e04bfd85797fb072fe9d197f59da7cab0e2c8607705b793"} build_url:https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011157 build_url: 'https://queue.taskcluster.net/v1/task/CJ19Hit7QvaiuvtVsiBdGw/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/dd0de25cc381306d54f031d42f88151eb3cbff73c05e27fd7a3363efb82c8c56c7cea4cabf8d5fc9405cfe77199b3df0985d01792cd49ad988896f4ba942d36d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7ba27de6ec5ad08d2b7112384e6a1680d8a36bdd085cb57068bf9e63a1404b163d985697efeaa0d3b139d49c8a571df59f1b1db157fc0d6e86fcfc77d74bc4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6004eecc2c516cfd3a5fe4e3799def76b546911c68a309e6fef83fd116478872220391261a4f8db9441ca5d6c9aa023fe82ff6f0a9f28221e72aa9117e8e8113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f9ada975b1324ac75a28cb2549a031c0c9564cde92360531df4575ee0a55f136a432746900a6a5f9e04bfd85797fb072fe9d197f59da7cab0e2c8607705b793"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 12:25:36.157070) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:25:36.157705) ========= 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.7FZmJQsXIF/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.h7xZ6k5Sus/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.004718 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:25:36.205692) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 12:25:36.206012) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 12:25:36.206322) ========= ========= Total master_lag: 0.25 =========