builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0305 starttime: 1495099716.14 results: success (0) buildid: 20170518010620 builduid: 54e22d892463456187fa29de05278853 revision: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.135438) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.135891) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.136178) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.162001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.162381) ========= 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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-18 02:28:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.7M=0.001s 2017-05-18 02:28:36 (17.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138175 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.584258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.584649) ========= 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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.027703 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:36.635477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-18 02:28:36.635826) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 --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 a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-18 02:28:36,747 truncating revision to first 12 chars 2017-05-18 02:28:36,748 Setting DEBUG logging. 2017-05-18 02:28:36,748 attempt 1/10 2017-05-18 02:28:36,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a3bc13b60d41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 02:28:38,240 unpacking tar archive at: mozilla-beta-a3bc13b60d41/testing/mozharness/ program finished with exit code 0 elapsedTime=1.787455 ========= master_lag: 0.39 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-18 02:28:38.817536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:38.822834) ========= script_repo_revision: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:38.823235) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:38.823499) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 02:28:39.231197) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-18 02:28:39.231465) ========= /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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:28:39 INFO - MultiFileLogger online at 20170518 02:28:39 in /builds/slave/test 02:28:39 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 02:28:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:39 INFO - {'append_to_log': False, 02:28:39 INFO - 'base_work_dir': '/builds/slave/test', 02:28:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:28:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:39 INFO - 'branch': 'Mozilla-Beta', 02:28:39 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:39 INFO - 'config_files': ('talos/mac_config.py',), 02:28:39 INFO - 'default_actions': ('clobber', 02:28:39 INFO - 'read-buildbot-config', 02:28:39 INFO - 'download-and-extract', 02:28:39 INFO - 'populate-webroot', 02:28:39 INFO - 'create-virtualenv', 02:28:39 INFO - 'install', 02:28:39 INFO - 'run-tests'), 02:28:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:39 INFO - 'download_minidump_stackwalk': True, 02:28:39 INFO - 'download_symbols': 'ondemand', 02:28:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:28:39 INFO - 'tooltool.py': '/tools/tooltool.py', 02:28:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:28:39 INFO - '/tools/misc-python/virtualenv.py')}, 02:28:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:39 INFO - 'gecko_profile': False, 02:28:39 INFO - 'gecko_profile_interval': 0, 02:28:39 INFO - 'installer_path': 'installer.exe', 02:28:39 INFO - 'log_level': 'info', 02:28:39 INFO - 'log_name': 'talos', 02:28:39 INFO - 'log_to_console': True, 02:28:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:28:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:28:39 INFO - 'opt_config_files': (), 02:28:39 INFO - 'pip_index': False, 02:28:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:39 INFO - 'cmd': ('bash', 02:28:39 INFO - '-c', 02:28:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:39 INFO - 'enabled': True, 02:28:39 INFO - 'halt_on_failure': False, 02:28:39 INFO - 'name': 'check_screen_resolution'},), 02:28:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:39 INFO - 'cmd': ('bash', 02:28:39 INFO - '-c', 02:28:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:39 INFO - 'enabled': True, 02:28:39 INFO - 'halt_on_failure': False, 02:28:39 INFO - 'name': 'check_screen_resolution'},), 02:28:39 INFO - 'run_cmd_checks_enabled': True, 02:28:39 INFO - 'suite': 'dromaeojs', 02:28:39 INFO - 'system_bits': '32', 02:28:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:39 INFO - 'title': 't-yosemite-r7-0305', 02:28:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:39 INFO - 'use_talos_json': True, 02:28:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:39 INFO - 'work_dir': 'build'} 02:28:39 INFO - [mozharness: 2017-05-18 09:28:39.448041Z] Running clobber step. 02:28:39 INFO - Running pre-action listener: _resource_record_pre_action 02:28:39 INFO - Running main action method: clobber 02:28:39 INFO - rmtree: /builds/slave/test/build 02:28:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:41 INFO - Running post-action listener: _resource_record_post_action 02:28:41 INFO - [mozharness: 2017-05-18 09:28:41.834510Z] Finished clobber step (success) 02:28:41 INFO - [mozharness: 2017-05-18 09:28:41.834680Z] Running read-buildbot-config step. 02:28:41 INFO - Running pre-action listener: _resource_record_pre_action 02:28:41 INFO - Running main action method: read_buildbot_config 02:28:41 INFO - Using buildbot properties: 02:28:41 INFO - { 02:28:41 INFO - "project": "", 02:28:41 INFO - "product": "firefox", 02:28:41 INFO - "script_repo_revision": "production", 02:28:41 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 02:28:41 INFO - "repository": "", 02:28:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 02:28:41 INFO - "buildid": "20170518010620", 02:28:41 INFO - "pgo_build": "False", 02:28:41 INFO - "basedir": "/builds/slave/test", 02:28:41 INFO - "buildnumber": 3, 02:28:41 INFO - "slavename": "t-yosemite-r7-0305", 02:28:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:28:41 INFO - "platform": "yosemite_r7", 02:28:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 02:28:41 INFO - "branch": "mozilla-beta", 02:28:41 INFO - "repo_path": "releases/mozilla-beta", 02:28:41 INFO - "revision": "a3bc13b60d416ad4a0dd42820ede5c0d69c288a0", 02:28:41 INFO - "stage_platform": "macosx64", 02:28:41 INFO - "builduid": "54e22d892463456187fa29de05278853", 02:28:41 INFO - "slavebuilddir": "test" 02:28:41 INFO - } 02:28:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 02:28:41 INFO - Running post-action listener: _resource_record_post_action 02:28:41 INFO - [mozharness: 2017-05-18 09:28:41.838119Z] Finished read-buildbot-config step (success) 02:28:41 INFO - [mozharness: 2017-05-18 09:28:41.838253Z] Running download-and-extract step. 02:28:41 INFO - Running pre-action listener: _resource_record_pre_action 02:28:41 INFO - Running main action method: download_and_extract 02:28:41 INFO - mkdir: /builds/slave/test/build/tests 02:28:41 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')]} 02:28:41 INFO - trying https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 02:28:41 INFO - Downloading https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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 02:28:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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 02:28:43 INFO - Downloaded 1287 bytes. 02:28:43 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:28:43 INFO - Using the following test package requirements: 02:28:43 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 02:28:43 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:28:43 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 02:28:43 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 02:28:43 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:28:43 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 02:28:43 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:28:43 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 02:28:43 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 02:28:43 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 02:28:43 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:28:43 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 02:28:43 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 02:28:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:28:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:28:43 INFO - Fetch https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:28:44 INFO - Expected file size: 51225509 02:28:44 INFO - Obtained file size: 51225509 02:29:00 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 02:29:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:29:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:29:00 INFO - Fetch https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:29:02 INFO - Expected file size: 51225509 02:29:02 INFO - Obtained file size: 51225509 02:29:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 02:29:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 02:29:17 INFO - Fetch https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 02:29:17 INFO - Expected file size: 14404449 02:29:17 INFO - Obtained file size: 14404449 02:29: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')]} 02:29:18 INFO - trying https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:29:18 INFO - Downloading https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 02:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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 02:29:20 INFO - Downloaded 59542855 bytes. 02:29:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:29:20 INFO - mkdir: /builds/slave/test/properties 02:29:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:20 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:20 INFO - Contents: 02:29:20 INFO - build_url:https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:29:21 INFO - Running post-action listener: _resource_record_post_action 02:29:21 INFO - Running post-action listener: set_extra_try_arguments 02:29:21 INFO - [mozharness: 2017-05-18 09:29:21.330100Z] Finished download-and-extract step (success) 02:29:21 INFO - [mozharness: 2017-05-18 09:29:21.330261Z] Running populate-webroot step. 02:29:21 INFO - Running pre-action listener: _resource_record_pre_action 02:29:21 INFO - Running main action method: populate_webroot 02:29:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:29:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:29:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:29:21 INFO - '--tppagecycles', 02:29:21 INFO - '7'], 02:29:21 INFO - 'tests': ['tsvgm']}}, 02:29:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['damp', 'tps']}, 02:29:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'tests': ['damp', 'tps']}, 02:29:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['dromaeo_dom']}, 02:29:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['basic_compositor_video']}, 02:29:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:29:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['a11yr', 02:29:21 INFO - 'ts_paint', 02:29:21 INFO - 'tpaint', 02:29:21 INFO - 'sessionrestore', 02:29:21 INFO - 'sessionrestore_no_auto_restore', 02:29:21 INFO - 'tabpaint']}, 02:29:21 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:21 INFO - 'ts_paint', 02:29:21 INFO - 'tpaint', 02:29:21 INFO - 'sessionrestore', 02:29:21 INFO - 'sessionrestore_no_auto_restore', 02:29:21 INFO - 'tabpaint']}, 02:29:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:29:21 INFO - 'ts_paint', 02:29:21 INFO - 'tpaint', 02:29:21 INFO - 'sessionrestore', 02:29:21 INFO - 'sessionrestore_no_auto_restore', 02:29:21 INFO - 'tabpaint']}, 02:29:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:29:21 INFO - 'ts_paint', 02:29:21 INFO - 'tpaint', 02:29:21 INFO - 'sessionrestore', 02:29:21 INFO - 'sessionrestore_no_auto_restore', 02:29:21 INFO - 'tabpaint']}, 02:29:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['a11yr', 02:29:21 INFO - 'ts_paint', 02:29:21 INFO - 'tpaint', 02:29:21 INFO - 'sessionrestore', 02:29:21 INFO - 'sessionrestore_no_auto_restore', 02:29:21 INFO - 'tabpaint']}, 02:29:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['a11yr', 02:29:21 INFO - 'ts_paint', 02:29:21 INFO - 'tpaint', 02:29:21 INFO - 'sessionrestore', 02:29:21 INFO - 'sessionrestore_no_auto_restore', 02:29:21 INFO - 'tabpaint']}, 02:29:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['tsvgx', 02:29:21 INFO - 'tsvgr_opacity', 02:29:21 INFO - 'tart', 02:29:21 INFO - 'tscrollx', 02:29:21 INFO - 'cart', 02:29:21 INFO - 'tsvg_static']}, 02:29:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:21 INFO - 'tsvgr_opacity', 02:29:21 INFO - 'tart', 02:29:21 INFO - 'tscrollx', 02:29:21 INFO - 'cart', 02:29:21 INFO - 'tsvg_static']}, 02:29:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'talos_options': ['--disable-e10s'], 02:29:21 INFO - 'tests': ['tp5o']}, 02:29:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:29:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'talos_options': ['--disable-e10s', 02:29:21 INFO - '--xperf_path', 02:29:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:21 INFO - 'tests': ['tp5n']}, 02:29:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:29:21 INFO - 'talos_options': ['--xperf_path', 02:29:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:21 INFO - 'tests': ['tp5n']}}, 02:29:21 INFO - 'talos.zip': {'path': '', 02:29:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:29:21 INFO - Running post-action listener: _resource_record_post_action 02:29:21 INFO - [mozharness: 2017-05-18 09:29:21.349400Z] Finished populate-webroot step (success) 02:29:21 INFO - [mozharness: 2017-05-18 09:29:21.349532Z] Running create-virtualenv step. 02:29:21 INFO - Running pre-action listener: _resource_record_pre_action 02:29:21 INFO - Running main action method: create_virtualenv 02:29:21 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:21 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 02:29:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:29:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:21 INFO - Using real prefix '/tools/python27' 02:29:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:22 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:25 INFO - Installing pip.................done. 02:29:25 INFO - Return code: 0 02:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:29:25 INFO - Reading from file tmpfile_stdout 02:29:25 INFO - Output received: 02:29:25 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) 02:29:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:25 INFO - 'HOME': '/Users/cltbld', 02:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:25 INFO - 'LOGNAME': 'cltbld', 02:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:25 INFO - 'MOZ_NO_REMOTE': '1', 02:29:25 INFO - 'NO_EM_RESTART': '1', 02:29:25 INFO - 'PAGER': '/bin/cat', 02:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:25 INFO - 'PWD': '/builds/slave/test', 02:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:25 INFO - 'SHELL': '/bin/bash', 02:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:25 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:25 INFO - 'USER': 'cltbld', 02:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:25 INFO - 'XPC_FLAGS': '0x0', 02:29:25 INFO - 'XPC_SERVICE_NAME': '0', 02:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:29:25 INFO - Cleaning up... 02:29:25 INFO - Return code: 0 02:29:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:25 INFO - 'HOME': '/Users/cltbld', 02:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:25 INFO - 'LOGNAME': 'cltbld', 02:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:25 INFO - 'MOZ_NO_REMOTE': '1', 02:29:25 INFO - 'NO_EM_RESTART': '1', 02:29:25 INFO - 'PAGER': '/bin/cat', 02:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:25 INFO - 'PWD': '/builds/slave/test', 02:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:25 INFO - 'SHELL': '/bin/bash', 02:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:25 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:25 INFO - 'USER': 'cltbld', 02:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:25 INFO - 'XPC_FLAGS': '0x0', 02:29:25 INFO - 'XPC_SERVICE_NAME': '0', 02:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:25 INFO - Downloading/unpacking psutil>=3.1.1 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:27 INFO - Installing collected packages: psutil 02:29:27 INFO - Running setup.py install for psutil 02:29:27 INFO - building 'psutil._psutil_osx' extension 02:29:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:29:28 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 02:29:28 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 02:29:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:29:28 INFO - building 'psutil._psutil_posix' extension 02:29:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:29:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:29:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:29:28 INFO - ^ 02:29:28 INFO - 1 warning generated. 02:29:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:29:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:28 INFO - Successfully installed psutil 02:29:28 INFO - Cleaning up... 02:29:29 INFO - Return code: 0 02:29:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29: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')]} 02:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:29 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 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:29 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 02:29:29 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 02:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:29 INFO - 'HOME': '/Users/cltbld', 02:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:29 INFO - 'LOGNAME': 'cltbld', 02:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:29 INFO - 'MOZ_NO_REMOTE': '1', 02:29:29 INFO - 'NO_EM_RESTART': '1', 02:29:29 INFO - 'PAGER': '/bin/cat', 02:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:29 INFO - 'PWD': '/builds/slave/test', 02:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:29 INFO - 'SHELL': '/bin/bash', 02:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:29 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:29 INFO - 'USER': 'cltbld', 02:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:29 INFO - 'XPC_FLAGS': '0x0', 02:29:29 INFO - 'XPC_SERVICE_NAME': '0', 02:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29:29 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 02:29:29 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 02:29:29 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 02:29:29 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 02:29:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:30 INFO - Installing collected packages: mozsystemmonitor 02:29:30 INFO - Running setup.py install for mozsystemmonitor 02:29:31 INFO - Successfully installed mozsystemmonitor 02:29:31 INFO - Cleaning up... 02:29:31 INFO - Return code: 0 02:29:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29:31 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')]} 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:31 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')]} 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:31 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 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:31 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 02:29:31 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 02:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:31 INFO - 'HOME': '/Users/cltbld', 02:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:31 INFO - 'LOGNAME': 'cltbld', 02:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:31 INFO - 'MOZ_NO_REMOTE': '1', 02:29:31 INFO - 'NO_EM_RESTART': '1', 02:29:31 INFO - 'PAGER': '/bin/cat', 02:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:31 INFO - 'PWD': '/builds/slave/test', 02:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:31 INFO - 'SHELL': '/bin/bash', 02:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:31 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:31 INFO - 'USER': 'cltbld', 02:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:31 INFO - 'XPC_FLAGS': '0x0', 02:29:31 INFO - 'XPC_SERVICE_NAME': '0', 02:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:31 INFO - Downloading/unpacking jsonschema==2.5.1 02:29: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 02:29: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 02:29: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 02:29: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 02:29:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:33 INFO - warning: no files found matching '*.txt' 02:29:33 INFO - no previously-included directories found matching 'build' 02:29:33 INFO - no previously-included directories found matching 'dist' 02:29:33 INFO - no previously-included directories found matching '.git*' 02:29:33 INFO - Installing collected packages: jsonschema, functools32 02:29:33 INFO - Running setup.py install for functools32 02:29:33 INFO - warning: no files found matching '*.txt' 02:29:33 INFO - no previously-included directories found matching 'build' 02:29:33 INFO - no previously-included directories found matching 'dist' 02:29:33 INFO - no previously-included directories found matching '.git*' 02:29:33 INFO - Successfully installed jsonschema functools32 02:29:33 INFO - Cleaning up... 02:29:33 INFO - Return code: 0 02:29:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29: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')]} 02:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:29:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:34 INFO - 'HOME': '/Users/cltbld', 02:29:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:34 INFO - 'LOGNAME': 'cltbld', 02:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:34 INFO - 'MOZ_NO_REMOTE': '1', 02:29:34 INFO - 'NO_EM_RESTART': '1', 02:29:34 INFO - 'PAGER': '/bin/cat', 02:29:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:34 INFO - 'PWD': '/builds/slave/test', 02:29:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:34 INFO - 'SHELL': '/bin/bash', 02:29:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:34 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:34 INFO - 'USER': 'cltbld', 02:29:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:34 INFO - 'XPC_FLAGS': '0x0', 02:29:34 INFO - 'XPC_SERVICE_NAME': '0', 02:29:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:34 INFO - Cleaning up... 02:29:34 INFO - Return code: 0 02:29:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:34 INFO - 'HOME': '/Users/cltbld', 02:29:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:34 INFO - 'LOGNAME': 'cltbld', 02:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:34 INFO - 'MOZ_NO_REMOTE': '1', 02:29:34 INFO - 'NO_EM_RESTART': '1', 02:29:34 INFO - 'PAGER': '/bin/cat', 02:29:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:34 INFO - 'PWD': '/builds/slave/test', 02:29:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:34 INFO - 'SHELL': '/bin/bash', 02:29:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:34 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:34 INFO - 'USER': 'cltbld', 02:29:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:34 INFO - 'XPC_FLAGS': '0x0', 02:29:34 INFO - 'XPC_SERVICE_NAME': '0', 02:29:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:35 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:36 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:37 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 02:29:37 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 02:29:37 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 02:29:37 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 02:29:37 INFO - Downloading docopt-0.6.1.tar.gz 02:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:37 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:37 INFO - Running setup.py install for blobuploader 02:29:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:37 INFO - Running setup.py install for requests 02:29:38 INFO - Running setup.py install for docopt 02:29:38 INFO - Successfully installed blobuploader requests docopt 02:29:38 INFO - Cleaning up... 02:29:38 INFO - Return code: 0 02:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:38 INFO - 'HOME': '/Users/cltbld', 02:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:38 INFO - 'LOGNAME': 'cltbld', 02:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:38 INFO - 'MOZ_NO_REMOTE': '1', 02:29:38 INFO - 'NO_EM_RESTART': '1', 02:29:38 INFO - 'PAGER': '/bin/cat', 02:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:38 INFO - 'PWD': '/builds/slave/test', 02:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:38 INFO - 'SHELL': '/bin/bash', 02:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:38 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:38 INFO - 'USER': 'cltbld', 02:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:38 INFO - 'XPC_FLAGS': '0x0', 02:29:38 INFO - 'XPC_SERVICE_NAME': '0', 02:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-WUcaaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:38 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-6JNfSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-U1K0Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-mcflnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-VknJJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-cL05dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-XNWiFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-XAyj30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-aS5rHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-TGevpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:39 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Wic8nK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-oW_jNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-4s7tXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-atsvOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Rg5Rm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-5gMjFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-7JLJph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:40 INFO - Running setup.py install for manifestparser 02:29:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:40 INFO - Running setup.py install for mozcrash 02:29:40 INFO - Running setup.py install for mozdebug 02:29:41 INFO - Running setup.py install for mozdevice 02:29:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Running setup.py install for mozfile 02:29:41 INFO - Running setup.py install for mozhttpd 02:29:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Running setup.py install for mozinfo 02:29:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Running setup.py install for mozInstall 02:29:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:41 INFO - Running setup.py install for mozleak 02:29:41 INFO - Running setup.py install for mozlog 02:29:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:42 INFO - Running setup.py install for moznetwork 02:29:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:42 INFO - Running setup.py install for mozprocess 02:29:42 INFO - Running setup.py install for mozprofile 02:29:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:42 INFO - Running setup.py install for mozrunner 02:29:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:42 INFO - Running setup.py install for mozscreenshot 02:29:42 INFO - Running setup.py install for moztest 02:29:43 INFO - Running setup.py install for mozversion 02:29:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:43 INFO - Cleaning up... 02:29:43 INFO - Return code: 0 02:29:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:43 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')]} 02:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:43 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')]} 02:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:43 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 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:43 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 02:29:43 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 02:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:43 INFO - 'HOME': '/Users/cltbld', 02:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:43 INFO - 'LOGNAME': 'cltbld', 02:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:43 INFO - 'MOZ_NO_REMOTE': '1', 02:29:43 INFO - 'NO_EM_RESTART': '1', 02:29:43 INFO - 'PAGER': '/bin/cat', 02:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:43 INFO - 'PWD': '/builds/slave/test', 02:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:43 INFO - 'SHELL': '/bin/bash', 02:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:43 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:43 INFO - 'USER': 'cltbld', 02:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:43 INFO - 'XPC_FLAGS': '0x0', 02:29:43 INFO - 'XPC_SERVICE_NAME': '0', 02:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-A9rR7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-kwgA8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Ldwj6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-oZARSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:43 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-4zCgXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:44 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)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-tMBlR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:44 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)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-wCoz_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:44 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)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-iVv8M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:44 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)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-tMhNhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:44 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)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-gJdBDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-WAI_bL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-PVF_7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-yTsg7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-xnbCzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-kpj0MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:45 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)) 02:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-DFLKJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:45 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)) 02:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-WduxI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:45 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)) 02:29:45 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)) 02:29:45 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)) 02:29:45 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)) 02:29:45 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)) 02:29:45 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)) 02:29:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:45 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 02:29:45 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 02:29:45 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 02:29:45 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 02:29:46 INFO - Downloading blessings-1.6.tar.gz 02:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:46 INFO - Installing collected packages: blessings 02:29:46 INFO - Running setup.py install for blessings 02:29:46 INFO - Successfully installed blessings 02:29:46 INFO - Cleaning up... 02:29:47 INFO - Return code: 0 02:29:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:47 INFO - Reading from file tmpfile_stdout 02:29:47 INFO - Current package versions: 02:29:47 INFO - blessings == 1.6 02:29:47 INFO - blobuploader == 1.2.4 02:29:47 INFO - docopt == 0.6.1 02:29:47 INFO - functools32 == 3.2.3-2 02:29:47 INFO - jsonschema == 2.5.1 02:29:47 INFO - manifestparser == 1.1 02:29:47 INFO - mozInstall == 1.12 02:29:47 INFO - mozcrash == 1.0 02:29:47 INFO - mozdebug == 0.1 02:29:47 INFO - mozdevice == 0.49 02:29:47 INFO - mozfile == 1.2 02:29:47 INFO - mozhttpd == 0.7 02:29:47 INFO - mozinfo == 0.9 02:29:47 INFO - mozleak == 0.1 02:29:47 INFO - mozlog == 3.4 02:29:47 INFO - moznetwork == 0.27 02:29:47 INFO - mozprocess == 0.25 02:29:47 INFO - mozprofile == 0.28 02:29:47 INFO - mozrunner == 6.13 02:29:47 INFO - mozscreenshot == 0.1 02:29:47 INFO - mozsystemmonitor == 0.3 02:29:47 INFO - moztest == 0.8 02:29:47 INFO - mozversion == 1.4 02:29:47 INFO - psutil == 3.1.1 02:29:47 INFO - requests == 1.2.3 02:29:47 INFO - wsgiref == 0.1.2 02:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:47 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 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:47 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 02:29:47 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 02:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:47 INFO - 'HOME': '/Users/cltbld', 02:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:47 INFO - 'LOGNAME': 'cltbld', 02:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:47 INFO - 'MOZ_NO_REMOTE': '1', 02:29:47 INFO - 'NO_EM_RESTART': '1', 02:29:47 INFO - 'PAGER': '/bin/cat', 02:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:47 INFO - 'PWD': '/builds/slave/test', 02:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:47 INFO - 'SHELL': '/bin/bash', 02:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:47 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:47 INFO - 'USER': 'cltbld', 02:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:47 INFO - 'XPC_FLAGS': '0x0', 02:29:47 INFO - 'XPC_SERVICE_NAME': '0', 02:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:47 INFO - Cleaning up... 02:29:47 INFO - Return code: 0 02:29:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b644b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b647768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd147ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, '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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 02:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:47 INFO - 'HOME': '/Users/cltbld', 02:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:47 INFO - 'LOGNAME': 'cltbld', 02:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:47 INFO - 'MOZ_NO_REMOTE': '1', 02:29:47 INFO - 'NO_EM_RESTART': '1', 02:29:47 INFO - 'PAGER': '/bin/cat', 02:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:47 INFO - 'PWD': '/builds/slave/test', 02:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:47 INFO - 'SHELL': '/bin/bash', 02:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:29:47 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:47 INFO - 'USER': 'cltbld', 02:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:47 INFO - 'XPC_FLAGS': '0x0', 02:29:47 INFO - 'XPC_SERVICE_NAME': '0', 02:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:47 INFO - Cleaning up... 02:29:47 INFO - Return code: 0 02:29:47 INFO - Running post-action listener: _resource_record_post_action 02:29:47 INFO - Running post-action listener: _start_resource_monitoring 02:29:47 INFO - Starting resource monitoring. 02:29:47 INFO - [mozharness: 2017-05-18 09:29:47.864085Z] Finished create-virtualenv step (success) 02:29:47 INFO - [mozharness: 2017-05-18 09:29:47.865046Z] Running install step. 02:29:47 INFO - Running pre-action listener: _resource_record_pre_action 02:29:47 INFO - Running main action method: install 02:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:48 INFO - Reading from file tmpfile_stdout 02:29:48 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:48 INFO - Reading from file tmpfile_stdout 02:29:48 INFO - Output received: 02:29:48 INFO - Usage: mozinstall [options] installer 02:29:48 INFO - Options: 02:29:48 INFO - -h, --help show this help message and exit 02:29:48 INFO - -d DEST, --destination=DEST 02:29:48 INFO - Directory to install application into. [default: 02:29:48 INFO - "/builds/slave/test"] 02:29:48 INFO - --app=APP Application being installed. [default: firefox] 02:29:48 INFO - mkdir: /builds/slave/test/build/application 02:29:48 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'] 02:29:48 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 02:30:03 INFO - Reading from file tmpfile_stdout 02:30:03 INFO - Output received: 02:30:03 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 02:30:03 INFO - Running post-action listener: _resource_record_post_action 02:30:03 INFO - [mozharness: 2017-05-18 09:30:03.184148Z] Finished install step (success) 02:30:03 INFO - [mozharness: 2017-05-18 09:30:03.184327Z] Running run-tests step. 02:30:03 INFO - Running pre-action listener: _resource_record_pre_action 02:30:03 INFO - Running main action method: run_tests 02:30:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:03 INFO - 2017-05-18 02:30:03.198 screenresolution[1628:7474] starting screenresolution argv=screenresolution get 02:30:03 INFO - 2017-05-18 02:30:03.201 screenresolution[1628:7474] Display 0: 1600x1200x32@60 02:30:03 INFO - 2017-05-18 02:30:03.207 screenresolution[1629:7476] starting screenresolution argv=screenresolution list 02:30:03 INFO - Available Modes on Display 0 02:30:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:03 INFO - Intel Iris: 02:30:03 INFO - Chipset Model: Intel Iris 02:30:03 INFO - Type: GPU 02:30:03 INFO - Bus: Built-In 02:30:03 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:03 INFO - Vendor: Intel (0x8086) 02:30:03 INFO - Device ID: 0x0a2e 02:30:03 INFO - Revision ID: 0x0009 02:30:03 INFO - Displays: 02:30:03 INFO - Display: 02:30:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:03 INFO - Display Serial Number: 1600x1200 60 02:30:03 INFO - Main Display: Yes 02:30:03 INFO - Mirror: Off 02:30:03 INFO - Online: Yes 02:30:03 INFO - Rotation: Supported 02:30:03 INFO - Return code: 0 02:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:30:03 INFO - Python 2.7.3 02:30:03 INFO - Return code: 0 02:30:03 INFO - grabbing minidump binary from tooltool 02:30:03 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')]} 02:30:03 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 0x10b422d30>, '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 0x10b349db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b417be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:30:03 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 02:30:03 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 02:30:03 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 02:30:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:30:03 INFO - Return code: 0 02:30:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:30:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:30:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:30:03 INFO - ENV: RUST_BACKTRACE is now 1 02:30:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:30:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:30:03 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/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 02:30:03 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/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0305 --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 02:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ofm85BZJQ8/Render', 02:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:03 INFO - 'HOME': '/Users/cltbld', 02:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:03 INFO - 'LOGNAME': 'cltbld', 02:30:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:03 INFO - 'MOZ_NO_REMOTE': '1', 02:30:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:03 INFO - 'NO_EM_RESTART': '1', 02:30:03 INFO - 'PAGER': '/bin/cat', 02:30:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:03 INFO - 'PWD': '/builds/slave/test', 02:30:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:03 INFO - 'RUST_BACKTRACE': '1', 02:30:03 INFO - 'SHELL': '/bin/bash', 02:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYkmS0Ucxm/Listeners', 02:30:03 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 02:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:03 INFO - 'USER': 'cltbld', 02:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:03 INFO - 'XPC_FLAGS': '0x0', 02:30:03 INFO - 'XPC_SERVICE_NAME': '0', 02:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:03 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/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 02:30:03 INFO - mozversion application_buildid: 20170518010620 02:30:03 INFO - mozversion application_changeset: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 02:30:03 INFO - mozversion application_display_name: Firefox 02:30:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:30:03 INFO - mozversion application_name: Firefox 02:30:03 INFO - mozversion application_remotingname: firefox 02:30:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:30:03 INFO - mozversion application_vendor: Mozilla 02:30:03 INFO - mozversion application_version: 54.0 02:30:03 INFO - mozversion platform_buildid: 20170518010620 02:30:03 INFO - mozversion platform_changeset: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 02:30:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:30:03 INFO - mozversion platform_version: 54.0 02:30:03 INFO - using testdate: 1495099803 02:30:03 INFO - actual date: 1495099803 02:30:03 INFO - starting webserver on 'localhost:49224' 02:30:03 INFO - SUITE-START | Running 2 tests 02:30:03 INFO - TEST-START | dromaeo_css 02:30:03 INFO - operating with platform_type : mac_ 02:30:03 INFO - Initialising browser for dromaeo_css test... 02:30:03 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmp_CRbSv/profile http://localhost:49224/getInfo.html) 02:30:06 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 02:30:06 INFO - PROCESS | 1639 | colorDepth:24 02:30:06 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 02:30:06 INFO - PROCESS | 1639 | __metrics 02:30:06 INFO - PROCESS | 1639 | 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" 02:30:06 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:30:07 INFO - TEST-INFO | 1639: exit 0 02:30:07 INFO - Browser initialized. 02:30:07 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:07 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmp_CRbSv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:18 INFO - PROCESS | 1642 | RSS: Main: 168943616 02:30:18 INFO - PROCESS | 1642 | 02:33:23 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 02:33:24 INFO - PROCESS | 1642 | RSS: Main: 313241600 02:33:24 INFO - PROCESS | 1642 | 02:36:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 02:36:28 INFO - PROCESS | 1642 | RSS: Main: 294367232 02:36:28 INFO - PROCESS | 1642 | 02:39:30 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 02:39:31 INFO - PROCESS | 1642 | RSS: Main: 280965120 02:39:31 INFO - PROCESS | 1642 | 02:42:33 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 02:42:34 INFO - PROCESS | 1642 | RSS: Main: 292851712 02:42:34 INFO - PROCESS | 1642 | 02:45:37 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 02:45:38 INFO - PROCESS | 1642 | RSS: Main: 269373440 02:45:38 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 02:48:41 INFO - PROCESS | 1642 | RSS: Main: 362532864 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | __start_tp_report 02:48:41 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 02:48:41 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 02:48:41 INFO - PROCESS | 1642 | |i|pagename|runs| 02:48:41 INFO - PROCESS | 1642 | |0;dojo.html;3523.1720545671765;3524.4889491023796;3531.311394278117;3541.1678255609945;3586.103474131467;5690.601657883944;6062.272527296731;6083.878322433548;6087.086936959453;6111.877762444748;6009.939900600993;6107.725152368143;6113.51091912647;6117.265928088625;6123.479504242134;5438;5463.2078348639425;5466.945330546683;5480.616356855022;5600.27196464459;5338.652987555816;5342.358916929974;5354.491323324298;5577.581681373893;5578.219049333096;5429.267048948401;5446.782128714866;5449.291592093023;5452.454754524536;5456.590755693319;5429.484199001088;5432.103702888999;5437.048516509634;5439.5920305977015;5445.945540544583;5283.313169288006;5389;5389.541888939445;5399.244105825188;5439.5376393006645;5978.983572792636;5995.640261584319;5997.7001149942325;6002.099685047234;6006.969965150146;6350.618962862243;6371.808845734636;6376.139221205124;6391.169148010776;6407.231132264112;6453.483721302285;6455.096286519891;6457.12828768115;6460.095586617877;6480.837979050561;5668.546516278729;6122.8775424491705;6153.169322141498;6163.137160797492;6232.220972378453;6341.619502829845;6375.330590288046;6389.488840892717;6537.803865884031;6553.967230163818;6361.395667411589;6398.168238129014;6413.518986076063;6447.129637498878;6455.128557644752;6441.291457939623;6464.838379040468;6465.676716164267;6470.967645161744;6473.870522589522;6140.657627863698;6443;6450.548461607643;6459;6462.935370646327;6405.839854003594;6407.871842563216;6414.326495717883;6418.069379939976;6432.935670643234;5175.068487672255;5191.454897235736;5258.368995720538;5276.419593844674;5280.313559237275;4222.21888950539;4541.9545804542195;4667.183242932473;4675.462321833033;4690.132325519789;6168.876622467616;6184;6188.876222475616;6194.225721784777;6195.2255968004;6364.395382438661;6392.296847346788;6411.967940160363;6445.613263204223;6446.161998940108;6120.660465672032;6146.631202127886;6154.753809847645;6167.290761562388;6170.444659980623;5661.971690141523;5705.800296989585;5707.71461426927;5716.542676585863;5744.655320680772;6369.649669268049;6413.102165696713;6539.084528166156;6650.3682150195655;6651.634160121143;6429.32492088324;6434.871302574016;6459.160309159779;6460.160179176677;6462;6030.5828130388445;6121.969390153199;6623.834404139935;6629.734810607714;6630;6251.124842521959;6277.466415354564;6376.234851817811;6379.904301435389;6465.3857883501;6344.429001389897;6412.839679007876;6439.8068057958335;6464.353564643498;6482.546221764431;5196.870078248074;6411.775587854403;6447.226332839901;6448.838779030561;6449.645269510034;6206.255249370104;6369.20384951881;6419.550631455753;6430.549861509649;6439.259485159079;6113.871604717183;6157.230346206724;6206.72069756853;6232.470240029648;6257.5932564383165;6398.072279519537;6402.679866006588;6412.903806442814;6412.903806443001;6419.518536109717;6251.874962500634;6301.086342480223;6301.590396624205;6304.464120549621;6315.463185629089 02:48:41 INFO - PROCESS | 1642 | |1;ext.html;772.088935056633;808.1352952340994;822.1080128061055;903.5708038681623;910.7222297199352;29945.700542994564;42480;42710.93222669435;42787.35818962714;43063;43436.3484547732;43632.12735745283;43656.34515482262;43681.781591092;43683.563164368345;7005.509614326999;9776.70669879903;9792.608295668164;9942.453165075918;9960.352577082467;8072.677092916277;11809.055275577934;11990.100742444309;12314;12318.44566994483;12060.698482537919;12075.758484830298;12078.15452918296;12091.395430228453;12110.939445302805;12029.879701202963;12053.879461205363;12084.758304833855;12088.456019479188;12091.274523528617;11680.532778688843;11770.646880593511;11771;11777.352245626573;11786.941065294704;41349.1730165395;41566.96082597929;41634.583654163675;41642.79178604088;41724;3947.5460322063045;4013.8795836124964;4018.0758425562285;4027.8791636250958;4040.2121586290687;25497.490050199078;29205.853970730222;29447.70552294471;30618.54072188919;31449.842750786327;3258.9348213035605;3296.587926509186;3313.9834300828343;3441.7590768646205;3446.3968805458944;335615;337845;339006.30496847606;339367;340006.299968501;44027;54525.45474545283;54647.45352546424;54696.45303546993;54747.45252547503;55933;55973.72013139908;56025.43974560202;56135.71932140313;56141;15066.623334416508;15400.22998850053;15407.075575465507;15409.306581203873;15415.383384664712;11871.872172143632;12082.972947299402;13057.281849498178;13073.215607063416;13092.607221783363;10397.32417392867;11290.7177320566;12705.872941270654;12854.807177892339;13077.476900924046;4983.750812459362;5772.9031484017905;5855.824325270249;5859.296884373903;5864.827034593099;902.8645703144581;911.4667919267303;917.348682435465;945.1115951006033;957.7270477913761;21024.89487552583;27481.587776183373;27545;27759.86120069427;28115.57826632602;841.3269384492381;956.4213650741341;1049.3651340938775;1091.4433638844246;1109.2290857853714;14086.366113524917;14546.709065818624;14665.266736663125;14927.477538286324;14955.700885982436;480060.5996969993;481169;481679;482419;483360;59730.40269597335;59889.40110598925;59901;59920.400795992355;59966.700166498886;1443.985560144385;1445.1401416157369;1479.4304192885602;1481.933313000896;1483.7922690823293;27606.5859012119;27892.442151156458;28077;28704.856475717486;28727;43281.78359108185;43456.782716086214;43465.78267108644;43518.78240608777;43562.78218609013;478330;480523;480588;480709;480923;30558.236044098187;30592.84703576468;30659.2335191622;31212.907548235704;31947;40162.3947763344;40396;40826.38760418655;41108;41195.7940210297;23025.309240722807;24445;28932.27669308284;29546.852265738533;30432.543511847784;23050.76949230553;28314.009009684563;28878.711212888444;29992.400151997273;30067.248318792204;33454.163645909044;33612.831935840164;34247.828760856035;34424;34623.13442163865;38305.80847095747;38396.80801596086;38747;39920.00199994907;40983.79508102559;30790.230244243176;32122;33034.504482433265;33409;33731 02:48:41 INFO - PROCESS | 1642 | |2;jquery.html;386.455098311381;410.5176417709191;417.25103439326404;425.82966813274675;442.80073966714986;20258.088386022628;20964;22178.334649960485;22288.21991230305;22323.44191395216;23236.76763232367;23802.92886820093;24086.87956560219;24233.030678772873;24464.021439142456;6874.415674667659;6992.741306564816;7265.709371625128;7287.635618219095;7289.599072051035;6980.441564674839;7105.076340075783;7169.89245161323;7182.892256616155;7191.280871912819;6909.792706218796;6977.965110174468;7116.893246601279;7120.750773722921;7191.316824901626;5848.766049358063;6810.548202647043;7093.184283807351;7147.53541019832;7180.3537681608905;5614.214010038598;5637.182608521783;5661.688607126606;5714.51426628737;5736.684482353469;26733;26918.057867974723;27157.728422715718;27295;27710.198837075524;2874.3963767608825;3081.7072378124044;3094.5977022987095;3110.3001824589514;3143.984280078585;16762.664746705;16955.660886782196;17058.402955896607;17528.91235543814;17935.192916318796;1956.9823691680247;2117;2138.8930553472264;2213.369189780905;2266.897989590472;64528;106536;163316;169033;182793.99999999866;19492.12285447158;20598;20723.378298651063;20765;20837;31512.912251284724;32774.8361258197;32792.67207327944;32844.507332390036;32875.34249315048;10960.890391096147;11164.609238676607;11194.272372295774;11321.830172547418;11329.943350283196;9259.953700231456;9265.536723163816;9283.16451519353;9464.384814987005;9471.715848524555;9386.202172815387;9726.70819875405;9843.950780246052;9863.605455781832;9873.111419972238;3356.4965255211914;4015.9397609035886;4060.269151552749;4079.63283304504;4099.528554216244;1023.130339211679;1037.8391349340864;1051.9585610245801;1067.247535782135;1069.2622090757352;21544.353669389944;21854.7499389853;22037.78792166414;22816.429589260402;24427.267181984567;1029.3566520924421;1029.917606591471;1041.094248004241;1062.6865074802952;1065.1585247654266;7072.92927070733;7142.750003749844;7179.5333303336165;7429.62851857405;7437.665305061285;764120;776821.2317876862;783384;799394;814960;135139;174622;174718;179020;180213.0989345071;1237.975240495185;1278.3097127551018;1330.1420583723443;1330.4545136494185;1344.2405041151717;20581.176752929714;20753;20870.895645521676;21249.04379302966;21555.892220538797;56902;62371;64999.67500162469;65361.67319163564;66485;72396.63801680958;89331;90409.54795225982;92526;92587.53706231425;9811.509424528887;11834.171607987359;12054.517819287481;12162.878371216175;12205.633830985085;13369.866301336862;13447.932760336136;13551.322433878464;13860.930695346458;13892.93053534726;11621.825672614748;11755.706872243874;12071.034317254775;12092.274463532216;12152.756944860876;10718.24972251935;10766.354018758899;11021.173411993972;11064.114870810477;11151.219414640897;11692.590759323382;11792.823107653563;12153.453094610586;12188.695282618006;12234.96002839733;11691.473883675426;11828.881711182778;11919.106067044833;12110.394480275774;12125.393730313626;11612.071034317065;11687.123465740273;11816.881831181921;11921.642350729491;12116.27302361861 02:48:41 INFO - PROCESS | 1642 | |3;mootools.html;1400.3068481101857;1594.6571487130266;1615.983840161598;1625.463597012986;1632.5347276026332;9555.56999935003;9905.405675659465;11052.171087168465;11247.83128253077;11559.826602600946;9677.419354838714;11314.886851131507;11355.261907975975;11398.943005284962;11418.257813242155;2118.8834614096218;2473.6907886514186;2508.924732258026;2514.333701569086;2518.0934863449143;2179.553191595727;2202.702635144259;2203.724534433196;2203.889805509726;2204.7244094488187;2048.94877628059;2094.6334391481505;2486.987565062172;2492.67595212623;2495.3262619092834;2445.3519817248443;2471.1968610201666;2480.2559232230437;2482.689663792026;2487.005197920837;2461.4215659320043;2465.7287698353166;2470.4688491974134;2472.443700167466;2478.8388754730886;11246;11371.715707107305;11395.772084558263;11405.429728513542;11493.885061149449;5411.702356370398;5745.425457454221;5985.551083668699;6117.204763380777;6131.693415329216;3583.229605634775;3811.7331786774935;3881.5342158940825;3893.0267433141717;3894.7078969077434;1218.3542722357165;1241.4599649152558;1287.427094942751;1290.5612091888802;1300.0704496285107;16155;16213.513594592181;16223.188840558043;16247.350105995745;16279.674406511804;5049.116404629175;5118.232265160255;5135.768890399941;5188.662736922088;5188.948110518922;13162.473501060042;13184.406701698446;13187.802182967263;13192.868071319355;13200.603981880558;5253.343332083489;6167.506599472032;6189.566730328834;6194.442500174916;6198.101275315054;5388.084025715638;5500.312460942382;5518.117101263779;5568.248286481273;5611.7474713637985;3794.051487128218;3879.0884142226555;4117.3206420940205;4234.110836724322;4284.057507348379;1085.3650614390513;1156.8843115688533;1157.242006485753;1179.4574495731888;1181.6159748081907;978.2174260591498;986.8158210147851;1001.4942454060653;1008.3395376028712;1026.5175367577226;5945.851353716192;6591.76928807499;6630.900536491957;6857.657117144123;6930.480213983971;1045.414567842011;1046.398320965448;1063.3460421840666;1071.3023392424275;1091.1270983213396;4036.788963310995;4063.688094203215;4253.744775313491;4409.11817636474;4480.924578101232;13341.799873001914;13613.455461781417;13807.654808629864;13917.373718182705;13999.230042347768;14024.08843425174;14043.016988810685;14052.71894562103;14053.086549374259;14055;9079.773005674911;10245;10251.026152515498;10359.274850760525;10531.104856087319;6743.393094621508;6994.965025174842;7010.614416207158;7154.39187669054;7268.418526517971;11243.49404276826;12656.810147847973;12876.098673092794;12916.483340666267;12945.417456214305;12630.684232894251;13008.284544350152;13040.54358097462;13040.869591303965;13068.869311306766;9305.348625596414;9649.083337082966;9661.855072174063;9672.516374181403;9675.564599593175;8150.98112735908;10429.113525350127;10502.947485262524;10584.84122738175;10635.308704934154;7788.84422311562;8335.833283334421;9026.86459703118;9066.637334506546;9100.408473449204;8818.735437936872;8864.06927272627;8871.866921996047;8917.375783695079;9052.592633331384;8154.633041513028;8266.586670666564;8287.917120828715;8293.875591866006;8296.170382961656;5916.585838991229;6058.878822423615;6151.108089326933;6177.289611694712;6213.991975176076;6004.039353703386;6060.848438796615;6105.267367915879;6288.591241569284;6341.239051313872 02:48:41 INFO - PROCESS | 1642 | |4;prototype.html;336.1948134218547;345.3903859687651;372.7521198403062;389.6298516409412;393.87592908233904;4957.289735041414;5034.874128146794;5081.263216833566;5229.927864787719;5272.683638981659;4126.690498212631;4264.637505812009;5020.949790502103;5026.748662566875;5050.469700681421;4208.852690155845;4431.645468362523;4447.577480139382;4449.755263460508;4465.548696673594;3553.4492153716214;3717.8846346096198;4362.912741745163;4373.666031860282;4434.957784920541;3626.0390996385977;3843.9039024024482;3872.7869967151796;4111.129436106579;4408.993907771956;3728.9254214915686;3960.4653371795016;4072.450219220397;4352.412424322739;4440.533743956887;3715.535558055243;3819.980900095482;4004.979975100135;4316.280743131838;4358.455193100863;4002.6197511236387;4520.954790452086;4816.470188279286;4927.285543596158;4992.350994370709;5059.418166910816;5125.436741793751;5126.461721519261;5127.307813445174;5130.256112863651;4441.311596702516;5424.783008679648;5456.972715136439;5488.012157811592;5518.779248830042;4226.154769046203;4408.493023302329;4602.562756538124;4672.3692301539195;4672.948586567995;5171.663841850268;5597.328320601513;5631.943680563224;5713.714314284269;5721.971390143022;4005.619466150711;4132.2561938850695;4834.6374021948495;4835.153848076643;4837.588804951548;4217.270918923288;4713.82456554101;4881.51184881509;4933.45731969483;4940.604751619861;4446.911061778746;4457.977710111429;5060.898782024339;5162.8967420652125;5205.47945205484;5343.251944727741;5348.732563371816;5363.892722145613;5452.945470545323;5485.175134805579;1465.9193744344163;1490.7987421697933;1578.2661062605944;1678.447436121607;1717.1156854220087;1340.9396577154052;1367.7826734206565;1375.651861176053;1389.9861001389856;1412.8940329475124;4466.665000125004;4609.1703493371515;4944.566075837966;4982.8754281143265;4987.850364489072;4663.790129444184;5350.3312085989255;5509.256250406224;5559.6664200148125;5561.915426491876;4359.520452750195;4552.580828658489;4806.7836947337455;4937.950620493821;5031.471695471996;2617.345663584104;2830.0614079355705;2896.811707239023;2913.7762139901292;2931.080142506619;4984.4174524532755;5060.873478163002;5085.898282034339;5315.107066007054;5586.385497595261;5183.5593974512585;5444.3594856494565;5459.863503412446;5526.364468086141;5585.609007369445;5414.10506322776;5525.723713814293;5810.302763668303;5846.824595262149;5847.427132061879;4640.976795116003;4915.6067514598035;5369.624126311277;5623.662580245198;5695.544356451391;4825;5393.865153371197;5514.035043867388;5527.695976721158;5577.6653400796085;4941.703497790144;5240.790368385375;5619.747111379916;5690.829275121753;5836.416358364129;4127.122159417518;4548.863534093983;4612.262038073892;4618.538146185355;4690.4202688521855;4783.918995237083;5060.443351231435;5322.441143679858;5605.103183490622;5630.718464076698;4756.9524304756505;5014.997000599822;5306.6550674206055;5391.81128660495;5484.7806087757635;5038.470960549236;5374.946250537445;5388.892222155457;5413.729313534229;5463.656393167362;4427.413794826201;4949.084419382426;5267.947320526899;5268.78924843002;5329.813456529003;4660.997885454643;4843.903121937471;5038.546530812245;5089.440161582149;5408.296921400193;4227.830886764495;4301.827926882889;4928.827491037797;5215.556677682288;5339.252504649275 02:48:41 INFO - PROCESS | 1642 | |5;yui.html;798.269583331252;828.3125006244817;839.3578912132224;910.9362344635875;917.9311551633627;16066.91966540167;17809.10954452277;17846.910765446188;17923.462296131136;17939.551511212227;14481.78277325841;14519;14539.273036348193;14545;14547.272636368192;988.4316518002141;989.529973262698;992.6525715999414;993.4635296939643;994.2543092680489;984.4093543873654;987.9753006174831;989.2382865193797;990.4107056301489;990.7721224118457;1034.2398337222135;1061.984070238947;1070.3417398300066;1080.1143062688598;1080.3140006096146;1065.222387657007;1065.7602039541118;1068.3910171202417;1068.3910171202417;1069.2141276162015;790.1426951757293;790.5651891459675;791.9683212671544;792.0614072324314;792.1009654042646;14821.407143714347;15071.47249846261;17286.91356543222;17706.114694265234;17729.556761081076;14812.777808332883;14962.251887405586;15225.238738063052;15267.465638702592;15830.129342886137;12297;12334.198277111958;12426.316552589604;12473.50105995759;12727.109102362838;3883.8834834954996;3934.0951581136214;3938.1532970411213;4107.609777071173;4117.814698338582;116220;117038.41480792627;119584;122610;125862.99999999908;11309.547618095185;11564;11605.76788464226;11714.180007399571;12268;23826.642600361007;24546;24707.382315442257;24736.5052698945;24737.752622473545;7970.760877173694;8009.31920786728;8034.959825200836;8059.798505037304;8362.665493380196;4476.082403107355;4598.954010459919;4622.653301002439;4633.791479383436;4734.526547345238;3507.8070706111407;3556.5376501055216;3570.8214589270433;3573.10672331917;3578.499010138583;631.504269148716;639.6322114784018;644.6003477843767;648.180052233915;651.2770824384929;655.015333313484;656.153561905137;674.1000763980142;675.5035049238805;712.6543626341324;9292.953535232416;10168;10264.897351026395;10464.424456654957;10482.895171048345;617.5029101573186;637.3562701671345;650.3626446082865;673.3372338632081;711.5943911970231;4607.562281583244;4725.873356989368;4756.548127927841;4770.785314660849;4852.951470485321;84089.15910840813;96148.51925740467;100144;100796.49601751944;102408;54249.72875135599;81506.18493815104;94739;95974;97334.53998190032;62388.37611623917;77414.612926935;79015;83412.58293708492;84360.57819710863;9040.954795225982;11447.656570302905;11514.424278786195;11576.942115289707;11804.881951180378;52386.73806630942;52860.471395287095;53060;53996.73001634967;54140.72929635484;58719.706401469426;65990;66172;67550.3244967544;68237;10953.671389858317;14788.186649734367;15030.699386012;15217.77173342423;15655.921720391325;13349.866501335251;15864.44474443389;16127.274272657523;16236.837631624005;16816;13194.538191163647;14552.199629020506;14583.92708036453;15114.288138892769;15349.539513814603;12783.552575659807;14349.139051656517;14373.856261437251;14803.555893323217;15240.314185861442;14779.92610036943;14935.17856517902;14943.701125977637;14963.40146394132;15061.548153555412;9897.364715949661;10604.575816967235;11087.833682494931;11197.496112674786;11361.431928403712;11547.364894930524;11766.294022358343;11901.880981190423;12111.212771170198;12170.208936419105 02:48:41 INFO - PROCESS | 1642 | __end_tp_report 02:48:41 INFO - PROCESS | 1642 | __start_cc_report 02:48:41 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1401 02:48:41 INFO - PROCESS | 1642 | __end_cc_report 02:48:41 INFO - PROCESS | 1642 | __startTimestamp1495100921838__endTimestamp 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | ------- Summary: start ------- 02:48:41 INFO - PROCESS | 1642 | Number of tests: 6 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5992.11 Median:6232.22 stddev:648.51 (10.4%) stddev-sans-first:620.90 02:48:41 INFO - PROCESS | 1642 | Values: 3523.2 3524.5 3531.3 3541.2 3586.1 5690.6 6062.3 6083.9 6087.1 6111.9 6009.9 6107.7 6113.5 6117.3 6123.5 5438.0 5463.2 5466.9 5480.6 5600.3 5338.7 5342.4 5354.5 5577.6 5578.2 5429.3 5446.8 5449.3 5452.5 5456.6 5429.5 5432.1 5437.0 5439.6 5445.9 5283.3 5389.0 5389.5 5399.2 5439.5 5979.0 5995.6 5997.7 6002.1 6007.0 6350.6 6371.8 6376.1 6391.2 6407.2 6453.5 6455.1 6457.1 6460.1 6480.8 5668.5 6122.9 6153.2 6163.1 6232.2 6341.6 6375.3 6389.5 6537.8 6554.0 6361.4 6398.2 6413.5 6447.1 6455.1 6441.3 6464.8 6465.7 6471.0 6473.9 6140.7 6443.0 6450.5 6459.0 6462.9 6405.8 6407.9 6414.3 6418.1 6432.9 5175.1 5191.5 5258.4 5276.4 5280.3 4222.2 4542.0 4667.2 4675.5 4690.1 6168.9 6184.0 6188.9 6194.2 6195.2 6364.4 6392.3 6412.0 6445.6 6446.2 6120.7 6146.6 6154.8 6167.3 6170.4 5662.0 5705.8 5707.7 5716.5 5744.7 6369.6 6413.1 6539.1 6650.4 6651.6 6429.3 6434.9 6459.2 6460.2 6462.0 6030.6 6122.0 6623.8 6629.7 6630.0 6251.1 6277.5 6376.2 6379.9 6465.4 6344.4 6412.8 6439.8 6464.4 6482.5 5196.9 6411.8 6447.2 6448.8 6449.6 6206.3 6369.2 6419.6 6430.5 6439.3 6113.9 6157.2 6206.7 6232.5 6257.6 6398.1 6402.7 6412.9 6412.9 6419.5 6251.9 6301.1 6301.6 6304.5 6315.5 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:57859.64 Median:339186.65 stddev:116403.99 (34.3%) stddev-sans-first:116651.62 02:48:41 INFO - PROCESS | 1642 | Values: 772.1 808.1 822.1 903.6 910.7 29945.7 42480.0 42710.9 42787.4 43063.0 43436.3 43632.1 43656.3 43681.8 43683.6 7005.5 9776.7 9792.6 9942.5 9960.4 8072.7 11809.1 11990.1 12314.0 12318.4 12060.7 12075.8 12078.2 12091.4 12110.9 12029.9 12053.9 12084.8 12088.5 12091.3 11680.5 11770.6 11771.0 11777.4 11786.9 41349.2 41567.0 41634.6 41642.8 41724.0 3947.5 4013.9 4018.1 4027.9 4040.2 25497.5 29205.9 29447.7 30618.5 31449.8 3258.9 3296.6 3314.0 3441.8 3446.4 335615.0 337845.0 339006.3 339367.0 340006.3 44027.0 54525.5 54647.5 54696.5 54747.5 55933.0 55973.7 56025.4 56135.7 56141.0 15066.6 15400.2 15407.1 15409.3 15415.4 11871.9 12083.0 13057.3 13073.2 13092.6 10397.3 11290.7 12705.9 12854.8 13077.5 4983.8 5772.9 5855.8 5859.3 5864.8 902.9 911.5 917.3 945.1 957.7 21024.9 27481.6 27545.0 27759.9 28115.6 841.3 956.4 1049.4 1091.4 1109.2 14086.4 14546.7 14665.3 14927.5 14955.7 480060.6 481169.0 481679.0 482419.0 483360.0 59730.4 59889.4 59901.0 59920.4 59966.7 1444.0 1445.1 1479.4 1481.9 1483.8 27606.6 27892.4 28077.0 28704.9 28727.0 43281.8 43456.8 43465.8 43518.8 43562.8 478330.0 480523.0 480588.0 480709.0 480923.0 30558.2 30592.8 30659.2 31212.9 31947.0 40162.4 40396.0 40826.4 41108.0 41195.8 23025.3 24445.0 28932.3 29546.9 30432.5 23050.8 28314.0 28878.7 29992.4 30067.2 33454.2 33612.8 34247.8 34424.0 34623.1 38305.8 38396.8 38747.0 39920.0 40983.8 30790.2 32122.0 33034.5 33409.0 33731.0 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:44420.71 Median:12541.66 stddev:131308.46 (1047.0%) stddev-sans-first:131635.19 02:48:41 INFO - PROCESS | 1642 | Values: 386.5 410.5 417.3 425.8 442.8 20258.1 20964.0 22178.3 22288.2 22323.4 23236.8 23802.9 24086.9 24233.0 24464.0 6874.4 6992.7 7265.7 7287.6 7289.6 6980.4 7105.1 7169.9 7182.9 7191.3 6909.8 6978.0 7116.9 7120.8 7191.3 5848.8 6810.5 7093.2 7147.5 7180.4 5614.2 5637.2 5661.7 5714.5 5736.7 26733.0 26918.1 27157.7 27295.0 27710.2 2874.4 3081.7 3094.6 3110.3 3144.0 16762.7 16955.7 17058.4 17528.9 17935.2 1957.0 2117.0 2138.9 2213.4 2266.9 64528.0 106536.0 163316.0 169033.0 182794.0 19492.1 20598.0 20723.4 20765.0 20837.0 31512.9 32774.8 32792.7 32844.5 32875.3 10960.9 11164.6 11194.3 11321.8 11329.9 9260.0 9265.5 9283.2 9464.4 9471.7 9386.2 9726.7 9844.0 9863.6 9873.1 3356.5 4015.9 4060.3 4079.6 4099.5 1023.1 1037.8 1052.0 1067.2 1069.3 21544.4 21854.7 22037.8 22816.4 24427.3 1029.4 1029.9 1041.1 1062.7 1065.2 7072.9 7142.8 7179.5 7429.6 7437.7 764120.0 776821.2 783384.0 799394.0 814960.0 135139.0 174622.0 174718.0 179020.0 180213.1 1238.0 1278.3 1330.1 1330.5 1344.2 20581.2 20753.0 20870.9 21249.0 21555.9 56902.0 62371.0 64999.7 65361.7 66485.0 72396.6 89331.0 90409.5 92526.0 92587.5 9811.5 11834.2 12054.5 12162.9 12205.6 13369.9 13447.9 13551.3 13860.9 13892.9 11621.8 11755.7 12071.0 12092.3 12152.8 10718.2 10766.4 11021.2 11064.1 11151.2 11692.6 11792.8 12153.5 12188.7 12235.0 11691.5 11828.9 11919.1 12110.4 12125.4 11612.1 11687.1 11816.9 11921.6 12116.3 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6939.61 Median:2484.84 stddev:4361.49 (175.5%) stddev-sans-first:4353.86 02:48:41 INFO - PROCESS | 1642 | Values: 1400.3 1594.7 1616.0 1625.5 1632.5 9555.6 9905.4 11052.2 11247.8 11559.8 9677.4 11314.9 11355.3 11398.9 11418.3 2118.9 2473.7 2508.9 2514.3 2518.1 2179.6 2202.7 2203.7 2203.9 2204.7 2048.9 2094.6 2487.0 2492.7 2495.3 2445.4 2471.2 2480.3 2482.7 2487.0 2461.4 2465.7 2470.5 2472.4 2478.8 11246.0 11371.7 11395.8 11405.4 11493.9 5411.7 5745.4 5985.6 6117.2 6131.7 3583.2 3811.7 3881.5 3893.0 3894.7 1218.4 1241.5 1287.4 1290.6 1300.1 16155.0 16213.5 16223.2 16247.4 16279.7 5049.1 5118.2 5135.8 5188.7 5188.9 13162.5 13184.4 13187.8 13192.9 13200.6 5253.3 6167.5 6189.6 6194.4 6198.1 5388.1 5500.3 5518.1 5568.2 5611.7 3794.1 3879.1 4117.3 4234.1 4284.1 1085.4 1156.9 1157.2 1179.5 1181.6 978.2 986.8 1001.5 1008.3 1026.5 5945.9 6591.8 6630.9 6857.7 6930.5 1045.4 1046.4 1063.3 1071.3 1091.1 4036.8 4063.7 4253.7 4409.1 4480.9 13341.8 13613.5 13807.7 13917.4 13999.2 14024.1 14043.0 14052.7 14053.1 14055.0 9079.8 10245.0 10251.0 10359.3 10531.1 6743.4 6995.0 7010.6 7154.4 7268.4 11243.5 12656.8 12876.1 12916.5 12945.4 12630.7 13008.3 13040.5 13040.9 13068.9 9305.3 9649.1 9661.9 9672.5 9675.6 8151.0 10429.1 10502.9 10584.8 10635.3 7788.8 8335.8 9026.9 9066.6 9100.4 8818.7 8864.1 8871.9 8917.4 9052.6 8154.6 8266.6 8287.9 8293.9 8296.2 5916.6 6058.9 6151.1 6177.3 6214.0 6004.0 6060.8 6105.3 6288.6 6341.2 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4547.63 Median:4939.28 stddev:1219.65 (24.7%) stddev-sans-first:1181.40 02:48:41 INFO - PROCESS | 1642 | Values: 336.2 345.4 372.8 389.6 393.9 4957.3 5034.9 5081.3 5229.9 5272.7 4126.7 4264.6 5020.9 5026.7 5050.5 4208.9 4431.6 4447.6 4449.8 4465.5 3553.4 3717.9 4362.9 4373.7 4435.0 3626.0 3843.9 3872.8 4111.1 4409.0 3728.9 3960.5 4072.5 4352.4 4440.5 3715.5 3820.0 4005.0 4316.3 4358.5 4002.6 4521.0 4816.5 4927.3 4992.4 5059.4 5125.4 5126.5 5127.3 5130.3 4441.3 5424.8 5457.0 5488.0 5518.8 4226.2 4408.5 4602.6 4672.4 4672.9 5171.7 5597.3 5631.9 5713.7 5722.0 4005.6 4132.3 4834.6 4835.2 4837.6 4217.3 4713.8 4881.5 4933.5 4940.6 4446.9 4458.0 5060.9 5162.9 5205.5 5343.3 5348.7 5363.9 5452.9 5485.2 1465.9 1490.8 1578.3 1678.4 1717.1 1340.9 1367.8 1375.7 1390.0 1412.9 4466.7 4609.2 4944.6 4982.9 4987.9 4663.8 5350.3 5509.3 5559.7 5561.9 4359.5 4552.6 4806.8 4938.0 5031.5 2617.3 2830.1 2896.8 2913.8 2931.1 4984.4 5060.9 5085.9 5315.1 5586.4 5183.6 5444.4 5459.9 5526.4 5585.6 5414.1 5525.7 5810.3 5846.8 5847.4 4641.0 4915.6 5369.6 5623.7 5695.5 4825.0 5393.9 5514.0 5527.7 5577.7 4941.7 5240.8 5619.7 5690.8 5836.4 4127.1 4548.9 4612.3 4618.5 4690.4 4783.9 5060.4 5322.4 5605.1 5630.7 4757.0 5015.0 5306.7 5391.8 5484.8 5038.5 5374.9 5388.9 5413.7 5463.7 4427.4 4949.1 5267.9 5268.8 5329.8 4661.0 4843.9 5038.5 5089.4 5408.3 4227.8 4301.8 4928.8 5215.6 5339.3 02:48:41 INFO - PROCESS | 1642 | 02:48:41 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:21128.16 Median:24186.32 stddev:29925.03 (123.7%) stddev-sans-first:29970.04 02:48:41 INFO - PROCESS | 1642 | Values: 798.3 828.3 839.4 910.9 917.9 16066.9 17809.1 17846.9 17923.5 17939.6 14481.8 14519.0 14539.3 14545.0 14547.3 988.4 989.5 992.7 993.5 994.3 984.4 988.0 989.2 990.4 990.8 1034.2 1062.0 1070.3 1080.1 1080.3 1065.2 1065.8 1068.4 1068.4 1069.2 790.1 790.6 792.0 792.1 792.1 14821.4 15071.5 17286.9 17706.1 17729.6 14812.8 14962.3 15225.2 15267.5 15830.1 12297.0 12334.2 12426.3 12473.5 12727.1 3883.9 3934.1 3938.2 4107.6 4117.8 116220.0 117038.4 119584.0 122610.0 125863.0 11309.5 11564.0 11605.8 11714.2 12268.0 23826.6 24546.0 24707.4 24736.5 24737.8 7970.8 8009.3 8035.0 8059.8 8362.7 4476.1 4599.0 4622.7 4633.8 4734.5 3507.8 3556.5 3570.8 3573.1 3578.5 631.5 639.6 644.6 648.2 651.3 655.0 656.2 674.1 675.5 712.7 9293.0 10168.0 10264.9 10464.4 10482.9 617.5 637.4 650.4 673.3 711.6 4607.6 4725.9 4756.5 4770.8 4853.0 84089.2 96148.5 100144.0 100796.5 102408.0 54249.7 81506.2 94739.0 95974.0 97334.5 62388.4 77414.6 79015.0 83412.6 84360.6 9041.0 11447.7 11514.4 11576.9 11804.9 52386.7 52860.5 53060.0 53996.7 54140.7 58719.7 65990.0 66172.0 67550.3 68237.0 10953.7 14788.2 15030.7 15217.8 15655.9 13349.9 15864.4 16127.3 16236.8 16816.0 13194.5 14552.2 14583.9 15114.3 15349.5 12783.6 14349.1 14373.9 14803.6 15240.3 14779.9 14935.2 14943.7 14963.4 15061.5 9897.4 10604.6 11087.8 11197.5 11361.4 11547.4 11766.3 11901.9 12111.2 12170.2 02:48:41 INFO - PROCESS | 1642 | -------- Summary: end -------- 02:48:41 INFO - PROCESS | 1642 | 02:48:42 INFO - TEST-INFO | 1642: exit 0 02:48:42 INFO - TEST-OK | dromaeo_css | took 1118736ms 02:48:42 INFO - TEST-START | kraken 02:48:42 INFO - operating with platform_type : mac_ 02:48:42 INFO - Initialising browser for kraken test... 02:48:42 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpRwRRbU/profile http://localhost:49224/getInfo.html) 02:48:44 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 02:48:44 INFO - PROCESS | 1651 | colorDepth:24 02:48:44 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 02:48:44 INFO - PROCESS | 1651 | __metrics 02:48:44 INFO - PROCESS | 1651 | 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" 02:48:44 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:48:45 INFO - TEST-INFO | 1651: exit 0 02:48:45 INFO - Browser initialized. 02:48:45 INFO - Running cycle 1/1 for kraken test... 02:48:45 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpRwRRbU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:57 INFO - PROCESS | 1653 | RSS: Main: 165236736 02:48:57 INFO - PROCESS | 1653 | 02:48:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 02:49:00 INFO - PROCESS | 1653 | RSS: Main: 216018944 02:49:00 INFO - PROCESS | 1653 | 02:49:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 02:49:04 INFO - PROCESS | 1653 | RSS: Main: 231444480 02:49:04 INFO - PROCESS | 1653 | 02:49:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 02:49:07 INFO - PROCESS | 1653 | RSS: Main: 328445952 02:49:07 INFO - PROCESS | 1653 | 02:49:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 02:49:10 INFO - PROCESS | 1653 | RSS: Main: 230232064 02:49:10 INFO - PROCESS | 1653 | 02:49:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:13 INFO - PROCESS | 1653 | RSS: Main: 231620608 02:49:13 INFO - PROCESS | 1653 | 02:49:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:16 INFO - PROCESS | 1653 | RSS: Main: 272306176 02:49:16 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:20 INFO - PROCESS | 1653 | RSS: Main: 272842752 02:49:20 INFO - PROCESS | 1653 | 02:49:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 02:49:23 INFO - PROCESS | 1653 | RSS: Main: 270868480 02:49:23 INFO - PROCESS | 1653 | 02:49:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:25 INFO - PROCESS | 1653 | RSS: Main: 246706176 02:49:25 INFO - PROCESS | 1653 | 02:49:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:28 INFO - PROCESS | 1653 | RSS: Main: 253296640 02:49:28 INFO - PROCESS | 1653 | 02:49:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:31 INFO - PROCESS | 1653 | RSS: Main: 254439424 02:49:31 INFO - PROCESS | 1653 | 02:49:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:34 INFO - PROCESS | 1653 | RSS: Main: 273506304 02:49:34 INFO - PROCESS | 1653 | 02:49:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:38 INFO - PROCESS | 1653 | RSS: Main: 267137024 02:49:38 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 02:49:40 INFO - PROCESS | 1653 | RSS: Main: 311914496 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | __start_tp_report 02:49:40 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 02:49:40 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 02:49:40 INFO - PROCESS | 1653 | |i|pagename|runs| 02:49:40 INFO - PROCESS | 1653 | |0;ai-astar;106;115;115;114;114;116;114;116;112;115 02:49:40 INFO - PROCESS | 1653 | |1;audio-beat-detection;169;110;110;144;110;109;108;111;143;136 02:49:40 INFO - PROCESS | 1653 | |2;audio-dft;185;183;182;186;182;183;185;185;184;184 02:49:40 INFO - PROCESS | 1653 | |3;audio-fft;80;59;58;89;62;59;59;62;93;81 02:49:40 INFO - PROCESS | 1653 | |4;audio-oscillator;85;67;86;68;69;69;81;69;67;82 02:49:40 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;95;95;95;94;95;94;95;94;95;90 02:49:40 INFO - PROCESS | 1653 | |6;imaging-darkroom;96;95;95;94;95;97;95;95;97;95 02:49:40 INFO - PROCESS | 1653 | |7;imaging-desaturate;85;83;86;85;82;83;86;84;83;85 02:49:40 INFO - PROCESS | 1653 | |8;json-parse-financial;62;62;62;62;64;63;65;66;63;64 02:49:40 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;57;72;100;98;89;60;61;59;60;59 02:49:40 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;79;92;71;90;89;98;76;81;84;80 02:49:40 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;138;133;138;122;148;142;141;127;174;144 02:49:40 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;160;161;164;168;163;160;158;199;214;165 02:49:40 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;64;63;64;65;63;63;63;62;64 02:49:40 INFO - PROCESS | 1653 | __end_tp_report 02:49:40 INFO - PROCESS | 1653 | __start_cc_report 02:49:40 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2045 02:49:40 INFO - PROCESS | 1653 | __end_cc_report 02:49:40 INFO - PROCESS | 1653 | __startTimestamp1495100980341__endTimestamp 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | ------- Summary: start ------- 02:49:40 INFO - PROCESS | 1653 | Number of tests: 14 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:2.95 (2.6%) stddev-sans-first:1.24 02:49:40 INFO - PROCESS | 1653 | Values: 106.0 115.0 115.0 114.0 114.0 116.0 114.0 116.0 112.0 115.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:123.50 stddev:21.49 (17.4%) stddev-sans-first:15.84 02:49:40 INFO - PROCESS | 1653 | Values: 169.0 110.0 110.0 144.0 110.0 109.0 108.0 111.0 143.0 136.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.37 (0.7%) stddev-sans-first:1.39 02:49:40 INFO - PROCESS | 1653 | Values: 185.0 183.0 182.0 186.0 182.0 183.0 185.0 185.0 184.0 184.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:70.20 Median:71.00 stddev:13.93 (19.6%) stddev-sans-first:14.31 02:49:40 INFO - PROCESS | 1653 | Values: 80.0 59.0 58.0 89.0 62.0 59.0 59.0 62.0 93.0 81.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:74.30 Median:75.00 stddev:8.07 (10.8%) stddev-sans-first:7.57 02:49:40 INFO - PROCESS | 1653 | Values: 85.0 67.0 86.0 68.0 69.0 69.0 81.0 69.0 67.0 82.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:95.00 stddev:1.55 (1.6%) stddev-sans-first:1.62 02:49:40 INFO - PROCESS | 1653 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 90.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:1.00 02:49:40 INFO - PROCESS | 1653 | Values: 96.0 95.0 95.0 94.0 95.0 97.0 95.0 95.0 97.0 95.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:85.00 stddev:1.40 (1.6%) stddev-sans-first:1.45 02:49:40 INFO - PROCESS | 1653 | Values: 85.0 83.0 86.0 85.0 82.0 83.0 86.0 84.0 83.0 85.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.50 stddev:1.42 (2.2%) stddev-sans-first:1.42 02:49:40 INFO - PROCESS | 1653 | Values: 62.0 62.0 62.0 62.0 64.0 63.0 65.0 66.0 63.0 64.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:60.50 stddev:17.38 (28.7%) stddev-sans-first:17.62 02:49:40 INFO - PROCESS | 1653 | Values: 57.0 72.0 100.0 98.0 89.0 60.0 61.0 59.0 60.0 59.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:86.50 stddev:8.19 (9.5%) stddev-sans-first:8.49 02:49:40 INFO - PROCESS | 1653 | Values: 79.0 92.0 71.0 90.0 89.0 98.0 76.0 81.0 84.0 80.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:141.50 stddev:14.09 (10.0%) stddev-sans-first:14.91 02:49:40 INFO - PROCESS | 1653 | Values: 138.0 133.0 138.0 122.0 148.0 142.0 141.0 127.0 174.0 144.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:164.50 stddev:19.15 (11.6%) stddev-sans-first:19.88 02:49:40 INFO - PROCESS | 1653 | Values: 160.0 161.0 164.0 168.0 163.0 160.0 158.0 199.0 214.0 165.0 02:49:40 INFO - PROCESS | 1653 | 02:49:40 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:0.97 (1.5%) stddev-sans-first:0.88 02:49:40 INFO - PROCESS | 1653 | Values: 65.0 64.0 63.0 64.0 65.0 63.0 63.0 63.0 62.0 64.0 02:49:40 INFO - PROCESS | 1653 | -------- Summary: end -------- 02:49:40 INFO - PROCESS | 1653 | 02:49:41 INFO - TEST-INFO | 1653: exit 0 02:49:41 INFO - TEST-OK | kraken | took 58638ms 02:49:41 INFO - SUITE-END | took 1177s 02:49:41 INFO - Completed test suite (00:19:37) 02:49:41 INFO - javascript benchmark 02:49:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7901.712094478648, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3523.1720545671765, 3524.4889491023796, 3531.311394278117, 3541.1678255609945, 3586.103474131467, 5690.601657883944, 6062.272527296731, 6083.878322433548, 6087.086936959453, 6111.877762444748, 6009.939900600993, 6107.725152368143, 6113.51091912647, 6117.265928088625, 6123.479504242134, 5438.0, 5463.2078348639425, 5466.945330546683, 5480.616356855022, 5600.27196464459, 5338.652987555816, 5342.358916929974, 5354.491323324298, 5577.581681373893, 5578.219049333096, 5429.267048948401, 5446.782128714866, 5449.291592093023, 5452.454754524536, 5456.590755693319, 5429.484199001088, 5432.103702888999, 5437.048516509634, 5439.5920305977015, 5445.945540544583, 5283.313169288006, 5389.0, 5389.541888939445, 5399.244105825188, 5439.5376393006645, 5978.983572792636, 5995.640261584319, 5997.7001149942325, 6002.099685047234, 6006.969965150146, 6350.618962862243, 6371.808845734636, 6376.139221205124, 6391.169148010776, 6407.231132264112, 6453.483721302285, 6455.096286519891, 6457.12828768115, 6460.095586617877, 6480.837979050561, 5668.546516278729, 6122.8775424491705, 6153.169322141498, 6163.137160797492, 6232.220972378453, 6341.619502829845, 6375.330590288046, 6389.488840892717, 6537.803865884031, 6553.967230163818, 6361.395667411589, 6398.168238129014, 6413.518986076063, 6447.129637498878, 6455.128557644752, 6441.291457939623, 6464.838379040468, 6465.676716164267, 6470.967645161744, 6473.870522589522, 6140.657627863698, 6443.0, 6450.548461607643, 6459.0, 6462.935370646327, 6405.839854003594, 6407.871842563216, 6414.326495717883, 6418.069379939976, 6432.935670643234, 5175.068487672255, 5191.454897235736, 5258.368995720538, 5276.419593844674, 5280.313559237275, 4222.21888950539, 4541.9545804542195, 4667.183242932473, 4675.462321833033, 4690.132325519789, 6168.876622467616, 6184.0, 6188.876222475616, 6194.225721784777, 6195.2255968004, 6364.395382438661, 6392.296847346788, 6411.967940160363, 6445.613263204223, 6446.161998940108, 6120.660465672032, 6146.631202127886, 6154.753809847645, 6167.290761562388, 6170.444659980623, 5661.971690141523, 5705.800296989585, 5707.71461426927, 5716.542676585863, 5744.655320680772, 6369.649669268049, 6413.102165696713, 6539.084528166156, 6650.3682150195655, 6651.634160121143, 6429.32492088324, 6434.871302574016, 6459.160309159779, 6460.160179176677, 6462.0, 6030.5828130388445, 6121.969390153199, 6623.834404139935, 6629.734810607714, 6630.0, 6251.124842521959, 6277.466415354564, 6376.234851817811, 6379.904301435389, 6465.3857883501, 6344.429001389897, 6412.839679007876, 6439.8068057958335, 6464.353564643498, 6482.546221764431, 5196.870078248074, 6411.775587854403, 6447.226332839901, 6448.838779030561, 6449.645269510034, 6206.255249370104, 6369.20384951881, 6419.550631455753, 6430.549861509649, 6439.259485159079, 6113.871604717183, 6157.230346206724, 6206.72069756853, 6232.470240029648, 6257.5932564383165, 6398.072279519537, 6402.679866006588, 6412.903806442814, 6412.903806443001, 6419.518536109717, 6251.874962500634, 6301.086342480223, 6301.590396624205, 6304.464120549621, 6315.463185629089], "value": 5951.525860940817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.088935056633, 808.1352952340994, 822.1080128061055, 903.5708038681623, 910.7222297199352, 29945.700542994564, 42480.0, 42710.93222669435, 42787.35818962714, 43063.0, 43436.3484547732, 43632.12735745283, 43656.34515482262, 43681.781591092, 43683.563164368345, 7005.509614326999, 9776.70669879903, 9792.608295668164, 9942.453165075918, 9960.352577082467, 8072.677092916277, 11809.055275577934, 11990.100742444309, 12314.0, 12318.44566994483, 12060.698482537919, 12075.758484830298, 12078.15452918296, 12091.395430228453, 12110.939445302805, 12029.879701202963, 12053.879461205363, 12084.758304833855, 12088.456019479188, 12091.274523528617, 11680.532778688843, 11770.646880593511, 11771.0, 11777.352245626573, 11786.941065294704, 41349.1730165395, 41566.96082597929, 41634.583654163675, 41642.79178604088, 41724.0, 3947.5460322063045, 4013.8795836124964, 4018.0758425562285, 4027.8791636250958, 4040.2121586290687, 25497.490050199078, 29205.853970730222, 29447.70552294471, 30618.54072188919, 31449.842750786327, 3258.9348213035605, 3296.587926509186, 3313.9834300828343, 3441.7590768646205, 3446.3968805458944, 335615.0, 337845.0, 339006.30496847606, 339367.0, 340006.299968501, 44027.0, 54525.45474545283, 54647.45352546424, 54696.45303546993, 54747.45252547503, 55933.0, 55973.72013139908, 56025.43974560202, 56135.71932140313, 56141.0, 15066.623334416508, 15400.22998850053, 15407.075575465507, 15409.306581203873, 15415.383384664712, 11871.872172143632, 12082.972947299402, 13057.281849498178, 13073.215607063416, 13092.607221783363, 10397.32417392867, 11290.7177320566, 12705.872941270654, 12854.807177892339, 13077.476900924046, 4983.750812459362, 5772.9031484017905, 5855.824325270249, 5859.296884373903, 5864.827034593099, 902.8645703144581, 911.4667919267303, 917.348682435465, 945.1115951006033, 957.7270477913761, 21024.89487552583, 27481.587776183373, 27545.0, 27759.86120069427, 28115.57826632602, 841.3269384492381, 956.4213650741341, 1049.3651340938775, 1091.4433638844246, 1109.2290857853714, 14086.366113524917, 14546.709065818624, 14665.266736663125, 14927.477538286324, 14955.700885982436, 480060.5996969993, 481169.0, 481679.0, 482419.0, 483360.0, 59730.40269597335, 59889.40110598925, 59901.0, 59920.400795992355, 59966.700166498886, 1443.985560144385, 1445.1401416157369, 1479.4304192885602, 1481.933313000896, 1483.7922690823293, 27606.5859012119, 27892.442151156458, 28077.0, 28704.856475717486, 28727.0, 43281.78359108185, 43456.782716086214, 43465.78267108644, 43518.78240608777, 43562.78218609013, 478330.0, 480523.0, 480588.0, 480709.0, 480923.0, 30558.236044098187, 30592.84703576468, 30659.2335191622, 31212.907548235704, 31947.0, 40162.3947763344, 40396.0, 40826.38760418655, 41108.0, 41195.7940210297, 23025.309240722807, 24445.0, 28932.27669308284, 29546.852265738533, 30432.543511847784, 23050.76949230553, 28314.009009684563, 28878.711212888444, 29992.400151997273, 30067.248318792204, 33454.163645909044, 33612.831935840164, 34247.828760856035, 34424.0, 34623.13442163865, 38305.80847095747, 38396.80801596086, 38747.0, 39920.00199994907, 40983.79508102559, 30790.230244243176, 32122.0, 33034.504482433265, 33409.0, 33731.0], "value": 19374.62537393692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.455098311381, 410.5176417709191, 417.25103439326404, 425.82966813274675, 442.80073966714986, 20258.088386022628, 20964.0, 22178.334649960485, 22288.21991230305, 22323.44191395216, 23236.76763232367, 23802.92886820093, 24086.87956560219, 24233.030678772873, 24464.021439142456, 6874.415674667659, 6992.741306564816, 7265.709371625128, 7287.635618219095, 7289.599072051035, 6980.441564674839, 7105.076340075783, 7169.89245161323, 7182.892256616155, 7191.280871912819, 6909.792706218796, 6977.965110174468, 7116.893246601279, 7120.750773722921, 7191.316824901626, 5848.766049358063, 6810.548202647043, 7093.184283807351, 7147.53541019832, 7180.3537681608905, 5614.214010038598, 5637.182608521783, 5661.688607126606, 5714.51426628737, 5736.684482353469, 26733.0, 26918.057867974723, 27157.728422715718, 27295.0, 27710.198837075524, 2874.3963767608825, 3081.7072378124044, 3094.5977022987095, 3110.3001824589514, 3143.984280078585, 16762.664746705, 16955.660886782196, 17058.402955896607, 17528.91235543814, 17935.192916318796, 1956.9823691680247, 2117.0, 2138.8930553472264, 2213.369189780905, 2266.897989590472, 64528.0, 106536.0, 163316.0, 169033.0, 182793.99999999866, 19492.12285447158, 20598.0, 20723.378298651063, 20765.0, 20837.0, 31512.912251284724, 32774.8361258197, 32792.67207327944, 32844.507332390036, 32875.34249315048, 10960.890391096147, 11164.609238676607, 11194.272372295774, 11321.830172547418, 11329.943350283196, 9259.953700231456, 9265.536723163816, 9283.16451519353, 9464.384814987005, 9471.715848524555, 9386.202172815387, 9726.70819875405, 9843.950780246052, 9863.605455781832, 9873.111419972238, 3356.4965255211914, 4015.9397609035886, 4060.269151552749, 4079.63283304504, 4099.528554216244, 1023.130339211679, 1037.8391349340864, 1051.9585610245801, 1067.247535782135, 1069.2622090757352, 21544.353669389944, 21854.7499389853, 22037.78792166414, 22816.429589260402, 24427.267181984567, 1029.3566520924421, 1029.917606591471, 1041.094248004241, 1062.6865074802952, 1065.1585247654266, 7072.92927070733, 7142.750003749844, 7179.5333303336165, 7429.62851857405, 7437.665305061285, 764120.0, 776821.2317876862, 783384.0, 799394.0, 814960.0, 135139.0, 174622.0, 174718.0, 179020.0, 180213.0989345071, 1237.975240495185, 1278.3097127551018, 1330.1420583723443, 1330.4545136494185, 1344.2405041151717, 20581.176752929714, 20753.0, 20870.895645521676, 21249.04379302966, 21555.892220538797, 56902.0, 62371.0, 64999.67500162469, 65361.67319163564, 66485.0, 72396.63801680958, 89331.0, 90409.54795225982, 92526.0, 92587.53706231425, 9811.509424528887, 11834.171607987359, 12054.517819287481, 12162.878371216175, 12205.633830985085, 13369.866301336862, 13447.932760336136, 13551.322433878464, 13860.930695346458, 13892.93053534726, 11621.825672614748, 11755.706872243874, 12071.034317254775, 12092.274463532216, 12152.756944860876, 10718.24972251935, 10766.354018758899, 11021.173411993972, 11064.114870810477, 11151.219414640897, 11692.590759323382, 11792.823107653563, 12153.453094610586, 12188.695282618006, 12234.96002839733, 11691.473883675426, 11828.881711182778, 11919.106067044833, 12110.394480275774, 12125.393730313626, 11612.071034317065, 11687.123465740273, 11816.881831181921, 11921.642350729491, 12116.27302361861], "value": 11833.29465399285, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.3068481101857, 1594.6571487130266, 1615.983840161598, 1625.463597012986, 1632.5347276026332, 9555.56999935003, 9905.405675659465, 11052.171087168465, 11247.83128253077, 11559.826602600946, 9677.419354838714, 11314.886851131507, 11355.261907975975, 11398.943005284962, 11418.257813242155, 2118.8834614096218, 2473.6907886514186, 2508.924732258026, 2514.333701569086, 2518.0934863449143, 2179.553191595727, 2202.702635144259, 2203.724534433196, 2203.889805509726, 2204.7244094488187, 2048.94877628059, 2094.6334391481505, 2486.987565062172, 2492.67595212623, 2495.3262619092834, 2445.3519817248443, 2471.1968610201666, 2480.2559232230437, 2482.689663792026, 2487.005197920837, 2461.4215659320043, 2465.7287698353166, 2470.4688491974134, 2472.443700167466, 2478.8388754730886, 11246.0, 11371.715707107305, 11395.772084558263, 11405.429728513542, 11493.885061149449, 5411.702356370398, 5745.425457454221, 5985.551083668699, 6117.204763380777, 6131.693415329216, 3583.229605634775, 3811.7331786774935, 3881.5342158940825, 3893.0267433141717, 3894.7078969077434, 1218.3542722357165, 1241.4599649152558, 1287.427094942751, 1290.5612091888802, 1300.0704496285107, 16155.0, 16213.513594592181, 16223.188840558043, 16247.350105995745, 16279.674406511804, 5049.116404629175, 5118.232265160255, 5135.768890399941, 5188.662736922088, 5188.948110518922, 13162.473501060042, 13184.406701698446, 13187.802182967263, 13192.868071319355, 13200.603981880558, 5253.343332083489, 6167.506599472032, 6189.566730328834, 6194.442500174916, 6198.101275315054, 5388.084025715638, 5500.312460942382, 5518.117101263779, 5568.248286481273, 5611.7474713637985, 3794.051487128218, 3879.0884142226555, 4117.3206420940205, 4234.110836724322, 4284.057507348379, 1085.3650614390513, 1156.8843115688533, 1157.242006485753, 1179.4574495731888, 1181.6159748081907, 978.2174260591498, 986.8158210147851, 1001.4942454060653, 1008.3395376028712, 1026.5175367577226, 5945.851353716192, 6591.76928807499, 6630.900536491957, 6857.657117144123, 6930.480213983971, 1045.414567842011, 1046.398320965448, 1063.3460421840666, 1071.3023392424275, 1091.1270983213396, 4036.788963310995, 4063.688094203215, 4253.744775313491, 4409.11817636474, 4480.924578101232, 13341.799873001914, 13613.455461781417, 13807.654808629864, 13917.373718182705, 13999.230042347768, 14024.08843425174, 14043.016988810685, 14052.71894562103, 14053.086549374259, 14055.0, 9079.773005674911, 10245.0, 10251.026152515498, 10359.274850760525, 10531.104856087319, 6743.393094621508, 6994.965025174842, 7010.614416207158, 7154.39187669054, 7268.418526517971, 11243.49404276826, 12656.810147847973, 12876.098673092794, 12916.483340666267, 12945.417456214305, 12630.684232894251, 13008.284544350152, 13040.54358097462, 13040.869591303965, 13068.869311306766, 9305.348625596414, 9649.083337082966, 9661.855072174063, 9672.516374181403, 9675.564599593175, 8150.98112735908, 10429.113525350127, 10502.947485262524, 10584.84122738175, 10635.308704934154, 7788.84422311562, 8335.833283334421, 9026.86459703118, 9066.637334506546, 9100.408473449204, 8818.735437936872, 8864.06927272627, 8871.866921996047, 8917.375783695079, 9052.592633331384, 8154.633041513028, 8266.586670666564, 8287.917120828715, 8293.875591866006, 8296.170382961656, 5916.585838991229, 6058.878822423615, 6151.108089326933, 6177.289611694712, 6213.991975176076, 6004.039353703386, 6060.848438796615, 6105.267367915879, 6288.591241569284, 6341.239051313872], "value": 5300.077556804702, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.1948134218547, 345.3903859687651, 372.7521198403062, 389.6298516409412, 393.87592908233904, 4957.289735041414, 5034.874128146794, 5081.263216833566, 5229.927864787719, 5272.683638981659, 4126.690498212631, 4264.637505812009, 5020.949790502103, 5026.748662566875, 5050.469700681421, 4208.852690155845, 4431.645468362523, 4447.577480139382, 4449.755263460508, 4465.548696673594, 3553.4492153716214, 3717.8846346096198, 4362.912741745163, 4373.666031860282, 4434.957784920541, 3626.0390996385977, 3843.9039024024482, 3872.7869967151796, 4111.129436106579, 4408.993907771956, 3728.9254214915686, 3960.4653371795016, 4072.450219220397, 4352.412424322739, 4440.533743956887, 3715.535558055243, 3819.980900095482, 4004.979975100135, 4316.280743131838, 4358.455193100863, 4002.6197511236387, 4520.954790452086, 4816.470188279286, 4927.285543596158, 4992.350994370709, 5059.418166910816, 5125.436741793751, 5126.461721519261, 5127.307813445174, 5130.256112863651, 4441.311596702516, 5424.783008679648, 5456.972715136439, 5488.012157811592, 5518.779248830042, 4226.154769046203, 4408.493023302329, 4602.562756538124, 4672.3692301539195, 4672.948586567995, 5171.663841850268, 5597.328320601513, 5631.943680563224, 5713.714314284269, 5721.971390143022, 4005.619466150711, 4132.2561938850695, 4834.6374021948495, 4835.153848076643, 4837.588804951548, 4217.270918923288, 4713.82456554101, 4881.51184881509, 4933.45731969483, 4940.604751619861, 4446.911061778746, 4457.977710111429, 5060.898782024339, 5162.8967420652125, 5205.47945205484, 5343.251944727741, 5348.732563371816, 5363.892722145613, 5452.945470545323, 5485.175134805579, 1465.9193744344163, 1490.7987421697933, 1578.2661062605944, 1678.447436121607, 1717.1156854220087, 1340.9396577154052, 1367.7826734206565, 1375.651861176053, 1389.9861001389856, 1412.8940329475124, 4466.665000125004, 4609.1703493371515, 4944.566075837966, 4982.8754281143265, 4987.850364489072, 4663.790129444184, 5350.3312085989255, 5509.256250406224, 5559.6664200148125, 5561.915426491876, 4359.520452750195, 4552.580828658489, 4806.7836947337455, 4937.950620493821, 5031.471695471996, 2617.345663584104, 2830.0614079355705, 2896.811707239023, 2913.7762139901292, 2931.080142506619, 4984.4174524532755, 5060.873478163002, 5085.898282034339, 5315.107066007054, 5586.385497595261, 5183.5593974512585, 5444.3594856494565, 5459.863503412446, 5526.364468086141, 5585.609007369445, 5414.10506322776, 5525.723713814293, 5810.302763668303, 5846.824595262149, 5847.427132061879, 4640.976795116003, 4915.6067514598035, 5369.624126311277, 5623.662580245198, 5695.544356451391, 4825.0, 5393.865153371197, 5514.035043867388, 5527.695976721158, 5577.6653400796085, 4941.703497790144, 5240.790368385375, 5619.747111379916, 5690.829275121753, 5836.416358364129, 4127.122159417518, 4548.863534093983, 4612.262038073892, 4618.538146185355, 4690.4202688521855, 4783.918995237083, 5060.443351231435, 5322.441143679858, 5605.103183490622, 5630.718464076698, 4756.9524304756505, 5014.997000599822, 5306.6550674206055, 5391.81128660495, 5484.7806087757635, 5038.470960549236, 5374.946250537445, 5388.892222155457, 5413.729313534229, 5463.656393167362, 4427.413794826201, 4949.084419382426, 5267.947320526899, 5268.78924843002, 5329.813456529003, 4660.997885454643, 4843.903121937471, 5038.546530812245, 5089.440161582149, 5408.296921400193, 4227.830886764495, 4301.827926882889, 4928.827491037797, 5215.556677682288, 5339.252504649275], "value": 4202.507948707289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.269583331252, 828.3125006244817, 839.3578912132224, 910.9362344635875, 917.9311551633627, 16066.91966540167, 17809.10954452277, 17846.910765446188, 17923.462296131136, 17939.551511212227, 14481.78277325841, 14519.0, 14539.273036348193, 14545.0, 14547.272636368192, 988.4316518002141, 989.529973262698, 992.6525715999414, 993.4635296939643, 994.2543092680489, 984.4093543873654, 987.9753006174831, 989.2382865193797, 990.4107056301489, 990.7721224118457, 1034.2398337222135, 1061.984070238947, 1070.3417398300066, 1080.1143062688598, 1080.3140006096146, 1065.222387657007, 1065.7602039541118, 1068.3910171202417, 1068.3910171202417, 1069.2141276162015, 790.1426951757293, 790.5651891459675, 791.9683212671544, 792.0614072324314, 792.1009654042646, 14821.407143714347, 15071.47249846261, 17286.91356543222, 17706.114694265234, 17729.556761081076, 14812.777808332883, 14962.251887405586, 15225.238738063052, 15267.465638702592, 15830.129342886137, 12297.0, 12334.198277111958, 12426.316552589604, 12473.50105995759, 12727.109102362838, 3883.8834834954996, 3934.0951581136214, 3938.1532970411213, 4107.609777071173, 4117.814698338582, 116220.0, 117038.41480792627, 119584.0, 122610.0, 125862.99999999908, 11309.547618095185, 11564.0, 11605.76788464226, 11714.180007399571, 12268.0, 23826.642600361007, 24546.0, 24707.382315442257, 24736.5052698945, 24737.752622473545, 7970.760877173694, 8009.31920786728, 8034.959825200836, 8059.798505037304, 8362.665493380196, 4476.082403107355, 4598.954010459919, 4622.653301002439, 4633.791479383436, 4734.526547345238, 3507.8070706111407, 3556.5376501055216, 3570.8214589270433, 3573.10672331917, 3578.499010138583, 631.504269148716, 639.6322114784018, 644.6003477843767, 648.180052233915, 651.2770824384929, 655.015333313484, 656.153561905137, 674.1000763980142, 675.5035049238805, 712.6543626341324, 9292.953535232416, 10168.0, 10264.897351026395, 10464.424456654957, 10482.895171048345, 617.5029101573186, 637.3562701671345, 650.3626446082865, 673.3372338632081, 711.5943911970231, 4607.562281583244, 4725.873356989368, 4756.548127927841, 4770.785314660849, 4852.951470485321, 84089.15910840813, 96148.51925740467, 100144.0, 100796.49601751944, 102408.0, 54249.72875135599, 81506.18493815104, 94739.0, 95974.0, 97334.53998190032, 62388.37611623917, 77414.612926935, 79015.0, 83412.58293708492, 84360.57819710863, 9040.954795225982, 11447.656570302905, 11514.424278786195, 11576.942115289707, 11804.881951180378, 52386.73806630942, 52860.471395287095, 53060.0, 53996.73001634967, 54140.72929635484, 58719.706401469426, 65990.0, 66172.0, 67550.3244967544, 68237.0, 10953.671389858317, 14788.186649734367, 15030.699386012, 15217.77173342423, 15655.921720391325, 13349.866501335251, 15864.44474443389, 16127.274272657523, 16236.837631624005, 16816.0, 13194.538191163647, 14552.199629020506, 14583.92708036453, 15114.288138892769, 15349.539513814603, 12783.552575659807, 14349.139051656517, 14373.856261437251, 14803.555893323217, 15240.314185861442, 14779.92610036943, 14935.17856517902, 14943.701125977637, 14963.40146394132, 15061.548153555412, 9897.364715949661, 10604.575816967235, 11087.833682494931, 11197.496112674786, 11361.431928403712, 11547.364894930524, 11766.294022358343, 11901.880981190423, 12111.212771170198, 12170.208936419105], "value": 8008.059736568528, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1435.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 115.0, 114.0, 114.0, 116.0, 114.0, 116.0, 112.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 110.0, 110.0, 144.0, 110.0, 109.0, 108.0, 111.0, 143.0, 136.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 182.0, 186.0, 182.0, 183.0, 185.0, 185.0, 184.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 89.0, 62.0, 59.0, 59.0, 62.0, 93.0, 81.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 67.0, 86.0, 68.0, 69.0, 69.0, 81.0, 69.0, 67.0, 82.0], "value": 74.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 90.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 94.0, 95.0, 97.0, 95.0, 95.0, 97.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 85.0, 82.0, 83.0, 86.0, 84.0, 83.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 64.0, 63.0, 65.0, 66.0, 63.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 72.0, 100.0, 98.0, 89.0, 60.0, 61.0, 59.0, 60.0, 59.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 92.0, 71.0, 90.0, 89.0, 98.0, 76.0, 81.0, 84.0, 80.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 133.0, 138.0, 122.0, 148.0, 142.0, 141.0, 127.0, 174.0, 144.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 164.0, 168.0, 163.0, 160.0, 158.0, 199.0, 214.0, 165.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 64.0, 65.0, 63.0, 63.0, 63.0, 62.0, 64.0], "value": 63.6, "unit": "score"}]}]} 02:49:41 INFO - Return code: 0 02:49:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:41 INFO - # TBPL SUCCESS # 02:49:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:41 INFO - 2017-05-18 02:49:41.683 screenresolution[1657:9867] starting screenresolution argv=screenresolution get 02:49:41 INFO - 2017-05-18 02:49:41.685 screenresolution[1657:9867] Display 0: 1600x1200x32@60 02:49:41 INFO - 2017-05-18 02:49:41.691 screenresolution[1658:9871] starting screenresolution argv=screenresolution list 02:49:41 INFO - Available Modes on Display 0 02:49:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:41 INFO - Intel Iris: 02:49:41 INFO - Chipset Model: Intel Iris 02:49:41 INFO - Type: GPU 02:49:41 INFO - Bus: Built-In 02:49:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:41 INFO - Vendor: Intel (0x8086) 02:49:41 INFO - Device ID: 0x0a2e 02:49:41 INFO - Revision ID: 0x0009 02:49:41 INFO - Displays: 02:49:41 INFO - Display: 02:49:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:41 INFO - Display Serial Number: 1600x1200 60 02:49:41 INFO - Main Display: Yes 02:49:41 INFO - Mirror: Off 02:49:41 INFO - Online: Yes 02:49:41 INFO - Rotation: Supported 02:49:41 INFO - Return code: 0 02:49:41 INFO - Running post-action listener: _resource_record_post_action 02:49:41 INFO - [mozharness: 2017-05-18 09:49:41.926696Z] Finished run-tests step (success) 02:49:41 INFO - Running post-run listener: _resource_record_post_run 02:49:41 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 38503424; Write bytes: 422876160; Read time: 246; Write time: 1390 02:49:41 INFO - TinderboxPrint: CPU usage
53.7% 02:49:41 INFO - TinderboxPrint: I/O read bytes / time
38,503,424 / 246 02:49:41 INFO - TinderboxPrint: I/O write bytes / time
422,876,160 / 1,390 02:49:41 INFO - TinderboxPrint: CPU idle
2,208.0 (46.3%) 02:49:41 INFO - TinderboxPrint: CPU system
952.1 (19.9%) 02:49:41 INFO - TinderboxPrint: CPU user
1,613.6 (33.8%) 02:49:41 INFO - TinderboxPrint: Swap in / out
160,268,288 / 0 02:49:41 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119636480; Write bytes: 115824640; Read time: 10891; Write time: 271 02:49:42 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36316160; Write bytes: 295984128; Read time: 223; Write time: 1082 02:49:42 INFO - Running post-run listener: _upload_blobber_files 02:49:42 INFO - Blob upload gear active. 02:49:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:42 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 02:49:42 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'] 02:49:42 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 02:49:42 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:49:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:49:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:43 INFO - (blobuploader) - INFO - Done attempting. 02:49:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:49:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:44 INFO - (blobuploader) - INFO - Done attempting. 02:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:45 INFO - (blobuploader) - INFO - Done attempting. 02:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:47 INFO - (blobuploader) - INFO - Done attempting. 02:49:47 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:47 INFO - Return code: 0 02:49:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d98e76d3f84744b4db6692691ca89b2f6db0598d04010752408385cd460e7e09042523998b3f77efd90f9126626f49fdc3dd35ccfd6c59534ff36f5fe623db4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14e964b277790ee5165372c00a6c0cd3d582bafc375b239cdac3f49a1782bf95809bdfcdb274114e1805a9eab32febfd3386ea0ddfe9ec4cefc162fc56772a12", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d0e8d1df46a07c979be348721b4f3b4b4460141ef5d3f99f65117b97d8657590e679bc73eae4cdf5b04f1c2d863b98e53069907c854dbada95d24d808c26461", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0840c804d2713196629b9ac209c89853b2208cfda6630bbd1edf14a918271875a585ee2e54472a78386af8b08607297c8713696cf45724d867dbd86076fa7508"} 02:49:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:47 INFO - Contents: 02:49:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d98e76d3f84744b4db6692691ca89b2f6db0598d04010752408385cd460e7e09042523998b3f77efd90f9126626f49fdc3dd35ccfd6c59534ff36f5fe623db4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14e964b277790ee5165372c00a6c0cd3d582bafc375b239cdac3f49a1782bf95809bdfcdb274114e1805a9eab32febfd3386ea0ddfe9ec4cefc162fc56772a12", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d0e8d1df46a07c979be348721b4f3b4b4460141ef5d3f99f65117b97d8657590e679bc73eae4cdf5b04f1c2d863b98e53069907c854dbada95d24d808c26461", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0840c804d2713196629b9ac209c89853b2208cfda6630bbd1edf14a918271875a585ee2e54472a78386af8b08607297c8713696cf45724d867dbd86076fa7508"} 02:49:47 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:47 INFO - Copying logs to upload dir... 02:49:47 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.769008 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-18 02:49:48.048119) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:48.052145) ========= 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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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/d98e76d3f84744b4db6692691ca89b2f6db0598d04010752408385cd460e7e09042523998b3f77efd90f9126626f49fdc3dd35ccfd6c59534ff36f5fe623db4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14e964b277790ee5165372c00a6c0cd3d582bafc375b239cdac3f49a1782bf95809bdfcdb274114e1805a9eab32febfd3386ea0ddfe9ec4cefc162fc56772a12", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d0e8d1df46a07c979be348721b4f3b4b4460141ef5d3f99f65117b97d8657590e679bc73eae4cdf5b04f1c2d863b98e53069907c854dbada95d24d808c26461", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0840c804d2713196629b9ac209c89853b2208cfda6630bbd1edf14a918271875a585ee2e54472a78386af8b08607297c8713696cf45724d867dbd86076fa7508"} build_url:https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010919 build_url: 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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/d98e76d3f84744b4db6692691ca89b2f6db0598d04010752408385cd460e7e09042523998b3f77efd90f9126626f49fdc3dd35ccfd6c59534ff36f5fe623db4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14e964b277790ee5165372c00a6c0cd3d582bafc375b239cdac3f49a1782bf95809bdfcdb274114e1805a9eab32febfd3386ea0ddfe9ec4cefc162fc56772a12", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d0e8d1df46a07c979be348721b4f3b4b4460141ef5d3f99f65117b97d8657590e679bc73eae4cdf5b04f1c2d863b98e53069907c854dbada95d24d808c26461", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0840c804d2713196629b9ac209c89853b2208cfda6630bbd1edf14a918271875a585ee2e54472a78386af8b08607297c8713696cf45724d867dbd86076fa7508"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:48.085688) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:48.086020) ========= 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.ofm85BZJQ8/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.QYkmS0Ucxm/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.005075 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:48.143159) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 02:49:48.143479) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 02:49:48.143794) ========= ========= Total master_lag: 0.82 =========