builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0268 starttime: 1496413573.7 results: success (0) buildid: 20170602062724 builduid: 831f3bd4da3c45eba8d960247119bf8f revision: a6a79438608114ac87218bcb467b1f8aa5d603f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:13.700783) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:13.701695) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:13.702017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:13.728663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:13.729214) ========= 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.ZLst6dsipp/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.zjIJ64nwew/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-02 07:26:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-06-02 07:26:14 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.632970 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:14.389394) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:14.390158) ========= 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.ZLst6dsipp/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.zjIJ64nwew/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.026948 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:14.442075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 07:26:14.442851) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6a79438608114ac87218bcb467b1f8aa5d603f3 --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 a6a79438608114ac87218bcb467b1f8aa5d603f3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZLst6dsipp/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.zjIJ64nwew/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-02 07:26:14,543 truncating revision to first 12 chars 2017-06-02 07:26:14,543 Setting DEBUG logging. 2017-06-02 07:26:14,543 attempt 1/10 2017-06-02 07:26:14,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6a794386081?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 07:26:15,532 unpacking tar archive at: mozilla-beta-a6a794386081/testing/mozharness/ program finished with exit code 0 elapsedTime=1.272798 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 07:26:15.740108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:15.740486) ========= script_repo_revision: a6a79438608114ac87218bcb467b1f8aa5d603f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:15.740967) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:15.741273) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 07:26:15.764222) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-02 07:26:15.764644) ========= /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.ZLst6dsipp/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.zjIJ64nwew/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:26:15 INFO - MultiFileLogger online at 20170602 07:26:15 in /builds/slave/test 07:26:15 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 07:26:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:26:15 INFO - {'append_to_log': False, 07:26:15 INFO - 'base_work_dir': '/builds/slave/test', 07:26:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:26:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:26:15 INFO - 'branch': 'Mozilla-Beta', 07:26:15 INFO - 'buildbot_json_path': 'buildprops.json', 07:26:15 INFO - 'config_files': ('talos/mac_config.py',), 07:26:15 INFO - 'default_actions': ('clobber', 07:26:15 INFO - 'read-buildbot-config', 07:26:15 INFO - 'download-and-extract', 07:26:15 INFO - 'populate-webroot', 07:26:15 INFO - 'create-virtualenv', 07:26:15 INFO - 'install', 07:26:15 INFO - 'run-tests'), 07:26:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:26:15 INFO - 'download_minidump_stackwalk': True, 07:26:15 INFO - 'download_symbols': 'ondemand', 07:26:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:26:15 INFO - 'tooltool.py': '/tools/tooltool.py', 07:26:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:26:15 INFO - '/tools/misc-python/virtualenv.py')}, 07:26:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:26:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:26:15 INFO - 'gecko_profile': False, 07:26:15 INFO - 'gecko_profile_interval': 0, 07:26:15 INFO - 'installer_path': 'installer.exe', 07:26:15 INFO - 'log_level': 'info', 07:26:15 INFO - 'log_name': 'talos', 07:26:15 INFO - 'log_to_console': True, 07:26:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:26:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:26:15 INFO - 'opt_config_files': (), 07:26:15 INFO - 'pip_index': False, 07:26:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:26:15 INFO - 'cmd': ('bash', 07:26:15 INFO - '-c', 07:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:26:15 INFO - 'enabled': True, 07:26:15 INFO - 'halt_on_failure': False, 07:26:15 INFO - 'name': 'check_screen_resolution'},), 07:26:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:26:15 INFO - 'cmd': ('bash', 07:26:15 INFO - '-c', 07:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:26:15 INFO - 'enabled': True, 07:26:15 INFO - 'halt_on_failure': False, 07:26:15 INFO - 'name': 'check_screen_resolution'},), 07:26:15 INFO - 'run_cmd_checks_enabled': True, 07:26:15 INFO - 'suite': 'dromaeojs', 07:26:15 INFO - 'system_bits': '32', 07:26:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:26:15 INFO - 'title': 't-yosemite-r7-0268', 07:26:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:26:15 INFO - 'use_talos_json': True, 07:26:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:26:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:26:15 INFO - 'work_dir': 'build'} 07:26:15 INFO - [mozharness: 2017-06-02 14:26:15.972882Z] Running clobber step. 07:26:15 INFO - Running pre-action listener: _resource_record_pre_action 07:26:15 INFO - Running main action method: clobber 07:26:15 INFO - rmtree: /builds/slave/test/build 07:26:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:26:18 INFO - Running post-action listener: _resource_record_post_action 07:26:18 INFO - [mozharness: 2017-06-02 14:26:18.417815Z] Finished clobber step (success) 07:26:18 INFO - [mozharness: 2017-06-02 14:26:18.417987Z] Running read-buildbot-config step. 07:26:18 INFO - Running pre-action listener: _resource_record_pre_action 07:26:18 INFO - Running main action method: read_buildbot_config 07:26:18 INFO - Using buildbot properties: 07:26:18 INFO - { 07:26:18 INFO - "project": "", 07:26:18 INFO - "product": "firefox", 07:26:18 INFO - "script_repo_revision": "production", 07:26:18 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 07:26:18 INFO - "repository": "", 07:26:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 07:26:18 INFO - "buildid": "20170602062724", 07:26:18 INFO - "pgo_build": "False", 07:26:18 INFO - "basedir": "/builds/slave/test", 07:26:18 INFO - "buildnumber": 1, 07:26:18 INFO - "slavename": "t-yosemite-r7-0268", 07:26:18 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:26:18 INFO - "platform": "yosemite_r7_devedition", 07:26:18 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 07:26:18 INFO - "branch": "mozilla-beta", 07:26:18 INFO - "repo_path": "releases/mozilla-beta", 07:26:18 INFO - "revision": "a6a79438608114ac87218bcb467b1f8aa5d603f3", 07:26:18 INFO - "stage_platform": "macosx64-devedition", 07:26:18 INFO - "builduid": "831f3bd4da3c45eba8d960247119bf8f", 07:26:18 INFO - "slavebuilddir": "test" 07:26:18 INFO - } 07:26:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:26:18 INFO - Running post-action listener: _resource_record_post_action 07:26:18 INFO - [mozharness: 2017-06-02 14:26:18.421515Z] Finished read-buildbot-config step (success) 07:26:18 INFO - [mozharness: 2017-06-02 14:26:18.421628Z] Running download-and-extract step. 07:26:18 INFO - Running pre-action listener: _resource_record_pre_action 07:26:18 INFO - Running main action method: download_and_extract 07:26:18 INFO - mkdir: /builds/slave/test/build/tests 07:26: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')]} 07:26:18 INFO - trying https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:26:18 INFO - Downloading https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/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 07:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/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 07:26:19 INFO - Downloaded 1287 bytes. 07:26:19 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:26:19 INFO - Using the following test package requirements: 07:26:19 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:26:19 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:26:19 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:26:19 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:26:19 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:26:19 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:26:19 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:26:19 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:26:19 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:26:19 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:26:19 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:26:19 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:26:19 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:26:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:26:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:26:19 INFO - Fetch https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:26:21 INFO - Expected file size: 51963761 07:26:21 INFO - Obtained file size: 51963761 07:26:37 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 07:26:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:26:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:26:37 INFO - Fetch https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:26:38 INFO - Expected file size: 51963761 07:26:38 INFO - Obtained file size: 51963761 07:26:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:26:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:26:53 INFO - Fetch https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:26:55 INFO - Expected file size: 14404461 07:26:55 INFO - Obtained file size: 14404461 07:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:55 INFO - trying https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:26:55 INFO - Downloading https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:26:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/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 07:26:58 INFO - Downloaded 74893964 bytes. 07:26:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:26:58 INFO - mkdir: /builds/slave/test/properties 07:26:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:26:58 INFO - Writing to file /builds/slave/test/properties/build_url 07:26:58 INFO - Contents: 07:26:58 INFO - build_url:https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:26:59 INFO - Running post-action listener: _resource_record_post_action 07:26:59 INFO - Running post-action listener: set_extra_try_arguments 07:26:59 INFO - [mozharness: 2017-06-02 14:26:59.897524Z] Finished download-and-extract step (success) 07:26:59 INFO - [mozharness: 2017-06-02 14:26:59.897691Z] Running populate-webroot step. 07:26:59 INFO - Running pre-action listener: _resource_record_pre_action 07:26:59 INFO - Running main action method: populate_webroot 07:26:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:26:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:26:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:26:59 INFO - '--tppagecycles', 07:26:59 INFO - '7'], 07:26:59 INFO - 'tests': ['tsvgm']}}, 07:26:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:26:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:26:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:26:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:26:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:26:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:26:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['damp', 'tps']}, 07:26:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'tests': ['damp', 'tps']}, 07:26:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['dromaeo_dom']}, 07:26:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:26:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['basic_compositor_video']}, 07:26:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:26:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['a11yr', 07:26:59 INFO - 'ts_paint', 07:26:59 INFO - 'tpaint', 07:26:59 INFO - 'sessionrestore', 07:26:59 INFO - 'sessionrestore_no_auto_restore', 07:26:59 INFO - 'tabpaint']}, 07:26:59 INFO - 'other-e10s': {'tests': ['a11yr', 07:26:59 INFO - 'ts_paint', 07:26:59 INFO - 'tpaint', 07:26:59 INFO - 'sessionrestore', 07:26:59 INFO - 'sessionrestore_no_auto_restore', 07:26:59 INFO - 'tabpaint']}, 07:26:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:26:59 INFO - 'ts_paint', 07:26:59 INFO - 'tpaint', 07:26:59 INFO - 'sessionrestore', 07:26:59 INFO - 'sessionrestore_no_auto_restore', 07:26:59 INFO - 'tabpaint']}, 07:26:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:26:59 INFO - 'ts_paint', 07:26:59 INFO - 'tpaint', 07:26:59 INFO - 'sessionrestore', 07:26:59 INFO - 'sessionrestore_no_auto_restore', 07:26:59 INFO - 'tabpaint']}, 07:26:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['a11yr', 07:26:59 INFO - 'ts_paint', 07:26:59 INFO - 'tpaint', 07:26:59 INFO - 'sessionrestore', 07:26:59 INFO - 'sessionrestore_no_auto_restore', 07:26:59 INFO - 'tabpaint']}, 07:26:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['a11yr', 07:26:59 INFO - 'ts_paint', 07:26:59 INFO - 'tpaint', 07:26:59 INFO - 'sessionrestore', 07:26:59 INFO - 'sessionrestore_no_auto_restore', 07:26:59 INFO - 'tabpaint']}, 07:26:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['tsvgx', 07:26:59 INFO - 'tsvgr_opacity', 07:26:59 INFO - 'tart', 07:26:59 INFO - 'tscrollx', 07:26:59 INFO - 'cart', 07:26:59 INFO - 'tsvg_static']}, 07:26:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:26:59 INFO - 'tsvgr_opacity', 07:26:59 INFO - 'tart', 07:26:59 INFO - 'tscrollx', 07:26:59 INFO - 'cart', 07:26:59 INFO - 'tsvg_static']}, 07:26:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'talos_options': ['--disable-e10s'], 07:26:59 INFO - 'tests': ['tp5o']}, 07:26:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:26:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'talos_options': ['--disable-e10s', 07:26:59 INFO - '--xperf_path', 07:26:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:26:59 INFO - 'tests': ['tp5n']}, 07:26:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:26:59 INFO - 'talos_options': ['--xperf_path', 07:26:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:26:59 INFO - 'tests': ['tp5n']}}, 07:26:59 INFO - 'talos.zip': {'path': '', 07:26:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:26:59 INFO - Running post-action listener: _resource_record_post_action 07:26:59 INFO - [mozharness: 2017-06-02 14:26:59.916239Z] Finished populate-webroot step (success) 07:26:59 INFO - [mozharness: 2017-06-02 14:26:59.916349Z] Running create-virtualenv step. 07:26:59 INFO - Running pre-action listener: _resource_record_pre_action 07:26:59 INFO - Running main action method: create_virtualenv 07:26:59 INFO - Creating virtualenv /builds/slave/test/build/venv 07:26:59 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 07:26:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:26:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:27:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:27:00 INFO - Using real prefix '/tools/python27' 07:27:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:27:00 INFO - Installing distribute.............................................................................................................................................................................................done. 07:27:03 INFO - Installing pip.................done. 07:27:03 INFO - Return code: 0 07:27:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:27:03 INFO - Reading from file tmpfile_stdout 07:27:03 INFO - Output received: 07:27:03 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) 07:27:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27: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')]} 07:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:03 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:03 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 07:27:03 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 07:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:03 INFO - 'HOME': '/Users/cltbld', 07:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:03 INFO - 'LOGNAME': 'cltbld', 07:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:03 INFO - 'MOZ_NO_REMOTE': '1', 07:27:03 INFO - 'NO_EM_RESTART': '1', 07:27:03 INFO - 'PAGER': '/bin/cat', 07:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:03 INFO - 'PWD': '/builds/slave/test', 07:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:03 INFO - 'SHELL': '/bin/bash', 07:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:03 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:03 INFO - 'USER': 'cltbld', 07:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:03 INFO - 'XPC_FLAGS': '0x0', 07:27:03 INFO - 'XPC_SERVICE_NAME': '0', 07:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:04 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 07:27:04 INFO - Cleaning up... 07:27:04 INFO - Return code: 0 07:27:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:04 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:04 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 07:27:04 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 07:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:04 INFO - 'HOME': '/Users/cltbld', 07:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:04 INFO - 'LOGNAME': 'cltbld', 07:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:04 INFO - 'MOZ_NO_REMOTE': '1', 07:27:04 INFO - 'NO_EM_RESTART': '1', 07:27:04 INFO - 'PAGER': '/bin/cat', 07:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:04 INFO - 'PWD': '/builds/slave/test', 07:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:04 INFO - 'SHELL': '/bin/bash', 07:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:04 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:04 INFO - 'USER': 'cltbld', 07:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:04 INFO - 'XPC_FLAGS': '0x0', 07:27:04 INFO - 'XPC_SERVICE_NAME': '0', 07:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:04 INFO - Downloading/unpacking psutil>=3.1.1 07:27:04 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 07:27:04 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 07:27:04 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 07:27:04 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 07:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:27:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:06 INFO - Installing collected packages: psutil 07:27:06 INFO - Running setup.py install for psutil 07:27:06 INFO - building 'psutil._psutil_osx' extension 07:27:06 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 07:27:07 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 07:27:07 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 07:27:07 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 07:27:07 INFO - building 'psutil._psutil_posix' extension 07:27:07 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 07:27:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:27:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:27:07 INFO - ^ 07:27:07 INFO - 1 warning generated. 07:27:07 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 07:27:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:07 INFO - Successfully installed psutil 07:27:07 INFO - Cleaning up... 07:27:07 INFO - Return code: 0 07:27:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:27:07 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')]} 07:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:07 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')]} 07:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:07 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:07 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 07:27:07 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 07:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:07 INFO - 'HOME': '/Users/cltbld', 07:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:07 INFO - 'LOGNAME': 'cltbld', 07:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:07 INFO - 'MOZ_NO_REMOTE': '1', 07:27:07 INFO - 'NO_EM_RESTART': '1', 07:27:07 INFO - 'PAGER': '/bin/cat', 07:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:08 INFO - 'PWD': '/builds/slave/test', 07:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:08 INFO - 'SHELL': '/bin/bash', 07:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:08 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:08 INFO - 'USER': 'cltbld', 07:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:08 INFO - 'XPC_FLAGS': '0x0', 07:27:08 INFO - 'XPC_SERVICE_NAME': '0', 07:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:27:08 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 07:27:08 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 07:27:08 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 07:27:08 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 07:27:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:27:09 INFO - Installing collected packages: mozsystemmonitor 07:27:09 INFO - Running setup.py install for mozsystemmonitor 07:27:09 INFO - Successfully installed mozsystemmonitor 07:27:09 INFO - Cleaning up... 07:27:09 INFO - Return code: 0 07:27:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:27:09 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')]} 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:09 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')]} 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:09 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:09 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 07:27:09 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 07:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:09 INFO - 'HOME': '/Users/cltbld', 07:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:09 INFO - 'LOGNAME': 'cltbld', 07:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:09 INFO - 'MOZ_NO_REMOTE': '1', 07:27:09 INFO - 'NO_EM_RESTART': '1', 07:27:09 INFO - 'PAGER': '/bin/cat', 07:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:09 INFO - 'PWD': '/builds/slave/test', 07:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:09 INFO - 'SHELL': '/bin/bash', 07:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:09 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:09 INFO - 'USER': 'cltbld', 07:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:09 INFO - 'XPC_FLAGS': '0x0', 07:27:09 INFO - 'XPC_SERVICE_NAME': '0', 07:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:10 INFO - Downloading/unpacking jsonschema==2.5.1 07:27:10 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 07:27:10 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 07:27:10 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 07:27:10 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 07:27:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:27:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:27:11 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 07:27:11 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 07:27:11 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 07:27:11 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 07:27:11 INFO - Downloading functools32-3.2.3-2.tar.gz 07:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:27:11 INFO - warning: no files found matching '*.txt' 07:27:11 INFO - no previously-included directories found matching 'build' 07:27:11 INFO - no previously-included directories found matching 'dist' 07:27:11 INFO - no previously-included directories found matching '.git*' 07:27:11 INFO - Installing collected packages: jsonschema, functools32 07:27:12 INFO - Running setup.py install for functools32 07:27:12 INFO - warning: no files found matching '*.txt' 07:27:12 INFO - no previously-included directories found matching 'build' 07:27:12 INFO - no previously-included directories found matching 'dist' 07:27:12 INFO - no previously-included directories found matching '.git*' 07:27:12 INFO - Successfully installed jsonschema functools32 07:27:12 INFO - Cleaning up... 07:27:12 INFO - Return code: 0 07:27:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:12 INFO - 'HOME': '/Users/cltbld', 07:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:12 INFO - 'LOGNAME': 'cltbld', 07:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:12 INFO - 'MOZ_NO_REMOTE': '1', 07:27:12 INFO - 'NO_EM_RESTART': '1', 07:27:12 INFO - 'PAGER': '/bin/cat', 07:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:12 INFO - 'PWD': '/builds/slave/test', 07:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:12 INFO - 'SHELL': '/bin/bash', 07:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:12 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:12 INFO - 'USER': 'cltbld', 07:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:12 INFO - 'XPC_FLAGS': '0x0', 07:27:12 INFO - 'XPC_SERVICE_NAME': '0', 07:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:27:12 INFO - Cleaning up... 07:27:12 INFO - Return code: 0 07:27:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:12 INFO - 'HOME': '/Users/cltbld', 07:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:12 INFO - 'LOGNAME': 'cltbld', 07:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:12 INFO - 'MOZ_NO_REMOTE': '1', 07:27:12 INFO - 'NO_EM_RESTART': '1', 07:27:12 INFO - 'PAGER': '/bin/cat', 07:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:12 INFO - 'PWD': '/builds/slave/test', 07:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:12 INFO - 'SHELL': '/bin/bash', 07:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:12 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:12 INFO - 'USER': 'cltbld', 07:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:12 INFO - 'XPC_FLAGS': '0x0', 07:27:12 INFO - 'XPC_SERVICE_NAME': '0', 07:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:12 INFO - Downloading/unpacking blobuploader==1.2.4 07:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:14 INFO - Downloading blobuploader-1.2.4.tar.gz 07:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:27:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:27:14 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 07:27:14 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 07:27:14 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 07:27:14 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 07:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:27:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:27:14 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 07:27:14 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 07:27:14 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 07:27:14 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 07:27:14 INFO - Downloading docopt-0.6.1.tar.gz 07:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:27:15 INFO - Installing collected packages: blobuploader, requests, docopt 07:27:15 INFO - Running setup.py install for blobuploader 07:27:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:27:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:27:15 INFO - Running setup.py install for requests 07:27:15 INFO - Running setup.py install for docopt 07:27:15 INFO - Successfully installed blobuploader requests docopt 07:27:15 INFO - Cleaning up... 07:27:15 INFO - Return code: 0 07:27:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:15 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:15 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 07:27:15 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 07:27:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:15 INFO - 'HOME': '/Users/cltbld', 07:27:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:15 INFO - 'LOGNAME': 'cltbld', 07:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:15 INFO - 'MOZ_NO_REMOTE': '1', 07:27:15 INFO - 'NO_EM_RESTART': '1', 07:27:15 INFO - 'PAGER': '/bin/cat', 07:27:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:15 INFO - 'PWD': '/builds/slave/test', 07:27:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:15 INFO - 'SHELL': '/bin/bash', 07:27:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:15 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:15 INFO - 'USER': 'cltbld', 07:27:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:15 INFO - 'XPC_FLAGS': '0x0', 07:27:15 INFO - 'XPC_SERVICE_NAME': '0', 07:27:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-8ezIl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-VSsy2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Okf1ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-q1dsKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-avaAVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-UduOmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-rms4R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-zgHI9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ZBbJd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-9nEzRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-4Q3KRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-oEgEwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ceUzks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-pxH7MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-XfTTOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-3KiuZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-i6mOus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:27:17 INFO - Running setup.py install for manifestparser 07:27:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Running setup.py install for mozcrash 07:27:18 INFO - Running setup.py install for mozdebug 07:27:18 INFO - Running setup.py install for mozdevice 07:27:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Running setup.py install for mozfile 07:27:18 INFO - Running setup.py install for mozhttpd 07:27:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Running setup.py install for mozinfo 07:27:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Running setup.py install for mozInstall 07:27:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozleak 07:27:19 INFO - Running setup.py install for mozlog 07:27:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for moznetwork 07:27:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozprocess 07:27:19 INFO - Running setup.py install for mozprofile 07:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozrunner 07:27:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:27:20 INFO - Running setup.py install for mozscreenshot 07:27:20 INFO - Running setup.py install for moztest 07:27:20 INFO - Running setup.py install for mozversion 07:27:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:27:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:27:20 INFO - Cleaning up... 07:27:20 INFO - Return code: 0 07:27:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:20 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:20 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 07:27:20 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 07:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:20 INFO - 'HOME': '/Users/cltbld', 07:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:20 INFO - 'LOGNAME': 'cltbld', 07:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:20 INFO - 'MOZ_NO_REMOTE': '1', 07:27:20 INFO - 'NO_EM_RESTART': '1', 07:27:20 INFO - 'PAGER': '/bin/cat', 07:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:20 INFO - 'PWD': '/builds/slave/test', 07:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:20 INFO - 'SHELL': '/bin/bash', 07:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:20 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:20 INFO - 'USER': 'cltbld', 07:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:20 INFO - 'XPC_FLAGS': '0x0', 07:27:20 INFO - 'XPC_SERVICE_NAME': '0', 07:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-13BanN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:20 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)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-F0shUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-gT4nI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-o_7QjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MdpmXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-1p22mK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MnAQzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Mqfzoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-hJutxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-vRjKWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:21 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)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-DNspIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:22 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)) 07:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-yGtgLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:22 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)) 07:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-yxgrVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:22 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)) 07:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-WVbnUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:22 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)) 07:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-gYT2lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:22 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)) 07:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-5oYB9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:22 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)) 07:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-YpKDzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:22 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)) 07:27:22 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)) 07:27:22 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)) 07:27:22 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)) 07:27:22 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)) 07:27:22 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)) 07:27:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:24 INFO - Downloading blessings-1.6.tar.gz 07:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:24 INFO - Installing collected packages: blessings 07:27:24 INFO - Running setup.py install for blessings 07:27:24 INFO - Successfully installed blessings 07:27:24 INFO - Cleaning up... 07:27:24 INFO - Return code: 0 07:27:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:24 INFO - Reading from file tmpfile_stdout 07:27:24 INFO - Current package versions: 07:27:24 INFO - blessings == 1.6 07:27:24 INFO - blobuploader == 1.2.4 07:27:24 INFO - docopt == 0.6.1 07:27:24 INFO - functools32 == 3.2.3-2 07:27:24 INFO - jsonschema == 2.5.1 07:27:24 INFO - manifestparser == 1.1 07:27:24 INFO - mozInstall == 1.12 07:27:24 INFO - mozcrash == 1.0 07:27:24 INFO - mozdebug == 0.1 07:27:24 INFO - mozdevice == 0.49 07:27:24 INFO - mozfile == 1.2 07:27:24 INFO - mozhttpd == 0.7 07:27:24 INFO - mozinfo == 0.9 07:27:24 INFO - mozleak == 0.1 07:27:24 INFO - mozlog == 3.4 07:27:24 INFO - moznetwork == 0.27 07:27:24 INFO - mozprocess == 0.25 07:27:24 INFO - mozprofile == 0.28 07:27:24 INFO - mozrunner == 6.13 07:27:24 INFO - mozscreenshot == 0.1 07:27:24 INFO - mozsystemmonitor == 0.3 07:27:24 INFO - moztest == 0.8 07:27:24 INFO - mozversion == 1.4 07:27:24 INFO - psutil == 3.1.1 07:27:24 INFO - requests == 1.2.3 07:27:24 INFO - wsgiref == 0.1.2 07:27:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:24 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 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27:24 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 07:27:24 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 07:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:24 INFO - 'HOME': '/Users/cltbld', 07:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:24 INFO - 'LOGNAME': 'cltbld', 07:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:24 INFO - 'MOZ_NO_REMOTE': '1', 07:27:24 INFO - 'NO_EM_RESTART': '1', 07:27:24 INFO - 'PAGER': '/bin/cat', 07:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:24 INFO - 'PWD': '/builds/slave/test', 07:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:24 INFO - 'SHELL': '/bin/bash', 07:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:24 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:24 INFO - 'USER': 'cltbld', 07:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:24 INFO - 'XPC_FLAGS': '0x0', 07:27:24 INFO - 'XPC_SERVICE_NAME': '0', 07:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 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)) 07:27:24 INFO - Cleaning up... 07:27:25 INFO - Return code: 0 07:27:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27: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')]} 07:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d11ddebf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, '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.ZLst6dsipp/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.zjIJ64nwew/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:27: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', 'jsonschema'] in /builds/slave/test/build 07:27: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 jsonschema 07:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:25 INFO - 'HOME': '/Users/cltbld', 07:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:25 INFO - 'LOGNAME': 'cltbld', 07:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:25 INFO - 'MOZ_NO_REMOTE': '1', 07:27:25 INFO - 'NO_EM_RESTART': '1', 07:27:25 INFO - 'PAGER': '/bin/cat', 07:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:25 INFO - 'PWD': '/builds/slave/test', 07:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:25 INFO - 'SHELL': '/bin/bash', 07:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:25 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:25 INFO - 'USER': 'cltbld', 07:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:25 INFO - 'XPC_FLAGS': '0x0', 07:27:25 INFO - 'XPC_SERVICE_NAME': '0', 07:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:27:25 INFO - Cleaning up... 07:27:25 INFO - Return code: 0 07:27:25 INFO - Running post-action listener: _resource_record_post_action 07:27:25 INFO - Running post-action listener: _start_resource_monitoring 07:27:25 INFO - Starting resource monitoring. 07:27:25 INFO - [mozharness: 2017-06-02 14:27:25.312905Z] Finished create-virtualenv step (success) 07:27:25 INFO - [mozharness: 2017-06-02 14:27:25.313815Z] Running install step. 07:27:25 INFO - Running pre-action listener: _resource_record_pre_action 07:27:25 INFO - Running main action method: install 07:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:25 INFO - Reading from file tmpfile_stdout 07:27:25 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:25 INFO - Reading from file tmpfile_stdout 07:27:25 INFO - Output received: 07:27:25 INFO - Usage: mozinstall [options] installer 07:27:25 INFO - Options: 07:27:25 INFO - -h, --help show this help message and exit 07:27:25 INFO - -d DEST, --destination=DEST 07:27:25 INFO - Directory to install application into. [default: 07:27:25 INFO - "/builds/slave/test"] 07:27:25 INFO - --app=APP Application being installed. [default: firefox] 07:27:25 INFO - mkdir: /builds/slave/test/build/application 07:27:25 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'] 07:27:25 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 07:27:46 INFO - Reading from file tmpfile_stdout 07:27:46 INFO - Output received: 07:27:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:27:46 INFO - Running post-action listener: _resource_record_post_action 07:27:46 INFO - [mozharness: 2017-06-02 14:27:46.311924Z] Finished install step (success) 07:27:46 INFO - [mozharness: 2017-06-02 14:27:46.312069Z] Running run-tests step. 07:27:46 INFO - Running pre-action listener: _resource_record_pre_action 07:27:46 INFO - Running main action method: run_tests 07:27:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:27:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:27:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:27:46 INFO - 2017-06-02 07:27:46.326 screenresolution[1631:8026] starting screenresolution argv=screenresolution get 07:27:46 INFO - 2017-06-02 07:27:46.329 screenresolution[1631:8026] Display 0: 1600x1200x32@60 07:27:46 INFO - 2017-06-02 07:27:46.335 screenresolution[1632:8028] starting screenresolution argv=screenresolution list 07:27:46 INFO - Available Modes on Display 0 07:27:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:27:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:27:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:27:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:27:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:27:46 INFO - Intel Iris: 07:27:46 INFO - Chipset Model: Intel Iris 07:27:46 INFO - Type: GPU 07:27:46 INFO - Bus: Built-In 07:27:46 INFO - VRAM (Dynamic, Max): 1536 MB 07:27:46 INFO - Vendor: Intel (0x8086) 07:27:46 INFO - Device ID: 0x0a2e 07:27:46 INFO - Revision ID: 0x0009 07:27:46 INFO - Displays: 07:27:46 INFO - Display: 07:27:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:27:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:27:46 INFO - Display Serial Number: 1600x1200 60 07:27:46 INFO - Main Display: Yes 07:27:46 INFO - Mirror: Off 07:27:46 INFO - Online: Yes 07:27:46 INFO - Rotation: Supported 07:27:46 INFO - Return code: 0 07:27:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:27:46 INFO - Python 2.7.3 07:27:46 INFO - Return code: 0 07:27:46 INFO - grabbing minidump binary from tooltool 07:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:46 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 0x10128ed30>, '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 0x1011b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101283be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:27:46 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 07:27:46 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 07:27:46 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 07:27:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:27:46 INFO - Return code: 0 07:27:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:27:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:27:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:27:46 INFO - ENV: RUST_BACKTRACE is now 1 07:27:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:27:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:27:46 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 07:27:46 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0268 --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 07:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZLst6dsipp/Render', 07:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:46 INFO - 'HOME': '/Users/cltbld', 07:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:46 INFO - 'LOGNAME': 'cltbld', 07:27:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:27:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:46 INFO - 'MOZ_NO_REMOTE': '1', 07:27:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:27:46 INFO - 'NO_EM_RESTART': '1', 07:27:46 INFO - 'PAGER': '/bin/cat', 07:27:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:46 INFO - 'PWD': '/builds/slave/test', 07:27:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:46 INFO - 'RUST_BACKTRACE': '1', 07:27:46 INFO - 'SHELL': '/bin/bash', 07:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zjIJ64nwew/Listeners', 07:27:46 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:46 INFO - 'USER': 'cltbld', 07:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:46 INFO - 'XPC_FLAGS': '0x0', 07:27:46 INFO - 'XPC_SERVICE_NAME': '0', 07:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:46 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 07:27:46 INFO - mozversion application_buildid: 20170602062724 07:27:46 INFO - mozversion application_changeset: a6a79438608114ac87218bcb467b1f8aa5d603f3 07:27:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:27:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:27:46 INFO - mozversion application_name: Firefox 07:27:46 INFO - mozversion application_remotingname: firefox-dev 07:27:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:27:46 INFO - mozversion application_vendor: Mozilla 07:27:46 INFO - mozversion application_version: 54.0 07:27:46 INFO - mozversion platform_buildid: 20170602062724 07:27:46 INFO - mozversion platform_changeset: a6a79438608114ac87218bcb467b1f8aa5d603f3 07:27:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:27:46 INFO - mozversion platform_version: 54.0 07:27:46 INFO - using testdate: 1496413666 07:27:46 INFO - actual date: 1496413666 07:27:46 INFO - starting webserver on 'localhost:49226' 07:27:46 INFO - SUITE-START | Running 2 tests 07:27:46 INFO - TEST-START | dromaeo_css 07:27:46 INFO - operating with platform_type : mac_ 07:27:46 INFO - Initialising browser for dromaeo_css test... 07:27:46 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpYaB1il/profile http://localhost:49226/getInfo.html) 07:27:49 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 07:27:49 INFO - PROCESS | 1642 | colorDepth:24 07:27:49 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 07:27:49 INFO - PROCESS | 1642 | __metrics 07:27:49 INFO - PROCESS | 1642 | 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" 07:27:49 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:27:50 INFO - TEST-INFO | 1642: exit 0 07:27:50 INFO - Browser initialized. 07:27:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:27:50 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpYaB1il/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:28:02 INFO - PROCESS | 1646 | RSS: Main: 194015232 07:28:02 INFO - PROCESS | 1646 | 07:31:06 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 07:31:08 INFO - PROCESS | 1646 | RSS: Main: 316121088 07:31:08 INFO - PROCESS | 1646 | 07:34:11 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 07:34:11 INFO - PROCESS | 1646 | RSS: Main: 326590464 07:34:11 INFO - PROCESS | 1646 | 07:37:14 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 07:37:15 INFO - PROCESS | 1646 | RSS: Main: 312119296 07:37:15 INFO - PROCESS | 1646 | 07:40:17 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 07:40:18 INFO - PROCESS | 1646 | RSS: Main: 324329472 07:40:18 INFO - PROCESS | 1646 | 07:43:21 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 07:43:22 INFO - PROCESS | 1646 | RSS: Main: 288714752 07:43:22 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 07:46:26 INFO - PROCESS | 1646 | RSS: Main: 366743552 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | __start_tp_report 07:46:26 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 07:46:26 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 07:46:26 INFO - PROCESS | 1646 | |i|pagename|runs| 07:46:26 INFO - PROCESS | 1646 | |0;dojo.html;3403.183236023355;3414.7951122932627;3429.2112814052775;3432.691057804797;3441.673041061099;5878.088896221087;5894.528437724977;5912.172295878575;5921.0230311998575;5933.614315069517;5882.794102206423;5951.49412299955;5955.315138759033;5973.1040343948425;5978.103284507326;4515.864524064284;5288.973555132228;5325.334333208349;5369.704666243355;5432.511074003339;3997.800109994503;4375.387445757597;5304.3634763828395;5333.066713325172;5469.480399362054;4468.843590474334;4484.4170257866435;5332.786688532454;5354.063038968185;5366.409694933554;5314.840554783363;5347.957148356072;5379.488948549882;5380.892382152374;5404.1083221268445;5293.527971313018;5392.865178370533;5396.19057141428;5409.783608655649;5436.7553460094;5726.169705392695;5763.567732420043;6013.669248191348;6017.63894166347;6098.908516372258;6293.590916590407;6301.212348456443;6320.083587879778;6334.80995570129;6360.300366959631;6297.716602752841;6365;6370.617762934239;6373.426391624776;6374.968125159345;5266.578673706133;5844.240248767633;6025.397460253939;6105.786297479567;6119.571629985859;6319.8104056878365;6320.178376811077;6320.747170113144;6334.144890439824;6334.841628959313;6317.399847014526;6339.492840572835;6394;6405.583637063576;6412.326705695835;6320.905186422207;6361.777337793155;6371.235451745728;6380.011098279681;6561.901571476432;6346.682665866688;6352.237731472253;6355.523335749837;6358.237011558643;6372.426481616677;6269.310375858652;6326.051092336094;6328.430441260309;6344.968275158594;6368.554201205964;4879.463259041502;4881;4889.364382630235;4890.070886531619;4898.216285394391;4316.805743741539;4352.39066530686;4392.099619578043;4431.7562534060935;4473.642108631302;5678.034734095214;5682.6306290091015;5693.629914055573;5703.62926409782;5712.400197979236;6022.126791615279;6290.087937249074;6364.872702545923;6372.330905254974;6391.328910464427;5772.740226689809;5802.970985145132;5805.042168042226;5829.80488999754;5841.795537156264;5507.228987941691;5527.640703354269;5528.281323427928;5529.889402212014;5530.6128571000445;6190.50475961922;6298.1812364392335;6347.96826015867;6386.010168423902;6442.09810626522;6249.187605611241;6321.367863213642;6331.240251169889;6344.270408903035;6359.109724638462;6477.546571739933;6484.027395890465;6500.219973603198;6517.120188774455;6622.139121914121;6158.168647232566;6248.781292654735;6311.211098612674;6324.778632748016;6419.903701444576;6252.656103914328;6376.075469057054;6377.553571249968;6384.585001974857;6391.616503009835;6334.113224148531;6373.5857169283845;6378.904316435164;6390.297067322498;6395.6482393468805;6191.071339299141;6366.522510811615;6373.171487706569;6375;6382.840428989312;5908.586398952032;5919.4968427684125;6116.487945662109;6210.223722034746;6212.9068063978175;6288.937110629018;6333.398327158912;6334.081558174131;6336.841578960563;6368.235811702625;6156.938430615636;6188.1027251049245;6216.191895053614;6216.378362163748;6223.937760622336 07:46:26 INFO - PROCESS | 1646 | |1;ext.html;801.5591424716406;884.4074470105031;893.6202114101505;896.6951236579562;989.4261328429511;38614.61385386149;39280;40788.59211407885;40790.184196316055;40926.795366023165;38865.30779575943;39132.60867391325;39144.21711565767;39172.60827391725;39235.80382098086;7535.9246407535775;7707.113681926566;7780.377569794403;8021.518708877456;8183;7601.733939312126;7685;11634.6509604712;11849.111316651242;12021.879781202208;11771.352575608338;11790.823137652942;11798.58704945327;11820.23168494045;11836.644900652995;11749.060075193964;11780.587679431264;11783.764324713544;11784.941075294611;11803.23278986863;11400.657980260605;11477.311361318347;11481.024112950408;11486.0811135109;11497.540098396054;39795;39997.80001100005;40025;40116.79649610487;40117.398239026435;3902.64876161144;3903.180332130256;3943.0142464383903;3950.5654378018394;3997.620226078518;28082.43835123286;28768.856155719295;29056.340191119652;29494.93535452519;30140.397192056254;2703.8918443262246;3070.370574032318;3138.4978403455566;3218.9034328969933;3280.885169019097;363976;366571;367139;367254;367701;43069.35395969063;43665.56334436678;45525.54474455213;53644.19533706998;53931.921361572546;55404.44595554074;55542.88914221693;56070.43929560652;56113.438865611635;58272.417275827545;14885;14933.25333733309;14947.654711075838;14953.327100280514;14964.979727736834;12744.872551274368;12790.424430900632;12796.552120675731;12800.61598152057;12808.679783005313;12488;12505.49978000888;12533.060020498498;12587.685307867376;12627.684307892192;4965;5107.514786095315;5778.508826749689;5784.421557844182;5818.156367326798;838.5513750143643;944.2870632672289;948.8719022931816;960.4381436859516;982.8083523712951;24382.512349752906;28636.997705080223;28703.856480717463;29114.7088529112;29204;1066.632011955874;1075.9515821787882;1076.7146706122883;1097.1990446973755;1102.9613963511238;14585.708285834224;14731.705365892622;14769.33537990793;15000.849991500163;15110.773338399933;466603;466739;467026;467200;468482;57995.13007304894;58043.70978145082;58067.12899306514;58159.70920145372;58211.126833097536;1402.2217669193492;1407.5636552668705;1436.3249272841747;1443.81952255968;1453.8473460286518;26998.46003079888;27089.20469308962;27768.722312776612;27894.442111157256;28076.71923280741;41237.38143927784;45206;45890;47891.76054119707;47898.04203915972;431450;435872;437517;438482.61517384415;439750;24500.387490312885;25028;25615.103471378414;25678.74321256838;29375.412491750474;30490.847545762128;30532.542011869395;31380;33414.665853341154;41309.79345103255;26883;27254.182374528795;27593.44813103767;28865.42269154563;29078.56382154227;27699.584506232826;27884.58173127445;27934.161975140778;28021.859890700416;29122.417551649272;32754.508682369305;33414.83292583522;33510.49734253939;33539;33647.4952875712;23001.309960701205;27813.860930695217;28090.578641320804;28699.569506457003;29071.7092829069;21668.808215548295;21814.018369173744;22588.887055565276;22990.885045575335;23056.30831075003 07:46:26 INFO - PROCESS | 1646 | |2;jquery.html;419.23280396873724;426.1585593946656;427.54466493184754;431.37019950871723;437.4881388775133;20975;21612.675809862863;22291.99686014129;22572.774272257273;22643.094276228952;21962.341129766133;22825.657615135744;22900.541989160207;23188;23902;6709.161354830646;6996.545224560413;7000.649967501605;7124.964375178117;7453.254674532557;7322.1579518355275;7549.207333230014;7617.009788727485;7617.238276172394;7621.733239336624;7581.658825352873;7620.580868052255;7625.733099341524;7627.8855817162785;7667.961660191691;7304.780856574284;7539.811504712398;7605.8478830423355;7622.504537205064;7626.504277221963;7137.2149063602965;7366.152892417387;7388.076490438695;7394.37147842434;7416.443766717518;24573;25659.871700641565;26238.606420903703;26734.33164170892;26995.325116872038;3267.738580913545;3299.191698033973;3415.880444184442;3421.4525675891987;3562.7327950403565;15997.120158391284;16021;16041.518754437386;16250.51248462548;16548.917255413766;1973.5954129403437;2137.9251726189664;2171.4462811983003;2205.2171479125;2225.543763528473;155407;163744;163780.07869331003;172085.1395743013;172382.13808931;17506.824931750773;18313;19672.803271967383;19748.506287342643;20032.799672003384;31237.37525249482;31241.21896952549;31241.53137702936;31268.84365578203;31385.52921706176;9804.558794854249;9977.551010204557;10136.493175341204;10301.587936482456;10315.48422578883;8482.575871206414;8499.235068843835;8521.659133634585;8689.6524139035;8700.34747393948;8916.465012099165;9166.67916622915;9291;9301.906980930104;9321.300902432346;3949.150932549458;4002.7372534240553;4020.9195816083516;4034.1326614777963;4038.676905847525;1044.4516628769957;1048.2033654422694;1058.227493929436;1069.2461814420815;1122.2817396866012;21580.244691435724;22140.003699833545;22158.77841221567;22171.44571385696;22196.003539521957;1094.3379255550437;1101.8870940350143;1115.8382034604938;1129.2998341028615;1130.3331034689572;11238.943805280922;11499.712507187387;11546.653600391834;11568.13239007061;11595.652130435932;548335;751779;780570;781449;787565;127311.36344318218;145014;147772;152283;156627.21686391722;1219.810929305959;1226.337777600086;1252.1297698099916;1252.6930901928904;1256.296473974559;18262.634747305245;19196.13617387193;19349.322773702854;19357.4192774217;19466.513337166118;48426;60616.48458788566;61214;62248;63328.41678958063;85441;85773.57113214394;88121.11878881385;89651;89998.95004724672;11172.05037571783;11201.495932683212;11279.154063445438;11376.374299413612;11686.766264674488;12504.187227830162;12557.24656520575;12653.683657908627;12853;12863.421146048264;10872.565097396016;11043.233082706729;11077.83383249268;11375;11395.658130256106;9607.855882161633;10346.275760696679;10413.53139108757;10461.9476902615;10561.471926403803;11432.657020289405;11450.141239406912;11478.655640330804;11569.074474042225;11700.824487632863;9886.653967111404;11274.436278186222;11404.600838970597;11751.529938802694;11756.294622322688;11040.282381645167;11082.390468524309;11087.279326843729;11243.494042767932;11320.660380188607 07:46:26 INFO - PROCESS | 1646 | |3;mootools.html;1361.2649169448498;1467.2663668165917;1588.610790356363;1612.2664187794555;1634.6975809475252;9259.953700231497;10883.020528152465;11257.493412796413;11428.028617567496;11432.256903301279;11088.33469991801;11171.273867198626;11201;11201.775964480727;11221.60724374647;2284.0635339510845;2319.0491898321693;2321.129576408847;2322.2104484475276;2323.3146221864504;1942.494951312662;2021.757389113301;2280.2247235939776;2360.103160798894;2368.9407764805846;2311.3874823171873;2333.436597479693;2335.9838470265404;2339.1111377676457;2339.555484457956;2270.421042634126;2301.9424514387274;2303.021215983217;2306.688597039395;2307.3193407944696;1967.2131147540954;2259.243153543565;2266.909323627053;2267.3424706835;2294.105298933417;11146.944265278702;11232.49453774582;11235.269707468988;11257.493412796444;11282;5400.729963501848;5573.359063707685;5576.498115169654;5578.246936663539;5587.636803607753;3185.5540224368606;3350.0619826448633;3424.5376874121926;3425.657434256579;3426.880059197916;1018.8777346718367;1061.5435362793996;1118.111101674171;1132.8074227381364;1137.6416428824894;15517.146556939364;15563.066216026962;15579.61050973712;15590.688186236324;15596.674282685985;4498.977505112453;4558.8632341029825;4599.264117741145;4669.95330046702;4671.252599584051;12818.871811281955;12859;12885.420156092998;12899.290539020256;12904.612861614167;5641.12562552805;5645.322561292671;5647.181158731961;5651.6043876928225;5666.065099258659;4288.849890253826;4877.170880950247;4977.9751101244265;5043.747812609428;5097.057044446789;2973.3012742005612;3463.7921724696093;3750.962490375116;3861.7103717220757;3884.9805750971063;972.6887396033202;1007.1187710753092;1016.755978565139;1020.5815615597718;1025.5897640943533;815.3721634341642;842.5366048673205;854.7222152800365;865.2169786343369;891.7948871759531;6347.936520634827;6512.869742605121;6667.466602671871;6719;6819;1037.092544023979;1055.4511653940062;1063.8297872340388;1095.118429664112;1099.312929419113;3755.6244375562774;4352.651787856964;4365.279728844768;4388.297872340474;4502;13389.732205355838;13652.249126297951;13835.723285534234;13839.446422143203;13892.23592702411;13452.32738363078;13708.86291137076;13736.969727270494;13742.65643358924;13749.381277842522;10034.949825250827;10264.948675256725;10288.074073333435;10296.330738501973;10307.381557106448;6551.21385433751;6578.703958321791;6634.369734875179;6688.799336019927;6744.258131605618;11873;12382.257064576153;12413.565525206574;12421.813672794735;12444.875551244733;10833.69995600533;12652.746945061232;12664.556740514407;12686.682832929251;12700.745985080062;8806.163414475615;9257.67598134062;9279.953600231956;9283.72148835509;9331.346805723535;9111.635534578543;9595.42427454355;10408.323458975143;10640.829508754186;10645.680629581126;8726.345524085593;8913.688020919237;9032.096790320915;9032.503212323123;9065.27477801761;8573.871391929251;8693.26107280863;8704.216620504185;8756.168164024662;8835.381523293308;6759.86480270382;7773.222677732181;7907.9604601976625;7956.6419511123295;7965.362770978192;5762.971185144047;5832.475077243069;5835.503982161564;5916.171735956884;5995.430434108927;4945.950540494549;5796.246487956539;5932.228810254639;6160.943413182615;6215.875682486235 07:46:26 INFO - PROCESS | 1646 | |4;prototype.html;365.69281803285236;367.89147201575565;373.64317077191276;381.85871227645777;382.58680624925074;3929.6659783918403;4224.978875105624;4747.1692453820615;5015.272785446108;5122.436531981483;4067.5932406759307;4167.916641667172;4218.7257828241145;4536.954630453694;4702.694324868889;3766.3597188478025;3768.567944181221;4315.395844581761;4348;4348.391225228471;3639.4358874374516;3674.574780644775;3975.74763949355;4300.784960751966;4412.448443944507;3706.499622550948;3736.794476303802;4091.5499295077516;4111.814968326417;4111.93089796652;4069.6947728920154;4081.4490043844;4098.344264917614;4145.689073319483;4148.79256037197;3422.066532409181;3446.7414943879057;3753.343164946151;3873.0511024798825;3886.6696330811915;4618.630509559227;4659.510751371091;4751.14479393709;4794;4820.794171875734;4436.831091778075;4684.789184486706;4721.952780472186;4750.904981900343;4780.354652121954;5027.748612569357;5176.792928282902;5178;5202.944836789485;5251.422343542207;3320.933581328384;3831.2912111259507;4142.523609784883;4279.743215407086;4281.281386319678;5398.595105367113;5443.59173062018;5463.344398672145;5482.451754824566;5484.287042684426;4238.978805106017;4256.063141594013;4307.504636966726;4374.475062992461;4377.802998865059;3677.4667673187237;3728.981355093207;3908.5895980922164;4393.84621538251;4436.245838207513;4184.623383895464;4590.495045544987;4605.835257429003;4610.2854057621125;4757.762111894392;4646.465656449484;5217.217417387422;5242.977619364264;5258.710770907637;5284.233786100994;1356.6811799227173;1388.5209602687057;1417.688108616103;1452.2666053643054;1481.7258807120718;1140.676814894718;1237.1834589171103;1331.4873773597042;1379.834419869602;1394.7586647883393;4417.3815665806815;4625.907481850344;4645;4649.3026046093355;4655.976720116378;4945.332380128709;5377.5429088527135;5398.406175320712;5402.324709411324;5453.890922181534;3957.762534247954;4417.841243510905;4439.977800110979;4513;4641.883952901205;2491.8380305280098;2733.5762956741737;2747.5329194037063;2800.789940754411;2808.4804311202492;5012.598992080625;5325.307709997753;5540.63985840919;5585.692786896679;5608.4111168326735;5073.543381095719;5157.716325602053;5175.576716403019;5242.208836119226;5398;4560.566746159176;5237.554807841376;5327.893442131213;5509.669419834822;5524.392316845144;5338.813141540028;5339.893202136013;5351.759170837399;5355.651882627617;5363.704996225089;4708.528022788064;4919.704817710949;5243.292155558951;5267.631265811357;5294.9085111381555;4608.3778689876435;5366.543843773323;5507.347795661205;5554.000279949648;5588.972055139861;4297.398364229073;4760.976195119002;4780;4803.447603525499;4814.735189564608;4652.790624421841;4945.2582112683385;5344.679319240858;5357.116075847442;5393.9730301348245;4413.691041627056;4710.175719249186;5105.846824595268;5216.634835561475;5244.527992480543;4101.179764047143;4464.843730469418;4679.917436740618;4923.384576927884;5065.898682026413;4591.645464588021;4647.745108820667;5071.87320316995;5127.333446651912;5148.30497882781;4852.8544143675745;5000.674956127986;5011.373578302712;5103.719295438638;5225.6603320784025;4615.976920115378;4890.24201248807;5140.640155189101;5156.324194636833;5177.378714554127 07:46:26 INFO - PROCESS | 1646 | |5;yui.html;724.2286964382931;748.2554857916374;793.1513280789558;846.2933450568775;854.3421565394647;15531.922340388297;16334.18329083544;16340.101294428801;16356.83643163568;16396.508104756846;15031.699366012674;15123.092614443114;15124.016938898963;15127.168005759679;15153.318100685468;972.2173650211595;991.6033586565395;995.4027583449945;995.7112437393147;998.001998001998;970.0493516353978;982.228950274035;993.6820217530357;996.4270544436941;996.9202463802915;1045.7124290820009;1048.0096308988009;1049.4752623688155;1050.768830857214;1051.2431049644244;1045.5974449836792;1046.272840375939;1046.48722126158;1046.5238316565983;1046.785408991155;774.6939958716275;775.6393277126107;776.0687175389555;776.3517462918413;777.4324742937631;13721.313934303245;16191.433299834567;16234.918825405915;16856.915715421466;17134.914325428417;14204.92897535516;14365.92817035908;14381.065230760072;14395.424183032666;14397.78403323951;11670.76658466826;11751.589809222947;11827.172097953147;11880.40597970098;12534.310612916286;3580;3619.565652121736;3854.5952674969285;3888.300105980922;3903.1217975955465;87929;111354;118772;118972;120418;10586.629467968738;10727.088197503299;10876.129909607213;11203.71990700239;11552.364619945816;23168.65247021296;23350.7664923352;23380.415489612555;23388.883055584614;23403.531929361317;7859.292663660298;7863.764087077397;7948.403869709795;8027.518348899085;8043.879341809878;4344.9565504344555;4356.237658409766;4463.955360446419;4512.323151527232;4664.86005419838;3387.8644854205554;3474.5309383233443;3489.2323688788424;3493.039414161126;3495.4582039783368;504.45518839653084;598.3956204233668;618.0790621973317;626.5332327416104;627.1783963008473;577.6765011593521;592.8043745563954;603.3604379357895;660.41883142834;661.0414898397343;8381.95809020951;9975.301728879054;10858.511366988505;11259.718507037389;11348.262362946381;560.4087687489738;584.6287607369351;595.4670569839969;602.6474512410201;716.638097760638;4882.658213924991;5084.161113416245;5100.617453690914;5157.277981082651;5164.3802743671;97577;97835;99363.50318248507;101320;102696;53159;80264;81701.59149204216;83342.58328708439;85231;48182;63556;65256.673716631114;68270.65864670645;74082.62958685172;9175.22010629104;9494.572744226389;9858.30991830565;10301.74245643865;10850.67447976562;40585.59414405936;44488;45395;46093.53906461027;46296.074078517566;54564;55340;58509;67885.3211467879;69250.30749692439;13376.398062087035;13664.180149191081;13700.794488082487;14090.647733806736;14312.427502900184;14073.274153919105;14223.857761422667;14462.638434039232;15090.622734431727;15525.37898484048;13052.934735326262;13788.517401890886;13955.720885582434;13956.720865582834;14190.716185676436;12996.139417096854;13586.116902401312;13867;14062.015658903778;14173.149611023371;13100.868991310346;13633.931830340785;13762.65593360134;13921.791173132613;14489.637759056108;9579.425234485952;9984.441385913435;10099.7475063124;10121;10229.795404091728;9867.605295788375;10791.56833726642;11334.546618135182;11534.769304613694;11603.767924641292 07:46:26 INFO - PROCESS | 1646 | __end_tp_report 07:46:26 INFO - PROCESS | 1646 | __start_cc_report 07:46:26 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2311 07:46:26 INFO - PROCESS | 1646 | __end_cc_report 07:46:26 INFO - PROCESS | 1646 | __startTimestamp1496414786281__endTimestamp 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | ------- Summary: start ------- 07:46:26 INFO - PROCESS | 1646 | Number of tests: 6 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5851.92 Median:6190.50 stddev:713.51 (11.5%) stddev-sans-first:689.35 07:46:26 INFO - PROCESS | 1646 | Values: 3403.2 3414.8 3429.2 3432.7 3441.7 5878.1 5894.5 5912.2 5921.0 5933.6 5882.8 5951.5 5955.3 5973.1 5978.1 4515.9 5289.0 5325.3 5369.7 5432.5 3997.8 4375.4 5304.4 5333.1 5469.5 4468.8 4484.4 5332.8 5354.1 5366.4 5314.8 5348.0 5379.5 5380.9 5404.1 5293.5 5392.9 5396.2 5409.8 5436.8 5726.2 5763.6 6013.7 6017.6 6098.9 6293.6 6301.2 6320.1 6334.8 6360.3 6297.7 6365.0 6370.6 6373.4 6375.0 5266.6 5844.2 6025.4 6105.8 6119.6 6319.8 6320.2 6320.7 6334.1 6334.8 6317.4 6339.5 6394.0 6405.6 6412.3 6320.9 6361.8 6371.2 6380.0 6561.9 6346.7 6352.2 6355.5 6358.2 6372.4 6269.3 6326.1 6328.4 6345.0 6368.6 4879.5 4881.0 4889.4 4890.1 4898.2 4316.8 4352.4 4392.1 4431.8 4473.6 5678.0 5682.6 5693.6 5703.6 5712.4 6022.1 6290.1 6364.9 6372.3 6391.3 5772.7 5803.0 5805.0 5829.8 5841.8 5507.2 5527.6 5528.3 5529.9 5530.6 6190.5 6298.2 6348.0 6386.0 6442.1 6249.2 6321.4 6331.2 6344.3 6359.1 6477.5 6484.0 6500.2 6517.1 6622.1 6158.2 6248.8 6311.2 6324.8 6419.9 6252.7 6376.1 6377.6 6384.6 6391.6 6334.1 6373.6 6378.9 6390.3 6395.6 6191.1 6366.5 6373.2 6375.0 6382.8 5908.6 5919.5 6116.5 6210.2 6212.9 6288.9 6333.4 6334.1 6336.8 6368.2 6156.9 6188.1 6216.2 6216.4 6223.9 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:55719.21 Median:16801.46 stddev:112953.36 (672.3%) stddev-sans-first:113194.97 07:46:26 INFO - PROCESS | 1646 | Values: 801.6 884.4 893.6 896.7 989.4 38614.6 39280.0 40788.6 40790.2 40926.8 38865.3 39132.6 39144.2 39172.6 39235.8 7535.9 7707.1 7780.4 8021.5 8183.0 7601.7 7685.0 11634.7 11849.1 12021.9 11771.4 11790.8 11798.6 11820.2 11836.6 11749.1 11780.6 11783.8 11784.9 11803.2 11400.7 11477.3 11481.0 11486.1 11497.5 39795.0 39997.8 40025.0 40116.8 40117.4 3902.6 3903.2 3943.0 3950.6 3997.6 28082.4 28768.9 29056.3 29494.9 30140.4 2703.9 3070.4 3138.5 3218.9 3280.9 363976.0 366571.0 367139.0 367254.0 367701.0 43069.4 43665.6 45525.5 53644.2 53931.9 55404.4 55542.9 56070.4 56113.4 58272.4 14885.0 14933.3 14947.7 14953.3 14965.0 12744.9 12790.4 12796.6 12800.6 12808.7 12488.0 12505.5 12533.1 12587.7 12627.7 4965.0 5107.5 5778.5 5784.4 5818.2 838.6 944.3 948.9 960.4 982.8 24382.5 28637.0 28703.9 29114.7 29204.0 1066.6 1076.0 1076.7 1097.2 1103.0 14585.7 14731.7 14769.3 15000.8 15110.8 466603.0 466739.0 467026.0 467200.0 468482.0 57995.1 58043.7 58067.1 58159.7 58211.1 1402.2 1407.6 1436.3 1443.8 1453.8 26998.5 27089.2 27768.7 27894.4 28076.7 41237.4 45206.0 45890.0 47891.8 47898.0 431450.0 435872.0 437517.0 438482.6 439750.0 24500.4 25028.0 25615.1 25678.7 29375.4 30490.8 30532.5 31380.0 33414.7 41309.8 26883.0 27254.2 27593.4 28865.4 29078.6 27699.6 27884.6 27934.2 28021.9 29122.4 32754.5 33414.8 33510.5 33539.0 33647.5 23001.3 27813.9 28090.6 28699.6 29071.7 21668.8 21814.0 22588.9 22990.9 23056.3 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:42669.82 Median:22165.11 stddev:122980.34 (554.8%) stddev-sans-first:123284.42 07:46:26 INFO - PROCESS | 1646 | Values: 419.2 426.2 427.5 431.4 437.5 20975.0 21612.7 22292.0 22572.8 22643.1 21962.3 22825.7 22900.5 23188.0 23902.0 6709.2 6996.5 7000.6 7125.0 7453.3 7322.2 7549.2 7617.0 7617.2 7621.7 7581.7 7620.6 7625.7 7627.9 7668.0 7304.8 7539.8 7605.8 7622.5 7626.5 7137.2 7366.2 7388.1 7394.4 7416.4 24573.0 25659.9 26238.6 26734.3 26995.3 3267.7 3299.2 3415.9 3421.5 3562.7 15997.1 16021.0 16041.5 16250.5 16548.9 1973.6 2137.9 2171.4 2205.2 2225.5 155407.0 163744.0 163780.1 172085.1 172382.1 17506.8 18313.0 19672.8 19748.5 20032.8 31237.4 31241.2 31241.5 31268.8 31385.5 9804.6 9977.6 10136.5 10301.6 10315.5 8482.6 8499.2 8521.7 8689.7 8700.3 8916.5 9166.7 9291.0 9301.9 9321.3 3949.2 4002.7 4020.9 4034.1 4038.7 1044.5 1048.2 1058.2 1069.2 1122.3 21580.2 22140.0 22158.8 22171.4 22196.0 1094.3 1101.9 1115.8 1129.3 1130.3 11238.9 11499.7 11546.7 11568.1 11595.7 548335.0 751779.0 780570.0 781449.0 787565.0 127311.4 145014.0 147772.0 152283.0 156627.2 1219.8 1226.3 1252.1 1252.7 1256.3 18262.6 19196.1 19349.3 19357.4 19466.5 48426.0 60616.5 61214.0 62248.0 63328.4 85441.0 85773.6 88121.1 89651.0 89999.0 11172.1 11201.5 11279.2 11376.4 11686.8 12504.2 12557.2 12653.7 12853.0 12863.4 10872.6 11043.2 11077.8 11375.0 11395.7 9607.9 10346.3 10413.5 10461.9 10561.5 11432.7 11450.1 11478.7 11569.1 11700.8 9886.7 11274.4 11404.6 11751.5 11756.3 11040.3 11082.4 11087.3 11243.5 11320.7 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:6729.99 Median:2334.71 stddev:4330.17 (185.5%) stddev-sans-first:4323.53 07:46:26 INFO - PROCESS | 1646 | Values: 1361.3 1467.3 1588.6 1612.3 1634.7 9260.0 10883.0 11257.5 11428.0 11432.3 11088.3 11171.3 11201.0 11201.8 11221.6 2284.1 2319.0 2321.1 2322.2 2323.3 1942.5 2021.8 2280.2 2360.1 2368.9 2311.4 2333.4 2336.0 2339.1 2339.6 2270.4 2301.9 2303.0 2306.7 2307.3 1967.2 2259.2 2266.9 2267.3 2294.1 11146.9 11232.5 11235.3 11257.5 11282.0 5400.7 5573.4 5576.5 5578.2 5587.6 3185.6 3350.1 3424.5 3425.7 3426.9 1018.9 1061.5 1118.1 1132.8 1137.6 15517.1 15563.1 15579.6 15590.7 15596.7 4499.0 4558.9 4599.3 4670.0 4671.3 12818.9 12859.0 12885.4 12899.3 12904.6 5641.1 5645.3 5647.2 5651.6 5666.1 4288.8 4877.2 4978.0 5043.7 5097.1 2973.3 3463.8 3751.0 3861.7 3885.0 972.7 1007.1 1016.8 1020.6 1025.6 815.4 842.5 854.7 865.2 891.8 6347.9 6512.9 6667.5 6719.0 6819.0 1037.1 1055.5 1063.8 1095.1 1099.3 3755.6 4352.7 4365.3 4388.3 4502.0 13389.7 13652.2 13835.7 13839.4 13892.2 13452.3 13708.9 13737.0 13742.7 13749.4 10034.9 10264.9 10288.1 10296.3 10307.4 6551.2 6578.7 6634.4 6688.8 6744.3 11873.0 12382.3 12413.6 12421.8 12444.9 10833.7 12652.7 12664.6 12686.7 12700.7 8806.2 9257.7 9280.0 9283.7 9331.3 9111.6 9595.4 10408.3 10640.8 10645.7 8726.3 8913.7 9032.1 9032.5 9065.3 8573.9 8693.3 8704.2 8756.2 8835.4 6759.9 7773.2 7908.0 7956.6 7965.4 5763.0 5832.5 5835.5 5916.2 5995.4 4946.0 5796.2 5932.2 6160.9 6215.9 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4358.63 Median:4651.05 stddev:1190.21 (25.6%) stddev-sans-first:1155.20 07:46:26 INFO - PROCESS | 1646 | Values: 365.7 367.9 373.6 381.9 382.6 3929.7 4225.0 4747.2 5015.3 5122.4 4067.6 4167.9 4218.7 4537.0 4702.7 3766.4 3768.6 4315.4 4348.0 4348.4 3639.4 3674.6 3975.7 4300.8 4412.4 3706.5 3736.8 4091.5 4111.8 4111.9 4069.7 4081.4 4098.3 4145.7 4148.8 3422.1 3446.7 3753.3 3873.1 3886.7 4618.6 4659.5 4751.1 4794.0 4820.8 4436.8 4684.8 4722.0 4750.9 4780.4 5027.7 5176.8 5178.0 5202.9 5251.4 3320.9 3831.3 4142.5 4279.7 4281.3 5398.6 5443.6 5463.3 5482.5 5484.3 4239.0 4256.1 4307.5 4374.5 4377.8 3677.5 3729.0 3908.6 4393.8 4436.2 4184.6 4590.5 4605.8 4610.3 4757.8 4646.5 5217.2 5243.0 5258.7 5284.2 1356.7 1388.5 1417.7 1452.3 1481.7 1140.7 1237.2 1331.5 1379.8 1394.8 4417.4 4625.9 4645.0 4649.3 4656.0 4945.3 5377.5 5398.4 5402.3 5453.9 3957.8 4417.8 4440.0 4513.0 4641.9 2491.8 2733.6 2747.5 2800.8 2808.5 5012.6 5325.3 5540.6 5585.7 5608.4 5073.5 5157.7 5175.6 5242.2 5398.0 4560.6 5237.6 5327.9 5509.7 5524.4 5338.8 5339.9 5351.8 5355.7 5363.7 4708.5 4919.7 5243.3 5267.6 5294.9 4608.4 5366.5 5507.3 5554.0 5589.0 4297.4 4761.0 4780.0 4803.4 4814.7 4652.8 4945.3 5344.7 5357.1 5394.0 4413.7 4710.2 5105.8 5216.6 5244.5 4101.2 4464.8 4679.9 4923.4 5065.9 4591.6 4647.7 5071.9 5127.3 5148.3 4852.9 5000.7 5011.4 5103.7 5225.7 4616.0 4890.2 5140.6 5156.3 5177.4 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:19689.50 Median:3491.14 stddev:27885.21 (798.7%) stddev-sans-first:27927.08 07:46:26 INFO - PROCESS | 1646 | Values: 724.2 748.3 793.2 846.3 854.3 15531.9 16334.2 16340.1 16356.8 16396.5 15031.7 15123.1 15124.0 15127.2 15153.3 972.2 991.6 995.4 995.7 998.0 970.0 982.2 993.7 996.4 996.9 1045.7 1048.0 1049.5 1050.8 1051.2 1045.6 1046.3 1046.5 1046.5 1046.8 774.7 775.6 776.1 776.4 777.4 13721.3 16191.4 16234.9 16856.9 17134.9 14204.9 14365.9 14381.1 14395.4 14397.8 11670.8 11751.6 11827.2 11880.4 12534.3 3580.0 3619.6 3854.6 3888.3 3903.1 87929.0 111354.0 118772.0 118972.0 120418.0 10586.6 10727.1 10876.1 11203.7 11552.4 23168.7 23350.8 23380.4 23388.9 23403.5 7859.3 7863.8 7948.4 8027.5 8043.9 4345.0 4356.2 4464.0 4512.3 4664.9 3387.9 3474.5 3489.2 3493.0 3495.5 504.5 598.4 618.1 626.5 627.2 577.7 592.8 603.4 660.4 661.0 8382.0 9975.3 10858.5 11259.7 11348.3 560.4 584.6 595.5 602.6 716.6 4882.7 5084.2 5100.6 5157.3 5164.4 97577.0 97835.0 99363.5 101320.0 102696.0 53159.0 80264.0 81701.6 83342.6 85231.0 48182.0 63556.0 65256.7 68270.7 74082.6 9175.2 9494.6 9858.3 10301.7 10850.7 40585.6 44488.0 45395.0 46093.5 46296.1 54564.0 55340.0 58509.0 67885.3 69250.3 13376.4 13664.2 13700.8 14090.6 14312.4 14073.3 14223.9 14462.6 15090.6 15525.4 13052.9 13788.5 13955.7 13956.7 14190.7 12996.1 13586.1 13867.0 14062.0 14173.1 13100.9 13633.9 13762.7 13921.8 14489.6 9579.4 9984.4 10099.7 10121.0 10229.8 9867.6 10791.6 11334.5 11534.8 11603.8 07:46:26 INFO - PROCESS | 1646 | -------- Summary: end -------- 07:46:26 INFO - PROCESS | 1646 | 07:46:26 INFO - TEST-INFO | 1646: exit 0 07:46:26 INFO - TEST-OK | dromaeo_css | took 1119824ms 07:46:26 INFO - TEST-START | kraken 07:46:26 INFO - operating with platform_type : mac_ 07:46:26 INFO - Initialising browser for kraken test... 07:46:26 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpoKrt9o/profile http://localhost:49226/getInfo.html) 07:46:28 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 07:46:28 INFO - PROCESS | 1652 | colorDepth:24 07:46:28 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 07:46:28 INFO - PROCESS | 1652 | __metrics 07:46:29 INFO - PROCESS | 1652 | 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" 07:46:29 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:46:29 INFO - TEST-INFO | 1652: exit 0 07:46:29 INFO - Browser initialized. 07:46:29 INFO - Running cycle 1/1 for kraken test... 07:46:29 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpoKrt9o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:41 INFO - PROCESS | 1654 | RSS: Main: 194023424 07:46:41 INFO - PROCESS | 1654 | 07:46:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 07:46:45 INFO - PROCESS | 1654 | RSS: Main: 244936704 07:46:45 INFO - PROCESS | 1654 | 07:46:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 07:46:48 INFO - PROCESS | 1654 | RSS: Main: 259821568 07:46:48 INFO - PROCESS | 1654 | 07:46:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 07:46:52 INFO - PROCESS | 1654 | RSS: Main: 356438016 07:46:52 INFO - PROCESS | 1654 | 07:46:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 07:46:54 INFO - PROCESS | 1654 | RSS: Main: 258420736 07:46:54 INFO - PROCESS | 1654 | 07:46:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:46:57 INFO - PROCESS | 1654 | RSS: Main: 260976640 07:46:57 INFO - PROCESS | 1654 | 07:47:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 07:47:01 INFO - PROCESS | 1654 | RSS: Main: 300687360 07:47:01 INFO - PROCESS | 1654 | 07:47:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 07:47:04 INFO - PROCESS | 1654 | RSS: Main: 302628864 07:47:04 INFO - PROCESS | 1654 | 07:47:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 07:47:08 INFO - PROCESS | 1654 | RSS: Main: 299634688 07:47:08 INFO - PROCESS | 1654 | 07:47:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:47:10 INFO - PROCESS | 1654 | RSS: Main: 278052864 07:47:10 INFO - PROCESS | 1654 | 07:47:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:47:13 INFO - PROCESS | 1654 | RSS: Main: 281870336 07:47:13 INFO - PROCESS | 1654 | 07:47:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:47:15 INFO - PROCESS | 1654 | RSS: Main: 287903744 07:47:15 INFO - PROCESS | 1654 | 07:47:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:47:19 INFO - PROCESS | 1654 | RSS: Main: 299855872 07:47:19 INFO - PROCESS | 1654 | 07:47:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:47:22 INFO - PROCESS | 1654 | RSS: Main: 293412864 07:47:22 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 07:47:24 INFO - PROCESS | 1654 | RSS: Main: 338137088 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | __start_tp_report 07:47:24 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 07:47:24 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 07:47:24 INFO - PROCESS | 1654 | |i|pagename|runs| 07:47:24 INFO - PROCESS | 1654 | |0;ai-astar;105;116;116;114;115;126;171;153;116;115 07:47:24 INFO - PROCESS | 1654 | |1;audio-beat-detection;139;104;108;143;109;107;108;105;125;109 07:47:24 INFO - PROCESS | 1654 | |2;audio-dft;186;185;195;186;184;185;185;185;186;184 07:47:24 INFO - PROCESS | 1654 | |3;audio-fft;82;60;60;94;64;62;59;60;77;59 07:47:24 INFO - PROCESS | 1654 | |4;audio-oscillator;84;70;87;69;70;69;82;68;69;84 07:47:24 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;89;89;88;134;115;90;90;89;90 07:47:24 INFO - PROCESS | 1654 | |6;imaging-darkroom;97;98;96;97;97;96;97;96;96;97 07:47:24 INFO - PROCESS | 1654 | |7;imaging-desaturate;86;84;85;83;93;131;87;86;86;86 07:47:24 INFO - PROCESS | 1654 | |8;json-parse-financial;63;61;60;61;64;62;65;65;64;63 07:47:24 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;55;66;72;63;61;58;57;60;58;57 07:47:24 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;88;87;83;81;82;84;85;81;84;90 07:47:24 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;136;134;139;137;136;119;134;138;182;147 07:47:24 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;167;164;163;165;164;163;163;162;166;161 07:47:24 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;64;65;65;63;63;64;64;62;64;63 07:47:24 INFO - PROCESS | 1654 | __end_tp_report 07:47:24 INFO - PROCESS | 1654 | __start_cc_report 07:47:24 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2181 07:47:24 INFO - PROCESS | 1654 | __end_cc_report 07:47:24 INFO - PROCESS | 1654 | __startTimestamp1496414844764__endTimestamp 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | ------- Summary: start ------- 07:47:24 INFO - PROCESS | 1654 | Number of tests: 14 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:124.70 Median:116.00 stddev:20.72 (17.9%) stddev-sans-first:20.71 07:47:24 INFO - PROCESS | 1654 | Values: 105.0 116.0 116.0 114.0 115.0 126.0 171.0 153.0 116.0 115.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:115.70 Median:109.00 stddev:14.57 (13.4%) stddev-sans-first:12.78 07:47:24 INFO - PROCESS | 1654 | Values: 139.0 104.0 108.0 143.0 109.0 107.0 108.0 105.0 125.0 109.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:186.10 Median:185.50 stddev:3.21 (1.7%) stddev-sans-first:3.41 07:47:24 INFO - PROCESS | 1654 | Values: 186.0 185.0 195.0 186.0 184.0 185.0 185.0 185.0 186.0 184.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:67.70 Median:63.00 stddev:12.28 (19.5%) stddev-sans-first:11.89 07:47:24 INFO - PROCESS | 1654 | Values: 82.0 60.0 60.0 94.0 64.0 62.0 59.0 60.0 77.0 59.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:7.90 (10.4%) stddev-sans-first:7.71 07:47:24 INFO - PROCESS | 1654 | Values: 84.0 70.0 87.0 69.0 70.0 69.0 82.0 68.0 69.0 84.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:89.50 stddev:15.49 (17.3%) stddev-sans-first:16.25 07:47:24 INFO - PROCESS | 1654 | Values: 90.0 89.0 89.0 88.0 134.0 115.0 90.0 90.0 89.0 90.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 07:47:24 INFO - PROCESS | 1654 | Values: 97.0 98.0 96.0 97.0 97.0 96.0 97.0 96.0 96.0 97.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:86.00 stddev:14.41 (16.8%) stddev-sans-first:15.18 07:47:24 INFO - PROCESS | 1654 | Values: 86.0 84.0 85.0 83.0 93.0 131.0 87.0 86.0 86.0 86.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.50 stddev:1.75 (2.8%) stddev-sans-first:1.86 07:47:24 INFO - PROCESS | 1654 | Values: 63.0 61.0 60.0 61.0 64.0 62.0 65.0 65.0 64.0 63.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:60.50 stddev:5.12 (8.5%) stddev-sans-first:5.00 07:47:24 INFO - PROCESS | 1654 | Values: 55.0 66.0 72.0 63.0 61.0 58.0 57.0 60.0 58.0 57.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.50 stddev:3.03 (3.6%) stddev-sans-first:2.93 07:47:24 INFO - PROCESS | 1654 | Values: 88.0 87.0 83.0 81.0 82.0 84.0 85.0 81.0 84.0 90.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:137.50 stddev:16.23 (11.8%) stddev-sans-first:17.15 07:47:24 INFO - PROCESS | 1654 | Values: 136.0 134.0 139.0 137.0 136.0 119.0 134.0 138.0 182.0 147.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.00 stddev:1.81 (1.1%) stddev-sans-first:1.51 07:47:24 INFO - PROCESS | 1654 | Values: 167.0 164.0 163.0 165.0 164.0 163.0 163.0 162.0 166.0 161.0 07:47:24 INFO - PROCESS | 1654 | 07:47:24 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:0.95 (1.5%) stddev-sans-first:1.00 07:47:24 INFO - PROCESS | 1654 | Values: 64.0 65.0 65.0 63.0 63.0 64.0 64.0 62.0 64.0 63.0 07:47:24 INFO - PROCESS | 1654 | -------- Summary: end -------- 07:47:24 INFO - PROCESS | 1654 | 07:47:25 INFO - TEST-INFO | 1654: exit 0 07:47:25 INFO - TEST-OK | kraken | took 58691ms 07:47:25 INFO - SUITE-END | took 1178s 07:47:25 INFO - Completed test suite (00:19:38) 07:47:25 INFO - javascript benchmark 07:47:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7628.38229939068, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.183236023355, 3414.7951122932627, 3429.2112814052775, 3432.691057804797, 3441.673041061099, 5878.088896221087, 5894.528437724977, 5912.172295878575, 5921.0230311998575, 5933.614315069517, 5882.794102206423, 5951.49412299955, 5955.315138759033, 5973.1040343948425, 5978.103284507326, 4515.864524064284, 5288.973555132228, 5325.334333208349, 5369.704666243355, 5432.511074003339, 3997.800109994503, 4375.387445757597, 5304.3634763828395, 5333.066713325172, 5469.480399362054, 4468.843590474334, 4484.4170257866435, 5332.786688532454, 5354.063038968185, 5366.409694933554, 5314.840554783363, 5347.957148356072, 5379.488948549882, 5380.892382152374, 5404.1083221268445, 5293.527971313018, 5392.865178370533, 5396.19057141428, 5409.783608655649, 5436.7553460094, 5726.169705392695, 5763.567732420043, 6013.669248191348, 6017.63894166347, 6098.908516372258, 6293.590916590407, 6301.212348456443, 6320.083587879778, 6334.80995570129, 6360.300366959631, 6297.716602752841, 6365.0, 6370.617762934239, 6373.426391624776, 6374.968125159345, 5266.578673706133, 5844.240248767633, 6025.397460253939, 6105.786297479567, 6119.571629985859, 6319.8104056878365, 6320.178376811077, 6320.747170113144, 6334.144890439824, 6334.841628959313, 6317.399847014526, 6339.492840572835, 6394.0, 6405.583637063576, 6412.326705695835, 6320.905186422207, 6361.777337793155, 6371.235451745728, 6380.011098279681, 6561.901571476432, 6346.682665866688, 6352.237731472253, 6355.523335749837, 6358.237011558643, 6372.426481616677, 6269.310375858652, 6326.051092336094, 6328.430441260309, 6344.968275158594, 6368.554201205964, 4879.463259041502, 4881.0, 4889.364382630235, 4890.070886531619, 4898.216285394391, 4316.805743741539, 4352.39066530686, 4392.099619578043, 4431.7562534060935, 4473.642108631302, 5678.034734095214, 5682.6306290091015, 5693.629914055573, 5703.62926409782, 5712.400197979236, 6022.126791615279, 6290.087937249074, 6364.872702545923, 6372.330905254974, 6391.328910464427, 5772.740226689809, 5802.970985145132, 5805.042168042226, 5829.80488999754, 5841.795537156264, 5507.228987941691, 5527.640703354269, 5528.281323427928, 5529.889402212014, 5530.6128571000445, 6190.50475961922, 6298.1812364392335, 6347.96826015867, 6386.010168423902, 6442.09810626522, 6249.187605611241, 6321.367863213642, 6331.240251169889, 6344.270408903035, 6359.109724638462, 6477.546571739933, 6484.027395890465, 6500.219973603198, 6517.120188774455, 6622.139121914121, 6158.168647232566, 6248.781292654735, 6311.211098612674, 6324.778632748016, 6419.903701444576, 6252.656103914328, 6376.075469057054, 6377.553571249968, 6384.585001974857, 6391.616503009835, 6334.113224148531, 6373.5857169283845, 6378.904316435164, 6390.297067322498, 6395.6482393468805, 6191.071339299141, 6366.522510811615, 6373.171487706569, 6375.0, 6382.840428989312, 5908.586398952032, 5919.4968427684125, 6116.487945662109, 6210.223722034746, 6212.9068063978175, 6288.937110629018, 6333.398327158912, 6334.081558174131, 6336.841578960563, 6368.235811702625, 6156.938430615636, 6188.1027251049245, 6216.191895053614, 6216.378362163748, 6223.937760622336], "value": 5803.408221147978, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.5591424716406, 884.4074470105031, 893.6202114101505, 896.6951236579562, 989.4261328429511, 38614.61385386149, 39280.0, 40788.59211407885, 40790.184196316055, 40926.795366023165, 38865.30779575943, 39132.60867391325, 39144.21711565767, 39172.60827391725, 39235.80382098086, 7535.9246407535775, 7707.113681926566, 7780.377569794403, 8021.518708877456, 8183.0, 7601.733939312126, 7685.0, 11634.6509604712, 11849.111316651242, 12021.879781202208, 11771.352575608338, 11790.823137652942, 11798.58704945327, 11820.23168494045, 11836.644900652995, 11749.060075193964, 11780.587679431264, 11783.764324713544, 11784.941075294611, 11803.23278986863, 11400.657980260605, 11477.311361318347, 11481.024112950408, 11486.0811135109, 11497.540098396054, 39795.0, 39997.80001100005, 40025.0, 40116.79649610487, 40117.398239026435, 3902.64876161144, 3903.180332130256, 3943.0142464383903, 3950.5654378018394, 3997.620226078518, 28082.43835123286, 28768.856155719295, 29056.340191119652, 29494.93535452519, 30140.397192056254, 2703.8918443262246, 3070.370574032318, 3138.4978403455566, 3218.9034328969933, 3280.885169019097, 363976.0, 366571.0, 367139.0, 367254.0, 367701.0, 43069.35395969063, 43665.56334436678, 45525.54474455213, 53644.19533706998, 53931.921361572546, 55404.44595554074, 55542.88914221693, 56070.43929560652, 56113.438865611635, 58272.417275827545, 14885.0, 14933.25333733309, 14947.654711075838, 14953.327100280514, 14964.979727736834, 12744.872551274368, 12790.424430900632, 12796.552120675731, 12800.61598152057, 12808.679783005313, 12488.0, 12505.49978000888, 12533.060020498498, 12587.685307867376, 12627.684307892192, 4965.0, 5107.514786095315, 5778.508826749689, 5784.421557844182, 5818.156367326798, 838.5513750143643, 944.2870632672289, 948.8719022931816, 960.4381436859516, 982.8083523712951, 24382.512349752906, 28636.997705080223, 28703.856480717463, 29114.7088529112, 29204.0, 1066.632011955874, 1075.9515821787882, 1076.7146706122883, 1097.1990446973755, 1102.9613963511238, 14585.708285834224, 14731.705365892622, 14769.33537990793, 15000.849991500163, 15110.773338399933, 466603.0, 466739.0, 467026.0, 467200.0, 468482.0, 57995.13007304894, 58043.70978145082, 58067.12899306514, 58159.70920145372, 58211.126833097536, 1402.2217669193492, 1407.5636552668705, 1436.3249272841747, 1443.81952255968, 1453.8473460286518, 26998.46003079888, 27089.20469308962, 27768.722312776612, 27894.442111157256, 28076.71923280741, 41237.38143927784, 45206.0, 45890.0, 47891.76054119707, 47898.04203915972, 431450.0, 435872.0, 437517.0, 438482.61517384415, 439750.0, 24500.387490312885, 25028.0, 25615.103471378414, 25678.74321256838, 29375.412491750474, 30490.847545762128, 30532.542011869395, 31380.0, 33414.665853341154, 41309.79345103255, 26883.0, 27254.182374528795, 27593.44813103767, 28865.42269154563, 29078.56382154227, 27699.584506232826, 27884.58173127445, 27934.161975140778, 28021.859890700416, 29122.417551649272, 32754.508682369305, 33414.83292583522, 33510.49734253939, 33539.0, 33647.4952875712, 23001.309960701205, 27813.860930695217, 28090.578641320804, 28699.569506457003, 29071.7092829069, 21668.808215548295, 21814.018369173744, 22588.887055565276, 22990.885045575335, 23056.30831075003], "value": 18497.27519569037, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.23280396873724, 426.1585593946656, 427.54466493184754, 431.37019950871723, 437.4881388775133, 20975.0, 21612.675809862863, 22291.99686014129, 22572.774272257273, 22643.094276228952, 21962.341129766133, 22825.657615135744, 22900.541989160207, 23188.0, 23902.0, 6709.161354830646, 6996.545224560413, 7000.649967501605, 7124.964375178117, 7453.254674532557, 7322.1579518355275, 7549.207333230014, 7617.009788727485, 7617.238276172394, 7621.733239336624, 7581.658825352873, 7620.580868052255, 7625.733099341524, 7627.8855817162785, 7667.961660191691, 7304.780856574284, 7539.811504712398, 7605.8478830423355, 7622.504537205064, 7626.504277221963, 7137.2149063602965, 7366.152892417387, 7388.076490438695, 7394.37147842434, 7416.443766717518, 24573.0, 25659.871700641565, 26238.606420903703, 26734.33164170892, 26995.325116872038, 3267.738580913545, 3299.191698033973, 3415.880444184442, 3421.4525675891987, 3562.7327950403565, 15997.120158391284, 16021.0, 16041.518754437386, 16250.51248462548, 16548.917255413766, 1973.5954129403437, 2137.9251726189664, 2171.4462811983003, 2205.2171479125, 2225.543763528473, 155407.0, 163744.0, 163780.07869331003, 172085.1395743013, 172382.13808931, 17506.824931750773, 18313.0, 19672.803271967383, 19748.506287342643, 20032.799672003384, 31237.37525249482, 31241.21896952549, 31241.53137702936, 31268.84365578203, 31385.52921706176, 9804.558794854249, 9977.551010204557, 10136.493175341204, 10301.587936482456, 10315.48422578883, 8482.575871206414, 8499.235068843835, 8521.659133634585, 8689.6524139035, 8700.34747393948, 8916.465012099165, 9166.67916622915, 9291.0, 9301.906980930104, 9321.300902432346, 3949.150932549458, 4002.7372534240553, 4020.9195816083516, 4034.1326614777963, 4038.676905847525, 1044.4516628769957, 1048.2033654422694, 1058.227493929436, 1069.2461814420815, 1122.2817396866012, 21580.244691435724, 22140.003699833545, 22158.77841221567, 22171.44571385696, 22196.003539521957, 1094.3379255550437, 1101.8870940350143, 1115.8382034604938, 1129.2998341028615, 1130.3331034689572, 11238.943805280922, 11499.712507187387, 11546.653600391834, 11568.13239007061, 11595.652130435932, 548335.0, 751779.0, 780570.0, 781449.0, 787565.0, 127311.36344318218, 145014.0, 147772.0, 152283.0, 156627.21686391722, 1219.810929305959, 1226.337777600086, 1252.1297698099916, 1252.6930901928904, 1256.296473974559, 18262.634747305245, 19196.13617387193, 19349.322773702854, 19357.4192774217, 19466.513337166118, 48426.0, 60616.48458788566, 61214.0, 62248.0, 63328.41678958063, 85441.0, 85773.57113214394, 88121.11878881385, 89651.0, 89998.95004724672, 11172.05037571783, 11201.495932683212, 11279.154063445438, 11376.374299413612, 11686.766264674488, 12504.187227830162, 12557.24656520575, 12653.683657908627, 12853.0, 12863.421146048264, 10872.565097396016, 11043.233082706729, 11077.83383249268, 11375.0, 11395.658130256106, 9607.855882161633, 10346.275760696679, 10413.53139108757, 10461.9476902615, 10561.471926403803, 11432.657020289405, 11450.141239406912, 11478.655640330804, 11569.074474042225, 11700.824487632863, 9886.653967111404, 11274.436278186222, 11404.600838970597, 11751.529938802694, 11756.294622322688, 11040.282381645167, 11082.390468524309, 11087.279326843729, 11243.494042767932, 11320.660380188607], "value": 11845.635506907474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.2649169448498, 1467.2663668165917, 1588.610790356363, 1612.2664187794555, 1634.6975809475252, 9259.953700231497, 10883.020528152465, 11257.493412796413, 11428.028617567496, 11432.256903301279, 11088.33469991801, 11171.273867198626, 11201.0, 11201.775964480727, 11221.60724374647, 2284.0635339510845, 2319.0491898321693, 2321.129576408847, 2322.2104484475276, 2323.3146221864504, 1942.494951312662, 2021.757389113301, 2280.2247235939776, 2360.103160798894, 2368.9407764805846, 2311.3874823171873, 2333.436597479693, 2335.9838470265404, 2339.1111377676457, 2339.555484457956, 2270.421042634126, 2301.9424514387274, 2303.021215983217, 2306.688597039395, 2307.3193407944696, 1967.2131147540954, 2259.243153543565, 2266.909323627053, 2267.3424706835, 2294.105298933417, 11146.944265278702, 11232.49453774582, 11235.269707468988, 11257.493412796444, 11282.0, 5400.729963501848, 5573.359063707685, 5576.498115169654, 5578.246936663539, 5587.636803607753, 3185.5540224368606, 3350.0619826448633, 3424.5376874121926, 3425.657434256579, 3426.880059197916, 1018.8777346718367, 1061.5435362793996, 1118.111101674171, 1132.8074227381364, 1137.6416428824894, 15517.146556939364, 15563.066216026962, 15579.61050973712, 15590.688186236324, 15596.674282685985, 4498.977505112453, 4558.8632341029825, 4599.264117741145, 4669.95330046702, 4671.252599584051, 12818.871811281955, 12859.0, 12885.420156092998, 12899.290539020256, 12904.612861614167, 5641.12562552805, 5645.322561292671, 5647.181158731961, 5651.6043876928225, 5666.065099258659, 4288.849890253826, 4877.170880950247, 4977.9751101244265, 5043.747812609428, 5097.057044446789, 2973.3012742005612, 3463.7921724696093, 3750.962490375116, 3861.7103717220757, 3884.9805750971063, 972.6887396033202, 1007.1187710753092, 1016.755978565139, 1020.5815615597718, 1025.5897640943533, 815.3721634341642, 842.5366048673205, 854.7222152800365, 865.2169786343369, 891.7948871759531, 6347.936520634827, 6512.869742605121, 6667.466602671871, 6719.0, 6819.0, 1037.092544023979, 1055.4511653940062, 1063.8297872340388, 1095.118429664112, 1099.312929419113, 3755.6244375562774, 4352.651787856964, 4365.279728844768, 4388.297872340474, 4502.0, 13389.732205355838, 13652.249126297951, 13835.723285534234, 13839.446422143203, 13892.23592702411, 13452.32738363078, 13708.86291137076, 13736.969727270494, 13742.65643358924, 13749.381277842522, 10034.949825250827, 10264.948675256725, 10288.074073333435, 10296.330738501973, 10307.381557106448, 6551.21385433751, 6578.703958321791, 6634.369734875179, 6688.799336019927, 6744.258131605618, 11873.0, 12382.257064576153, 12413.565525206574, 12421.813672794735, 12444.875551244733, 10833.69995600533, 12652.746945061232, 12664.556740514407, 12686.682832929251, 12700.745985080062, 8806.163414475615, 9257.67598134062, 9279.953600231956, 9283.72148835509, 9331.346805723535, 9111.635534578543, 9595.42427454355, 10408.323458975143, 10640.829508754186, 10645.680629581126, 8726.345524085593, 8913.688020919237, 9032.096790320915, 9032.503212323123, 9065.27477801761, 8573.871391929251, 8693.26107280863, 8704.216620504185, 8756.168164024662, 8835.381523293308, 6759.86480270382, 7773.222677732181, 7907.9604601976625, 7956.6419511123295, 7965.362770978192, 5762.971185144047, 5832.475077243069, 5835.503982161564, 5916.171735956884, 5995.430434108927, 4945.950540494549, 5796.246487956539, 5932.228810254639, 6160.943413182615, 6215.875682486235], "value": 5058.990765703984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.69281803285236, 367.89147201575565, 373.64317077191276, 381.85871227645777, 382.58680624925074, 3929.6659783918403, 4224.978875105624, 4747.1692453820615, 5015.272785446108, 5122.436531981483, 4067.5932406759307, 4167.916641667172, 4218.7257828241145, 4536.954630453694, 4702.694324868889, 3766.3597188478025, 3768.567944181221, 4315.395844581761, 4348.0, 4348.391225228471, 3639.4358874374516, 3674.574780644775, 3975.74763949355, 4300.784960751966, 4412.448443944507, 3706.499622550948, 3736.794476303802, 4091.5499295077516, 4111.814968326417, 4111.93089796652, 4069.6947728920154, 4081.4490043844, 4098.344264917614, 4145.689073319483, 4148.79256037197, 3422.066532409181, 3446.7414943879057, 3753.343164946151, 3873.0511024798825, 3886.6696330811915, 4618.630509559227, 4659.510751371091, 4751.14479393709, 4794.0, 4820.794171875734, 4436.831091778075, 4684.789184486706, 4721.952780472186, 4750.904981900343, 4780.354652121954, 5027.748612569357, 5176.792928282902, 5178.0, 5202.944836789485, 5251.422343542207, 3320.933581328384, 3831.2912111259507, 4142.523609784883, 4279.743215407086, 4281.281386319678, 5398.595105367113, 5443.59173062018, 5463.344398672145, 5482.451754824566, 5484.287042684426, 4238.978805106017, 4256.063141594013, 4307.504636966726, 4374.475062992461, 4377.802998865059, 3677.4667673187237, 3728.981355093207, 3908.5895980922164, 4393.84621538251, 4436.245838207513, 4184.623383895464, 4590.495045544987, 4605.835257429003, 4610.2854057621125, 4757.762111894392, 4646.465656449484, 5217.217417387422, 5242.977619364264, 5258.710770907637, 5284.233786100994, 1356.6811799227173, 1388.5209602687057, 1417.688108616103, 1452.2666053643054, 1481.7258807120718, 1140.676814894718, 1237.1834589171103, 1331.4873773597042, 1379.834419869602, 1394.7586647883393, 4417.3815665806815, 4625.907481850344, 4645.0, 4649.3026046093355, 4655.976720116378, 4945.332380128709, 5377.5429088527135, 5398.406175320712, 5402.324709411324, 5453.890922181534, 3957.762534247954, 4417.841243510905, 4439.977800110979, 4513.0, 4641.883952901205, 2491.8380305280098, 2733.5762956741737, 2747.5329194037063, 2800.789940754411, 2808.4804311202492, 5012.598992080625, 5325.307709997753, 5540.63985840919, 5585.692786896679, 5608.4111168326735, 5073.543381095719, 5157.716325602053, 5175.576716403019, 5242.208836119226, 5398.0, 4560.566746159176, 5237.554807841376, 5327.893442131213, 5509.669419834822, 5524.392316845144, 5338.813141540028, 5339.893202136013, 5351.759170837399, 5355.651882627617, 5363.704996225089, 4708.528022788064, 4919.704817710949, 5243.292155558951, 5267.631265811357, 5294.9085111381555, 4608.3778689876435, 5366.543843773323, 5507.347795661205, 5554.000279949648, 5588.972055139861, 4297.398364229073, 4760.976195119002, 4780.0, 4803.447603525499, 4814.735189564608, 4652.790624421841, 4945.2582112683385, 5344.679319240858, 5357.116075847442, 5393.9730301348245, 4413.691041627056, 4710.175719249186, 5105.846824595268, 5216.634835561475, 5244.527992480543, 4101.179764047143, 4464.843730469418, 4679.917436740618, 4923.384576927884, 5065.898682026413, 4591.645464588021, 4647.745108820667, 5071.87320316995, 5127.333446651912, 5148.30497882781, 4852.8544143675745, 5000.674956127986, 5011.373578302712, 5103.719295438638, 5225.6603320784025, 4615.976920115378, 4890.24201248807, 5140.640155189101, 5156.324194636833, 5177.378714554127], "value": 4023.0759671561455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.2286964382931, 748.2554857916374, 793.1513280789558, 846.2933450568775, 854.3421565394647, 15531.922340388297, 16334.18329083544, 16340.101294428801, 16356.83643163568, 16396.508104756846, 15031.699366012674, 15123.092614443114, 15124.016938898963, 15127.168005759679, 15153.318100685468, 972.2173650211595, 991.6033586565395, 995.4027583449945, 995.7112437393147, 998.001998001998, 970.0493516353978, 982.228950274035, 993.6820217530357, 996.4270544436941, 996.9202463802915, 1045.7124290820009, 1048.0096308988009, 1049.4752623688155, 1050.768830857214, 1051.2431049644244, 1045.5974449836792, 1046.272840375939, 1046.48722126158, 1046.5238316565983, 1046.785408991155, 774.6939958716275, 775.6393277126107, 776.0687175389555, 776.3517462918413, 777.4324742937631, 13721.313934303245, 16191.433299834567, 16234.918825405915, 16856.915715421466, 17134.914325428417, 14204.92897535516, 14365.92817035908, 14381.065230760072, 14395.424183032666, 14397.78403323951, 11670.76658466826, 11751.589809222947, 11827.172097953147, 11880.40597970098, 12534.310612916286, 3580.0, 3619.565652121736, 3854.5952674969285, 3888.300105980922, 3903.1217975955465, 87929.0, 111354.0, 118772.0, 118972.0, 120418.0, 10586.629467968738, 10727.088197503299, 10876.129909607213, 11203.71990700239, 11552.364619945816, 23168.65247021296, 23350.7664923352, 23380.415489612555, 23388.883055584614, 23403.531929361317, 7859.292663660298, 7863.764087077397, 7948.403869709795, 8027.518348899085, 8043.879341809878, 4344.9565504344555, 4356.237658409766, 4463.955360446419, 4512.323151527232, 4664.86005419838, 3387.8644854205554, 3474.5309383233443, 3489.2323688788424, 3493.039414161126, 3495.4582039783368, 504.45518839653084, 598.3956204233668, 618.0790621973317, 626.5332327416104, 627.1783963008473, 577.6765011593521, 592.8043745563954, 603.3604379357895, 660.41883142834, 661.0414898397343, 8381.95809020951, 9975.301728879054, 10858.511366988505, 11259.718507037389, 11348.262362946381, 560.4087687489738, 584.6287607369351, 595.4670569839969, 602.6474512410201, 716.638097760638, 4882.658213924991, 5084.161113416245, 5100.617453690914, 5157.277981082651, 5164.3802743671, 97577.0, 97835.0, 99363.50318248507, 101320.0, 102696.0, 53159.0, 80264.0, 81701.59149204216, 83342.58328708439, 85231.0, 48182.0, 63556.0, 65256.673716631114, 68270.65864670645, 74082.62958685172, 9175.22010629104, 9494.572744226389, 9858.30991830565, 10301.74245643865, 10850.67447976562, 40585.59414405936, 44488.0, 45395.0, 46093.53906461027, 46296.074078517566, 54564.0, 55340.0, 58509.0, 67885.3211467879, 69250.30749692439, 13376.398062087035, 13664.180149191081, 13700.794488082487, 14090.647733806736, 14312.427502900184, 14073.274153919105, 14223.857761422667, 14462.638434039232, 15090.622734431727, 15525.37898484048, 13052.934735326262, 13788.517401890886, 13955.720885582434, 13956.720865582834, 14190.716185676436, 12996.139417096854, 13586.116902401312, 13867.0, 14062.015658903778, 14173.149611023371, 13100.868991310346, 13633.931830340785, 13762.65593360134, 13921.791173132613, 14489.637759056108, 9579.425234485952, 9984.441385913435, 10099.7475063124, 10121.0, 10229.795404091728, 9867.605295788375, 10791.56833726642, 11334.546618135182, 11534.769304613694, 11603.767924641292], "value": 7613.408257116399, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1428.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 116.0, 114.0, 115.0, 126.0, 171.0, 153.0, 116.0, 115.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 104.0, 108.0, 143.0, 109.0, 107.0, 108.0, 105.0, 125.0, 109.0], "value": 115.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 195.0, 186.0, 184.0, 185.0, 185.0, 185.0, 186.0, 184.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 94.0, 64.0, 62.0, 59.0, 60.0, 77.0, 59.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 87.0, 69.0, 70.0, 69.0, 82.0, 68.0, 69.0, 84.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 88.0, 134.0, 115.0, 90.0, 90.0, 89.0, 90.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 97.0, 97.0, 96.0, 97.0, 96.0, 96.0, 97.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 83.0, 93.0, 131.0, 87.0, 86.0, 86.0, 86.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 60.0, 61.0, 64.0, 62.0, 65.0, 65.0, 64.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 66.0, 72.0, 63.0, 61.0, 58.0, 57.0, 60.0, 58.0, 57.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 83.0, 81.0, 82.0, 84.0, 85.0, 81.0, 84.0, 90.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 139.0, 137.0, 136.0, 119.0, 134.0, 138.0, 182.0, 147.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 163.0, 165.0, 164.0, 163.0, 163.0, 162.0, 166.0, 161.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 63.0, 63.0, 64.0, 64.0, 62.0, 64.0, 63.0], "value": 63.7, "unit": "score"}]}]} 07:47:26 INFO - Return code: 0 07:47:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:47:26 INFO - # TBPL SUCCESS # 07:47:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:47:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:47:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:47:26 INFO - 2017-06-02 07:47:26.099 screenresolution[1658:10341] starting screenresolution argv=screenresolution get 07:47:26 INFO - 2017-06-02 07:47:26.101 screenresolution[1658:10341] Display 0: 1600x1200x32@60 07:47:26 INFO - 2017-06-02 07:47:26.107 screenresolution[1659:10344] starting screenresolution argv=screenresolution list 07:47:26 INFO - Available Modes on Display 0 07:47:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:47:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:47:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:47:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:47:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:47:26 INFO - Intel Iris: 07:47:26 INFO - Chipset Model: Intel Iris 07:47:26 INFO - Type: GPU 07:47:26 INFO - Bus: Built-In 07:47:26 INFO - VRAM (Dynamic, Max): 1536 MB 07:47:26 INFO - Vendor: Intel (0x8086) 07:47:26 INFO - Device ID: 0x0a2e 07:47:26 INFO - Revision ID: 0x0009 07:47:26 INFO - Displays: 07:47:26 INFO - Display: 07:47:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:47:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:47:26 INFO - Display Serial Number: 1600x1200 60 07:47:26 INFO - Main Display: Yes 07:47:26 INFO - Mirror: Off 07:47:26 INFO - Online: Yes 07:47:26 INFO - Rotation: Supported 07:47:26 INFO - Return code: 0 07:47:26 INFO - Running post-action listener: _resource_record_post_action 07:47:26 INFO - [mozharness: 2017-06-02 14:47:26.342667Z] Finished run-tests step (success) 07:47:26 INFO - Running post-run listener: _resource_record_post_run 07:47:26 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 33283072; Write bytes: 509848576; Read time: 204; Write time: 1563 07:47:26 INFO - TinderboxPrint: CPU usage
54.0% 07:47:26 INFO - TinderboxPrint: I/O read bytes / time
33,283,072 / 204 07:47:26 INFO - TinderboxPrint: I/O write bytes / time
509,848,576 / 1,563 07:47:26 INFO - TinderboxPrint: CPU idle
2,208.8 (46.0%) 07:47:26 INFO - TinderboxPrint: CPU system
957.8 (19.9%) 07:47:26 INFO - TinderboxPrint: CPU user
1,635.0 (34.1%) 07:47:26 INFO - TinderboxPrint: Swap in / out
237,842,432 / 0 07:47:26 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 197511680; Write bytes: 189837312; Read time: 15705; Write time: 413 07:47:26 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32328704; Write bytes: 300219392; Read time: 192; Write time: 1093 07:47:26 INFO - Running post-run listener: _upload_blobber_files 07:47:26 INFO - Blob upload gear active. 07:47:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:47:26 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 07:47:26 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'] 07:47:26 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 07:47:27 INFO - (blobuploader) - INFO - Open directory for files ... 07:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:47:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:28 INFO - (blobuploader) - INFO - Done attempting. 07:47:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:47:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:47:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:29 INFO - (blobuploader) - INFO - Done attempting. 07:47:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:47:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:47:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:30 INFO - (blobuploader) - INFO - Done attempting. 07:47:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:47:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:47:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:32 INFO - (blobuploader) - INFO - Done attempting. 07:47:32 INFO - (blobuploader) - INFO - Iteration through files over. 07:47:32 INFO - Return code: 0 07:47:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:47:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:47:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04b26c2abbfdd897b868f14a1f83287e16cbe9740b71771ff92565c022a33bc1f4d2012f221984f69ce9e2c2d58731bcc98ca6bede1b989f2a15fe0539f8762d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/feeca121d832cb9c7e763d423a955b73f023d9667ed7103a8b5b09fac1112c57f4dd5b644333559c9a12143712308b9ccba6a292ba2a88616b0a0853a345a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fc97ff71e231be7d03f58a0ed8f5ab99969a7c4f57ac34fb19d34d9edba7c197fb9d05e879ea381f2bf36f7f43e8a8472e9dd42763a7f9d3421c66888a664f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62e8a86acd215923379f296cb5c0dacc1a62171ed718dda539ba6d003a7ae0d900be0c3527c747385bbfd2fb151f6f3c7100ef5aa23d9f2593deb6fa291c37c4"} 07:47:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:47:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:47:32 INFO - Contents: 07:47:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04b26c2abbfdd897b868f14a1f83287e16cbe9740b71771ff92565c022a33bc1f4d2012f221984f69ce9e2c2d58731bcc98ca6bede1b989f2a15fe0539f8762d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/feeca121d832cb9c7e763d423a955b73f023d9667ed7103a8b5b09fac1112c57f4dd5b644333559c9a12143712308b9ccba6a292ba2a88616b0a0853a345a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fc97ff71e231be7d03f58a0ed8f5ab99969a7c4f57ac34fb19d34d9edba7c197fb9d05e879ea381f2bf36f7f43e8a8472e9dd42763a7f9d3421c66888a664f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62e8a86acd215923379f296cb5c0dacc1a62171ed718dda539ba6d003a7ae0d900be0c3527c747385bbfd2fb151f6f3c7100ef5aa23d9f2593deb6fa291c37c4"} 07:47:32 INFO - Running post-run listener: copy_logs_to_upload_dir 07:47:32 INFO - Copying logs to upload dir... 07:47:32 INFO - mkdir: /builds/slave/test/build/upload/logs 07:47:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.363762 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-02 07:47:32.167873) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 07:47:32.171210) ========= 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.ZLst6dsipp/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.zjIJ64nwew/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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/04b26c2abbfdd897b868f14a1f83287e16cbe9740b71771ff92565c022a33bc1f4d2012f221984f69ce9e2c2d58731bcc98ca6bede1b989f2a15fe0539f8762d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/feeca121d832cb9c7e763d423a955b73f023d9667ed7103a8b5b09fac1112c57f4dd5b644333559c9a12143712308b9ccba6a292ba2a88616b0a0853a345a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fc97ff71e231be7d03f58a0ed8f5ab99969a7c4f57ac34fb19d34d9edba7c197fb9d05e879ea381f2bf36f7f43e8a8472e9dd42763a7f9d3421c66888a664f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62e8a86acd215923379f296cb5c0dacc1a62171ed718dda539ba6d003a7ae0d900be0c3527c747385bbfd2fb151f6f3c7100ef5aa23d9f2593deb6fa291c37c4"} build_url:https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011308 build_url: 'https://queue.taskcluster.net/v1/task/diXUKxGXRC-Nlvt5AngfOw/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/04b26c2abbfdd897b868f14a1f83287e16cbe9740b71771ff92565c022a33bc1f4d2012f221984f69ce9e2c2d58731bcc98ca6bede1b989f2a15fe0539f8762d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/feeca121d832cb9c7e763d423a955b73f023d9667ed7103a8b5b09fac1112c57f4dd5b644333559c9a12143712308b9ccba6a292ba2a88616b0a0853a345a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fc97ff71e231be7d03f58a0ed8f5ab99969a7c4f57ac34fb19d34d9edba7c197fb9d05e879ea381f2bf36f7f43e8a8472e9dd42763a7f9d3421c66888a664f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62e8a86acd215923379f296cb5c0dacc1a62171ed718dda539ba6d003a7ae0d900be0c3527c747385bbfd2fb151f6f3c7100ef5aa23d9f2593deb6fa291c37c4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 07:47:32.208013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:47:32.208557) ========= 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.ZLst6dsipp/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.zjIJ64nwew/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.005225 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:47:32.260169) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 07:47:32.260698) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 07:47:32.261279) ========= ========= Total master_lag: 0.19 =========