builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1494609323.16 results: success (0) buildid: 20170512081421 builduid: 1375b0e5c7244d378871441f640cadfc revision: bc096a7e31e236620e40d1dc94e0f4b4af63f75c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.161172) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.161584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.161879) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.189726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.190084) ========= 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.8CqXne6aPG/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.MDNlvzyFc0/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-12 10:15:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-05-12 10:15:23 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.364139) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.364469) ========= 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.8CqXne6aPG/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.MDNlvzyFc0/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.029942 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:23.415006) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-12 10:15:23.415510) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bc096a7e31e236620e40d1dc94e0f4b4af63f75c --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 bc096a7e31e236620e40d1dc94e0f4b4af63f75c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8CqXne6aPG/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.MDNlvzyFc0/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-12 10:15:23,521 truncating revision to first 12 chars 2017-05-12 10:15:23,522 Setting DEBUG logging. 2017-05-12 10:15:23,522 attempt 1/10 2017-05-12 10:15:23,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bc096a7e31e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 10:15:25,265 unpacking tar archive at: mozilla-beta-bc096a7e31e2/testing/mozharness/ program finished with exit code 0 elapsedTime=2.018503 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-12 10:15:25.460183) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:25.460589) ========= script_repo_revision: bc096a7e31e236620e40d1dc94e0f4b4af63f75c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:25.461004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:25.461307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 10:15:25.480326) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-12 10:15:25.480703) ========= /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.8CqXne6aPG/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.MDNlvzyFc0/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:15:25 INFO - MultiFileLogger online at 20170512 10:15:25 in /builds/slave/test 10:15:25 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 10:15:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:15:25 INFO - {'append_to_log': False, 10:15:25 INFO - 'base_work_dir': '/builds/slave/test', 10:15:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:15:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:15:25 INFO - 'branch': 'Mozilla-Beta', 10:15:25 INFO - 'buildbot_json_path': 'buildprops.json', 10:15:25 INFO - 'config_files': ('talos/mac_config.py',), 10:15:25 INFO - 'default_actions': ('clobber', 10:15:25 INFO - 'read-buildbot-config', 10:15:25 INFO - 'download-and-extract', 10:15:25 INFO - 'populate-webroot', 10:15:25 INFO - 'create-virtualenv', 10:15:25 INFO - 'install', 10:15:25 INFO - 'run-tests'), 10:15:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:15:25 INFO - 'download_minidump_stackwalk': True, 10:15:25 INFO - 'download_symbols': 'ondemand', 10:15:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:15:25 INFO - 'tooltool.py': '/tools/tooltool.py', 10:15:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:15:25 INFO - '/tools/misc-python/virtualenv.py')}, 10:15:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:15:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:15:25 INFO - 'gecko_profile': False, 10:15:25 INFO - 'gecko_profile_interval': 0, 10:15:25 INFO - 'installer_path': 'installer.exe', 10:15:25 INFO - 'log_level': 'info', 10:15:25 INFO - 'log_name': 'talos', 10:15:25 INFO - 'log_to_console': True, 10:15:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:15:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:15:25 INFO - 'opt_config_files': (), 10:15:25 INFO - 'pip_index': False, 10:15:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:15:25 INFO - 'cmd': ('bash', 10:15:25 INFO - '-c', 10:15:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:15:25 INFO - 'enabled': True, 10:15:25 INFO - 'halt_on_failure': False, 10:15:25 INFO - 'name': 'check_screen_resolution'},), 10:15:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:15:25 INFO - 'cmd': ('bash', 10:15:25 INFO - '-c', 10:15:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:15:25 INFO - 'enabled': True, 10:15:25 INFO - 'halt_on_failure': False, 10:15:25 INFO - 'name': 'check_screen_resolution'},), 10:15:25 INFO - 'run_cmd_checks_enabled': True, 10:15:25 INFO - 'suite': 'dromaeojs', 10:15:25 INFO - 'system_bits': '32', 10:15:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:15:25 INFO - 'title': 't-yosemite-r7-0180', 10:15:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:15:25 INFO - 'use_talos_json': True, 10:15:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:15:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:15:25 INFO - 'work_dir': 'build'} 10:15:25 INFO - [mozharness: 2017-05-12 17:15:25.700630Z] Running clobber step. 10:15:25 INFO - Running pre-action listener: _resource_record_pre_action 10:15:25 INFO - Running main action method: clobber 10:15:25 INFO - rmtree: /builds/slave/test/build 10:15:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:15:28 INFO - Running post-action listener: _resource_record_post_action 10:15:28 INFO - [mozharness: 2017-05-12 17:15:28.196248Z] Finished clobber step (success) 10:15:28 INFO - [mozharness: 2017-05-12 17:15:28.196442Z] Running read-buildbot-config step. 10:15:28 INFO - Running pre-action listener: _resource_record_pre_action 10:15:28 INFO - Running main action method: read_buildbot_config 10:15:28 INFO - Using buildbot properties: 10:15:28 INFO - { 10:15:28 INFO - "project": "", 10:15:28 INFO - "product": "firefox", 10:15:28 INFO - "script_repo_revision": "production", 10:15:28 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 10:15:28 INFO - "repository": "", 10:15:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 10:15:28 INFO - "buildid": "20170512081421", 10:15:28 INFO - "pgo_build": "False", 10:15:28 INFO - "basedir": "/builds/slave/test", 10:15:28 INFO - "buildnumber": 25, 10:15:28 INFO - "slavename": "t-yosemite-r7-0180", 10:15:28 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:15:28 INFO - "platform": "yosemite_r7", 10:15:28 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:15:28 INFO - "branch": "mozilla-beta", 10:15:28 INFO - "repo_path": "releases/mozilla-beta", 10:15:28 INFO - "revision": "bc096a7e31e236620e40d1dc94e0f4b4af63f75c", 10:15:28 INFO - "stage_platform": "macosx64", 10:15:28 INFO - "builduid": "1375b0e5c7244d378871441f640cadfc", 10:15:28 INFO - "slavebuilddir": "test" 10:15:28 INFO - } 10:15:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:15:28 INFO - Running post-action listener: _resource_record_post_action 10:15:28 INFO - [mozharness: 2017-05-12 17:15:28.202316Z] Finished read-buildbot-config step (success) 10:15:28 INFO - [mozharness: 2017-05-12 17:15:28.202532Z] Running download-and-extract step. 10:15:28 INFO - Running pre-action listener: _resource_record_pre_action 10:15:28 INFO - Running main action method: download_and_extract 10:15:28 INFO - mkdir: /builds/slave/test/build/tests 10:15: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')]} 10:15:28 INFO - trying https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:15:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/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 10:15:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/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 10:15:29 INFO - Downloaded 1287 bytes. 10:15:29 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:15:29 INFO - Using the following test package requirements: 10:15:29 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:15:29 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:15:29 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:15:29 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:15:29 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:15:29 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:15:29 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:15:29 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:15:29 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:15:29 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:15:29 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:15:29 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:15:29 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:15:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:15:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:15:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:15:31 INFO - Expected file size: 51227184 10:15:31 INFO - Obtained file size: 51227184 10:15: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 10:15:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:15:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:15:47 INFO - Fetch https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:15:48 INFO - Expected file size: 51227184 10:15:48 INFO - Obtained file size: 51227184 10:16:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:16:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:16:04 INFO - Fetch https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:16:05 INFO - Expected file size: 14404449 10:16:05 INFO - Obtained file size: 14404449 10:16:05 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')]} 10:16:05 INFO - trying https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:16:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:16:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/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 10:16:07 INFO - Downloaded 59561847 bytes. 10:16:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:16:07 INFO - mkdir: /builds/slave/test/properties 10:16:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:16:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:16:07 INFO - Contents: 10:16:07 INFO - build_url:https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:16:08 INFO - Running post-action listener: _resource_record_post_action 10:16:08 INFO - Running post-action listener: set_extra_try_arguments 10:16:08 INFO - [mozharness: 2017-05-12 17:16:08.081653Z] Finished download-and-extract step (success) 10:16:08 INFO - [mozharness: 2017-05-12 17:16:08.081796Z] Running populate-webroot step. 10:16:08 INFO - Running pre-action listener: _resource_record_pre_action 10:16:08 INFO - Running main action method: populate_webroot 10:16:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:16:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:16:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:16:08 INFO - '--tppagecycles', 10:16:08 INFO - '7'], 10:16:08 INFO - 'tests': ['tsvgm']}}, 10:16:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:16:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:16:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:16:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:16:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:16:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:16:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['damp', 'tps']}, 10:16:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'tests': ['damp', 'tps']}, 10:16:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['dromaeo_dom']}, 10:16:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:16:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['basic_compositor_video']}, 10:16:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:16:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['a11yr', 10:16:08 INFO - 'ts_paint', 10:16:08 INFO - 'tpaint', 10:16:08 INFO - 'sessionrestore', 10:16:08 INFO - 'sessionrestore_no_auto_restore', 10:16:08 INFO - 'tabpaint']}, 10:16:08 INFO - 'other-e10s': {'tests': ['a11yr', 10:16:08 INFO - 'ts_paint', 10:16:08 INFO - 'tpaint', 10:16:08 INFO - 'sessionrestore', 10:16:08 INFO - 'sessionrestore_no_auto_restore', 10:16:08 INFO - 'tabpaint']}, 10:16:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:16:08 INFO - 'ts_paint', 10:16:08 INFO - 'tpaint', 10:16:08 INFO - 'sessionrestore', 10:16:08 INFO - 'sessionrestore_no_auto_restore', 10:16:08 INFO - 'tabpaint']}, 10:16:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:16:08 INFO - 'ts_paint', 10:16:08 INFO - 'tpaint', 10:16:08 INFO - 'sessionrestore', 10:16:08 INFO - 'sessionrestore_no_auto_restore', 10:16:08 INFO - 'tabpaint']}, 10:16:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['a11yr', 10:16:08 INFO - 'ts_paint', 10:16:08 INFO - 'tpaint', 10:16:08 INFO - 'sessionrestore', 10:16:08 INFO - 'sessionrestore_no_auto_restore', 10:16:08 INFO - 'tabpaint']}, 10:16:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['a11yr', 10:16:08 INFO - 'ts_paint', 10:16:08 INFO - 'tpaint', 10:16:08 INFO - 'sessionrestore', 10:16:08 INFO - 'sessionrestore_no_auto_restore', 10:16:08 INFO - 'tabpaint']}, 10:16:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['tsvgx', 10:16:08 INFO - 'tsvgr_opacity', 10:16:08 INFO - 'tart', 10:16:08 INFO - 'tscrollx', 10:16:08 INFO - 'cart', 10:16:08 INFO - 'tsvg_static']}, 10:16:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:16:08 INFO - 'tsvgr_opacity', 10:16:08 INFO - 'tart', 10:16:08 INFO - 'tscrollx', 10:16:08 INFO - 'cart', 10:16:08 INFO - 'tsvg_static']}, 10:16:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'talos_options': ['--disable-e10s'], 10:16:08 INFO - 'tests': ['tp5o']}, 10:16:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:16:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'talos_options': ['--disable-e10s', 10:16:08 INFO - '--xperf_path', 10:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:16:08 INFO - 'tests': ['tp5n']}, 10:16:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:16:08 INFO - 'talos_options': ['--xperf_path', 10:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:16:08 INFO - 'tests': ['tp5n']}}, 10:16:08 INFO - 'talos.zip': {'path': '', 10:16:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:16:08 INFO - Running post-action listener: _resource_record_post_action 10:16:08 INFO - [mozharness: 2017-05-12 17:16:08.100510Z] Finished populate-webroot step (success) 10:16:08 INFO - [mozharness: 2017-05-12 17:16:08.100644Z] Running create-virtualenv step. 10:16:08 INFO - Running pre-action listener: _resource_record_pre_action 10:16:08 INFO - Running main action method: create_virtualenv 10:16:08 INFO - Creating virtualenv /builds/slave/test/build/venv 10:16: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 10:16:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:16:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:16:08 INFO - Using real prefix '/tools/python27' 10:16:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:16:08 INFO - Installing distribute.............................................................................................................................................................................................done. 10:16:11 INFO - Installing pip.................done. 10:16:11 INFO - Return code: 0 10:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:16:12 INFO - Reading from file tmpfile_stdout 10:16:12 INFO - Output received: 10:16: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) 10:16:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16: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')]} 10:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16: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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16: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 10:16: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 10:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:12 INFO - 'HOME': '/Users/cltbld', 10:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:12 INFO - 'LOGNAME': 'cltbld', 10:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:12 INFO - 'MOZ_NO_REMOTE': '1', 10:16:12 INFO - 'NO_EM_RESTART': '1', 10:16:12 INFO - 'PAGER': '/bin/cat', 10:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:12 INFO - 'PWD': '/builds/slave/test', 10:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:12 INFO - 'SHELL': '/bin/bash', 10:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:12 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:12 INFO - 'USER': 'cltbld', 10:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:12 INFO - 'XPC_FLAGS': '0x0', 10:16:12 INFO - 'XPC_SERVICE_NAME': '0', 10:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:12 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 10:16:12 INFO - Cleaning up... 10:16:12 INFO - Return code: 0 10:16:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16: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')]} 10:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16: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', '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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:16: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 psutil>=3.1.1 10:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:12 INFO - 'HOME': '/Users/cltbld', 10:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:12 INFO - 'LOGNAME': 'cltbld', 10:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:12 INFO - 'MOZ_NO_REMOTE': '1', 10:16:12 INFO - 'NO_EM_RESTART': '1', 10:16:12 INFO - 'PAGER': '/bin/cat', 10:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:12 INFO - 'PWD': '/builds/slave/test', 10:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:12 INFO - 'SHELL': '/bin/bash', 10:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:12 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:12 INFO - 'USER': 'cltbld', 10:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:12 INFO - 'XPC_FLAGS': '0x0', 10:16:12 INFO - 'XPC_SERVICE_NAME': '0', 10:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:12 INFO - Downloading/unpacking psutil>=3.1.1 10:16:12 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 10:16:12 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 10:16:12 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 10:16:12 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 10:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:16:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:14 INFO - Installing collected packages: psutil 10:16:14 INFO - Running setup.py install for psutil 10:16:14 INFO - building 'psutil._psutil_osx' extension 10:16:14 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 10:16: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:16: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:16:15 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 10:16:15 INFO - building 'psutil._psutil_posix' extension 10:16:15 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 10:16:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:16:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:16:15 INFO - ^ 10:16:15 INFO - 1 warning generated. 10:16:15 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 10:16:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:15 INFO - Successfully installed psutil 10:16:15 INFO - Cleaning up... 10:16:15 INFO - Return code: 0 10:16:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:16:15 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')]} 10:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:15 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')]} 10:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:15 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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16:15 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 10:16:15 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 10:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:15 INFO - 'HOME': '/Users/cltbld', 10:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:15 INFO - 'LOGNAME': 'cltbld', 10:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:15 INFO - 'MOZ_NO_REMOTE': '1', 10:16:15 INFO - 'NO_EM_RESTART': '1', 10:16:15 INFO - 'PAGER': '/bin/cat', 10:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:15 INFO - 'PWD': '/builds/slave/test', 10:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:15 INFO - 'SHELL': '/bin/bash', 10:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:15 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:15 INFO - 'USER': 'cltbld', 10:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:15 INFO - 'XPC_FLAGS': '0x0', 10:16:15 INFO - 'XPC_SERVICE_NAME': '0', 10:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:16: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 10:16: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 10:16: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 10:16: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 10:16:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:16:17 INFO - Installing collected packages: mozsystemmonitor 10:16:17 INFO - Running setup.py install for mozsystemmonitor 10:16:17 INFO - Successfully installed mozsystemmonitor 10:16:17 INFO - Cleaning up... 10:16:17 INFO - Return code: 0 10:16:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:16:17 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')]} 10:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16: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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16: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 10:16: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 10:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:18 INFO - 'HOME': '/Users/cltbld', 10:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:18 INFO - 'LOGNAME': 'cltbld', 10:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:18 INFO - 'MOZ_NO_REMOTE': '1', 10:16:18 INFO - 'NO_EM_RESTART': '1', 10:16:18 INFO - 'PAGER': '/bin/cat', 10:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:18 INFO - 'PWD': '/builds/slave/test', 10:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:18 INFO - 'SHELL': '/bin/bash', 10:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:18 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:18 INFO - 'USER': 'cltbld', 10:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:18 INFO - 'XPC_FLAGS': '0x0', 10:16:18 INFO - 'XPC_SERVICE_NAME': '0', 10:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:18 INFO - Downloading/unpacking jsonschema==2.5.1 10:16: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 10:16: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 10:16: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 10:16: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 10:16:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:16:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:16: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 10:16: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 10:16: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 10:16: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 10:16:20 INFO - Downloading functools32-3.2.3-2.tar.gz 10:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:16:20 INFO - warning: no files found matching '*.txt' 10:16:20 INFO - no previously-included directories found matching 'build' 10:16:20 INFO - no previously-included directories found matching 'dist' 10:16:20 INFO - no previously-included directories found matching '.git*' 10:16:20 INFO - Installing collected packages: jsonschema, functools32 10:16:20 INFO - Running setup.py install for functools32 10:16:21 INFO - warning: no files found matching '*.txt' 10:16:21 INFO - no previously-included directories found matching 'build' 10:16:21 INFO - no previously-included directories found matching 'dist' 10:16:21 INFO - no previously-included directories found matching '.git*' 10:16:21 INFO - Successfully installed jsonschema functools32 10:16:21 INFO - Cleaning up... 10:16:21 INFO - Return code: 0 10:16:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16: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')]} 10:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16: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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16: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 10:16: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 10:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:21 INFO - 'HOME': '/Users/cltbld', 10:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:21 INFO - 'LOGNAME': 'cltbld', 10:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:21 INFO - 'MOZ_NO_REMOTE': '1', 10:16:21 INFO - 'NO_EM_RESTART': '1', 10:16:21 INFO - 'PAGER': '/bin/cat', 10:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:21 INFO - 'PWD': '/builds/slave/test', 10:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:21 INFO - 'SHELL': '/bin/bash', 10:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:21 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:21 INFO - 'USER': 'cltbld', 10:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:21 INFO - 'XPC_FLAGS': '0x0', 10:16:21 INFO - 'XPC_SERVICE_NAME': '0', 10:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:16:21 INFO - Cleaning up... 10:16:21 INFO - Return code: 0 10:16:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16: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')]} 10:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16: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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16: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 10:16: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 10:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:21 INFO - 'HOME': '/Users/cltbld', 10:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:21 INFO - 'LOGNAME': 'cltbld', 10:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:21 INFO - 'MOZ_NO_REMOTE': '1', 10:16:21 INFO - 'NO_EM_RESTART': '1', 10:16:21 INFO - 'PAGER': '/bin/cat', 10:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:21 INFO - 'PWD': '/builds/slave/test', 10:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:21 INFO - 'SHELL': '/bin/bash', 10:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:21 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:21 INFO - 'USER': 'cltbld', 10:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:21 INFO - 'XPC_FLAGS': '0x0', 10:16:21 INFO - 'XPC_SERVICE_NAME': '0', 10:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:21 INFO - Downloading/unpacking blobuploader==1.2.4 10:16:21 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 10:16:21 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 10:16:21 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 10:16:21 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 10:16:23 INFO - Downloading blobuploader-1.2.4.tar.gz 10:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:16:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:16: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 10:16: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 10:16: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 10:16: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 10:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:16:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:16: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 10:16: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 10:16: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 10:16: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 10:16:23 INFO - Downloading docopt-0.6.1.tar.gz 10:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:16:24 INFO - Installing collected packages: blobuploader, requests, docopt 10:16:24 INFO - Running setup.py install for blobuploader 10:16:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:16:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:16:24 INFO - Running setup.py install for requests 10:16:24 INFO - Running setup.py install for docopt 10:16:24 INFO - Successfully installed blobuploader requests docopt 10:16:24 INFO - Cleaning up... 10:16:24 INFO - Return code: 0 10:16:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:24 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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16:24 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 10:16:24 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 10:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:24 INFO - 'HOME': '/Users/cltbld', 10:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:24 INFO - 'LOGNAME': 'cltbld', 10:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:24 INFO - 'MOZ_NO_REMOTE': '1', 10:16:24 INFO - 'NO_EM_RESTART': '1', 10:16:24 INFO - 'PAGER': '/bin/cat', 10:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:24 INFO - 'PWD': '/builds/slave/test', 10:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:24 INFO - 'SHELL': '/bin/bash', 10:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:24 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:24 INFO - 'USER': 'cltbld', 10:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:24 INFO - 'XPC_FLAGS': '0x0', 10:16:24 INFO - 'XPC_SERVICE_NAME': '0', 10:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-wcLW39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-m5Lbbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-liKksc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-mB5yj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-kYgDIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-21J7SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-8ApApR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:16:25 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-s4ROSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ZEY6NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-k2Sk8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-F2H5v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hW5XQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-fCY8Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-emjtpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ww30NS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-SoW9vF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:16:26 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-HNyybE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:16:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:16:27 INFO - Running setup.py install for manifestparser 10:16:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:16:27 INFO - Running setup.py install for mozcrash 10:16:27 INFO - Running setup.py install for mozdebug 10:16:27 INFO - Running setup.py install for mozdevice 10:16:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:16:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:16:27 INFO - Running setup.py install for mozfile 10:16:27 INFO - Running setup.py install for mozhttpd 10:16:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:16:27 INFO - Running setup.py install for mozinfo 10:16:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Running setup.py install for mozInstall 10:16:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Running setup.py install for mozleak 10:16:28 INFO - Running setup.py install for mozlog 10:16:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Running setup.py install for moznetwork 10:16:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Running setup.py install for mozprocess 10:16:28 INFO - Running setup.py install for mozprofile 10:16:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:16:28 INFO - Running setup.py install for mozrunner 10:16:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:16:29 INFO - Running setup.py install for mozscreenshot 10:16:29 INFO - Running setup.py install for moztest 10:16:29 INFO - Running setup.py install for mozversion 10:16:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:16:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:16:29 INFO - Cleaning up... 10:16:29 INFO - Return code: 0 10:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:16:29 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')]} 10:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:29 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')]} 10:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:29 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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16:29 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 10:16:29 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 10:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:29 INFO - 'HOME': '/Users/cltbld', 10:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:29 INFO - 'LOGNAME': 'cltbld', 10:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:29 INFO - 'MOZ_NO_REMOTE': '1', 10:16:29 INFO - 'NO_EM_RESTART': '1', 10:16:29 INFO - 'PAGER': '/bin/cat', 10:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:29 INFO - 'PWD': '/builds/slave/test', 10:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:29 INFO - 'SHELL': '/bin/bash', 10:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:29 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:29 INFO - 'USER': 'cltbld', 10:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:29 INFO - 'XPC_FLAGS': '0x0', 10:16:29 INFO - 'XPC_SERVICE_NAME': '0', 10:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-1RLIB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:16: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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-EN_rDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:16: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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-py7tB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:16: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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-FIxEYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:16: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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-LSFMp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:16:30 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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-yv2Kk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:16:30 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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-_6_1fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:16:30 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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-SpFQDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:16:30 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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-qh6RY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:16:30 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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-YRXoH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:16:30 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)) 10:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hFtiVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:16: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)) 10:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:16:31 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-wXgj4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:16: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)) 10:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:16:31 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-wtta_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:16: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)) 10:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:16:31 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dV3X4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:16: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)) 10:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:31 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-p9uoXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:31 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)) 10:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:16:31 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hCc7Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:16:31 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)) 10:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:16:31 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-P8OTjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:16:31 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)) 10:16:31 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)) 10:16:31 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)) 10:16:31 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)) 10:16:31 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)) 10:16:31 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)) 10:16:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:33 INFO - Downloading blessings-1.6.tar.gz 10:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:16:33 INFO - Installing collected packages: blessings 10:16:33 INFO - Running setup.py install for blessings 10:16:33 INFO - Successfully installed blessings 10:16:33 INFO - Cleaning up... 10:16:33 INFO - Return code: 0 10:16:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:16:33 INFO - Reading from file tmpfile_stdout 10:16:33 INFO - Current package versions: 10:16:33 INFO - blessings == 1.6 10:16:33 INFO - blobuploader == 1.2.4 10:16:33 INFO - docopt == 0.6.1 10:16:33 INFO - functools32 == 3.2.3-2 10:16:33 INFO - jsonschema == 2.5.1 10:16:33 INFO - manifestparser == 1.1 10:16:33 INFO - mozInstall == 1.12 10:16:33 INFO - mozcrash == 1.0 10:16:33 INFO - mozdebug == 0.1 10:16:33 INFO - mozdevice == 0.49 10:16:33 INFO - mozfile == 1.2 10:16:33 INFO - mozhttpd == 0.7 10:16:33 INFO - mozinfo == 0.9 10:16:33 INFO - mozleak == 0.1 10:16:33 INFO - mozlog == 3.4 10:16:33 INFO - moznetwork == 0.27 10:16:33 INFO - mozprocess == 0.25 10:16:33 INFO - mozprofile == 0.28 10:16:33 INFO - mozrunner == 6.13 10:16:33 INFO - mozscreenshot == 0.1 10:16:33 INFO - mozsystemmonitor == 0.3 10:16:33 INFO - moztest == 0.8 10:16:33 INFO - mozversion == 1.4 10:16:33 INFO - psutil == 3.1.1 10:16:33 INFO - requests == 1.2.3 10:16:33 INFO - wsgiref == 0.1.2 10:16:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:33 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 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16:33 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 10:16:33 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 10:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:33 INFO - 'HOME': '/Users/cltbld', 10:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:33 INFO - 'LOGNAME': 'cltbld', 10:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:33 INFO - 'MOZ_NO_REMOTE': '1', 10:16:33 INFO - 'NO_EM_RESTART': '1', 10:16:33 INFO - 'PAGER': '/bin/cat', 10:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:33 INFO - 'PWD': '/builds/slave/test', 10:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:33 INFO - 'SHELL': '/bin/bash', 10:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:33 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:33 INFO - 'USER': 'cltbld', 10:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:33 INFO - 'XPC_FLAGS': '0x0', 10:16:33 INFO - 'XPC_SERVICE_NAME': '0', 10:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 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)) 10:16:33 INFO - Cleaning up... 10:16:33 INFO - Return code: 0 10:16:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a256f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, '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.8CqXne6aPG/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.MDNlvzyFc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 10:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:33 INFO - 'HOME': '/Users/cltbld', 10:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:33 INFO - 'LOGNAME': 'cltbld', 10:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:33 INFO - 'MOZ_NO_REMOTE': '1', 10:16:33 INFO - 'NO_EM_RESTART': '1', 10:16:33 INFO - 'PAGER': '/bin/cat', 10:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:33 INFO - 'PWD': '/builds/slave/test', 10:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:33 INFO - 'SHELL': '/bin/bash', 10:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:33 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:33 INFO - 'USER': 'cltbld', 10:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:33 INFO - 'XPC_FLAGS': '0x0', 10:16:33 INFO - 'XPC_SERVICE_NAME': '0', 10:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:16:34 INFO - Cleaning up... 10:16:34 INFO - Return code: 0 10:16:34 INFO - Running post-action listener: _resource_record_post_action 10:16:34 INFO - Running post-action listener: _start_resource_monitoring 10:16:34 INFO - Starting resource monitoring. 10:16:34 INFO - [mozharness: 2017-05-12 17:16:34.240517Z] Finished create-virtualenv step (success) 10:16:34 INFO - [mozharness: 2017-05-12 17:16:34.241534Z] Running install step. 10:16:34 INFO - Running pre-action listener: _resource_record_pre_action 10:16:34 INFO - Running main action method: install 10:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:16:34 INFO - Reading from file tmpfile_stdout 10:16:34 INFO - Detecting whether we're running mozinstall >=1.0... 10:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:16:34 INFO - Reading from file tmpfile_stdout 10:16:34 INFO - Output received: 10:16:34 INFO - Usage: mozinstall [options] installer 10:16:34 INFO - Options: 10:16:34 INFO - -h, --help show this help message and exit 10:16:34 INFO - -d DEST, --destination=DEST 10:16:34 INFO - Directory to install application into. [default: 10:16:34 INFO - "/builds/slave/test"] 10:16:34 INFO - --app=APP Application being installed. [default: firefox] 10:16:34 INFO - mkdir: /builds/slave/test/build/application 10:16:34 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'] 10:16:34 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 10:16:49 INFO - Reading from file tmpfile_stdout 10:16:49 INFO - Output received: 10:16:49 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:16:49 INFO - Running post-action listener: _resource_record_post_action 10:16:49 INFO - [mozharness: 2017-05-12 17:16:49.512974Z] Finished install step (success) 10:16:49 INFO - [mozharness: 2017-05-12 17:16:49.513113Z] Running run-tests step. 10:16:49 INFO - Running pre-action listener: _resource_record_pre_action 10:16:49 INFO - Running main action method: run_tests 10:16:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:16:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:16:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:16:49 INFO - 2017-05-12 10:16:49.527 screenresolution[1646:7548] starting screenresolution argv=screenresolution get 10:16:49 INFO - 2017-05-12 10:16:49.530 screenresolution[1646:7548] Display 0: 1600x1200x32@60 10:16:49 INFO - 2017-05-12 10:16:49.536 screenresolution[1647:7550] starting screenresolution argv=screenresolution list 10:16:49 INFO - Available Modes on Display 0 10:16:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:16:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:16:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:16:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:16:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:16:49 INFO - Intel Iris: 10:16:49 INFO - Chipset Model: Intel Iris 10:16:49 INFO - Type: GPU 10:16:49 INFO - Bus: Built-In 10:16:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:16:49 INFO - Vendor: Intel (0x8086) 10:16:49 INFO - Device ID: 0x0a2e 10:16:49 INFO - Revision ID: 0x0009 10:16:49 INFO - Displays: 10:16:49 INFO - Display: 10:16:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:16:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:16:49 INFO - Display Serial Number: 1600x1200 60 10:16:49 INFO - Main Display: Yes 10:16:49 INFO - Mirror: Off 10:16:49 INFO - Online: Yes 10:16:49 INFO - Rotation: Supported 10:16:49 INFO - Return code: 0 10:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:16:49 INFO - Python 2.7.3 10:16:49 INFO - Return code: 0 10:16:49 INFO - grabbing minidump binary from tooltool 10:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:49 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 0x10a2b2d30>, '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 0x10a1d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:16:49 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 10:16:49 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 10:16:49 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 10:16:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:16:49 INFO - Return code: 0 10:16:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:16:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:16:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:16:49 INFO - ENV: RUST_BACKTRACE is now 1 10:16:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:16:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:16:49 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/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 10:16:49 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/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 10:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CqXne6aPG/Render', 10:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:49 INFO - 'HOME': '/Users/cltbld', 10:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:49 INFO - 'LOGNAME': 'cltbld', 10:16:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:16:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:49 INFO - 'MOZ_NO_REMOTE': '1', 10:16:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:16:49 INFO - 'NO_EM_RESTART': '1', 10:16:49 INFO - 'PAGER': '/bin/cat', 10:16:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:49 INFO - 'PWD': '/builds/slave/test', 10:16:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:49 INFO - 'RUST_BACKTRACE': '1', 10:16:49 INFO - 'SHELL': '/bin/bash', 10:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MDNlvzyFc0/Listeners', 10:16:49 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 10:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:49 INFO - 'USER': 'cltbld', 10:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:49 INFO - 'XPC_FLAGS': '0x0', 10:16:49 INFO - 'XPC_SERVICE_NAME': '0', 10:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:49 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/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 10:16:50 INFO - mozversion application_buildid: 20170512081421 10:16:50 INFO - mozversion application_changeset: bc096a7e31e236620e40d1dc94e0f4b4af63f75c 10:16:50 INFO - mozversion application_display_name: Firefox 10:16:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:16:50 INFO - mozversion application_name: Firefox 10:16:50 INFO - mozversion application_remotingname: firefox 10:16:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:16:50 INFO - mozversion application_vendor: Mozilla 10:16:50 INFO - mozversion application_version: 54.0 10:16:50 INFO - mozversion platform_buildid: 20170512081421 10:16:50 INFO - mozversion platform_changeset: bc096a7e31e236620e40d1dc94e0f4b4af63f75c 10:16:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:16:50 INFO - mozversion platform_version: 54.0 10:16:50 INFO - using testdate: 1494609410 10:16:50 INFO - actual date: 1494609410 10:16:50 INFO - starting webserver on 'localhost:49225' 10:16:50 INFO - SUITE-START | Running 2 tests 10:16:50 INFO - TEST-START | dromaeo_css 10:16:50 INFO - operating with platform_type : mac_ 10:16:50 INFO - Initialising browser for dromaeo_css test... 10:16:50 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpQWO9Ti/profile http://localhost:49225/getInfo.html) 10:16:52 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 10:16:52 INFO - PROCESS | 1657 | colorDepth:24 10:16:52 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 10:16:52 INFO - PROCESS | 1657 | __metrics 10:16:52 INFO - PROCESS | 1657 | 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" 10:16:52 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:16:53 INFO - TEST-INFO | 1657: exit 0 10:16:53 INFO - Browser initialized. 10:16:53 INFO - Running cycle 1/1 for dromaeo_css test... 10:16:53 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpQWO9Ti/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:05 INFO - PROCESS | 1659 | RSS: Main: 164900864 10:17:05 INFO - PROCESS | 1659 | 10:20:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 10:20:10 INFO - PROCESS | 1659 | RSS: Main: 292552704 10:20:10 INFO - PROCESS | 1659 | 10:23:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 10:23:13 INFO - PROCESS | 1659 | RSS: Main: 301711360 10:23:13 INFO - PROCESS | 1659 | 10:26:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 10:26:17 INFO - PROCESS | 1659 | RSS: Main: 270082048 10:26:17 INFO - PROCESS | 1659 | 10:29:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 10:29:20 INFO - PROCESS | 1659 | RSS: Main: 276647936 10:29:20 INFO - PROCESS | 1659 | 10:32:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 10:32:24 INFO - PROCESS | 1659 | RSS: Main: 273551360 10:32:24 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 10:35:27 INFO - PROCESS | 1659 | RSS: Main: 372219904 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | __start_tp_report 10:35:27 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 10:35:27 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 10:35:27 INFO - PROCESS | 1659 | |i|pagename|runs| 10:35:27 INFO - PROCESS | 1659 | |0;dojo.html;3496.3182179475;3522.33075715614;3523.1016090896824;3560.6617371349744;3567.0547304964175;5725.312962444507;6071.817845464632;6126.540509461792;6127.908081378778;6140.447359737623;6065.939340606593;6132.417420345071;6148.415900489457;6158.107074474198;6160.722767475463;5366.114591092475;5390.407055223922;5397.27136836526;5407.810726624569;5523.613347065707;5292.497212764782;5301.310829592167;5309.5752339812725;5503.724813759316;5510.090835012218;5357.223202635615;5431;5442.727863606824;5455.781768729246;5461.918071228934;5426.701531415771;5431.34823821142;5445.210444485548;5457.836264912019;5469.917951230695;5315.335583052119;5425.538829199483;5431.402545719968;5460.989716902386;5488.588355873326;5867.178594996704;5885.5585831062845;5898.7640494380175;6126.908096378558;6139.2632884053755;6334.334894836068;6407.583507072072;6434.420902118832;6456.709448074801;6488.480921526267;6476.579022363531;6478.384553467413;6507.089007538948;6556.803295901131;6559.8032059038305;6005.249343832021;6148.84627884302;6214.5649804513705;6249.218847644044;6253.405926436982;6466.482681385478;6507.186601674791;6544;6595.307492713196;6722.630255336004;6375.904361434582;6388.5528013039575;6393.552451328455;6395.136656551306;6405.935940640534;6367.554271201064;6502.089707440962;6515.315891831384;6520.445762110274;6523.119378883885;6249.843753906098;6311.873762524724;6333.651649159248;6395.872082558323;6410.134631824737;6434.453071488976;6454.1609590752905;6458.257300410513;6458.54790164684;6464.321246269074;5146.948530514722;5213.7393130343335;5215.947840521622;5227.424983251839;5255.78976840922;4446.9777651111535;4537.956270057905;4546.704464209815;4667.579917807346;4694.36626055485;6158.291796443466;6222.688865556704;6237.96881015592;6295.527835412362;6310.463610593151;6480.611163330215;6493.51298652603;6496.707648155844;6504.219493660791;6509.44669703071;6274.247090349188;6283.560150789493;6283.968580157161;6299.212598425197;6318.4313411793155;5490.149026900836;5589.077802162679;5622.91565626516;5669.546436285087;5700.201971723879;6044.153818465506;6762.966185169043;6781.11845460087;6803.25164231944;6813.318668133147;6194;6429.357064293533;6522.445592124533;6524.641144737085;6526.608403495806;6457.0637257596445;6761.830954226184;6775.4240889524945;6792.96603516999;6803.013563033233;6340.017297318923;6376.776812811715;6376.872462550816;6418.4865210783955;6441.935580644134;6286.025666021774;6330.873382532415;6360.618362898241;6469.320721324381;6478.222613286436;6219.689015549114;6313.40022697843;6334.778282760081;6338.8732225354315;6346.682665866781;6268.435840774352;6302.1492098566105;6323.683815809283;6347.90478142819;6356.650384228912;5951.404859514014;6108.918862504554;6143.419763699168;6186.5360097993735;6233.844153896189;6314.084457753692;6321.747130114744;6334.714937827716;6336.493080553637;6342.714577843916;6273.247210334789;6274.623522588659;6279.780207692709;6281.59169653971;6282.183316168869 10:35:27 INFO - PROCESS | 1659 | |1;ext.html;758.5107605594392;776.2974508070195;826.1490664615444;899.7300809757076;906.6509393883352;29802.850985745066;42630.78684606576;43020.924476888096;43100;43238.56761432381;44021;44236;44349.44776932808;44388;44576.554234457646;8164.306033987118;9746.074122958344;9882.160016398615;9893.90106098937;9902.603895844157;8130.55281959492;11929.98595119416;12073.698157546043;12387.009039276882;12418.254904705702;12059.276443413422;12151.696207594836;12181.33002684852;12186.451609677542;12187.939060304687;12102.757944841098;12103.213291136048;12152.696182595417;12153.93923030388;12157.45291461882;11817.645470635895;11863.2288901222;11870.169088163831;11874.584389546324;11875.762484750343;40979.7951010246;41107;41126.94365281724;41141.58858411408;41214.587854121375;3985.6811455083525;4021.3766866135797;4066.471358723347;4067.064575147703;4076.388541718736;29029.41941161186;29196.68614912334;30497.23756906073;31332.530012049836;31428.52857207144;3239.352129574071;3281.4585593377064;3314.3371325734706;3450.1202193440763;3452.637473065317;353997.23001385084;358360.9999999974;359129;360284;360862;55335;55404.99999999919;55418;55462.44537554573;55576.72211638916;57375.42624573784;57436.11792997558;57470;57500;57636.711816441486;15159.242037898282;15688.529344119695;15692.372305107669;15704.29330680122;15717.607059823597;12030.097742669335;13279.601611951657;13292.601221963163;13296.002799789861;13320.600381988555;11831.467583958742;11914.225575337576;13072.019598529958;13329.466821327233;13503.987200959968;5750.511206547407;5781.335146458127;5786.189933409326;5795.507381872504;5806.622569532967;956.7177682583655;964.3153361113548;976.9218462522981;985.737209835447;988.4711679251566;26522.734772652027;28850.855745721135;29274.414511709645;29389.11832645024;29457.263568410963;1071.7588542577857;1076.5101878645198;1080.935143891369;1084.6095405653957;1115.866096068477;11858.881411185777;14803.407863685332;14847.406103755944;14859.925700371428;15166.924165379103;440755;440976;441011;441432;441757;59233;59266.70366648139;59342;59440.70279648574;59524.80950380968;1446.3274577321597;1465.172177719585;1483.6142602923317;1495.6559991202084;1508.2157278215266;27196.864015679796;28500.714992849807;28798;29118.854405727834;29119.85440072786;30646.387072258876;38409;38420.6157938417;38421;38475.2304953905;379976;415029;430027;431786;433100;30138.24654383658;31048.068557943297;31086.844565777028;31127.688723113384;31153.688463115985;31705.841470792497;33123.00630981074;34540.30919381548;40749;42727.78636106924;22708.205212817473;28815.84736610512;29084.56373154362;29469.852650736608;29940.550891736206;29101.12696619105;29110;29176.416471670873;29968;30163.547546787253;33274.168145796546;33662.3267534643;34259.828700856335;34520.30959380849;34606.480902786985;29527.114186574436;29702.405951881272;30315;30409.847950760104;30495.847520762254;22663.660045099667;23956.92193851246;24126.155584554457;24458.51082978366;24593.262202133963 10:35:27 INFO - PROCESS | 1659 | |2;jquery.html;428.5508319951477;435.6231859441582;435.82784800004;444.2935732185824;448.41705782482785;19838.504037399056;20004.799712017288;21146.25988090417;22299.44251393716;22407.103715851368;21180.046897889595;23419.531609367845;24007.279781606536;24007.919643616035;24271.029158833626;6639.668016599187;6907.13660792401;6923.930760692379;6933.47998900082;7520.586367749772;7718.2281771822645;7833.394154198376;7858.057033156029;7860.292573668368;7871.9212807872045;7565.848683026336;7803.02462192226;7870.567118808463;7873.566953817538;7883;6901.482388820833;7645.3501452376895;7664.616769161547;7880.724174653917;7964.641591128355;5769.394213607595;7678.884816727754;7690.807729806744;7714.4214183935865;7749.883751743729;26974.325641858915;27276.04533841306;27449;27897.302567435774;28257.85871070652;2584.689837219527;3040.6959304069637;3215.6302025266928;3426.7258619310396;3513.2973405318785;15505.457308994131;15866.841331586767;15962.680746385124;16001.439949601709;16970.40603578869;2007.096806437109;2016.1129103194548;2020.5150763816725;2029.725986991752;2103.463616777727;142413.28793355968;164771;169569.07111770735;172333;175389.12305438519;15857;20375.694364584542;20472.69290960637;20504.69242961357;20555.280565180146;32461.35077298441;32869.67130328714;32887;32921;32958.3408331832;10315.742106447398;10437.791244175074;10679.733006674895;10684.946575267073;10849;8873.378863479624;8881.644734210688;8886.022537520868;9080.455172689659;9115.498647574315;7809.297163255334;7863.52299025805;8945.23965462943;9433.05669433065;9495.38280011797;2467.7162126355697;3727.8322475488667;3748.5314335708035;3913.4521167036637;4143.446207672123;868.2923417414913;876.815868667587;1087.9891201087887;1091.0071834630608;1130.6834086455806;21688.891555542123;23025.42436439069;23059.07763689467;23084.7691523086;23108.422289442562;805.6052534258172;942.604106275366;948.2271948362063;957.3872721458272;976.951152442375;11613.883861161281;11684.532618695326;11844.644660660195;11912.344821034927;11925.642230733092;809269;809737;810506;817501;818146;149490;150014;150112;153902;155824;1259.703969567151;1303.1008604063356;1366.631009627395;1366.9111507751963;1368.261138984957;19590.90204548968;20077.59844803125;20591.382258532267;20591.691124633442;20796.58406831885;64241;64925.70148597096;65388.01917971139;65458.69082618416;66197.33802662104;85428;88385.55807220923;90847.54576227076;94297.52851235699;95203.04796951942;11546.480408381474;11848.111391645489;12000.15998880105;12176.939115304367;12288.1398302118;13604.115732477358;13611.931940340235;13949.3722782473;13989.720205595628;13993.020488565702;10581.947090264499;10689.984451477098;11002.229843910849;11012.229143960167;11016.50425730828;11013.2841365311;11107.111431085656;11361.147913906325;11468.770624587629;11481.770364592829;11461.942690286494;11869.228500147809;11974.401279936143;12202.572909948109;12217.08371872131;11761.353125577833;11855.288682679067;11883.465244063866;12109.818352724893;12175.512979480722;11714.297142171155;11806.704832379259;11870.287782733063;12016.218945768496;12022.196670499496 10:35:27 INFO - PROCESS | 1659 | |3;mootools.html;1633.4283000949677;1640.803103627565;1662.9501114966547;1667.8915870468416;1685.334292954283;10887.727806804834;11614.18700690952;11632.767344653103;11640.825387619192;11643.825342619846;11257.662270131888;11354.205205635588;11602.709932251699;11625;11630.941845290761;2449.5835707929696;2520.2565243253284;2521.8739063046864;2521.9873900630473;2522.8738563071865;2464.4824586836694;2488.6515887775727;2526.9747302527016;2534.6958364996226;2536.7843733282693;2389.545986262613;2427.720812106613;2430.2223288547584;2472.888720007595;2504.686914135733;2459.523666926195;2477.157766359446;2477.2815883393796;2500.6624105745673;2509.5608268553024;2449.8040156787415;2476.4923190746035;2482.466269751994;2484.6521486991837;2486.5151295497385;11231.943840280828;11530.76938461226;11535.769284614345;11545.942270288679;11559.942200288944;6043.879122417571;6217.440430361289;6219.315875253764;6223.471004964584;6238.1266622672865;3736.084659258499;3793.241351729665;3859.8131770838672;3869.342211823997;3917.4319723640197;1251.993740031303;1271.961841144767;1281.605829271438;1313.159577870164;1314.835645544307;14310.928445357811;14637;15012.399504019826;15846.920765396215;16170.191490425432;4428.800703968329;5218.243354713545;5238.057149713089;5274.657147285414;5289.074411977888;13508.86491135076;13520.729585408237;13521.932390337985;13523.256220907944;13569.321533923265;5329.733513324319;5506;6073.423024812636;6193.721282542297;6266.467350275278;5504.422035686275;5622.437756224426;5622.690752008679;5713.885722285531;5720.170575266563;4267.701260911706;4286.7856607169515;4320.805563749576;4352.564743525622;4376.846810361622;1157.8406272302263;1190.3750530971308;1206.252123683322;1207.209277922962;1209.0629761934395;956.2827879090682;991.3952488981715;997.680742162501;1005.0853723111933;1011.438651548383;6595.142631457879;6639.302873198341;6709.29552397001;6726.293739157318;6789.93210067893;1012.7012531303283;1031.6073301043682;1046.4872212615915;1057.5082586597116;1058.6506452870535;4233.534311225762;4281.507626622916;4293.355996600535;4332.285172946428;4398.912021759547;13769.967252456107;14376.568702938928;14395.352209150613;14404.063735857137;14414.990950633355;13948;14147.221902795445;14282.3572939218;14300.928495357457;14334.928325358514;10553;10713.89286107129;10721.731956701145;10734.409607471664;10741.83887241692;6405;6698.56459330142;6723.899141512979;6733.3939945405145;6781.169084347297;11331.603393881174;12407.565735199225;12759.298238596966;12959.935200323938;13061.542846000344;12253.571125010581;12262;12802.295873726662;12878.227306361649;13275.07074504814;8499.065102838806;8841.734747957571;8862.911370886466;8869.600867960828;8872.645094196418;10372.481375931324;10452.477376131012;10520.947395263282;10549.314294570828;10578.259521833706;8891.377603567687;8963.596638151168;9024.684136055206;9128.406653567497;9248.583813728534;8724.78188045304;8729.78175545591;8762.956185219033;8829.337799664921;8892.066333034938;7986.640601173078;7987.081485628994;8063;8078.959605201936;8081.191880811872;5888.558358123244;5890.057590785454;5921.17103605487;6072;6119.938800611937;5865.384134665798;5932.139839723302;6108.08378743192;6230.345813689497;6281.057841323655 10:35:27 INFO - PROCESS | 1659 | |4;prototype.html;307.64620686210856;322.22504875773757;388.3999221203242;390.0560643243354;390.9550401703804;4531.252343363345;4789.209780386241;5238.4499627539135;5251.763670634821;5262.894742105161;4311.611954924057;4730.4625996551085;4854.621235594551;5127.923191107598;5144.691318520882;4046.705054382599;4155.33514637658;4165.375193720935;4224.577542245774;4237.8516751913685;3560.6825474574316;3688.4467329900594;3966.504186976628;4232.957670423302;4301.311790113583;3802.7147963902817;4219.556946520617;4236.623097493311;4240.300350442173;4264.573542645741;3380.898573042788;3660.9450858237146;3982.7012974026775;4239.321708526621;4263.744175349458;3169.8415079246174;3385.5272956264103;3629.8185090745355;3884.1454879926373;3939.5666476687543;3948.6643635291034;4375.71557848742;4746.644001699887;4783.95216047842;4942.1351263529095;4644.581987621096;4647.883802904955;4763.928541071887;4923.433805112386;4925.482824303468;5022.899542009176;5450.5367043801325;5475.893080189858;5476.3702174250075;5542.667439953616;3865.8982190075694;4067.1662309226836;4388.605025547716;4458.175237581057;4488.259437192859;4671.789769460383;4945.332380128709;5183.611229157828;5701.187081437683;6023.789167379121;4028.67770578353;4239.682023848224;4469.486009108994;4589.128065667513;4636.235021221528;3683.747525841227;4015.7992100394863;4201.957980420218;4570;4680.3915490985955;4815.422149342101;4829.331216511268;4897.338859254026;4937.21004639256;4981.089844472072;4764.618830493552;5043.344365232496;5427.782888684408;5452.536534394541;5498.010358135574;1459.7737350710656;1480.5484327280283;1539.0996267183823;1572.1274692545746;1575.763635454668;966.7341481092756;1090.4493230918335;1106.1372129738816;1124.6288724720823;1185.9051275897907;4731.645126615517;5117.162128403697;5205.869853253624;5220.843374698765;5267.947320526746;5073.695578265242;5192.299039629677;5681.375048744635;5689.800856969986;5720.342160651495;4704.835330763407;4708.858734237979;4821.710697358169;5190.013897359489;5210.38478071799;2570.3060173753356;2728.0724553651853;2735.6048415308337;2950.215119852501;2964.555316702512;4693.647976401783;4965.772248038804;5316.973415132899;5338.119210330329;5765.913511297334;5136.357955255593;5151.948480515147;5364;5435.809746658848;5538.667679939216;5031.924521132113;5152.562032227228;5647.604667673306;5658.227468540468;5690.382424084967;4740.6681532292405;4983.700977941335;5565;5690.601657883908;5700.429957004267;4677.649176311858;4710.740909250024;4972.975135124301;5123.538881500683;5596.86007849807;4855.984766321937;5842.415758424124;5872.265966754156;5889.823305300848;5936.753281810868;4044.555098939173;4474.963891429326;4485.48416932057;4515.571020753023;4562.954370456253;4741.83403580873;5339.813106541253;5601.859953501195;5737.655740655574;5804.94195058044;4784.497627749175;5422.864428389322;5469;5483.4516548344845;5510.669359838423;5082.618803589671;5282.656627319212;5509.429812503417;5639.407862174413;5639.464250896158;5301.156661420439;5352.839414817562;5387.434319396407;5408.161734931092;5411.864703382447;4875;5279.86482906177;5484.424135465878;5512;5515.4760297771645;4201.852935147255;4823.758812059453;4982.800687972585;5150.534217738477;5336.519713225828 10:35:27 INFO - PROCESS | 1659 | |5;yui.html;756.6557216466508;760.0309605253298;875.8511053120969;884.3986089459167;908.9909100908989;12162.574309899155;13645.863541364582;16918.984860908353;17187.742183867238;18043.548911277223;16307.673846523063;16502.917485412556;16507.33970641176;16553.337866485326;16783.83216167838;988.4612885977124;990.3760630802584;990.4107056301489;991.603358656536;991.9305648604601;968.7432830299941;992.6724181020282;1006.3307900246327;1008.7831116309992;1026.240581969341;1066.1151244467073;1067.8451624514441;1071.1109778883508;1071.1109778883547;1071.73742433104;1064.3773392565358;1064.542246833861;1065.5897479470382;1067.0556557446678;1068.3269540189708;707.762899428692;751.3538357013019;790.2097902097902;790.3953475591177;791.0112359550562;17448.30206791727;17565.736513952303;17634.118294085372;18111.63776724458;18392.816071839377;14643.34104965279;14792.926035369863;14880.70238595222;14944.925275373553;15276.159811210378;10295;11794.941025294904;12232.81650775239;12303.75392492154;12540.81188782169;3755.098776293697;3761.2101458693705;3790.2798468290935;3889.747166434173;3928.7839168845703;101813;103483;103654;107326;111029;11254.549818007352;11450.37022963745;11493.597724079616;11703.239289446328;11824.94087529574;23663.171788987303;24001.039958401467;24031.639525407132;24402.26793196207;24424.26727198187;7597.886031709529;7882.211778822072;8081.151479094728;8118.472299300526;8378.162183781573;4495.179097766872;4555.931661025087;4574.382458368144;4618.584327410482;4698.107359601665;3448.9997900609046;3520.806355650464;3529.6823285904393;3531.434970404723;3533.3993221152464;734.9522281051715;760.3840888880025;763.1948294549304;778.1479280188184;786.9055713314325;725.561035573481;750.7785203365021;754.5925200391837;780.6448066129898;780.9379244227846;9517.619295228113;9845.60617575289;9957.253206009433;10467.476626168662;10879.184061195283;726.498715886037;739.3973911262304;744.244591739385;744.5941961630924;752.762879692895;4834.299026641117;5023.02051100032;5069.974650126799;5344.43883392246;5536.806211782568;79420.60289698515;94314;103018.96981030244;105103.2982603194;105417;73394;84381.57809210915;91987;92605.51761376773;92974;81302.99999999882;81802;83375.583122084;83406;83819;11094.722631934266;11140.178766077892;11368.374739389414;11474.655760327203;11525.884741152817;46390.30414543717;49167;51155.744221278655;51966.74016629893;52840.47159528509;56905;65100.674496627216;66313.67372652616;67716.64566708737;68524.31475685179;12516;12590.685232869251;12779.36103194818;12917.160384574972;13043.608691739262;15408;15417.845821541641;15469.922650386676;16322.591935201715;16514.504564863073;14386.712265754417;14406.207658578878;14517.346719397863;15019;15036.17301048409;13610.523631672844;14362.066465680115;15062.020968637004;15352.846471535142;15423.305951232445;12562.937185314015;12709.745805084032;12953.481860725842;12970;12978.480860765841;10039.96897929966;10445.216608754527;10684;11077.723056923642;11190.384528850665;11065.5020524075;11867.58463453775;11952.76094478088;12111.515539378326;12413.379331033231 10:35:27 INFO - PROCESS | 1659 | __end_tp_report 10:35:27 INFO - PROCESS | 1659 | __start_cc_report 10:35:27 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1305 10:35:27 INFO - PROCESS | 1659 | __end_cc_report 10:35:27 INFO - PROCESS | 1659 | __startTimestamp1494610527570__endTimestamp 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | ------- Summary: start ------- 10:35:27 INFO - PROCESS | 1659 | Number of tests: 6 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:6015.74 Median:6281.59 stddev:665.74 (10.6%) stddev-sans-first:637.77 10:35:27 INFO - PROCESS | 1659 | Values: 3496.3 3522.3 3523.1 3560.7 3567.1 5725.3 6071.8 6126.5 6127.9 6140.4 6065.9 6132.4 6148.4 6158.1 6160.7 5366.1 5390.4 5397.3 5407.8 5523.6 5292.5 5301.3 5309.6 5503.7 5510.1 5357.2 5431.0 5442.7 5455.8 5461.9 5426.7 5431.3 5445.2 5457.8 5469.9 5315.3 5425.5 5431.4 5461.0 5488.6 5867.2 5885.6 5898.8 6126.9 6139.3 6334.3 6407.6 6434.4 6456.7 6488.5 6476.6 6478.4 6507.1 6556.8 6559.8 6005.2 6148.8 6214.6 6249.2 6253.4 6466.5 6507.2 6544.0 6595.3 6722.6 6375.9 6388.6 6393.6 6395.1 6405.9 6367.6 6502.1 6515.3 6520.4 6523.1 6249.8 6311.9 6333.7 6395.9 6410.1 6434.5 6454.2 6458.3 6458.5 6464.3 5146.9 5213.7 5215.9 5227.4 5255.8 4447.0 4538.0 4546.7 4667.6 4694.4 6158.3 6222.7 6238.0 6295.5 6310.5 6480.6 6493.5 6496.7 6504.2 6509.4 6274.2 6283.6 6284.0 6299.2 6318.4 5490.1 5589.1 5622.9 5669.5 5700.2 6044.2 6763.0 6781.1 6803.3 6813.3 6194.0 6429.4 6522.4 6524.6 6526.6 6457.1 6761.8 6775.4 6793.0 6803.0 6340.0 6376.8 6376.9 6418.5 6441.9 6286.0 6330.9 6360.6 6469.3 6478.2 6219.7 6313.4 6334.8 6338.9 6346.7 6268.4 6302.1 6323.7 6347.9 6356.7 5951.4 6108.9 6143.4 6186.5 6233.8 6314.1 6321.7 6334.7 6336.5 6342.7 6273.2 6274.6 6279.8 6281.6 6282.2 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:55098.96 Median:31567.19 stddev:107832.58 (341.6%) stddev-sans-first:108057.89 10:35:27 INFO - PROCESS | 1659 | Values: 758.5 776.3 826.1 899.7 906.7 29802.9 42630.8 43020.9 43100.0 43238.6 44021.0 44236.0 44349.4 44388.0 44576.6 8164.3 9746.1 9882.2 9893.9 9902.6 8130.6 11930.0 12073.7 12387.0 12418.3 12059.3 12151.7 12181.3 12186.5 12187.9 12102.8 12103.2 12152.7 12153.9 12157.5 11817.6 11863.2 11870.2 11874.6 11875.8 40979.8 41107.0 41126.9 41141.6 41214.6 3985.7 4021.4 4066.5 4067.1 4076.4 29029.4 29196.7 30497.2 31332.5 31428.5 3239.4 3281.5 3314.3 3450.1 3452.6 353997.2 358361.0 359129.0 360284.0 360862.0 55335.0 55405.0 55418.0 55462.4 55576.7 57375.4 57436.1 57470.0 57500.0 57636.7 15159.2 15688.5 15692.4 15704.3 15717.6 12030.1 13279.6 13292.6 13296.0 13320.6 11831.5 11914.2 13072.0 13329.5 13504.0 5750.5 5781.3 5786.2 5795.5 5806.6 956.7 964.3 976.9 985.7 988.5 26522.7 28850.9 29274.4 29389.1 29457.3 1071.8 1076.5 1080.9 1084.6 1115.9 11858.9 14803.4 14847.4 14859.9 15166.9 440755.0 440976.0 441011.0 441432.0 441757.0 59233.0 59266.7 59342.0 59440.7 59524.8 1446.3 1465.2 1483.6 1495.7 1508.2 27196.9 28500.7 28798.0 29118.9 29119.9 30646.4 38409.0 38420.6 38421.0 38475.2 379976.0 415029.0 430027.0 431786.0 433100.0 30138.2 31048.1 31086.8 31127.7 31153.7 31705.8 33123.0 34540.3 40749.0 42727.8 22708.2 28815.8 29084.6 29469.9 29940.6 29101.1 29110.0 29176.4 29968.0 30163.5 33274.2 33662.3 34259.8 34520.3 34606.5 29527.1 29702.4 30315.0 30409.8 30495.8 22663.7 23956.9 24126.2 24458.5 24593.3 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:45648.94 Median:23042.25 stddev:135271.22 (587.1%) stddev-sans-first:135608.07 10:35:27 INFO - PROCESS | 1659 | Values: 428.6 435.6 435.8 444.3 448.4 19838.5 20004.8 21146.3 22299.4 22407.1 21180.0 23419.5 24007.3 24007.9 24271.0 6639.7 6907.1 6923.9 6933.5 7520.6 7718.2 7833.4 7858.1 7860.3 7871.9 7565.8 7803.0 7870.6 7873.6 7883.0 6901.5 7645.4 7664.6 7880.7 7964.6 5769.4 7678.9 7690.8 7714.4 7749.9 26974.3 27276.0 27449.0 27897.3 28257.9 2584.7 3040.7 3215.6 3426.7 3513.3 15505.5 15866.8 15962.7 16001.4 16970.4 2007.1 2016.1 2020.5 2029.7 2103.5 142413.3 164771.0 169569.1 172333.0 175389.1 15857.0 20375.7 20472.7 20504.7 20555.3 32461.4 32869.7 32887.0 32921.0 32958.3 10315.7 10437.8 10679.7 10684.9 10849.0 8873.4 8881.6 8886.0 9080.5 9115.5 7809.3 7863.5 8945.2 9433.1 9495.4 2467.7 3727.8 3748.5 3913.5 4143.4 868.3 876.8 1088.0 1091.0 1130.7 21688.9 23025.4 23059.1 23084.8 23108.4 805.6 942.6 948.2 957.4 977.0 11613.9 11684.5 11844.6 11912.3 11925.6 809269.0 809737.0 810506.0 817501.0 818146.0 149490.0 150014.0 150112.0 153902.0 155824.0 1259.7 1303.1 1366.6 1366.9 1368.3 19590.9 20077.6 20591.4 20591.7 20796.6 64241.0 64925.7 65388.0 65458.7 66197.3 85428.0 88385.6 90847.5 94297.5 95203.0 11546.5 11848.1 12000.2 12176.9 12288.1 13604.1 13611.9 13949.4 13989.7 13993.0 10581.9 10690.0 11002.2 11012.2 11016.5 11013.3 11107.1 11361.1 11468.8 11481.8 11461.9 11869.2 11974.4 12202.6 12217.1 11761.4 11855.3 11883.5 12109.8 12175.5 11714.3 11806.7 11870.3 12016.2 12022.2 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6999.52 Median:2487.58 stddev:4355.80 (175.1%) stddev-sans-first:4349.35 10:35:27 INFO - PROCESS | 1659 | Values: 1633.4 1640.8 1663.0 1667.9 1685.3 10887.7 11614.2 11632.8 11640.8 11643.8 11257.7 11354.2 11602.7 11625.0 11630.9 2449.6 2520.3 2521.9 2522.0 2522.9 2464.5 2488.7 2527.0 2534.7 2536.8 2389.5 2427.7 2430.2 2472.9 2504.7 2459.5 2477.2 2477.3 2500.7 2509.6 2449.8 2476.5 2482.5 2484.7 2486.5 11231.9 11530.8 11535.8 11545.9 11559.9 6043.9 6217.4 6219.3 6223.5 6238.1 3736.1 3793.2 3859.8 3869.3 3917.4 1252.0 1272.0 1281.6 1313.2 1314.8 14310.9 14637.0 15012.4 15846.9 16170.2 4428.8 5218.2 5238.1 5274.7 5289.1 13508.9 13520.7 13521.9 13523.3 13569.3 5329.7 5506.0 6073.4 6193.7 6266.5 5504.4 5622.4 5622.7 5713.9 5720.2 4267.7 4286.8 4320.8 4352.6 4376.8 1157.8 1190.4 1206.3 1207.2 1209.1 956.3 991.4 997.7 1005.1 1011.4 6595.1 6639.3 6709.3 6726.3 6789.9 1012.7 1031.6 1046.5 1057.5 1058.7 4233.5 4281.5 4293.4 4332.3 4398.9 13770.0 14376.6 14395.4 14404.1 14415.0 13948.0 14147.2 14282.4 14300.9 14334.9 10553.0 10713.9 10721.7 10734.4 10741.8 6405.0 6698.6 6723.9 6733.4 6781.2 11331.6 12407.6 12759.3 12959.9 13061.5 12253.6 12262.0 12802.3 12878.2 13275.1 8499.1 8841.7 8862.9 8869.6 8872.6 10372.5 10452.5 10520.9 10549.3 10578.3 8891.4 8963.6 9024.7 9128.4 9248.6 8724.8 8729.8 8763.0 8829.3 8892.1 7986.6 7987.1 8063.0 8079.0 8081.2 5888.6 5890.1 5921.2 6072.0 6119.9 5865.4 5932.1 6108.1 6230.3 6281.1 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4505.22 Median:4841.98 stddev:1268.53 (26.2%) stddev-sans-first:1232.34 10:35:27 INFO - PROCESS | 1659 | Values: 307.6 322.2 388.4 390.1 391.0 4531.3 4789.2 5238.4 5251.8 5262.9 4311.6 4730.5 4854.6 5127.9 5144.7 4046.7 4155.3 4165.4 4224.6 4237.9 3560.7 3688.4 3966.5 4233.0 4301.3 3802.7 4219.6 4236.6 4240.3 4264.6 3380.9 3660.9 3982.7 4239.3 4263.7 3169.8 3385.5 3629.8 3884.1 3939.6 3948.7 4375.7 4746.6 4784.0 4942.1 4644.6 4647.9 4763.9 4923.4 4925.5 5022.9 5450.5 5475.9 5476.4 5542.7 3865.9 4067.2 4388.6 4458.2 4488.3 4671.8 4945.3 5183.6 5701.2 6023.8 4028.7 4239.7 4469.5 4589.1 4636.2 3683.7 4015.8 4202.0 4570.0 4680.4 4815.4 4829.3 4897.3 4937.2 4981.1 4764.6 5043.3 5427.8 5452.5 5498.0 1459.8 1480.5 1539.1 1572.1 1575.8 966.7 1090.4 1106.1 1124.6 1185.9 4731.6 5117.2 5205.9 5220.8 5267.9 5073.7 5192.3 5681.4 5689.8 5720.3 4704.8 4708.9 4821.7 5190.0 5210.4 2570.3 2728.1 2735.6 2950.2 2964.6 4693.6 4965.8 5317.0 5338.1 5765.9 5136.4 5151.9 5364.0 5435.8 5538.7 5031.9 5152.6 5647.6 5658.2 5690.4 4740.7 4983.7 5565.0 5690.6 5700.4 4677.6 4710.7 4973.0 5123.5 5596.9 4856.0 5842.4 5872.3 5889.8 5936.8 4044.6 4475.0 4485.5 4515.6 4563.0 4741.8 5339.8 5601.9 5737.7 5804.9 4784.5 5422.9 5469.0 5483.5 5510.7 5082.6 5282.7 5509.4 5639.4 5639.5 5301.2 5352.8 5387.4 5408.2 5411.9 4875.0 5279.9 5484.4 5512.0 5515.5 4201.9 4823.8 4982.8 5150.5 5336.5 10:35:27 INFO - PROCESS | 1659 | 10:35:27 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:20785.27 Median:18077.59 stddev:28942.80 (160.1%) stddev-sans-first:28984.92 10:35:27 INFO - PROCESS | 1659 | Values: 756.7 760.0 875.9 884.4 909.0 12162.6 13645.9 16919.0 17187.7 18043.5 16307.7 16502.9 16507.3 16553.3 16783.8 988.5 990.4 990.4 991.6 991.9 968.7 992.7 1006.3 1008.8 1026.2 1066.1 1067.8 1071.1 1071.1 1071.7 1064.4 1064.5 1065.6 1067.1 1068.3 707.8 751.4 790.2 790.4 791.0 17448.3 17565.7 17634.1 18111.6 18392.8 14643.3 14792.9 14880.7 14944.9 15276.2 10295.0 11794.9 12232.8 12303.8 12540.8 3755.1 3761.2 3790.3 3889.7 3928.8 101813.0 103483.0 103654.0 107326.0 111029.0 11254.5 11450.4 11493.6 11703.2 11824.9 23663.2 24001.0 24031.6 24402.3 24424.3 7597.9 7882.2 8081.2 8118.5 8378.2 4495.2 4555.9 4574.4 4618.6 4698.1 3449.0 3520.8 3529.7 3531.4 3533.4 735.0 760.4 763.2 778.1 786.9 725.6 750.8 754.6 780.6 780.9 9517.6 9845.6 9957.3 10467.5 10879.2 726.5 739.4 744.2 744.6 752.8 4834.3 5023.0 5070.0 5344.4 5536.8 79420.6 94314.0 103019.0 105103.3 105417.0 73394.0 84381.6 91987.0 92605.5 92974.0 81303.0 81802.0 83375.6 83406.0 83819.0 11094.7 11140.2 11368.4 11474.7 11525.9 46390.3 49167.0 51155.7 51966.7 52840.5 56905.0 65100.7 66313.7 67716.6 68524.3 12516.0 12590.7 12779.4 12917.2 13043.6 15408.0 15417.8 15469.9 16322.6 16514.5 14386.7 14406.2 14517.3 15019.0 15036.2 13610.5 14362.1 15062.0 15352.8 15423.3 12562.9 12709.7 12953.5 12970.0 12978.5 10040.0 10445.2 10684.0 11077.7 11190.4 11065.5 11867.6 11952.8 12111.5 12413.4 10:35:27 INFO - PROCESS | 1659 | -------- Summary: end -------- 10:35:27 INFO - PROCESS | 1659 | 10:35:28 INFO - TEST-INFO | 1659: exit 0 10:35:28 INFO - TEST-OK | dromaeo_css | took 1118030ms 10:35:28 INFO - TEST-START | kraken 10:35:28 INFO - operating with platform_type : mac_ 10:35:28 INFO - Initialising browser for kraken test... 10:35:28 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpxJ2K5W/profile http://localhost:49225/getInfo.html) 10:35:30 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 10:35:30 INFO - PROCESS | 1670 | colorDepth:24 10:35:30 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 10:35:30 INFO - PROCESS | 1670 | __metrics 10:35:30 INFO - PROCESS | 1670 | 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" 10:35:30 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:35:31 INFO - TEST-INFO | 1670: exit 0 10:35:31 INFO - Browser initialized. 10:35:31 INFO - Running cycle 1/1 for kraken test... 10:35:31 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpxJ2K5W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:35:43 INFO - PROCESS | 1672 | RSS: Main: 164827136 10:35:43 INFO - PROCESS | 1672 | 10:35:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 10:35:46 INFO - PROCESS | 1672 | RSS: Main: 215756800 10:35:46 INFO - PROCESS | 1672 | 10:35:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 10:35:50 INFO - PROCESS | 1672 | RSS: Main: 231403520 10:35:50 INFO - PROCESS | 1672 | 10:35:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 10:35:53 INFO - PROCESS | 1672 | RSS: Main: 328421376 10:35:53 INFO - PROCESS | 1672 | 10:35:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 10:35:56 INFO - PROCESS | 1672 | RSS: Main: 230256640 10:35:56 INFO - PROCESS | 1672 | 10:35:58 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:35:59 INFO - PROCESS | 1672 | RSS: Main: 232468480 10:35:59 INFO - PROCESS | 1672 | 10:36:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 10:36:02 INFO - PROCESS | 1672 | RSS: Main: 271761408 10:36:02 INFO - PROCESS | 1672 | 10:36:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 10:36:06 INFO - PROCESS | 1672 | RSS: Main: 272510976 10:36:06 INFO - PROCESS | 1672 | 10:36:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 10:36:10 INFO - PROCESS | 1672 | RSS: Main: 270630912 10:36:10 INFO - PROCESS | 1672 | 10:36:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:36:12 INFO - PROCESS | 1672 | RSS: Main: 247406592 10:36:12 INFO - PROCESS | 1672 | 10:36:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:36:14 INFO - PROCESS | 1672 | RSS: Main: 249479168 10:36:14 INFO - PROCESS | 1672 | 10:36:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:36:17 INFO - PROCESS | 1672 | RSS: Main: 260956160 10:36:17 INFO - PROCESS | 1672 | 10:36:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:36:21 INFO - PROCESS | 1672 | RSS: Main: 273149952 10:36:21 INFO - PROCESS | 1672 | 10:36:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:36:24 INFO - PROCESS | 1672 | RSS: Main: 266944512 10:36:24 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 10:36:26 INFO - PROCESS | 1672 | RSS: Main: 311173120 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | __start_tp_report 10:36:26 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 10:36:26 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 10:36:26 INFO - PROCESS | 1672 | |i|pagename|runs| 10:36:26 INFO - PROCESS | 1672 | |0;ai-astar;105;116;115;114;110;108;115;115;118;117 10:36:26 INFO - PROCESS | 1672 | |1;audio-beat-detection;135;131;134;161;109;107;148;106;125;108 10:36:26 INFO - PROCESS | 1672 | |2;audio-dft;188;186;187;186;184;186;186;187;186;186 10:36:26 INFO - PROCESS | 1672 | |3;audio-fft;81;68;59;93;63;59;61;60;73;59 10:36:26 INFO - PROCESS | 1672 | |4;audio-oscillator;85;70;87;70;70;69;82;69;68;83 10:36:26 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;88;88;87;92;135;113;89;89;87 10:36:26 INFO - PROCESS | 1672 | |6;imaging-darkroom;95;94;94;95;93;97;95;95;105;151 10:36:26 INFO - PROCESS | 1672 | |7;imaging-desaturate;86;87;84;84;84;96;128;133;85;84 10:36:26 INFO - PROCESS | 1672 | |8;json-parse-financial;60;58;58;60;64;62;63;64;64;64 10:36:26 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;57;64;56;65;61;58;59;59;59;58 10:36:26 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;88;81;87;85;83;82;86;82;78;85 10:36:26 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;135;145;121;133;132;135;133;132;186;173 10:36:26 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;161;162;163;161;160;161;161;164;158;161 10:36:26 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;58;79;85;56;64;76;75;83;81;84 10:36:26 INFO - PROCESS | 1672 | __end_tp_report 10:36:26 INFO - PROCESS | 1672 | __start_cc_report 10:36:26 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2153 10:36:26 INFO - PROCESS | 1672 | __end_cc_report 10:36:26 INFO - PROCESS | 1672 | __startTimestamp1494610586651__endTimestamp 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | ------- Summary: start ------- 10:36:26 INFO - PROCESS | 1672 | Number of tests: 14 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:4.22 (3.7%) stddev-sans-first:3.23 10:36:26 INFO - PROCESS | 1672 | Values: 105.0 116.0 115.0 114.0 110.0 108.0 115.0 115.0 118.0 117.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:132.50 stddev:19.01 (14.3%) stddev-sans-first:19.91 10:36:26 INFO - PROCESS | 1672 | Values: 135.0 131.0 134.0 161.0 109.0 107.0 148.0 106.0 125.0 108.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:186.20 Median:186.00 stddev:1.03 (0.6%) stddev-sans-first:0.87 10:36:26 INFO - PROCESS | 1672 | Values: 188.0 186.0 187.0 186.0 184.0 186.0 186.0 187.0 186.0 186.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:67.60 Median:65.50 stddev:11.54 (17.6%) stddev-sans-first:11.17 10:36:26 INFO - PROCESS | 1672 | Values: 81.0 68.0 59.0 93.0 63.0 59.0 61.0 60.0 73.0 59.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.00 stddev:7.83 (10.3%) stddev-sans-first:7.48 10:36:26 INFO - PROCESS | 1672 | Values: 85.0 70.0 87.0 70.0 70.0 69.0 82.0 69.0 68.0 83.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:88.00 stddev:15.90 (18.1%) stddev-sans-first:16.63 10:36:26 INFO - PROCESS | 1672 | Values: 88.0 88.0 88.0 87.0 92.0 135.0 113.0 89.0 89.0 87.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:95.00 stddev:17.75 (18.7%) stddev-sans-first:18.68 10:36:26 INFO - PROCESS | 1672 | Values: 95.0 94.0 94.0 95.0 93.0 97.0 95.0 95.0 105.0 151.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:84.50 stddev:19.04 (22.5%) stddev-sans-first:19.91 10:36:26 INFO - PROCESS | 1672 | Values: 86.0 87.0 84.0 84.0 84.0 96.0 128.0 133.0 85.0 84.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:63.50 stddev:2.50 (3.9%) stddev-sans-first:2.57 10:36:26 INFO - PROCESS | 1672 | Values: 60.0 58.0 58.0 60.0 64.0 62.0 63.0 64.0 64.0 64.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:2.91 (4.9%) stddev-sans-first:2.93 10:36:26 INFO - PROCESS | 1672 | Values: 57.0 64.0 56.0 65.0 61.0 58.0 59.0 59.0 59.0 58.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:85.00 stddev:3.06 (3.6%) stddev-sans-first:2.82 10:36:26 INFO - PROCESS | 1672 | Values: 88.0 81.0 87.0 85.0 83.0 82.0 86.0 82.0 78.0 85.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:135.00 stddev:20.56 (15.2%) stddev-sans-first:21.63 10:36:26 INFO - PROCESS | 1672 | Values: 135.0 145.0 121.0 133.0 132.0 135.0 133.0 132.0 186.0 173.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:161.00 stddev:1.62 (1.0%) stddev-sans-first:1.72 10:36:26 INFO - PROCESS | 1672 | Values: 161.0 162.0 163.0 161.0 160.0 161.0 161.0 164.0 158.0 161.0 10:36:26 INFO - PROCESS | 1672 | 10:36:26 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:80.00 stddev:10.86 (13.6%) stddev-sans-first:9.83 10:36:26 INFO - PROCESS | 1672 | Values: 58.0 79.0 85.0 56.0 64.0 76.0 75.0 83.0 81.0 84.0 10:36:26 INFO - PROCESS | 1672 | -------- Summary: end -------- 10:36:26 INFO - PROCESS | 1672 | 10:36:27 INFO - TEST-INFO | 1672: exit 0 10:36:27 INFO - TEST-OK | kraken | took 59349ms 10:36:27 INFO - SUITE-END | took 1177s 10:36:27 INFO - Completed test suite (00:19:37) 10:36:27 INFO - javascript benchmark 10:36:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7910.161728698684, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3496.3182179475, 3522.33075715614, 3523.1016090896824, 3560.6617371349744, 3567.0547304964175, 5725.312962444507, 6071.817845464632, 6126.540509461792, 6127.908081378778, 6140.447359737623, 6065.939340606593, 6132.417420345071, 6148.415900489457, 6158.107074474198, 6160.722767475463, 5366.114591092475, 5390.407055223922, 5397.27136836526, 5407.810726624569, 5523.613347065707, 5292.497212764782, 5301.310829592167, 5309.5752339812725, 5503.724813759316, 5510.090835012218, 5357.223202635615, 5431.0, 5442.727863606824, 5455.781768729246, 5461.918071228934, 5426.701531415771, 5431.34823821142, 5445.210444485548, 5457.836264912019, 5469.917951230695, 5315.335583052119, 5425.538829199483, 5431.402545719968, 5460.989716902386, 5488.588355873326, 5867.178594996704, 5885.5585831062845, 5898.7640494380175, 6126.908096378558, 6139.2632884053755, 6334.334894836068, 6407.583507072072, 6434.420902118832, 6456.709448074801, 6488.480921526267, 6476.579022363531, 6478.384553467413, 6507.089007538948, 6556.803295901131, 6559.8032059038305, 6005.249343832021, 6148.84627884302, 6214.5649804513705, 6249.218847644044, 6253.405926436982, 6466.482681385478, 6507.186601674791, 6544.0, 6595.307492713196, 6722.630255336004, 6375.904361434582, 6388.5528013039575, 6393.552451328455, 6395.136656551306, 6405.935940640534, 6367.554271201064, 6502.089707440962, 6515.315891831384, 6520.445762110274, 6523.119378883885, 6249.843753906098, 6311.873762524724, 6333.651649159248, 6395.872082558323, 6410.134631824737, 6434.453071488976, 6454.1609590752905, 6458.257300410513, 6458.54790164684, 6464.321246269074, 5146.948530514722, 5213.7393130343335, 5215.947840521622, 5227.424983251839, 5255.78976840922, 4446.9777651111535, 4537.956270057905, 4546.704464209815, 4667.579917807346, 4694.36626055485, 6158.291796443466, 6222.688865556704, 6237.96881015592, 6295.527835412362, 6310.463610593151, 6480.611163330215, 6493.51298652603, 6496.707648155844, 6504.219493660791, 6509.44669703071, 6274.247090349188, 6283.560150789493, 6283.968580157161, 6299.212598425197, 6318.4313411793155, 5490.149026900836, 5589.077802162679, 5622.91565626516, 5669.546436285087, 5700.201971723879, 6044.153818465506, 6762.966185169043, 6781.11845460087, 6803.25164231944, 6813.318668133147, 6194.0, 6429.357064293533, 6522.445592124533, 6524.641144737085, 6526.608403495806, 6457.0637257596445, 6761.830954226184, 6775.4240889524945, 6792.96603516999, 6803.013563033233, 6340.017297318923, 6376.776812811715, 6376.872462550816, 6418.4865210783955, 6441.935580644134, 6286.025666021774, 6330.873382532415, 6360.618362898241, 6469.320721324381, 6478.222613286436, 6219.689015549114, 6313.40022697843, 6334.778282760081, 6338.8732225354315, 6346.682665866781, 6268.435840774352, 6302.1492098566105, 6323.683815809283, 6347.90478142819, 6356.650384228912, 5951.404859514014, 6108.918862504554, 6143.419763699168, 6186.5360097993735, 6233.844153896189, 6314.084457753692, 6321.747130114744, 6334.714937827716, 6336.493080553637, 6342.714577843916, 6273.247210334789, 6274.623522588659, 6279.780207692709, 6281.59169653971, 6282.183316168869], "value": 5972.586823328745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.5107605594392, 776.2974508070195, 826.1490664615444, 899.7300809757076, 906.6509393883352, 29802.850985745066, 42630.78684606576, 43020.924476888096, 43100.0, 43238.56761432381, 44021.0, 44236.0, 44349.44776932808, 44388.0, 44576.554234457646, 8164.306033987118, 9746.074122958344, 9882.160016398615, 9893.90106098937, 9902.603895844157, 8130.55281959492, 11929.98595119416, 12073.698157546043, 12387.009039276882, 12418.254904705702, 12059.276443413422, 12151.696207594836, 12181.33002684852, 12186.451609677542, 12187.939060304687, 12102.757944841098, 12103.213291136048, 12152.696182595417, 12153.93923030388, 12157.45291461882, 11817.645470635895, 11863.2288901222, 11870.169088163831, 11874.584389546324, 11875.762484750343, 40979.7951010246, 41107.0, 41126.94365281724, 41141.58858411408, 41214.587854121375, 3985.6811455083525, 4021.3766866135797, 4066.471358723347, 4067.064575147703, 4076.388541718736, 29029.41941161186, 29196.68614912334, 30497.23756906073, 31332.530012049836, 31428.52857207144, 3239.352129574071, 3281.4585593377064, 3314.3371325734706, 3450.1202193440763, 3452.637473065317, 353997.23001385084, 358360.9999999974, 359129.0, 360284.0, 360862.0, 55335.0, 55404.99999999919, 55418.0, 55462.44537554573, 55576.72211638916, 57375.42624573784, 57436.11792997558, 57470.0, 57500.0, 57636.711816441486, 15159.242037898282, 15688.529344119695, 15692.372305107669, 15704.29330680122, 15717.607059823597, 12030.097742669335, 13279.601611951657, 13292.601221963163, 13296.002799789861, 13320.600381988555, 11831.467583958742, 11914.225575337576, 13072.019598529958, 13329.466821327233, 13503.987200959968, 5750.511206547407, 5781.335146458127, 5786.189933409326, 5795.507381872504, 5806.622569532967, 956.7177682583655, 964.3153361113548, 976.9218462522981, 985.737209835447, 988.4711679251566, 26522.734772652027, 28850.855745721135, 29274.414511709645, 29389.11832645024, 29457.263568410963, 1071.7588542577857, 1076.5101878645198, 1080.935143891369, 1084.6095405653957, 1115.866096068477, 11858.881411185777, 14803.407863685332, 14847.406103755944, 14859.925700371428, 15166.924165379103, 440755.0, 440976.0, 441011.0, 441432.0, 441757.0, 59233.0, 59266.70366648139, 59342.0, 59440.70279648574, 59524.80950380968, 1446.3274577321597, 1465.172177719585, 1483.6142602923317, 1495.6559991202084, 1508.2157278215266, 27196.864015679796, 28500.714992849807, 28798.0, 29118.854405727834, 29119.85440072786, 30646.387072258876, 38409.0, 38420.6157938417, 38421.0, 38475.2304953905, 379976.0, 415029.0, 430027.0, 431786.0, 433100.0, 30138.24654383658, 31048.068557943297, 31086.844565777028, 31127.688723113384, 31153.688463115985, 31705.841470792497, 33123.00630981074, 34540.30919381548, 40749.0, 42727.78636106924, 22708.205212817473, 28815.84736610512, 29084.56373154362, 29469.852650736608, 29940.550891736206, 29101.12696619105, 29110.0, 29176.416471670873, 29968.0, 30163.547546787253, 33274.168145796546, 33662.3267534643, 34259.828700856335, 34520.30959380849, 34606.480902786985, 29527.114186574436, 29702.405951881272, 30315.0, 30409.847950760104, 30495.847520762254, 22663.660045099667, 23956.92193851246, 24126.155584554457, 24458.51082978366, 24593.262202133963], "value": 19131.842144717768, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.5508319951477, 435.6231859441582, 435.82784800004, 444.2935732185824, 448.41705782482785, 19838.504037399056, 20004.799712017288, 21146.25988090417, 22299.44251393716, 22407.103715851368, 21180.046897889595, 23419.531609367845, 24007.279781606536, 24007.919643616035, 24271.029158833626, 6639.668016599187, 6907.13660792401, 6923.930760692379, 6933.47998900082, 7520.586367749772, 7718.2281771822645, 7833.394154198376, 7858.057033156029, 7860.292573668368, 7871.9212807872045, 7565.848683026336, 7803.02462192226, 7870.567118808463, 7873.566953817538, 7883.0, 6901.482388820833, 7645.3501452376895, 7664.616769161547, 7880.724174653917, 7964.641591128355, 5769.394213607595, 7678.884816727754, 7690.807729806744, 7714.4214183935865, 7749.883751743729, 26974.325641858915, 27276.04533841306, 27449.0, 27897.302567435774, 28257.85871070652, 2584.689837219527, 3040.6959304069637, 3215.6302025266928, 3426.7258619310396, 3513.2973405318785, 15505.457308994131, 15866.841331586767, 15962.680746385124, 16001.439949601709, 16970.40603578869, 2007.096806437109, 2016.1129103194548, 2020.5150763816725, 2029.725986991752, 2103.463616777727, 142413.28793355968, 164771.0, 169569.07111770735, 172333.0, 175389.12305438519, 15857.0, 20375.694364584542, 20472.69290960637, 20504.69242961357, 20555.280565180146, 32461.35077298441, 32869.67130328714, 32887.0, 32921.0, 32958.3408331832, 10315.742106447398, 10437.791244175074, 10679.733006674895, 10684.946575267073, 10849.0, 8873.378863479624, 8881.644734210688, 8886.022537520868, 9080.455172689659, 9115.498647574315, 7809.297163255334, 7863.52299025805, 8945.23965462943, 9433.05669433065, 9495.38280011797, 2467.7162126355697, 3727.8322475488667, 3748.5314335708035, 3913.4521167036637, 4143.446207672123, 868.2923417414913, 876.815868667587, 1087.9891201087887, 1091.0071834630608, 1130.6834086455806, 21688.891555542123, 23025.42436439069, 23059.07763689467, 23084.7691523086, 23108.422289442562, 805.6052534258172, 942.604106275366, 948.2271948362063, 957.3872721458272, 976.951152442375, 11613.883861161281, 11684.532618695326, 11844.644660660195, 11912.344821034927, 11925.642230733092, 809269.0, 809737.0, 810506.0, 817501.0, 818146.0, 149490.0, 150014.0, 150112.0, 153902.0, 155824.0, 1259.703969567151, 1303.1008604063356, 1366.631009627395, 1366.9111507751963, 1368.261138984957, 19590.90204548968, 20077.59844803125, 20591.382258532267, 20591.691124633442, 20796.58406831885, 64241.0, 64925.70148597096, 65388.01917971139, 65458.69082618416, 66197.33802662104, 85428.0, 88385.55807220923, 90847.54576227076, 94297.52851235699, 95203.04796951942, 11546.480408381474, 11848.111391645489, 12000.15998880105, 12176.939115304367, 12288.1398302118, 13604.115732477358, 13611.931940340235, 13949.3722782473, 13989.720205595628, 13993.020488565702, 10581.947090264499, 10689.984451477098, 11002.229843910849, 11012.229143960167, 11016.50425730828, 11013.2841365311, 11107.111431085656, 11361.147913906325, 11468.770624587629, 11481.770364592829, 11461.942690286494, 11869.228500147809, 11974.401279936143, 12202.572909948109, 12217.08371872131, 11761.353125577833, 11855.288682679067, 11883.465244063866, 12109.818352724893, 12175.512979480722, 11714.297142171155, 11806.704832379259, 11870.287782733063, 12016.218945768496, 12022.196670499496], "value": 12039.699574620323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1633.4283000949677, 1640.803103627565, 1662.9501114966547, 1667.8915870468416, 1685.334292954283, 10887.727806804834, 11614.18700690952, 11632.767344653103, 11640.825387619192, 11643.825342619846, 11257.662270131888, 11354.205205635588, 11602.709932251699, 11625.0, 11630.941845290761, 2449.5835707929696, 2520.2565243253284, 2521.8739063046864, 2521.9873900630473, 2522.8738563071865, 2464.4824586836694, 2488.6515887775727, 2526.9747302527016, 2534.6958364996226, 2536.7843733282693, 2389.545986262613, 2427.720812106613, 2430.2223288547584, 2472.888720007595, 2504.686914135733, 2459.523666926195, 2477.157766359446, 2477.2815883393796, 2500.6624105745673, 2509.5608268553024, 2449.8040156787415, 2476.4923190746035, 2482.466269751994, 2484.6521486991837, 2486.5151295497385, 11231.943840280828, 11530.76938461226, 11535.769284614345, 11545.942270288679, 11559.942200288944, 6043.879122417571, 6217.440430361289, 6219.315875253764, 6223.471004964584, 6238.1266622672865, 3736.084659258499, 3793.241351729665, 3859.8131770838672, 3869.342211823997, 3917.4319723640197, 1251.993740031303, 1271.961841144767, 1281.605829271438, 1313.159577870164, 1314.835645544307, 14310.928445357811, 14637.0, 15012.399504019826, 15846.920765396215, 16170.191490425432, 4428.800703968329, 5218.243354713545, 5238.057149713089, 5274.657147285414, 5289.074411977888, 13508.86491135076, 13520.729585408237, 13521.932390337985, 13523.256220907944, 13569.321533923265, 5329.733513324319, 5506.0, 6073.423024812636, 6193.721282542297, 6266.467350275278, 5504.422035686275, 5622.437756224426, 5622.690752008679, 5713.885722285531, 5720.170575266563, 4267.701260911706, 4286.7856607169515, 4320.805563749576, 4352.564743525622, 4376.846810361622, 1157.8406272302263, 1190.3750530971308, 1206.252123683322, 1207.209277922962, 1209.0629761934395, 956.2827879090682, 991.3952488981715, 997.680742162501, 1005.0853723111933, 1011.438651548383, 6595.142631457879, 6639.302873198341, 6709.29552397001, 6726.293739157318, 6789.93210067893, 1012.7012531303283, 1031.6073301043682, 1046.4872212615915, 1057.5082586597116, 1058.6506452870535, 4233.534311225762, 4281.507626622916, 4293.355996600535, 4332.285172946428, 4398.912021759547, 13769.967252456107, 14376.568702938928, 14395.352209150613, 14404.063735857137, 14414.990950633355, 13948.0, 14147.221902795445, 14282.3572939218, 14300.928495357457, 14334.928325358514, 10553.0, 10713.89286107129, 10721.731956701145, 10734.409607471664, 10741.83887241692, 6405.0, 6698.56459330142, 6723.899141512979, 6733.3939945405145, 6781.169084347297, 11331.603393881174, 12407.565735199225, 12759.298238596966, 12959.935200323938, 13061.542846000344, 12253.571125010581, 12262.0, 12802.295873726662, 12878.227306361649, 13275.07074504814, 8499.065102838806, 8841.734747957571, 8862.911370886466, 8869.600867960828, 8872.645094196418, 10372.481375931324, 10452.477376131012, 10520.947395263282, 10549.314294570828, 10578.259521833706, 8891.377603567687, 8963.596638151168, 9024.684136055206, 9128.406653567497, 9248.583813728534, 8724.78188045304, 8729.78175545591, 8762.956185219033, 8829.337799664921, 8892.066333034938, 7986.640601173078, 7987.081485628994, 8063.0, 8078.959605201936, 8081.191880811872, 5888.558358123244, 5890.057590785454, 5921.17103605487, 6072.0, 6119.938800611937, 5865.384134665798, 5932.139839723302, 6108.08378743192, 6230.345813689497, 6281.057841323655], "value": 5370.65797664481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.64620686210856, 322.22504875773757, 388.3999221203242, 390.0560643243354, 390.9550401703804, 4531.252343363345, 4789.209780386241, 5238.4499627539135, 5251.763670634821, 5262.894742105161, 4311.611954924057, 4730.4625996551085, 4854.621235594551, 5127.923191107598, 5144.691318520882, 4046.705054382599, 4155.33514637658, 4165.375193720935, 4224.577542245774, 4237.8516751913685, 3560.6825474574316, 3688.4467329900594, 3966.504186976628, 4232.957670423302, 4301.311790113583, 3802.7147963902817, 4219.556946520617, 4236.623097493311, 4240.300350442173, 4264.573542645741, 3380.898573042788, 3660.9450858237146, 3982.7012974026775, 4239.321708526621, 4263.744175349458, 3169.8415079246174, 3385.5272956264103, 3629.8185090745355, 3884.1454879926373, 3939.5666476687543, 3948.6643635291034, 4375.71557848742, 4746.644001699887, 4783.95216047842, 4942.1351263529095, 4644.581987621096, 4647.883802904955, 4763.928541071887, 4923.433805112386, 4925.482824303468, 5022.899542009176, 5450.5367043801325, 5475.893080189858, 5476.3702174250075, 5542.667439953616, 3865.8982190075694, 4067.1662309226836, 4388.605025547716, 4458.175237581057, 4488.259437192859, 4671.789769460383, 4945.332380128709, 5183.611229157828, 5701.187081437683, 6023.789167379121, 4028.67770578353, 4239.682023848224, 4469.486009108994, 4589.128065667513, 4636.235021221528, 3683.747525841227, 4015.7992100394863, 4201.957980420218, 4570.0, 4680.3915490985955, 4815.422149342101, 4829.331216511268, 4897.338859254026, 4937.21004639256, 4981.089844472072, 4764.618830493552, 5043.344365232496, 5427.782888684408, 5452.536534394541, 5498.010358135574, 1459.7737350710656, 1480.5484327280283, 1539.0996267183823, 1572.1274692545746, 1575.763635454668, 966.7341481092756, 1090.4493230918335, 1106.1372129738816, 1124.6288724720823, 1185.9051275897907, 4731.645126615517, 5117.162128403697, 5205.869853253624, 5220.843374698765, 5267.947320526746, 5073.695578265242, 5192.299039629677, 5681.375048744635, 5689.800856969986, 5720.342160651495, 4704.835330763407, 4708.858734237979, 4821.710697358169, 5190.013897359489, 5210.38478071799, 2570.3060173753356, 2728.0724553651853, 2735.6048415308337, 2950.215119852501, 2964.555316702512, 4693.647976401783, 4965.772248038804, 5316.973415132899, 5338.119210330329, 5765.913511297334, 5136.357955255593, 5151.948480515147, 5364.0, 5435.809746658848, 5538.667679939216, 5031.924521132113, 5152.562032227228, 5647.604667673306, 5658.227468540468, 5690.382424084967, 4740.6681532292405, 4983.700977941335, 5565.0, 5690.601657883908, 5700.429957004267, 4677.649176311858, 4710.740909250024, 4972.975135124301, 5123.538881500683, 5596.86007849807, 4855.984766321937, 5842.415758424124, 5872.265966754156, 5889.823305300848, 5936.753281810868, 4044.555098939173, 4474.963891429326, 4485.48416932057, 4515.571020753023, 4562.954370456253, 4741.83403580873, 5339.813106541253, 5601.859953501195, 5737.655740655574, 5804.94195058044, 4784.497627749175, 5422.864428389322, 5469.0, 5483.4516548344845, 5510.669359838423, 5082.618803589671, 5282.656627319212, 5509.429812503417, 5639.407862174413, 5639.464250896158, 5301.156661420439, 5352.839414817562, 5387.434319396407, 5408.161734931092, 5411.864703382447, 4875.0, 5279.86482906177, 5484.424135465878, 5512.0, 5515.4760297771645, 4201.852935147255, 4823.758812059453, 4982.800687972585, 5150.534217738477, 5336.519713225828], "value": 4132.759221368665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.6557216466508, 760.0309605253298, 875.8511053120969, 884.3986089459167, 908.9909100908989, 12162.574309899155, 13645.863541364582, 16918.984860908353, 17187.742183867238, 18043.548911277223, 16307.673846523063, 16502.917485412556, 16507.33970641176, 16553.337866485326, 16783.83216167838, 988.4612885977124, 990.3760630802584, 990.4107056301489, 991.603358656536, 991.9305648604601, 968.7432830299941, 992.6724181020282, 1006.3307900246327, 1008.7831116309992, 1026.240581969341, 1066.1151244467073, 1067.8451624514441, 1071.1109778883508, 1071.1109778883547, 1071.73742433104, 1064.3773392565358, 1064.542246833861, 1065.5897479470382, 1067.0556557446678, 1068.3269540189708, 707.762899428692, 751.3538357013019, 790.2097902097902, 790.3953475591177, 791.0112359550562, 17448.30206791727, 17565.736513952303, 17634.118294085372, 18111.63776724458, 18392.816071839377, 14643.34104965279, 14792.926035369863, 14880.70238595222, 14944.925275373553, 15276.159811210378, 10295.0, 11794.941025294904, 12232.81650775239, 12303.75392492154, 12540.81188782169, 3755.098776293697, 3761.2101458693705, 3790.2798468290935, 3889.747166434173, 3928.7839168845703, 101813.0, 103483.0, 103654.0, 107326.0, 111029.0, 11254.549818007352, 11450.37022963745, 11493.597724079616, 11703.239289446328, 11824.94087529574, 23663.171788987303, 24001.039958401467, 24031.639525407132, 24402.26793196207, 24424.26727198187, 7597.886031709529, 7882.211778822072, 8081.151479094728, 8118.472299300526, 8378.162183781573, 4495.179097766872, 4555.931661025087, 4574.382458368144, 4618.584327410482, 4698.107359601665, 3448.9997900609046, 3520.806355650464, 3529.6823285904393, 3531.434970404723, 3533.3993221152464, 734.9522281051715, 760.3840888880025, 763.1948294549304, 778.1479280188184, 786.9055713314325, 725.561035573481, 750.7785203365021, 754.5925200391837, 780.6448066129898, 780.9379244227846, 9517.619295228113, 9845.60617575289, 9957.253206009433, 10467.476626168662, 10879.184061195283, 726.498715886037, 739.3973911262304, 744.244591739385, 744.5941961630924, 752.762879692895, 4834.299026641117, 5023.02051100032, 5069.974650126799, 5344.43883392246, 5536.806211782568, 79420.60289698515, 94314.0, 103018.96981030244, 105103.2982603194, 105417.0, 73394.0, 84381.57809210915, 91987.0, 92605.51761376773, 92974.0, 81302.99999999882, 81802.0, 83375.583122084, 83406.0, 83819.0, 11094.722631934266, 11140.178766077892, 11368.374739389414, 11474.655760327203, 11525.884741152817, 46390.30414543717, 49167.0, 51155.744221278655, 51966.74016629893, 52840.47159528509, 56905.0, 65100.674496627216, 66313.67372652616, 67716.64566708737, 68524.31475685179, 12516.0, 12590.685232869251, 12779.36103194818, 12917.160384574972, 13043.608691739262, 15408.0, 15417.845821541641, 15469.922650386676, 16322.591935201715, 16514.504564863073, 14386.712265754417, 14406.207658578878, 14517.346719397863, 15019.0, 15036.17301048409, 13610.523631672844, 14362.066465680115, 15062.020968637004, 15352.846471535142, 15423.305951232445, 12562.937185314015, 12709.745805084032, 12953.481860725842, 12970.0, 12978.480860765841, 10039.96897929966, 10445.216608754527, 10684.0, 11077.723056923642, 11190.384528850665, 11065.5020524075, 11867.58463453775, 11952.76094478088, 12111.515539378326, 12413.379331033231], "value": 8021.720440744312, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1443.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 114.0, 110.0, 108.0, 115.0, 115.0, 118.0, 117.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 134.0, 161.0, 109.0, 107.0, 148.0, 106.0, 125.0, 108.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 186.0, 187.0, 186.0, 184.0, 186.0, 186.0, 187.0, 186.0, 186.0], "value": 186.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 68.0, 59.0, 93.0, 63.0, 59.0, 61.0, 60.0, 73.0, 59.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 87.0, 70.0, 70.0, 69.0, 82.0, 69.0, 68.0, 83.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 87.0, 92.0, 135.0, 113.0, 89.0, 89.0, 87.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 93.0, 97.0, 95.0, 95.0, 105.0, 151.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 84.0, 84.0, 84.0, 96.0, 128.0, 133.0, 85.0, 84.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 60.0, 64.0, 62.0, 63.0, 64.0, 64.0, 64.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 56.0, 65.0, 61.0, 58.0, 59.0, 59.0, 59.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 87.0, 85.0, 83.0, 82.0, 86.0, 82.0, 78.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 145.0, 121.0, 133.0, 132.0, 135.0, 133.0, 132.0, 186.0, 173.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 160.0, 161.0, 161.0, 164.0, 158.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 79.0, 85.0, 56.0, 64.0, 76.0, 75.0, 83.0, 81.0, 84.0], "value": 74.1, "unit": "score"}]}]} 10:36:27 INFO - Return code: 0 10:36:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:36:27 INFO - # TBPL SUCCESS # 10:36:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:36:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:36:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:36:27 INFO - 2017-05-12 10:36:27.958 screenresolution[1676:9988] starting screenresolution argv=screenresolution get 10:36:27 INFO - 2017-05-12 10:36:27.961 screenresolution[1676:9988] Display 0: 1600x1200x32@60 10:36:27 INFO - 2017-05-12 10:36:27.967 screenresolution[1677:9991] starting screenresolution argv=screenresolution list 10:36:27 INFO - Available Modes on Display 0 10:36:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:36:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:36:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:36:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:36:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:36:28 INFO - Intel Iris: 10:36:28 INFO - Chipset Model: Intel Iris 10:36:28 INFO - Type: GPU 10:36:28 INFO - Bus: Built-In 10:36:28 INFO - VRAM (Dynamic, Max): 1536 MB 10:36:28 INFO - Vendor: Intel (0x8086) 10:36:28 INFO - Device ID: 0x0a2e 10:36:28 INFO - Revision ID: 0x0009 10:36:28 INFO - Displays: 10:36:28 INFO - Display: 10:36:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:36:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:36:28 INFO - Display Serial Number: 1600x1200 60 10:36:28 INFO - Main Display: Yes 10:36:28 INFO - Mirror: Off 10:36:28 INFO - Online: Yes 10:36:28 INFO - Rotation: Supported 10:36:28 INFO - Return code: 0 10:36:28 INFO - Running post-action listener: _resource_record_post_action 10:36:28 INFO - [mozharness: 2017-05-12 17:36:28.202809Z] Finished run-tests step (success) 10:36:28 INFO - Running post-run listener: _resource_record_post_run 10:36:28 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 36824064; Write bytes: 421135360; Read time: 380; Write time: 2782 10:36:28 INFO - TinderboxPrint: CPU usage
53.8% 10:36:28 INFO - TinderboxPrint: I/O read bytes / time
36,824,064 / 380 10:36:28 INFO - TinderboxPrint: I/O write bytes / time
421,135,360 / 2,782 10:36:28 INFO - TinderboxPrint: CPU idle
2,207.4 (46.2%) 10:36:28 INFO - TinderboxPrint: CPU system
952.5 (20.0%) 10:36:28 INFO - TinderboxPrint: CPU user
1,613.9 (33.8%) 10:36:28 INFO - TinderboxPrint: Swap in / out
158,429,184 / 0 10:36:28 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119607808; Write bytes: 115202048; Read time: 10911; Write time: 362 10:36:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34653184; Write bytes: 294865920; Read time: 350; Write time: 2384 10:36:28 INFO - Running post-run listener: _upload_blobber_files 10:36:28 INFO - Blob upload gear active. 10:36:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:36:28 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 10:36:28 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'] 10:36:28 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 10:36:29 INFO - (blobuploader) - INFO - Open directory for files ... 10:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:36:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:36:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:36:30 INFO - (blobuploader) - INFO - Done attempting. 10:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:36:31 INFO - (blobuploader) - INFO - Done attempting. 10:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:36:32 INFO - (blobuploader) - INFO - Done attempting. 10:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:36:34 INFO - (blobuploader) - INFO - Done attempting. 10:36:34 INFO - (blobuploader) - INFO - Iteration through files over. 10:36:34 INFO - Return code: 0 10:36:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:36:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:36:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b76b63804539bb3e13f5779b02339b5cf75673dd43ea2abe7c5bb0e6e5c93a389275811583f38ec45bfd8562354a04bb79429a8f743f9b86e22e9e1927c360e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c64a51227f4d9dbb50dd0ecf080428cdfa14fc9cb44f97304a3ce8f2ca869254884c2aebe261242989ad09a98196d02a9b0d86089ca99d8a832774abaa44e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/365280818c7aef87ea9f3fa0f8ad7e5290aad98e7794f0c99585759e54b847478095113277bac4177a22bf8f069791c252836cf42df8cd137cf9ecf075fb62af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d148c4ba741c58b0364da03b77681b369f480f56555765e06b5ee3d22fc1ce91bf77c6acda1622e18fb7d9d8b55c45b5d581e944665f90d6f6ada42af316e66"} 10:36:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:36:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:36:34 INFO - Contents: 10:36:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b76b63804539bb3e13f5779b02339b5cf75673dd43ea2abe7c5bb0e6e5c93a389275811583f38ec45bfd8562354a04bb79429a8f743f9b86e22e9e1927c360e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c64a51227f4d9dbb50dd0ecf080428cdfa14fc9cb44f97304a3ce8f2ca869254884c2aebe261242989ad09a98196d02a9b0d86089ca99d8a832774abaa44e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/365280818c7aef87ea9f3fa0f8ad7e5290aad98e7794f0c99585759e54b847478095113277bac4177a22bf8f069791c252836cf42df8cd137cf9ecf075fb62af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d148c4ba741c58b0364da03b77681b369f480f56555765e06b5ee3d22fc1ce91bf77c6acda1622e18fb7d9d8b55c45b5d581e944665f90d6f6ada42af316e66"} 10:36:34 INFO - Running post-run listener: copy_logs_to_upload_dir 10:36:34 INFO - Copying logs to upload dir... 10:36:34 INFO - mkdir: /builds/slave/test/build/upload/logs 10:36:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.655727 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-12 10:36:34.173495) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:36:34.176750) ========= 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.8CqXne6aPG/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.MDNlvzyFc0/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/7b76b63804539bb3e13f5779b02339b5cf75673dd43ea2abe7c5bb0e6e5c93a389275811583f38ec45bfd8562354a04bb79429a8f743f9b86e22e9e1927c360e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c64a51227f4d9dbb50dd0ecf080428cdfa14fc9cb44f97304a3ce8f2ca869254884c2aebe261242989ad09a98196d02a9b0d86089ca99d8a832774abaa44e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/365280818c7aef87ea9f3fa0f8ad7e5290aad98e7794f0c99585759e54b847478095113277bac4177a22bf8f069791c252836cf42df8cd137cf9ecf075fb62af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d148c4ba741c58b0364da03b77681b369f480f56555765e06b5ee3d22fc1ce91bf77c6acda1622e18fb7d9d8b55c45b5d581e944665f90d6f6ada42af316e66"} build_url:https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/ft2Ql4CyQyeb6Va0wMFEjg/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/7b76b63804539bb3e13f5779b02339b5cf75673dd43ea2abe7c5bb0e6e5c93a389275811583f38ec45bfd8562354a04bb79429a8f743f9b86e22e9e1927c360e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c64a51227f4d9dbb50dd0ecf080428cdfa14fc9cb44f97304a3ce8f2ca869254884c2aebe261242989ad09a98196d02a9b0d86089ca99d8a832774abaa44e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/365280818c7aef87ea9f3fa0f8ad7e5290aad98e7794f0c99585759e54b847478095113277bac4177a22bf8f069791c252836cf42df8cd137cf9ecf075fb62af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d148c4ba741c58b0364da03b77681b369f480f56555765e06b5ee3d22fc1ce91bf77c6acda1622e18fb7d9d8b55c45b5d581e944665f90d6f6ada42af316e66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:36:34.210402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:36:34.210724) ========= 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.8CqXne6aPG/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.MDNlvzyFc0/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005173 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:36:34.259011) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:36:34.259343) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:36:34.259645) ========= ========= Total master_lag: 0.17 =========