builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0323 starttime: 1496420778.92 results: success (0) buildid: 20170602081524 builduid: f217fdfa0ae54fe483e69e8db9cb5084 revision: ed54d16cb4d183ed808ba7fbb260f9e526358ff0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:18.917912) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:18.918456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:18.918781) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:18.945231) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:18.945661) ========= 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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:26:18-- 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.1M=0.001s 2017-06-02 09:26:19 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.495907 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:19.474833) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:19.475181) ========= 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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.027133 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:19.525157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 09:26:19.525573) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ed54d16cb4d183ed808ba7fbb260f9e526358ff0 --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 ed54d16cb4d183ed808ba7fbb260f9e526358ff0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvJdOBSkUa/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.8BRGh2SB2M/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:26:19,625 truncating revision to first 12 chars 2017-06-02 09:26:19,625 Setting DEBUG logging. 2017-06-02 09:26:19,625 attempt 1/10 2017-06-02 09:26:19,625 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ed54d16cb4d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 09:26:20,918 unpacking tar archive at: mozilla-beta-ed54d16cb4d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.575649 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 09:26:21.124479) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:21.124829) ========= script_repo_revision: ed54d16cb4d183ed808ba7fbb260f9e526358ff0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:21.125238) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:21.125522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 09:26:21.149955) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-06-02 09:26:21.150262) ========= /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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:26:21 INFO - MultiFileLogger online at 20170602 09:26:21 in /builds/slave/test 09:26:21 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 09:26:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:21 INFO - {'append_to_log': False, 09:26:21 INFO - 'base_work_dir': '/builds/slave/test', 09:26:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:26:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:21 INFO - 'branch': 'Mozilla-Beta', 09:26:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:21 INFO - 'config_files': ('talos/mac_config.py',), 09:26:21 INFO - 'default_actions': ('clobber', 09:26:21 INFO - 'read-buildbot-config', 09:26:21 INFO - 'download-and-extract', 09:26:21 INFO - 'populate-webroot', 09:26:21 INFO - 'create-virtualenv', 09:26:21 INFO - 'install', 09:26:21 INFO - 'run-tests'), 09:26:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:21 INFO - 'download_minidump_stackwalk': True, 09:26:21 INFO - 'download_symbols': 'ondemand', 09:26:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:21 INFO - 'gecko_profile': False, 09:26:21 INFO - 'gecko_profile_interval': 0, 09:26:21 INFO - 'installer_path': 'installer.exe', 09:26:21 INFO - 'log_level': 'info', 09:26:21 INFO - 'log_name': 'talos', 09:26:21 INFO - 'log_to_console': True, 09:26:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:21 INFO - 'opt_config_files': (), 09:26:21 INFO - 'pip_index': False, 09:26:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:21 INFO - 'cmd': ('bash', 09:26:21 INFO - '-c', 09:26:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:21 INFO - 'enabled': True, 09:26:21 INFO - 'halt_on_failure': False, 09:26:21 INFO - 'name': 'check_screen_resolution'},), 09:26:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:21 INFO - 'cmd': ('bash', 09:26:21 INFO - '-c', 09:26:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:21 INFO - 'enabled': True, 09:26:21 INFO - 'halt_on_failure': False, 09:26:21 INFO - 'name': 'check_screen_resolution'},), 09:26:21 INFO - 'run_cmd_checks_enabled': True, 09:26:21 INFO - 'suite': 'dromaeojs', 09:26:21 INFO - 'system_bits': '32', 09:26:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:21 INFO - 'title': 't-yosemite-r7-0323', 09:26:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:21 INFO - 'use_talos_json': True, 09:26:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:21 INFO - 'work_dir': 'build'} 09:26:21 INFO - [mozharness: 2017-06-02 16:26:21.361082Z] Running clobber step. 09:26:21 INFO - Running pre-action listener: _resource_record_pre_action 09:26:21 INFO - Running main action method: clobber 09:26:21 INFO - rmtree: /builds/slave/test/build 09:26:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:23 INFO - Running post-action listener: _resource_record_post_action 09:26:23 INFO - [mozharness: 2017-06-02 16:26:23.763785Z] Finished clobber step (success) 09:26:23 INFO - [mozharness: 2017-06-02 16:26:23.764050Z] Running read-buildbot-config step. 09:26:23 INFO - Running pre-action listener: _resource_record_pre_action 09:26:23 INFO - Running main action method: read_buildbot_config 09:26:23 INFO - Using buildbot properties: 09:26:23 INFO - { 09:26:23 INFO - "project": "", 09:26:23 INFO - "product": "firefox", 09:26:23 INFO - "script_repo_revision": "production", 09:26:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:26:23 INFO - "repository": "", 09:26:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:26:23 INFO - "buildid": "20170602081524", 09:26:23 INFO - "pgo_build": "False", 09:26:23 INFO - "basedir": "/builds/slave/test", 09:26:23 INFO - "buildnumber": 15, 09:26:23 INFO - "slavename": "t-yosemite-r7-0323", 09:26:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:26:23 INFO - "platform": "yosemite_r7", 09:26:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:26:23 INFO - "branch": "mozilla-beta", 09:26:23 INFO - "repo_path": "releases/mozilla-beta", 09:26:23 INFO - "revision": "ed54d16cb4d183ed808ba7fbb260f9e526358ff0", 09:26:23 INFO - "stage_platform": "macosx64", 09:26:23 INFO - "builduid": "f217fdfa0ae54fe483e69e8db9cb5084", 09:26:23 INFO - "slavebuilddir": "test" 09:26:23 INFO - } 09:26:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:26:23 INFO - Running post-action listener: _resource_record_post_action 09:26:23 INFO - [mozharness: 2017-06-02 16:26:23.768054Z] Finished read-buildbot-config step (success) 09:26:23 INFO - [mozharness: 2017-06-02 16:26:23.768197Z] Running download-and-extract step. 09:26:23 INFO - Running pre-action listener: _resource_record_pre_action 09:26:23 INFO - Running main action method: download_and_extract 09:26:23 INFO - mkdir: /builds/slave/test/build/tests 09:26:23 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')]} 09:26:23 INFO - trying https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:26:23 INFO - Downloading https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/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 09:26:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/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 09:26:25 INFO - Downloaded 1287 bytes. 09:26:25 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:26:25 INFO - Using the following test package requirements: 09:26:25 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:26:25 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:26:25 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:26:25 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:26:25 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:26:25 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:26:25 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:26:25 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:26:25 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:26:25 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:26:25 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:26:25 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:26:25 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:26:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:26:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:26:25 INFO - Fetch https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:26:27 INFO - Expected file size: 51397536 09:26:27 INFO - Obtained file size: 51397536 09:26:43 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 09:26:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:26:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:26:43 INFO - Fetch https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:26:44 INFO - Expected file size: 51397536 09:26:44 INFO - Obtained file size: 51397536 09:26:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:26:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:26:59 INFO - Fetch https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:27:01 INFO - Expected file size: 14404461 09:27:01 INFO - Obtained file size: 14404461 09:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:02 INFO - trying https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:27:02 INFO - Downloading https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:27:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/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 09:27:03 INFO - Downloaded 59648197 bytes. 09:27:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:27:03 INFO - mkdir: /builds/slave/test/properties 09:27:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:03 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:03 INFO - Contents: 09:27:03 INFO - build_url:https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:27:04 INFO - Running post-action listener: _resource_record_post_action 09:27:04 INFO - Running post-action listener: set_extra_try_arguments 09:27:04 INFO - [mozharness: 2017-06-02 16:27:04.445627Z] Finished download-and-extract step (success) 09:27:04 INFO - [mozharness: 2017-06-02 16:27:04.445769Z] Running populate-webroot step. 09:27:04 INFO - Running pre-action listener: _resource_record_pre_action 09:27:04 INFO - Running main action method: populate_webroot 09:27:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:04 INFO - '--tppagecycles', 09:27:04 INFO - '7'], 09:27:04 INFO - 'tests': ['tsvgm']}}, 09:27:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['damp', 'tps']}, 09:27:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'tests': ['damp', 'tps']}, 09:27:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['dromaeo_dom']}, 09:27:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['basic_compositor_video']}, 09:27:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['a11yr', 09:27:04 INFO - 'ts_paint', 09:27:04 INFO - 'tpaint', 09:27:04 INFO - 'sessionrestore', 09:27:04 INFO - 'sessionrestore_no_auto_restore', 09:27:04 INFO - 'tabpaint']}, 09:27:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:04 INFO - 'ts_paint', 09:27:04 INFO - 'tpaint', 09:27:04 INFO - 'sessionrestore', 09:27:04 INFO - 'sessionrestore_no_auto_restore', 09:27:04 INFO - 'tabpaint']}, 09:27:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:04 INFO - 'ts_paint', 09:27:04 INFO - 'tpaint', 09:27:04 INFO - 'sessionrestore', 09:27:04 INFO - 'sessionrestore_no_auto_restore', 09:27:04 INFO - 'tabpaint']}, 09:27:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:04 INFO - 'ts_paint', 09:27:04 INFO - 'tpaint', 09:27:04 INFO - 'sessionrestore', 09:27:04 INFO - 'sessionrestore_no_auto_restore', 09:27:04 INFO - 'tabpaint']}, 09:27:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['a11yr', 09:27:04 INFO - 'ts_paint', 09:27:04 INFO - 'tpaint', 09:27:04 INFO - 'sessionrestore', 09:27:04 INFO - 'sessionrestore_no_auto_restore', 09:27:04 INFO - 'tabpaint']}, 09:27:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['a11yr', 09:27:04 INFO - 'ts_paint', 09:27:04 INFO - 'tpaint', 09:27:04 INFO - 'sessionrestore', 09:27:04 INFO - 'sessionrestore_no_auto_restore', 09:27:04 INFO - 'tabpaint']}, 09:27:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['tsvgx', 09:27:04 INFO - 'tsvgr_opacity', 09:27:04 INFO - 'tart', 09:27:04 INFO - 'tscrollx', 09:27:04 INFO - 'cart', 09:27:04 INFO - 'tsvg_static']}, 09:27:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:04 INFO - 'tsvgr_opacity', 09:27:04 INFO - 'tart', 09:27:04 INFO - 'tscrollx', 09:27:04 INFO - 'cart', 09:27:04 INFO - 'tsvg_static']}, 09:27:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'talos_options': ['--disable-e10s'], 09:27:04 INFO - 'tests': ['tp5o']}, 09:27:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:27:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'talos_options': ['--disable-e10s', 09:27:04 INFO - '--xperf_path', 09:27:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:04 INFO - 'tests': ['tp5n']}, 09:27:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:27:04 INFO - 'talos_options': ['--xperf_path', 09:27:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:04 INFO - 'tests': ['tp5n']}}, 09:27:04 INFO - 'talos.zip': {'path': '', 09:27:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:04 INFO - Running post-action listener: _resource_record_post_action 09:27:04 INFO - [mozharness: 2017-06-02 16:27:04.465560Z] Finished populate-webroot step (success) 09:27:04 INFO - [mozharness: 2017-06-02 16:27:04.465715Z] Running create-virtualenv step. 09:27:04 INFO - Running pre-action listener: _resource_record_pre_action 09:27:04 INFO - Running main action method: create_virtualenv 09:27:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:04 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 09:27:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:27:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:04 INFO - Using real prefix '/tools/python27' 09:27:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:08 INFO - Installing pip.................done. 09:27:08 INFO - Return code: 0 09:27:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:08 INFO - Reading from file tmpfile_stdout 09:27:08 INFO - Output received: 09:27:08 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) 09:27:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:08 INFO - 'HOME': '/Users/cltbld', 09:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:08 INFO - 'LOGNAME': 'cltbld', 09:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:08 INFO - 'MOZ_NO_REMOTE': '1', 09:27:08 INFO - 'NO_EM_RESTART': '1', 09:27:08 INFO - 'PAGER': '/bin/cat', 09:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:08 INFO - 'PWD': '/builds/slave/test', 09:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:08 INFO - 'SHELL': '/bin/bash', 09:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:08 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:08 INFO - 'USER': 'cltbld', 09:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:08 INFO - 'XPC_FLAGS': '0x0', 09:27:08 INFO - 'XPC_SERVICE_NAME': '0', 09:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:08 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 09:27:08 INFO - Cleaning up... 09:27:08 INFO - Return code: 0 09:27:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:08 INFO - 'HOME': '/Users/cltbld', 09:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:08 INFO - 'LOGNAME': 'cltbld', 09:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:08 INFO - 'MOZ_NO_REMOTE': '1', 09:27:08 INFO - 'NO_EM_RESTART': '1', 09:27:08 INFO - 'PAGER': '/bin/cat', 09:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:08 INFO - 'PWD': '/builds/slave/test', 09:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:08 INFO - 'SHELL': '/bin/bash', 09:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:08 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:08 INFO - 'USER': 'cltbld', 09:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:08 INFO - 'XPC_FLAGS': '0x0', 09:27:08 INFO - 'XPC_SERVICE_NAME': '0', 09:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:08 INFO - Downloading/unpacking psutil>=3.1.1 09: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 09: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 09: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 09: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 09:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:11 INFO - Installing collected packages: psutil 09:27:11 INFO - Running setup.py install for psutil 09:27:11 INFO - building 'psutil._psutil_osx' extension 09:27:11 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 09:27:12 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 09:27:12 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 09:27:12 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 09:27:12 INFO - building 'psutil._psutil_posix' extension 09:27:12 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 09:27:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:12 INFO - ^ 09:27:12 INFO - 1 warning generated. 09:27:12 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 09:27:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:12 INFO - Successfully installed psutil 09:27:12 INFO - Cleaning up... 09:27:12 INFO - Return code: 0 09:27:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09: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 mozsystemmonitor==0.3 09:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:12 INFO - 'HOME': '/Users/cltbld', 09:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:12 INFO - 'LOGNAME': 'cltbld', 09:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:12 INFO - 'MOZ_NO_REMOTE': '1', 09:27:12 INFO - 'NO_EM_RESTART': '1', 09:27:12 INFO - 'PAGER': '/bin/cat', 09:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:12 INFO - 'PWD': '/builds/slave/test', 09:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:12 INFO - 'SHELL': '/bin/bash', 09:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:12 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:12 INFO - 'USER': 'cltbld', 09:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:12 INFO - 'XPC_FLAGS': '0x0', 09:27:12 INFO - 'XPC_SERVICE_NAME': '0', 09:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 09: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 09: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 09: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 09: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 09:27:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:14 INFO - Installing collected packages: mozsystemmonitor 09:27:14 INFO - Running setup.py install for mozsystemmonitor 09:27:14 INFO - Successfully installed mozsystemmonitor 09:27:14 INFO - Cleaning up... 09:27:14 INFO - Return code: 0 09:27:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27:14 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')]} 09:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:14 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')]} 09:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:14 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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:14 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 09:27:14 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 09:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:14 INFO - 'HOME': '/Users/cltbld', 09:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:14 INFO - 'LOGNAME': 'cltbld', 09:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:14 INFO - 'MOZ_NO_REMOTE': '1', 09:27:14 INFO - 'NO_EM_RESTART': '1', 09:27:14 INFO - 'PAGER': '/bin/cat', 09:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:14 INFO - 'PWD': '/builds/slave/test', 09:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:14 INFO - 'SHELL': '/bin/bash', 09:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:14 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:14 INFO - 'USER': 'cltbld', 09:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:14 INFO - 'XPC_FLAGS': '0x0', 09:27:14 INFO - 'XPC_SERVICE_NAME': '0', 09:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:14 INFO - Downloading/unpacking jsonschema==2.5.1 09: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 09: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 09: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 09: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 09:27:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:16 INFO - warning: no files found matching '*.txt' 09:27:16 INFO - no previously-included directories found matching 'build' 09:27:16 INFO - no previously-included directories found matching 'dist' 09:27:16 INFO - no previously-included directories found matching '.git*' 09:27:16 INFO - Installing collected packages: jsonschema, functools32 09:27:16 INFO - Running setup.py install for functools32 09:27:16 INFO - warning: no files found matching '*.txt' 09:27:16 INFO - no previously-included directories found matching 'build' 09:27:16 INFO - no previously-included directories found matching 'dist' 09:27:16 INFO - no previously-included directories found matching '.git*' 09:27:16 INFO - Successfully installed jsonschema functools32 09:27:16 INFO - Cleaning up... 09:27:16 INFO - Return code: 0 09:27:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:16 INFO - 'HOME': '/Users/cltbld', 09:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:16 INFO - 'LOGNAME': 'cltbld', 09:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:16 INFO - 'MOZ_NO_REMOTE': '1', 09:27:16 INFO - 'NO_EM_RESTART': '1', 09:27:16 INFO - 'PAGER': '/bin/cat', 09:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:16 INFO - 'PWD': '/builds/slave/test', 09:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:16 INFO - 'SHELL': '/bin/bash', 09:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:16 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:16 INFO - 'USER': 'cltbld', 09:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:16 INFO - 'XPC_FLAGS': '0x0', 09:27:16 INFO - 'XPC_SERVICE_NAME': '0', 09:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:17 INFO - Cleaning up... 09:27:17 INFO - Return code: 0 09:27:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:17 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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:17 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 09:27:17 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 09:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:17 INFO - 'HOME': '/Users/cltbld', 09:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:17 INFO - 'LOGNAME': 'cltbld', 09:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:17 INFO - 'MOZ_NO_REMOTE': '1', 09:27:17 INFO - 'NO_EM_RESTART': '1', 09:27:17 INFO - 'PAGER': '/bin/cat', 09:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:17 INFO - 'PWD': '/builds/slave/test', 09:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:17 INFO - 'SHELL': '/bin/bash', 09:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:17 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:17 INFO - 'USER': 'cltbld', 09:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:17 INFO - 'XPC_FLAGS': '0x0', 09:27:17 INFO - 'XPC_SERVICE_NAME': '0', 09:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:17 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 09:27:17 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 09:27:17 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 09:27:17 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 09:27:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:19 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 09:27:19 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 09:27:19 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 09:27:19 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 09:27:19 INFO - Downloading docopt-0.6.1.tar.gz 09:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:19 INFO - Running setup.py install for blobuploader 09:27:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:19 INFO - Running setup.py install for requests 09:27:20 INFO - Running setup.py install for docopt 09:27:20 INFO - Successfully installed blobuploader requests docopt 09:27:20 INFO - Cleaning up... 09:27:20 INFO - Return code: 0 09:27:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:20 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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:20 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 09:27:20 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 09:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:20 INFO - 'HOME': '/Users/cltbld', 09:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:20 INFO - 'LOGNAME': 'cltbld', 09:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:20 INFO - 'MOZ_NO_REMOTE': '1', 09:27:20 INFO - 'NO_EM_RESTART': '1', 09:27:20 INFO - 'PAGER': '/bin/cat', 09:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:20 INFO - 'PWD': '/builds/slave/test', 09:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:20 INFO - 'SHELL': '/bin/bash', 09:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:20 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:20 INFO - 'USER': 'cltbld', 09:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:20 INFO - 'XPC_FLAGS': '0x0', 09:27:20 INFO - 'XPC_SERVICE_NAME': '0', 09:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-BJ7F7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-L02uxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-C1Hgq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-bQfIg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-gbHN4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-wrJcqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-ghlmXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-DaMV10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-3dmOj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-A9HDcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-l2NNmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-6GuUue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:22 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-JpAXL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:22 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-tPIrdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:22 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-amh3AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:22 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-s4DHnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:22 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-hJFJ7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:22 INFO - Running setup.py install for manifestparser 09:27:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:22 INFO - Running setup.py install for mozcrash 09:27:22 INFO - Running setup.py install for mozdebug 09:27:23 INFO - Running setup.py install for mozdevice 09:27:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozfile 09:27:23 INFO - Running setup.py install for mozhttpd 09:27:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozinfo 09:27:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozInstall 09:27:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozleak 09:27:23 INFO - Running setup.py install for mozlog 09:27:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Running setup.py install for moznetwork 09:27:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Running setup.py install for mozprocess 09:27:24 INFO - Running setup.py install for mozprofile 09:27:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Running setup.py install for mozrunner 09:27:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Running setup.py install for mozscreenshot 09:27:24 INFO - Running setup.py install for moztest 09:27:25 INFO - Running setup.py install for mozversion 09:27:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:25 INFO - Cleaning up... 09:27:25 INFO - Return code: 0 09:27:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:25 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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:25 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 09:27:25 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 09:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:25 INFO - 'HOME': '/Users/cltbld', 09:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:25 INFO - 'LOGNAME': 'cltbld', 09:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:25 INFO - 'MOZ_NO_REMOTE': '1', 09:27:25 INFO - 'NO_EM_RESTART': '1', 09:27:25 INFO - 'PAGER': '/bin/cat', 09:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:25 INFO - 'PWD': '/builds/slave/test', 09:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:25 INFO - 'SHELL': '/bin/bash', 09:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:25 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:25 INFO - 'USER': 'cltbld', 09:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:25 INFO - 'XPC_FLAGS': '0x0', 09:27:25 INFO - 'XPC_SERVICE_NAME': '0', 09:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:25 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-wASKC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:25 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-_UFB8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:25 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-73k4NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:25 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-FFulcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:25 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-gIhi50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-VEKW08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Vhvhg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-z2FbBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-MsGUZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-LPXZcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-vYfjt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-0KWb2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-hvawEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-2qXmzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:26 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-r94pd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:27 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)) 09:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:27 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-gbfr_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:27 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)) 09:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:27 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-S5T0b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:27 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)) 09:27:27 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)) 09:27:27 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)) 09:27:27 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)) 09:27:27 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)) 09:27:27 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)) 09:27:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:27 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 09:27:27 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 09:27:27 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 09:27:27 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 09:27:28 INFO - Downloading blessings-1.6.tar.gz 09:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:28 INFO - Installing collected packages: blessings 09:27:28 INFO - Running setup.py install for blessings 09:27:28 INFO - Successfully installed blessings 09:27:28 INFO - Cleaning up... 09:27:29 INFO - Return code: 0 09:27:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:29 INFO - Reading from file tmpfile_stdout 09:27:29 INFO - Current package versions: 09:27:29 INFO - blessings == 1.6 09:27:29 INFO - blobuploader == 1.2.4 09:27:29 INFO - docopt == 0.6.1 09:27:29 INFO - functools32 == 3.2.3-2 09:27:29 INFO - jsonschema == 2.5.1 09:27:29 INFO - manifestparser == 1.1 09:27:29 INFO - mozInstall == 1.12 09:27:29 INFO - mozcrash == 1.0 09:27:29 INFO - mozdebug == 0.1 09:27:29 INFO - mozdevice == 0.49 09:27:29 INFO - mozfile == 1.2 09:27:29 INFO - mozhttpd == 0.7 09:27:29 INFO - mozinfo == 0.9 09:27:29 INFO - mozleak == 0.1 09:27:29 INFO - mozlog == 3.4 09:27:29 INFO - moznetwork == 0.27 09:27:29 INFO - mozprocess == 0.25 09:27:29 INFO - mozprofile == 0.28 09:27:29 INFO - mozrunner == 6.13 09:27:29 INFO - mozscreenshot == 0.1 09:27:29 INFO - mozsystemmonitor == 0.3 09:27:29 INFO - moztest == 0.8 09:27:29 INFO - mozversion == 1.4 09:27:29 INFO - psutil == 3.1.1 09:27:29 INFO - requests == 1.2.3 09:27:29 INFO - wsgiref == 0.1.2 09:27:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:29 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 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:29 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 09:27:29 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 09:27:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:29 INFO - 'HOME': '/Users/cltbld', 09:27:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:29 INFO - 'LOGNAME': 'cltbld', 09:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:29 INFO - 'MOZ_NO_REMOTE': '1', 09:27:29 INFO - 'NO_EM_RESTART': '1', 09:27:29 INFO - 'PAGER': '/bin/cat', 09:27:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:29 INFO - 'PWD': '/builds/slave/test', 09:27:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:29 INFO - 'SHELL': '/bin/bash', 09:27:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:29 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:29 INFO - 'USER': 'cltbld', 09:27:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:29 INFO - 'XPC_FLAGS': '0x0', 09:27:29 INFO - 'XPC_SERVICE_NAME': '0', 09:27:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 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)) 09:27:29 INFO - Cleaning up... 09:27:29 INFO - Return code: 0 09:27:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe739d666e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, '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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 09:27:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:27:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:29 INFO - 'HOME': '/Users/cltbld', 09:27:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:29 INFO - 'LOGNAME': 'cltbld', 09:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:29 INFO - 'MOZ_NO_REMOTE': '1', 09:27:29 INFO - 'NO_EM_RESTART': '1', 09:27:29 INFO - 'PAGER': '/bin/cat', 09:27:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:29 INFO - 'PWD': '/builds/slave/test', 09:27:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:29 INFO - 'SHELL': '/bin/bash', 09:27:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:29 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:29 INFO - 'USER': 'cltbld', 09:27:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:29 INFO - 'XPC_FLAGS': '0x0', 09:27:29 INFO - 'XPC_SERVICE_NAME': '0', 09:27:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:29 INFO - Cleaning up... 09:27:29 INFO - Return code: 0 09:27:29 INFO - Running post-action listener: _resource_record_post_action 09:27:29 INFO - Running post-action listener: _start_resource_monitoring 09:27:29 INFO - Starting resource monitoring. 09:27:29 INFO - [mozharness: 2017-06-02 16:27:29.870084Z] Finished create-virtualenv step (success) 09:27:29 INFO - [mozharness: 2017-06-02 16:27:29.871114Z] Running install step. 09:27:29 INFO - Running pre-action listener: _resource_record_pre_action 09:27:29 INFO - Running main action method: install 09:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:30 INFO - Reading from file tmpfile_stdout 09:27:30 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:30 INFO - Reading from file tmpfile_stdout 09:27:30 INFO - Output received: 09:27:30 INFO - Usage: mozinstall [options] installer 09:27:30 INFO - Options: 09:27:30 INFO - -h, --help show this help message and exit 09:27:30 INFO - -d DEST, --destination=DEST 09:27:30 INFO - Directory to install application into. [default: 09:27:30 INFO - "/builds/slave/test"] 09:27:30 INFO - --app=APP Application being installed. [default: firefox] 09:27:30 INFO - mkdir: /builds/slave/test/build/application 09:27:30 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'] 09:27:30 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 09:27:44 INFO - Reading from file tmpfile_stdout 09:27:44 INFO - Output received: 09:27:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:27:44 INFO - Running post-action listener: _resource_record_post_action 09:27:44 INFO - [mozharness: 2017-06-02 16:27:44.956010Z] Finished install step (success) 09:27:44 INFO - [mozharness: 2017-06-02 16:27:44.956168Z] Running run-tests step. 09:27:44 INFO - Running pre-action listener: _resource_record_pre_action 09:27:44 INFO - Running main action method: run_tests 09:27:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:44 INFO - 2017-06-02 09:27:44.970 screenresolution[1627:7770] starting screenresolution argv=screenresolution get 09:27:44 INFO - 2017-06-02 09:27:44.973 screenresolution[1627:7770] Display 0: 1600x1200x32@60 09:27:44 INFO - 2017-06-02 09:27:44.979 screenresolution[1628:7772] starting screenresolution argv=screenresolution list 09:27:44 INFO - Available Modes on Display 0 09:27:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:45 INFO - Intel Iris: 09:27:45 INFO - Chipset Model: Intel Iris 09:27:45 INFO - Type: GPU 09:27:45 INFO - Bus: Built-In 09:27:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:45 INFO - Vendor: Intel (0x8086) 09:27:45 INFO - Device ID: 0x0a2e 09:27:45 INFO - Revision ID: 0x0009 09:27:45 INFO - Displays: 09:27:45 INFO - Display: 09:27:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:45 INFO - Display Serial Number: 1600x1200 60 09:27:45 INFO - Main Display: Yes 09:27:45 INFO - Mirror: Off 09:27:45 INFO - Online: Yes 09:27:45 INFO - Rotation: Supported 09:27:45 INFO - Return code: 0 09:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:45 INFO - Python 2.7.3 09:27:45 INFO - Return code: 0 09:27:45 INFO - grabbing minidump binary from tooltool 09:27:45 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')]} 09:27:45 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 0x10bd5cd30>, '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 0x10bc83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd51be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:45 INFO - Return code: 0 09:27:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:45 INFO - ENV: RUST_BACKTRACE is now 1 09:27:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 09:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0323 --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 09:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvJdOBSkUa/Render', 09:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:45 INFO - 'HOME': '/Users/cltbld', 09:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:45 INFO - 'LOGNAME': 'cltbld', 09:27:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:45 INFO - 'MOZ_NO_REMOTE': '1', 09:27:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:45 INFO - 'NO_EM_RESTART': '1', 09:27:45 INFO - 'PAGER': '/bin/cat', 09:27:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:45 INFO - 'PWD': '/builds/slave/test', 09:27:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:45 INFO - 'RUST_BACKTRACE': '1', 09:27:45 INFO - 'SHELL': '/bin/bash', 09:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BRGh2SB2M/Listeners', 09:27:45 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 09:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:45 INFO - 'USER': 'cltbld', 09:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:45 INFO - 'XPC_FLAGS': '0x0', 09:27:45 INFO - 'XPC_SERVICE_NAME': '0', 09:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 09:27:45 INFO - mozversion application_buildid: 20170602081524 09:27:45 INFO - mozversion application_changeset: ed54d16cb4d183ed808ba7fbb260f9e526358ff0 09:27:45 INFO - mozversion application_display_name: Firefox 09:27:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:45 INFO - mozversion application_name: Firefox 09:27:45 INFO - mozversion application_remotingname: firefox 09:27:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:27:45 INFO - mozversion application_vendor: Mozilla 09:27:45 INFO - mozversion application_version: 54.0 09:27:45 INFO - mozversion platform_buildid: 20170602081524 09:27:45 INFO - mozversion platform_changeset: ed54d16cb4d183ed808ba7fbb260f9e526358ff0 09:27:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:27:45 INFO - mozversion platform_version: 54.0 09:27:45 INFO - using testdate: 1496420865 09:27:45 INFO - actual date: 1496420865 09:27:45 INFO - starting webserver on 'localhost:49219' 09:27:45 INFO - SUITE-START | Running 2 tests 09:27:45 INFO - TEST-START | dromaeo_css 09:27:45 INFO - operating with platform_type : mac_ 09:27:45 INFO - Initialising browser for dromaeo_css test... 09:27:45 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpcOcfT1/profile http://localhost:49219/getInfo.html) 09:27:48 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 09:27:48 INFO - PROCESS | 1638 | colorDepth:24 09:27:48 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 09:27:48 INFO - PROCESS | 1638 | __metrics 09:27:48 INFO - PROCESS | 1638 | 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" 09:27:48 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:27:49 INFO - TEST-INFO | 1638: exit 0 09:27:49 INFO - Browser initialized. 09:27:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:49 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpcOcfT1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:01 INFO - PROCESS | 1643 | RSS: Main: 165199872 09:28:01 INFO - PROCESS | 1643 | 09:31:04 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 09:31:06 INFO - PROCESS | 1643 | RSS: Main: 291000320 09:31:06 INFO - PROCESS | 1643 | 09:34:09 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 09:34:10 INFO - PROCESS | 1643 | RSS: Main: 302309376 09:34:10 INFO - PROCESS | 1643 | 09:37:12 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 09:37:13 INFO - PROCESS | 1643 | RSS: Main: 286846976 09:37:13 INFO - PROCESS | 1643 | 09:40:15 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 09:40:16 INFO - PROCESS | 1643 | RSS: Main: 275734528 09:40:16 INFO - PROCESS | 1643 | 09:43:19 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 09:43:20 INFO - PROCESS | 1643 | RSS: Main: 267202560 09:43:20 INFO - PROCESS | 1643 | 09:46:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 09:46:23 INFO - PROCESS | 1643 | RSS: Main: 568217600 09:46:23 INFO - PROCESS | 1643 | 09:46:23 INFO - PROCESS | 1643 | __start_tp_report 09:46:23 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:46:23 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:46:23 INFO - PROCESS | 1643 | |i|pagename|runs| 09:46:23 INFO - PROCESS | 1643 | |0;dojo.html;3605.7475976681635;3624.3657359962;3631.564212294525;3631.6005239423657;3683.650053244941;5197.480251974801;6266.655333956631;6302.590331628441;6303.99136138218;6314.305426403098;6273.937260627404;6307.274663413708;6334.113224148623;6344;6344.016677414997;5494.011078005968;5527.53015993641;5553.028220061473;5570.108782594785;5669.518090962252;5465.426130256305;5480.068388373966;5525.834224973257;5653.293338332708;5676.063449530843;5555.972220138894;5556;5588.385277619459;5590.776368945238;5607.327120745516;5601.60788744788;5625.296837895263;5633.40849210831;5636.267285252911;5641.943580564224;4592.862214133581;5411.702356370398;5434.510894019518;5578.553715702734;5582.972085139589;6024.78913238039;6087.512998960073;6102.51954821894;6140.946974996481;6202.224721909762;6506.446952009085;6548.541602087856;6551.246606640249;6564.146660934096;6564.376384243489;6579.80260592183;6621.404073633348;6632.535722499427;6657.201135863679;6658.434033107192;6108.80532842904;6156.445919867234;6229.595076320024;6272.686365681743;6363.427291543737;6596.60420374779;6596.703148358335;6603.405693487513;6706.26231114577;6761.628110453972;6426.164598602152;6473.546851720334;6484.870302593922;6488.124103246096;6494.707738151795;6623.801285961429;6627.469802415795;6639.170103737033;6643.867122657616;6649.069130321759;6325.367463253638;6339;6510.479161667055;6534;6548.377904099103;6428.614283142932;6471.644059576768;6577.14497115372;6580.440662543738;6590.7693230736695;5272.2882410874045;5306.28365170697;5310.92033619496;5325.440828713007;5355.839324820184;4684.180268453007;4734.976325118352;4744.501827308152;4748.833790817304;4802.51974802517;6188.8762224755255;6212.968935155295;6256.468200202943;6280.5603607747935;6308.400701933309;6643.50173736962;6669.266380698119;6671.432928201158;6677.732890684319;6684.398404143553;6293.527985401113;6297.4332310091395;6300.779472718433;6313.684315784192;6315.526335524854;4887.828925987574;5656.123300888369;5698.629589076697;5715.599908006483;5753.079507278815;6906.792796216121;6927.203371612329;6928;6931.792046238621;6933.306669333027;6597.769078082436;6620.768273110418;6622.503312251504;6632.568883022588;6635.734570617121;6545;6815.591064536144;6896.724131034703;6933.272006439453;6935.89596156048;6397.360263973565;6410.551261411656;6416.743330266737;6431.967840160769;6435.163428754419;5384.353877534721;5453.15476101205;6439.259485159079;6573.211214654272;6671.166104236971;6441.5490915635455;6464.612123272619;6485.643289619116;6494.318096599978;6498.3826536478955;6475.708593113415;6493.8701225976165;6496.935030649633;6497.480201583957;6499.6425196612745;6225.875482490415;6318.652474113968;6375.1712277405495;6384.425401713868;6386.552941294251;6343.238811342484;6370.55406121567;6430.742770289136;6462.644554549545;6484.254310754323;6325.936740632535;6335.65153916539;6339.619622822645;6341.0805433212845;6342.746290148342 09:46:23 INFO - PROCESS | 1643 | |1;ext.html;823.464747913856;842.7092653034703;910.0353625157329;924.8520236762115;930.1163894300416;41505.58494415055;42325;43724.344134838;44069;44076.77961610188;43345.56654433455;43511;43537.56462435375;43632.56367436325;43658.563414365846;7969.800754981114;9594.664186753465;9770.951145244264;9778.804423911517;9786.804263914717;7949.364050875916;11641.767164656701;11826.053915686725;12077.456514456826;12152.210106343104;11813;11895.345755983417;11916.7616647667;11926.582569610066;11926.940365298162;11826.704332391717;11857.229280096766;11859.169858109935;11895.464704088337;11903.58337458189;11529.077673786162;11570;11581.478833452515;11607;11612.767744645143;41245.58754412447;41423.75728728143;41459.58540414618;41594;41605.375919361235;4030.4558884550797;4074.653654439376;4081.7142800004012;4097;4105.014796448831;28297;29039.709602903913;30555.69444305551;30833.229169270722;31109.37781244385;3176.1583180457196;3217.6621454747146;3334.416477116495;3409.232922592422;3411.4541673332396;365502.9999999973;365550;365935;366070;366256;44552.10895782066;45310.32034519485;54231.72884135633;54999.450005499435;55078.72460637671;47737;56723.71638141783;56803.43196568064;59754;60110.69944650249;15449.768253476206;15510.767338489932;15526.301316440788;15531.5340539784;15536.84463155354;11057.336559806437;13075.738485230433;13141.605751827461;13144.671383215496;13146.671333216746;11418.371989540488;12257.509699611915;12887.355632218352;12893.48426062947;13101.672458188621;5753.712314384347;5788.450097240755;5788.453080644503;5819.679917604488;5823.272090988627;944.3767113704922;1072.2333531524996;1137.2664631312757;1144.547903578082;1176.4411904345368;24878;27315.844449220305;28002.019929302376;28792.856035719687;28818.423631527254;1192.7316353820322;1199.5022065842577;1202.3867827408085;1218.9512419503121;1221.3648902570615;14439.783403248959;14547.709045819025;14757.114573125433;14834.703305933821;14853.885958552935;423590;423915;424887.8755606202;426792;431080;59585.70207149023;59622.40377596255;59689.10466343008;59692.104618430756;59702;1398.1471302505463;1434.8493408192408;1442.7475191841595;1533.1567637799167;1536.2395614171057;27381;27872.303192420353;28191.71808281891;28488.715112848608;28551.000714974874;38909.41635875521;47632.7618361906;47696.7615161922;47873.32443364466;48025;479130;480309;480928;481295;481501;21004.789952100895;24326.51346973086;25766.613500797877;26465.33836654099;30533.694663053084;32384.514232286063;38398;38698.22603547857;38918.6108138915;40314.798426007685;26753.598696019188;28554.57168142438;28755;28934;29574.408511829213;27463;28302.575461367684;28714.42571148607;29278.560821587267;29540.852295738383;27721.722782771914;32868.50697239591;33099.84150554719;33282;33284.835030774775;28471;28658.426831462835;28797.856010719814;29064.692088856475;29069;21009.369718908456;21791.673124902823;23072.307830765105;23505.17731879376;23785.762142379048 09:46:23 INFO - PROCESS | 1643 | |2;jquery.html;369.22647054420986;427.1180013272592;435.16014092800884;440.5990548600773;464.1529209193223;21090.789092109095;21431.249906253266;22088.668669969964;22229.333120006406;22268.88865555674;21992.8900355498;23498.17756378527;23560.29319120429;23586;23870.76129238707;6765.898511522331;7141.785746427589;7142.928570714304;7151.392131668815;7601;7876.684932602718;8016.719414820483;8020.398470114736;8026.197380261986;8037.316828069592;7785.2214778521975;7974.840503189933;8065.2741253287195;8068.636911339004;8069.838603227932;7734.497257678261;7933.880991785128;7954.920450795534;8008.879866801974;8027.394521095745;6511.316311787242;7413.96204531366;7758.883616745753;7773.2226777322385;7791.8441631167625;26635.467290654273;26770.464590708078;27393;27394.5890811638;27792.444151116866;2962.60004899338;2997.8800847966054;3002.8198308101364;3006.0831446409056;3093.566900633913;15344.309506072253;15481.303341349667;15535.92232038844;15975.520734377988;16273.593160170973;1870.485616455472;2048.8873111978837;2109.556993031465;2116.322776711453;2137.1237792505153;129175.7082429173;134703.32648336794;136653.6334636651;137361.31319343438;137994.3100284502;15300.628402597615;18067.470554122432;19064.95142767161;19444.70832937507;19529.632925695067;29584.852075739484;29675.258118547208;29833.552496712568;29866.850665746533;29909.40181196364;10474.790504189874;10491.055804977588;10538.20963427746;10640.627578034731;10787.56849726018;9046.095390461032;9087.22758565516;9094.272458203328;9260.583273752698;9288;8858;9143.77140571491;9235.953820230856;9236.307276954256;9248.78029272983;3420.640832712529;4095.198112830345;4117.732347397409;4121.176993759284;4121.90856857357;1049.9580016799243;1079.578964203961;1098.4452851310036;1120.4957769003818;1155.92486488378;21438.57122857534;21642.02610882514;21768.89115554444;21784.34646960594;21854.7979861106;1092.847001419802;1094.6059418609295;1096.1942971915635;1124.460259075632;1125.5329038449115;9606.183474404754;10367.066963973279;11482.655520334403;11508.597199098158;11512.330712046707;750734;763488;766215;775125;793701;102719;141056;157504;159579;161710;1215.2647648172906;1224.7244370016854;1272.287518989369;1272.7263638317806;1273.6306471123455;19325.13036913314;19573.60852782965;19964.3012494562;20218.595628087063;20249.493762656053;61580.69209653923;62630;62901.37098629138;63682.68158659177;63824.68087659718;89028;89652;89714;91236.17527649277;91716.08283917342;10851.945740271249;11591.594294199662;11636.650900473;11782.293062416282;11878.8715072068;12140.964758810298;12946.676333091747;13080.672983175496;13104.672383190496;13465.865341346462;11250.381229032482;11259.887401125883;11498.08015358786;11665.416729163677;11786.76426471483;10511.684649460223;10675.733106672085;10870.184736144663;11068.391238481961;11167.776644467227;11462.4268786562;11541;11690.941545292219;11897.226680265756;11941.46263418131;9671.371360861522;11399.715007124889;11491.080713543064;11578.71053223676;11596.188266821242;10011.24915631316;10608.151347892304;11429.028532574825;11497;11651.766964660828 09:46:23 INFO - PROCESS | 1643 | |3;mootools.html;1559.5711179425664;1576.0464918724172;1596.7684685720565;1613.9677206455867;1625.9756103658447;10930.508127134279;11582.59460918868;11610.36143012134;11615.245009074404;11638.243514171594;10761.784764304728;11124.833127503074;11314.434278286093;11319.717007074827;11324.32054076756;2336.906523739053;2364.668946347504;2371.1345358944036;2374.774396432345;2376.8098552115875;2279.920202792894;2352.1649814315842;2362.787349138577;2372.074890792592;2382.3210385040265;2268.9432764180947;2333.7549557296493;2335.1593426366494;2358.7523310052457;2367.6093444581625;2293.7706229377095;2304.320225533472;2316.131450705985;2318.1422873536817;2334.8832558372096;2328.918487852934;2334.4747431827873;2336.240721765433;2340.0288880114904;2343.3321503371626;11437.313761174357;11521.308721476738;11543.94228028863;11546.249493782878;11566.47950842214;5349.812756553541;5509;5570.721463926788;5621;5644.8024319148635;3144.7544702234886;3241.2707140893344;3297.1097803592897;3330.333933213343;3378.3074469733892;1115.559932888234;1158.490264283721;1163.1215586827298;1174.794410978084;1196.7785959597502;14176.929115354356;14419.125513683153;14850.925745371203;15638.13990230537;15665.216739162996;4459.038023269751;4633.698809577367;4662.696924699883;4696.178168820443;4709.222978208557;12506.061560458144;12592.4333404997;12642.620721378373;12664;12708.809367859489;4947.851564453073;5122.615803814729;5158.535731784157;5542.806001789999;5564.360098588714;4402.185595664812;5145.511176438233;5173.3533308336455;5190.987757387274;5214.687118772886;3305.504174373863;3702.129999450127;3776.867809626705;3831.214601006787;3909.2181563687377;1019.9745006374751;1023.5138309303021;1033.245730616654;1034.9896501035043;1050.1179009631946;750.5009168902618;838.5094719588986;912.270183852915;916.5279880861361;922.4511415707643;6363.618182908947;6391.328910464427;6445.9355406446275;6575.013747937847;6644.368784965421;882.5013867165031;893.9005023820737;987.2151639446657;1024.3700124423574;1049.1587264351167;3711.870084547028;3907.5701672815967;4013.3578926876066;4188.141431006637;4209.17921005401;13015.804762928563;13896.513622023384;14061.578152655436;14064.08583442073;14075.718485630436;13603.727925441633;13719.176849389069;13954.09298395601;13972.231527265894;13976;10136.746581335525;10393.688189354332;10436.42599657011;10575.947120264504;10627.574897004188;5924.11138329254;6365.363463653597;6405.807825765234;6464.577792885558;6493.967530162319;11648.009919156966;12215.75568488643;12484.687882803002;12591.748165036832;12647.988160947287;9558.61765529371;11522.884771152516;12874.420651070912;13059.673508162372;13066.869331306945;8437.156284371515;8688.956555217184;8751.91248087511;8756;8786.341024423322;10395.792084158124;10561.996610322007;10573.682789516326;10578.471076446302;10608.257421980386;8754;8944.910550894409;9016.143466370684;9029.81940361176;9033.59348829291;8785.956070219863;8811.251043661361;8824.117588241124;8826.382153249468;8906.599203036008;6684.699188536625;7801.4929029612895;7803.804904877424;7881.448298619041;7883.369330453435;5568.192612071146;5703.344115426779;5810.912836307374;5849.502792262705;5955.67243801595;5575.526080283222;5706.80026199081;5810.244668193108;5845.17147723145;5948.821535353946 09:46:23 INFO - PROCESS | 1643 | |4;prototype.html;340.2718183088191;382.18975771364694;383.77740910272047;388.6890487609911;388.7424182768741;4548.999999999995;5210.635255532115;5244.39689435715;5266.262723218748;5283.309341010878;4374.562543745624;4568.428946381702;5124.308218390516;5200.713960732158;5267.736613169345;3913.002184442977;4008.6993475489453;4326.84856030039;4344.500382456026;4411.228035093854;3826.445165451008;3990.9600903990877;4188.623023927846;4230.667339787972;4279.33670281107;4024.9597504024878;4051.6961227907877;4144.834206631731;4178;4384.517703052662;4206.896551724138;4313;4345.631126195254;4366.75547468973;4441.578694817644;3931.719602630496;3947.4868267125375;3950.2889479893606;3986.880393588197;4046.291898917678;4215.283401821697;4218.599233072853;4738.312944623045;5010.273510341017;5080.669756465818;4353.385411463827;4704.929426058612;4900.215965445512;4954.491412814322;5041.495850414965;4580.977095114503;4810.78351474181;5061.519155680205;5524.502794748492;5549.556035517189;4012.779297138656;4200.831966721327;4431.158079964828;4442.089371678798;4482.8344630396;5284.656497327738;5434.375046869582;5757.625754325955;5838.795642152504;6023.307319658208;4048.1903619276263;4084.959150408458;4321.049369138785;4408.470983482002;4549.72607669853;4236.347824348507;4446.399736035593;4594.402727645384;4691.5308469152815;4722.881927951828;4130.938035929464;4351.912961740811;4643.605293550018;5018.723970181675;5059.443461219263;5305.893882122413;5507;5507.191332420414;5526.530244929146;5602.299712535933;1532.2798284806124;1535.7849901013867;1556.813182418117;1566.4908904606048;1591.3793620488018;1051.6371851711147;1081.6809041332826;1111.7832022755647;1168.9298642081503;1190.2501424102934;4957.975210123926;5174.379074511083;5249.737513124329;5317.245309048005;5320.733963301819;5225.085610018232;5259.342582177228;5737.3688894221605;5742.48317651405;5758.856028599319;4852.975735121302;4993.725345106054;5120.8719782005;5191.3647201131525;5255.5532779713385;2662.6272321874953;2735.931601709973;3013.4575776360025;3031.4998025325576;3061.280599059219;5718.942810571924;6018;6029.728662210211;6084.300305464928;6107.938920610826;5274.235235890851;5758.27251824451;5804.245448091721;5846.4153584642045;5863.003289440805;4602.907941841145;4917.385326834145;4950.955162575063;5771.6537007778825;5781.855453613609;4518.570735780096;5588.888222235451;5840.532757379315;5870.207521984477;5878;5008.923081537487;5293.47065293483;5415.967058447282;5772.913406298993;5788.82633520995;5036.571891389273;5961.731722072431;5985.790497332572;6045.767962426231;6072.969635151796;3828.5497281740268;4281.901868084678;4294.028024077653;4519.367288579667;4551.771021824023;5365.651232669864;5694.430556944272;5759.1649210865025;5802.6808525531505;5869.589128760946;4774.66041639601;5174.767135478837;5291.930680205549;5542.418046105101;5678.927191575128;4758.76206189696;5086.237064440364;5270.154116776897;5291.894162116659;5669.3480249770155;4785.928211076767;5060.721660308718;5253.395859476056;5443.510084092451;5493.642913210629;4592.862214133581;5082.466341034138;5549.669138961726;5554.472325129106;5625.353084395183;4308.849190278325;4557.883318586967;4742.818303580391;4798.384880240347;5542.695151766691 09:46:23 INFO - PROCESS | 1643 | |5;yui.html;759.4835511851938;787.9566623835686;805.3154818404354;896.6996056321132;908.5593487158729;16185.757213641804;16649.167541622915;17080.06059666718;17706.64586708265;17709.380171693992;15672.216389180552;15940.760888586681;16063.598410039725;16305;16324.918375408106;1006.6476733143378;1034.7361422837166;1034.8499467577196;1035.9899098379058;1038.0501840815646;1009.8790342719575;1022.5194158745378;1022.5858527296419;1026.3533973596623;1038.3250886923522;1075.42464781342;1077.3051381858697;1077.9245452818307;1078.9946050269737;1080.692002779208;901.792587704825;1072.3244356055714;1072.3565860483686;1074.0119090436817;1074.2211896375104;791.1337085890998;791.1850793682516;791.2799352589117;792.5561685456163;794.6582969323189;17114.65770684592;17147.05691186984;17239.56901077483;17726.37957671488;18040.639187216315;14726.63183420423;14738.55784326472;14833.332500037524;14901.627459313495;14904;12044.150903835469;12060.216085954473;12257.816132758016;12343.567975120828;12532.498700051987;3791.9810400947817;3905.1018265798743;3934.449177115206;3943.3493473576827;4074.6129117734063;117814.99999999914;119535.80464195333;121033;123464;125662.3716881419;10973.670789876316;11122.55509779616;11490.94254528722;11593.478293476814;11802.291862488279;21526.354209373745;22143.335699929026;23855.80720963945;23868.40328991789;23913.880430597736;8193.262606365455;8291.917080829115;8301.418900676894;8330.958345208235;8494.193051660082;4576.807514548987;4594.977025114853;4647.674662773641;4660.813567457264;4699.0132072265205;3134.623845138598;3225.6935591118804;3243.821589812583;3278.344331133783;3279.049075768048;645.2225068792094;645.3320812958565;659.2089492608889;660.0429377402785;667.5560752099897;584.4914924016048;595.9553033522503;599.886021655884;611.1443978430233;621.1767516636896;10576.312539684895;10663.73340666474;10734.73163170927;11194.824543422823;11472.94263528677;588.9322727886263;639.7696829141505;645.5803727577113;676.5027704637088;739.443277833622;4696.725979358767;5122.078025955289;5131.87170320745;5152.819651312186;5168.819091331786;91756;97576.42344729575;97813.25790210304;99588.77203450991;101180.49409752904;42985.570144298785;57036.71481642565;69209;85669;88285;68151;68840.65579672069;70934;72286.63856680683;72339.6383018092;10698.786024279627;10976.888407431707;11262.38056906878;11347.829782553434;11364.715882103013;39017.414738778374;49871.75064124656;50157.74921125371;51344.74327628488;51413.20053798099;64390.71218575509;64646;65137;65238.34761652323;65286.34713652803;12706;12777.23336599807;12876.22742635445;13374.933125334312;13949.651258718208;15626.296816643051;15634.843651563793;15688.058716477048;16031.919840400724;16042.197890105681;13820;14401.42394304216;14662.120272783666;14683.486077987647;15087.4719384821;13993.650158746113;14663.120212787268;14721.55835324942;14928.225874337322;15063.246837658293;13302.93348533251;13911.652208694864;14213.573592792232;14232.35954382076;14293.428262869786;9642.38832093562;9807.9019209807;10040.899591003996;10068.496575171066;10342.741431464274;10350.844737328796;11035.889641103486;11536.480858361227;11746.236494627821;11779.29324240514 09:46:23 INFO - PROCESS | 1643 | __end_tp_report 09:46:23 INFO - PROCESS | 1643 | __start_cc_report 09:46:23 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,2332 09:46:23 INFO - PROCESS | 1643 | __end_cc_report 09:46:23 INFO - PROCESS | 1643 | __startTimestamp1496421983984__endTimestamp 09:46:23 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:46:24 INFO - PROCESS | 1643 | Number of tests: 6 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:6112.82 Median:6342.75 stddev:685.76 (10.8%) stddev-sans-first:659.06 09:46:24 INFO - PROCESS | 1643 | Values: 3605.7 3624.4 3631.6 3631.6 3683.7 5197.5 6266.7 6302.6 6304.0 6314.3 6273.9 6307.3 6334.1 6344.0 6344.0 5494.0 5527.5 5553.0 5570.1 5669.5 5465.4 5480.1 5525.8 5653.3 5676.1 5556.0 5556.0 5588.4 5590.8 5607.3 5601.6 5625.3 5633.4 5636.3 5641.9 4592.9 5411.7 5434.5 5578.6 5583.0 6024.8 6087.5 6102.5 6140.9 6202.2 6506.4 6548.5 6551.2 6564.1 6564.4 6579.8 6621.4 6632.5 6657.2 6658.4 6108.8 6156.4 6229.6 6272.7 6363.4 6596.6 6596.7 6603.4 6706.3 6761.6 6426.2 6473.5 6484.9 6488.1 6494.7 6623.8 6627.5 6639.2 6643.9 6649.1 6325.4 6339.0 6510.5 6534.0 6548.4 6428.6 6471.6 6577.1 6580.4 6590.8 5272.3 5306.3 5310.9 5325.4 5355.8 4684.2 4735.0 4744.5 4748.8 4802.5 6188.9 6213.0 6256.5 6280.6 6308.4 6643.5 6669.3 6671.4 6677.7 6684.4 6293.5 6297.4 6300.8 6313.7 6315.5 4887.8 5656.1 5698.6 5715.6 5753.1 6906.8 6927.2 6928.0 6931.8 6933.3 6597.8 6620.8 6622.5 6632.6 6635.7 6545.0 6815.6 6896.7 6933.3 6935.9 6397.4 6410.6 6416.7 6432.0 6435.2 5384.4 5453.2 6439.3 6573.2 6671.2 6441.5 6464.6 6485.6 6494.3 6498.4 6475.7 6493.9 6496.9 6497.5 6499.6 6225.9 6318.7 6375.2 6384.4 6386.6 6343.2 6370.6 6430.7 6462.6 6484.3 6325.9 6335.7 6339.6 6341.1 6342.7 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:56450.31 Median:30544.69 stddev:113189.41 (370.6%) stddev-sans-first:113429.88 09:46:24 INFO - PROCESS | 1643 | Values: 823.5 842.7 910.0 924.9 930.1 41505.6 42325.0 43724.3 44069.0 44076.8 43345.6 43511.0 43537.6 43632.6 43658.6 7969.8 9594.7 9771.0 9778.8 9786.8 7949.4 11641.8 11826.1 12077.5 12152.2 11813.0 11895.3 11916.8 11926.6 11926.9 11826.7 11857.2 11859.2 11895.5 11903.6 11529.1 11570.0 11581.5 11607.0 11612.8 41245.6 41423.8 41459.6 41594.0 41605.4 4030.5 4074.7 4081.7 4097.0 4105.0 28297.0 29039.7 30555.7 30833.2 31109.4 3176.2 3217.7 3334.4 3409.2 3411.5 365503.0 365550.0 365935.0 366070.0 366256.0 44552.1 45310.3 54231.7 54999.5 55078.7 47737.0 56723.7 56803.4 59754.0 60110.7 15449.8 15510.8 15526.3 15531.5 15536.8 11057.3 13075.7 13141.6 13144.7 13146.7 11418.4 12257.5 12887.4 12893.5 13101.7 5753.7 5788.5 5788.5 5819.7 5823.3 944.4 1072.2 1137.3 1144.5 1176.4 24878.0 27315.8 28002.0 28792.9 28818.4 1192.7 1199.5 1202.4 1219.0 1221.4 14439.8 14547.7 14757.1 14834.7 14853.9 423590.0 423915.0 424887.9 426792.0 431080.0 59585.7 59622.4 59689.1 59692.1 59702.0 1398.1 1434.8 1442.7 1533.2 1536.2 27381.0 27872.3 28191.7 28488.7 28551.0 38909.4 47632.8 47696.8 47873.3 48025.0 479130.0 480309.0 480928.0 481295.0 481501.0 21004.8 24326.5 25766.6 26465.3 30533.7 32384.5 38398.0 38698.2 38918.6 40314.8 26753.6 28554.6 28755.0 28934.0 29574.4 27463.0 28302.6 28714.4 29278.6 29540.9 27721.7 32868.5 33099.8 33282.0 33284.8 28471.0 28658.4 28797.9 29064.7 29069.0 21009.4 21791.7 23072.3 23505.2 23785.8 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:43121.74 Median:21540.30 stddev:127663.07 (592.7%) stddev-sans-first:127980.83 09:46:24 INFO - PROCESS | 1643 | Values: 369.2 427.1 435.2 440.6 464.2 21090.8 21431.2 22088.7 22229.3 22268.9 21992.9 23498.2 23560.3 23586.0 23870.8 6765.9 7141.8 7142.9 7151.4 7601.0 7876.7 8016.7 8020.4 8026.2 8037.3 7785.2 7974.8 8065.3 8068.6 8069.8 7734.5 7933.9 7954.9 8008.9 8027.4 6511.3 7414.0 7758.9 7773.2 7791.8 26635.5 26770.5 27393.0 27394.6 27792.4 2962.6 2997.9 3002.8 3006.1 3093.6 15344.3 15481.3 15535.9 15975.5 16273.6 1870.5 2048.9 2109.6 2116.3 2137.1 129175.7 134703.3 136653.6 137361.3 137994.3 15300.6 18067.5 19065.0 19444.7 19529.6 29584.9 29675.3 29833.6 29866.9 29909.4 10474.8 10491.1 10538.2 10640.6 10787.6 9046.1 9087.2 9094.3 9260.6 9288.0 8858.0 9143.8 9236.0 9236.3 9248.8 3420.6 4095.2 4117.7 4121.2 4121.9 1050.0 1079.6 1098.4 1120.5 1155.9 21438.6 21642.0 21768.9 21784.3 21854.8 1092.8 1094.6 1096.2 1124.5 1125.5 9606.2 10367.1 11482.7 11508.6 11512.3 750734.0 763488.0 766215.0 775125.0 793701.0 102719.0 141056.0 157504.0 159579.0 161710.0 1215.3 1224.7 1272.3 1272.7 1273.6 19325.1 19573.6 19964.3 20218.6 20249.5 61580.7 62630.0 62901.4 63682.7 63824.7 89028.0 89652.0 89714.0 91236.2 91716.1 10851.9 11591.6 11636.7 11782.3 11878.9 12141.0 12946.7 13080.7 13104.7 13465.9 11250.4 11259.9 11498.1 11665.4 11786.8 10511.7 10675.7 10870.2 11068.4 11167.8 11462.4 11541.0 11690.9 11897.2 11941.5 9671.4 11399.7 11491.1 11578.7 11596.2 10011.2 10608.2 11429.0 11497.0 11651.8 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6720.72 Median:2369.37 stddev:4320.63 (182.4%) stddev-sans-first:4315.34 09:46:24 INFO - PROCESS | 1643 | Values: 1559.6 1576.0 1596.8 1614.0 1626.0 10930.5 11582.6 11610.4 11615.2 11638.2 10761.8 11124.8 11314.4 11319.7 11324.3 2336.9 2364.7 2371.1 2374.8 2376.8 2279.9 2352.2 2362.8 2372.1 2382.3 2268.9 2333.8 2335.2 2358.8 2367.6 2293.8 2304.3 2316.1 2318.1 2334.9 2328.9 2334.5 2336.2 2340.0 2343.3 11437.3 11521.3 11543.9 11546.2 11566.5 5349.8 5509.0 5570.7 5621.0 5644.8 3144.8 3241.3 3297.1 3330.3 3378.3 1115.6 1158.5 1163.1 1174.8 1196.8 14176.9 14419.1 14850.9 15638.1 15665.2 4459.0 4633.7 4662.7 4696.2 4709.2 12506.1 12592.4 12642.6 12664.0 12708.8 4947.9 5122.6 5158.5 5542.8 5564.4 4402.2 5145.5 5173.4 5191.0 5214.7 3305.5 3702.1 3776.9 3831.2 3909.2 1020.0 1023.5 1033.2 1035.0 1050.1 750.5 838.5 912.3 916.5 922.5 6363.6 6391.3 6445.9 6575.0 6644.4 882.5 893.9 987.2 1024.4 1049.2 3711.9 3907.6 4013.4 4188.1 4209.2 13015.8 13896.5 14061.6 14064.1 14075.7 13603.7 13719.2 13954.1 13972.2 13976.0 10136.7 10393.7 10436.4 10575.9 10627.6 5924.1 6365.4 6405.8 6464.6 6494.0 11648.0 12215.8 12484.7 12591.7 12648.0 9558.6 11522.9 12874.4 13059.7 13066.9 8437.2 8689.0 8751.9 8756.0 8786.3 10395.8 10562.0 10573.7 10578.5 10608.3 8754.0 8944.9 9016.1 9029.8 9033.6 8786.0 8811.3 8824.1 8826.4 8906.6 6684.7 7801.5 7803.8 7881.4 7883.4 5568.2 5703.3 5810.9 5849.5 5955.7 5575.5 5706.8 5810.2 5845.2 5948.8 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4598.79 Median:4934.17 stddev:1291.08 (26.2%) stddev-sans-first:1254.51 09:46:24 INFO - PROCESS | 1643 | Values: 340.3 382.2 383.8 388.7 388.7 4549.0 5210.6 5244.4 5266.3 5283.3 4374.6 4568.4 5124.3 5200.7 5267.7 3913.0 4008.7 4326.8 4344.5 4411.2 3826.4 3991.0 4188.6 4230.7 4279.3 4025.0 4051.7 4144.8 4178.0 4384.5 4206.9 4313.0 4345.6 4366.8 4441.6 3931.7 3947.5 3950.3 3986.9 4046.3 4215.3 4218.6 4738.3 5010.3 5080.7 4353.4 4704.9 4900.2 4954.5 5041.5 4581.0 4810.8 5061.5 5524.5 5549.6 4012.8 4200.8 4431.2 4442.1 4482.8 5284.7 5434.4 5757.6 5838.8 6023.3 4048.2 4085.0 4321.0 4408.5 4549.7 4236.3 4446.4 4594.4 4691.5 4722.9 4130.9 4351.9 4643.6 5018.7 5059.4 5305.9 5507.0 5507.2 5526.5 5602.3 1532.3 1535.8 1556.8 1566.5 1591.4 1051.6 1081.7 1111.8 1168.9 1190.3 4958.0 5174.4 5249.7 5317.2 5320.7 5225.1 5259.3 5737.4 5742.5 5758.9 4853.0 4993.7 5120.9 5191.4 5255.6 2662.6 2735.9 3013.5 3031.5 3061.3 5718.9 6018.0 6029.7 6084.3 6107.9 5274.2 5758.3 5804.2 5846.4 5863.0 4602.9 4917.4 4951.0 5771.7 5781.9 4518.6 5588.9 5840.5 5870.2 5878.0 5008.9 5293.5 5416.0 5772.9 5788.8 5036.6 5961.7 5985.8 6045.8 6073.0 3828.5 4281.9 4294.0 4519.4 4551.8 5365.7 5694.4 5759.2 5802.7 5869.6 4774.7 5174.8 5291.9 5542.4 5678.9 4758.8 5086.2 5270.2 5291.9 5669.3 4785.9 5060.7 5253.4 5443.5 5493.6 4592.9 5082.5 5549.7 5554.5 5625.4 4308.8 4557.9 4742.8 4798.4 5542.7 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20333.13 Median:17097.36 stddev:28720.19 (168.0%) stddev-sans-first:28763.15 09:46:24 INFO - PROCESS | 1643 | Values: 759.5 788.0 805.3 896.7 908.6 16185.8 16649.2 17080.1 17706.6 17709.4 15672.2 15940.8 16063.6 16305.0 16324.9 1006.6 1034.7 1034.8 1036.0 1038.1 1009.9 1022.5 1022.6 1026.4 1038.3 1075.4 1077.3 1077.9 1079.0 1080.7 901.8 1072.3 1072.4 1074.0 1074.2 791.1 791.2 791.3 792.6 794.7 17114.7 17147.1 17239.6 17726.4 18040.6 14726.6 14738.6 14833.3 14901.6 14904.0 12044.2 12060.2 12257.8 12343.6 12532.5 3792.0 3905.1 3934.4 3943.3 4074.6 117815.0 119535.8 121033.0 123464.0 125662.4 10973.7 11122.6 11490.9 11593.5 11802.3 21526.4 22143.3 23855.8 23868.4 23913.9 8193.3 8291.9 8301.4 8331.0 8494.2 4576.8 4595.0 4647.7 4660.8 4699.0 3134.6 3225.7 3243.8 3278.3 3279.0 645.2 645.3 659.2 660.0 667.6 584.5 596.0 599.9 611.1 621.2 10576.3 10663.7 10734.7 11194.8 11472.9 588.9 639.8 645.6 676.5 739.4 4696.7 5122.1 5131.9 5152.8 5168.8 91756.0 97576.4 97813.3 99588.8 101180.5 42985.6 57036.7 69209.0 85669.0 88285.0 68151.0 68840.7 70934.0 72286.6 72339.6 10698.8 10976.9 11262.4 11347.8 11364.7 39017.4 49871.8 50157.7 51344.7 51413.2 64390.7 64646.0 65137.0 65238.3 65286.3 12706.0 12777.2 12876.2 13374.9 13949.7 15626.3 15634.8 15688.1 16031.9 16042.2 13820.0 14401.4 14662.1 14683.5 15087.5 13993.7 14663.1 14721.6 14928.2 15063.2 13302.9 13911.7 14213.6 14232.4 14293.4 9642.4 9807.9 10040.9 10068.5 10342.7 10350.8 11035.9 11536.5 11746.2 11779.3 09:46:24 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:46:24 INFO - PROCESS | 1643 | 09:46:24 INFO - TEST-INFO | 1643: exit 0 09:46:24 INFO - TEST-OK | dromaeo_css | took 1119053ms 09:46:24 INFO - TEST-START | kraken 09:46:24 INFO - operating with platform_type : mac_ 09:46:24 INFO - Initialising browser for kraken test... 09:46:24 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpDkFPEI/profile http://localhost:49219/getInfo.html) 09:46:26 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:46:26 INFO - PROCESS | 1649 | colorDepth:24 09:46:26 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:46:26 INFO - PROCESS | 1649 | __metrics 09:46:26 INFO - PROCESS | 1649 | 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" 09:46:26 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:46:27 INFO - TEST-INFO | 1649: exit 0 09:46:27 INFO - Browser initialized. 09:46:27 INFO - Running cycle 1/1 for kraken test... 09:46:27 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpDkFPEI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:39 INFO - PROCESS | 1651 | RSS: Main: 165109760 09:46:39 INFO - PROCESS | 1651 | 09:46:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:43 INFO - PROCESS | 1651 | RSS: Main: 215293952 09:46:43 INFO - PROCESS | 1651 | 09:46:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 09:46:46 INFO - PROCESS | 1651 | RSS: Main: 231047168 09:46:46 INFO - PROCESS | 1651 | 09:46:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 09:46:50 INFO - PROCESS | 1651 | RSS: Main: 327106560 09:46:50 INFO - PROCESS | 1651 | 09:46:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 09:46:52 INFO - PROCESS | 1651 | RSS: Main: 229838848 09:46:52 INFO - PROCESS | 1651 | 09:46:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:55 INFO - PROCESS | 1651 | RSS: Main: 231518208 09:46:55 INFO - PROCESS | 1651 | 09:46:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:59 INFO - PROCESS | 1651 | RSS: Main: 271495168 09:46:59 INFO - PROCESS | 1651 | 09:47:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:02 INFO - PROCESS | 1651 | RSS: Main: 272519168 09:47:02 INFO - PROCESS | 1651 | 09:47:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 09:47:06 INFO - PROCESS | 1651 | RSS: Main: 271781888 09:47:06 INFO - PROCESS | 1651 | 09:47:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:08 INFO - PROCESS | 1651 | RSS: Main: 247308288 09:47:08 INFO - PROCESS | 1651 | 09:47:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:10 INFO - PROCESS | 1651 | RSS: Main: 250990592 09:47:10 INFO - PROCESS | 1651 | 09:47:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:13 INFO - PROCESS | 1651 | RSS: Main: 256270336 09:47:13 INFO - PROCESS | 1651 | 09:47:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:17 INFO - PROCESS | 1651 | RSS: Main: 260861952 09:47:17 INFO - PROCESS | 1651 | 09:47:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:20 INFO - PROCESS | 1651 | RSS: Main: 262025216 09:47:20 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 09:47:22 INFO - PROCESS | 1651 | RSS: Main: 306511872 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | __start_tp_report 09:47:22 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 09:47:22 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 09:47:22 INFO - PROCESS | 1651 | |i|pagename|runs| 09:47:22 INFO - PROCESS | 1651 | |0;ai-astar;105;116;116;117;115;117;116;114;114;123 09:47:22 INFO - PROCESS | 1651 | |1;audio-beat-detection;166;136;137;142;110;108;113;94;140;140 09:47:22 INFO - PROCESS | 1651 | |2;audio-dft;185;182;182;185;181;182;184;182;187;183 09:47:22 INFO - PROCESS | 1651 | |3;audio-fft;81;60;61;107;51;61;71;53;74;74 09:47:22 INFO - PROCESS | 1651 | |4;audio-oscillator;92;69;85;68;69;68;82;68;68;83 09:47:22 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;87;86;91;91;95;86;89;87;90;88 09:47:22 INFO - PROCESS | 1651 | |6;imaging-darkroom;94;93;94;93;94;93;94;93;94;95 09:47:22 INFO - PROCESS | 1651 | |7;imaging-desaturate;83;82;83;85;85;85;85;85;83;83 09:47:22 INFO - PROCESS | 1651 | |8;json-parse-financial;63;61;61;61;62;62;63;62;62;62 09:47:22 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;54;68;88;91;86;56;58;59;59;58 09:47:22 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;84;84;84;84;85;81;86;88;116;83 09:47:22 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;136;135;135;132;134;133;137;139;152;135 09:47:22 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;165;164;163;165;165;163;161;163;160;163 09:47:22 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;65;65;65;64;62;63;63;63;61;62 09:47:22 INFO - PROCESS | 1651 | __end_tp_report 09:47:22 INFO - PROCESS | 1651 | __start_cc_report 09:47:22 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2089 09:47:22 INFO - PROCESS | 1651 | __end_cc_report 09:47:22 INFO - PROCESS | 1651 | __startTimestamp1496422042598__endTimestamp 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | ------- Summary: start ------- 09:47:22 INFO - PROCESS | 1651 | Number of tests: 14 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.00 stddev:4.42 (3.8%) stddev-sans-first:2.70 09:47:22 INFO - PROCESS | 1651 | Values: 105.0 116.0 116.0 117.0 115.0 117.0 116.0 114.0 114.0 123.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:140.00 stddev:21.54 (15.4%) stddev-sans-first:18.10 09:47:22 INFO - PROCESS | 1651 | Values: 166.0 136.0 137.0 142.0 110.0 108.0 113.0 94.0 140.0 140.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.89 (1.0%) stddev-sans-first:1.90 09:47:22 INFO - PROCESS | 1651 | Values: 185.0 182.0 182.0 185.0 181.0 182.0 184.0 182.0 187.0 183.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:69.30 Median:66.00 stddev:16.43 (24.9%) stddev-sans-first:16.87 09:47:22 INFO - PROCESS | 1651 | Values: 81.0 60.0 61.0 107.0 51.0 61.0 71.0 53.0 74.0 74.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:75.50 stddev:9.25 (12.2%) stddev-sans-first:7.55 09:47:22 INFO - PROCESS | 1651 | Values: 92.0 69.0 85.0 68.0 69.0 68.0 82.0 68.0 68.0 83.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.50 stddev:2.83 (3.2%) stddev-sans-first:2.91 09:47:22 INFO - PROCESS | 1651 | Values: 87.0 86.0 91.0 91.0 95.0 86.0 89.0 87.0 90.0 88.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:47:22 INFO - PROCESS | 1651 | Values: 94.0 93.0 94.0 93.0 94.0 93.0 94.0 93.0 94.0 95.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:85.00 stddev:1.20 (1.4%) stddev-sans-first:1.22 09:47:22 INFO - PROCESS | 1651 | Values: 83.0 82.0 83.0 85.0 85.0 85.0 85.0 85.0 83.0 83.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 09:47:22 INFO - PROCESS | 1651 | Values: 63.0 61.0 61.0 61.0 62.0 62.0 63.0 62.0 62.0 62.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:63.50 stddev:14.74 (23.2%) stddev-sans-first:14.77 09:47:22 INFO - PROCESS | 1651 | Values: 54.0 68.0 88.0 91.0 86.0 56.0 58.0 59.0 59.0 58.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:84.00 stddev:10.18 (12.1%) stddev-sans-first:10.72 09:47:22 INFO - PROCESS | 1651 | Values: 84.0 84.0 84.0 84.0 85.0 81.0 86.0 88.0 116.0 83.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:135.50 stddev:5.69 (4.2%) stddev-sans-first:6.03 09:47:22 INFO - PROCESS | 1651 | Values: 136.0 135.0 135.0 132.0 134.0 133.0 137.0 139.0 152.0 135.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:163.50 stddev:1.69 (1.0%) stddev-sans-first:1.66 09:47:22 INFO - PROCESS | 1651 | Values: 165.0 164.0 163.0 165.0 165.0 163.0 161.0 163.0 160.0 163.0 09:47:22 INFO - PROCESS | 1651 | 09:47:22 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.42 (2.2%) stddev-sans-first:1.36 09:47:22 INFO - PROCESS | 1651 | Values: 65.0 65.0 65.0 64.0 62.0 63.0 63.0 63.0 61.0 62.0 09:47:22 INFO - PROCESS | 1651 | -------- Summary: end -------- 09:47:22 INFO - PROCESS | 1651 | 09:47:23 INFO - TEST-INFO | 1651: exit 0 09:47:23 INFO - TEST-OK | kraken | took 58835ms 09:47:23 INFO - SUITE-END | took 1177s 09:47:23 INFO - Completed test suite (00:19:38) 09:47:23 INFO - javascript benchmark 09:47:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7841.534106314087, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3605.7475976681635, 3624.3657359962, 3631.564212294525, 3631.6005239423657, 3683.650053244941, 5197.480251974801, 6266.655333956631, 6302.590331628441, 6303.99136138218, 6314.305426403098, 6273.937260627404, 6307.274663413708, 6334.113224148623, 6344.0, 6344.016677414997, 5494.011078005968, 5527.53015993641, 5553.028220061473, 5570.108782594785, 5669.518090962252, 5465.426130256305, 5480.068388373966, 5525.834224973257, 5653.293338332708, 5676.063449530843, 5555.972220138894, 5556.0, 5588.385277619459, 5590.776368945238, 5607.327120745516, 5601.60788744788, 5625.296837895263, 5633.40849210831, 5636.267285252911, 5641.943580564224, 4592.862214133581, 5411.702356370398, 5434.510894019518, 5578.553715702734, 5582.972085139589, 6024.78913238039, 6087.512998960073, 6102.51954821894, 6140.946974996481, 6202.224721909762, 6506.446952009085, 6548.541602087856, 6551.246606640249, 6564.146660934096, 6564.376384243489, 6579.80260592183, 6621.404073633348, 6632.535722499427, 6657.201135863679, 6658.434033107192, 6108.80532842904, 6156.445919867234, 6229.595076320024, 6272.686365681743, 6363.427291543737, 6596.60420374779, 6596.703148358335, 6603.405693487513, 6706.26231114577, 6761.628110453972, 6426.164598602152, 6473.546851720334, 6484.870302593922, 6488.124103246096, 6494.707738151795, 6623.801285961429, 6627.469802415795, 6639.170103737033, 6643.867122657616, 6649.069130321759, 6325.367463253638, 6339.0, 6510.479161667055, 6534.0, 6548.377904099103, 6428.614283142932, 6471.644059576768, 6577.14497115372, 6580.440662543738, 6590.7693230736695, 5272.2882410874045, 5306.28365170697, 5310.92033619496, 5325.440828713007, 5355.839324820184, 4684.180268453007, 4734.976325118352, 4744.501827308152, 4748.833790817304, 4802.51974802517, 6188.8762224755255, 6212.968935155295, 6256.468200202943, 6280.5603607747935, 6308.400701933309, 6643.50173736962, 6669.266380698119, 6671.432928201158, 6677.732890684319, 6684.398404143553, 6293.527985401113, 6297.4332310091395, 6300.779472718433, 6313.684315784192, 6315.526335524854, 4887.828925987574, 5656.123300888369, 5698.629589076697, 5715.599908006483, 5753.079507278815, 6906.792796216121, 6927.203371612329, 6928.0, 6931.792046238621, 6933.306669333027, 6597.769078082436, 6620.768273110418, 6622.503312251504, 6632.568883022588, 6635.734570617121, 6545.0, 6815.591064536144, 6896.724131034703, 6933.272006439453, 6935.89596156048, 6397.360263973565, 6410.551261411656, 6416.743330266737, 6431.967840160769, 6435.163428754419, 5384.353877534721, 5453.15476101205, 6439.259485159079, 6573.211214654272, 6671.166104236971, 6441.5490915635455, 6464.612123272619, 6485.643289619116, 6494.318096599978, 6498.3826536478955, 6475.708593113415, 6493.8701225976165, 6496.935030649633, 6497.480201583957, 6499.6425196612745, 6225.875482490415, 6318.652474113968, 6375.1712277405495, 6384.425401713868, 6386.552941294251, 6343.238811342484, 6370.55406121567, 6430.742770289136, 6462.644554549545, 6484.254310754323, 6325.936740632535, 6335.65153916539, 6339.619622822645, 6341.0805433212845, 6342.746290148342], "value": 6070.000597242194, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.464747913856, 842.7092653034703, 910.0353625157329, 924.8520236762115, 930.1163894300416, 41505.58494415055, 42325.0, 43724.344134838, 44069.0, 44076.77961610188, 43345.56654433455, 43511.0, 43537.56462435375, 43632.56367436325, 43658.563414365846, 7969.800754981114, 9594.664186753465, 9770.951145244264, 9778.804423911517, 9786.804263914717, 7949.364050875916, 11641.767164656701, 11826.053915686725, 12077.456514456826, 12152.210106343104, 11813.0, 11895.345755983417, 11916.7616647667, 11926.582569610066, 11926.940365298162, 11826.704332391717, 11857.229280096766, 11859.169858109935, 11895.464704088337, 11903.58337458189, 11529.077673786162, 11570.0, 11581.478833452515, 11607.0, 11612.767744645143, 41245.58754412447, 41423.75728728143, 41459.58540414618, 41594.0, 41605.375919361235, 4030.4558884550797, 4074.653654439376, 4081.7142800004012, 4097.0, 4105.014796448831, 28297.0, 29039.709602903913, 30555.69444305551, 30833.229169270722, 31109.37781244385, 3176.1583180457196, 3217.6621454747146, 3334.416477116495, 3409.232922592422, 3411.4541673332396, 365502.9999999973, 365550.0, 365935.0, 366070.0, 366256.0, 44552.10895782066, 45310.32034519485, 54231.72884135633, 54999.450005499435, 55078.72460637671, 47737.0, 56723.71638141783, 56803.43196568064, 59754.0, 60110.69944650249, 15449.768253476206, 15510.767338489932, 15526.301316440788, 15531.5340539784, 15536.84463155354, 11057.336559806437, 13075.738485230433, 13141.605751827461, 13144.671383215496, 13146.671333216746, 11418.371989540488, 12257.509699611915, 12887.355632218352, 12893.48426062947, 13101.672458188621, 5753.712314384347, 5788.450097240755, 5788.453080644503, 5819.679917604488, 5823.272090988627, 944.3767113704922, 1072.2333531524996, 1137.2664631312757, 1144.547903578082, 1176.4411904345368, 24878.0, 27315.844449220305, 28002.019929302376, 28792.856035719687, 28818.423631527254, 1192.7316353820322, 1199.5022065842577, 1202.3867827408085, 1218.9512419503121, 1221.3648902570615, 14439.783403248959, 14547.709045819025, 14757.114573125433, 14834.703305933821, 14853.885958552935, 423590.0, 423915.0, 424887.8755606202, 426792.0, 431080.0, 59585.70207149023, 59622.40377596255, 59689.10466343008, 59692.104618430756, 59702.0, 1398.1471302505463, 1434.8493408192408, 1442.7475191841595, 1533.1567637799167, 1536.2395614171057, 27381.0, 27872.303192420353, 28191.71808281891, 28488.715112848608, 28551.000714974874, 38909.41635875521, 47632.7618361906, 47696.7615161922, 47873.32443364466, 48025.0, 479130.0, 480309.0, 480928.0, 481295.0, 481501.0, 21004.789952100895, 24326.51346973086, 25766.613500797877, 26465.33836654099, 30533.694663053084, 32384.514232286063, 38398.0, 38698.22603547857, 38918.6108138915, 40314.798426007685, 26753.598696019188, 28554.57168142438, 28755.0, 28934.0, 29574.408511829213, 27463.0, 28302.575461367684, 28714.42571148607, 29278.560821587267, 29540.852295738383, 27721.722782771914, 32868.50697239591, 33099.84150554719, 33282.0, 33284.835030774775, 28471.0, 28658.426831462835, 28797.856010719814, 29064.692088856475, 29069.0, 21009.369718908456, 21791.673124902823, 23072.307830765105, 23505.17731879376, 23785.762142379048], "value": 19159.88458807576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.22647054420986, 427.1180013272592, 435.16014092800884, 440.5990548600773, 464.1529209193223, 21090.789092109095, 21431.249906253266, 22088.668669969964, 22229.333120006406, 22268.88865555674, 21992.8900355498, 23498.17756378527, 23560.29319120429, 23586.0, 23870.76129238707, 6765.898511522331, 7141.785746427589, 7142.928570714304, 7151.392131668815, 7601.0, 7876.684932602718, 8016.719414820483, 8020.398470114736, 8026.197380261986, 8037.316828069592, 7785.2214778521975, 7974.840503189933, 8065.2741253287195, 8068.636911339004, 8069.838603227932, 7734.497257678261, 7933.880991785128, 7954.920450795534, 8008.879866801974, 8027.394521095745, 6511.316311787242, 7413.96204531366, 7758.883616745753, 7773.2226777322385, 7791.8441631167625, 26635.467290654273, 26770.464590708078, 27393.0, 27394.5890811638, 27792.444151116866, 2962.60004899338, 2997.8800847966054, 3002.8198308101364, 3006.0831446409056, 3093.566900633913, 15344.309506072253, 15481.303341349667, 15535.92232038844, 15975.520734377988, 16273.593160170973, 1870.485616455472, 2048.8873111978837, 2109.556993031465, 2116.322776711453, 2137.1237792505153, 129175.7082429173, 134703.32648336794, 136653.6334636651, 137361.31319343438, 137994.3100284502, 15300.628402597615, 18067.470554122432, 19064.95142767161, 19444.70832937507, 19529.632925695067, 29584.852075739484, 29675.258118547208, 29833.552496712568, 29866.850665746533, 29909.40181196364, 10474.790504189874, 10491.055804977588, 10538.20963427746, 10640.627578034731, 10787.56849726018, 9046.095390461032, 9087.22758565516, 9094.272458203328, 9260.583273752698, 9288.0, 8858.0, 9143.77140571491, 9235.953820230856, 9236.307276954256, 9248.78029272983, 3420.640832712529, 4095.198112830345, 4117.732347397409, 4121.176993759284, 4121.90856857357, 1049.9580016799243, 1079.578964203961, 1098.4452851310036, 1120.4957769003818, 1155.92486488378, 21438.57122857534, 21642.02610882514, 21768.89115554444, 21784.34646960594, 21854.7979861106, 1092.847001419802, 1094.6059418609295, 1096.1942971915635, 1124.460259075632, 1125.5329038449115, 9606.183474404754, 10367.066963973279, 11482.655520334403, 11508.597199098158, 11512.330712046707, 750734.0, 763488.0, 766215.0, 775125.0, 793701.0, 102719.0, 141056.0, 157504.0, 159579.0, 161710.0, 1215.2647648172906, 1224.7244370016854, 1272.287518989369, 1272.7263638317806, 1273.6306471123455, 19325.13036913314, 19573.60852782965, 19964.3012494562, 20218.595628087063, 20249.493762656053, 61580.69209653923, 62630.0, 62901.37098629138, 63682.68158659177, 63824.68087659718, 89028.0, 89652.0, 89714.0, 91236.17527649277, 91716.08283917342, 10851.945740271249, 11591.594294199662, 11636.650900473, 11782.293062416282, 11878.8715072068, 12140.964758810298, 12946.676333091747, 13080.672983175496, 13104.672383190496, 13465.865341346462, 11250.381229032482, 11259.887401125883, 11498.08015358786, 11665.416729163677, 11786.76426471483, 10511.684649460223, 10675.733106672085, 10870.184736144663, 11068.391238481961, 11167.776644467227, 11462.4268786562, 11541.0, 11690.941545292219, 11897.226680265756, 11941.46263418131, 9671.371360861522, 11399.715007124889, 11491.080713543064, 11578.71053223676, 11596.188266821242, 10011.24915631316, 10608.151347892304, 11429.028532574825, 11497.0, 11651.766964660828], "value": 11879.825118425451, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1559.5711179425664, 1576.0464918724172, 1596.7684685720565, 1613.9677206455867, 1625.9756103658447, 10930.508127134279, 11582.59460918868, 11610.36143012134, 11615.245009074404, 11638.243514171594, 10761.784764304728, 11124.833127503074, 11314.434278286093, 11319.717007074827, 11324.32054076756, 2336.906523739053, 2364.668946347504, 2371.1345358944036, 2374.774396432345, 2376.8098552115875, 2279.920202792894, 2352.1649814315842, 2362.787349138577, 2372.074890792592, 2382.3210385040265, 2268.9432764180947, 2333.7549557296493, 2335.1593426366494, 2358.7523310052457, 2367.6093444581625, 2293.7706229377095, 2304.320225533472, 2316.131450705985, 2318.1422873536817, 2334.8832558372096, 2328.918487852934, 2334.4747431827873, 2336.240721765433, 2340.0288880114904, 2343.3321503371626, 11437.313761174357, 11521.308721476738, 11543.94228028863, 11546.249493782878, 11566.47950842214, 5349.812756553541, 5509.0, 5570.721463926788, 5621.0, 5644.8024319148635, 3144.7544702234886, 3241.2707140893344, 3297.1097803592897, 3330.333933213343, 3378.3074469733892, 1115.559932888234, 1158.490264283721, 1163.1215586827298, 1174.794410978084, 1196.7785959597502, 14176.929115354356, 14419.125513683153, 14850.925745371203, 15638.13990230537, 15665.216739162996, 4459.038023269751, 4633.698809577367, 4662.696924699883, 4696.178168820443, 4709.222978208557, 12506.061560458144, 12592.4333404997, 12642.620721378373, 12664.0, 12708.809367859489, 4947.851564453073, 5122.615803814729, 5158.535731784157, 5542.806001789999, 5564.360098588714, 4402.185595664812, 5145.511176438233, 5173.3533308336455, 5190.987757387274, 5214.687118772886, 3305.504174373863, 3702.129999450127, 3776.867809626705, 3831.214601006787, 3909.2181563687377, 1019.9745006374751, 1023.5138309303021, 1033.245730616654, 1034.9896501035043, 1050.1179009631946, 750.5009168902618, 838.5094719588986, 912.270183852915, 916.5279880861361, 922.4511415707643, 6363.618182908947, 6391.328910464427, 6445.9355406446275, 6575.013747937847, 6644.368784965421, 882.5013867165031, 893.9005023820737, 987.2151639446657, 1024.3700124423574, 1049.1587264351167, 3711.870084547028, 3907.5701672815967, 4013.3578926876066, 4188.141431006637, 4209.17921005401, 13015.804762928563, 13896.513622023384, 14061.578152655436, 14064.08583442073, 14075.718485630436, 13603.727925441633, 13719.176849389069, 13954.09298395601, 13972.231527265894, 13976.0, 10136.746581335525, 10393.688189354332, 10436.42599657011, 10575.947120264504, 10627.574897004188, 5924.11138329254, 6365.363463653597, 6405.807825765234, 6464.577792885558, 6493.967530162319, 11648.009919156966, 12215.75568488643, 12484.687882803002, 12591.748165036832, 12647.988160947287, 9558.61765529371, 11522.884771152516, 12874.420651070912, 13059.673508162372, 13066.869331306945, 8437.156284371515, 8688.956555217184, 8751.91248087511, 8756.0, 8786.341024423322, 10395.792084158124, 10561.996610322007, 10573.682789516326, 10578.471076446302, 10608.257421980386, 8754.0, 8944.910550894409, 9016.143466370684, 9029.81940361176, 9033.59348829291, 8785.956070219863, 8811.251043661361, 8824.117588241124, 8826.382153249468, 8906.599203036008, 6684.699188536625, 7801.4929029612895, 7803.804904877424, 7881.448298619041, 7883.369330453435, 5568.192612071146, 5703.344115426779, 5810.912836307374, 5849.502792262705, 5955.67243801595, 5575.526080283222, 5706.80026199081, 5810.244668193108, 5845.17147723145, 5948.821535353946], "value": 5065.269707068871, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.2718183088191, 382.18975771364694, 383.77740910272047, 388.6890487609911, 388.7424182768741, 4548.999999999995, 5210.635255532115, 5244.39689435715, 5266.262723218748, 5283.309341010878, 4374.562543745624, 4568.428946381702, 5124.308218390516, 5200.713960732158, 5267.736613169345, 3913.002184442977, 4008.6993475489453, 4326.84856030039, 4344.500382456026, 4411.228035093854, 3826.445165451008, 3990.9600903990877, 4188.623023927846, 4230.667339787972, 4279.33670281107, 4024.9597504024878, 4051.6961227907877, 4144.834206631731, 4178.0, 4384.517703052662, 4206.896551724138, 4313.0, 4345.631126195254, 4366.75547468973, 4441.578694817644, 3931.719602630496, 3947.4868267125375, 3950.2889479893606, 3986.880393588197, 4046.291898917678, 4215.283401821697, 4218.599233072853, 4738.312944623045, 5010.273510341017, 5080.669756465818, 4353.385411463827, 4704.929426058612, 4900.215965445512, 4954.491412814322, 5041.495850414965, 4580.977095114503, 4810.78351474181, 5061.519155680205, 5524.502794748492, 5549.556035517189, 4012.779297138656, 4200.831966721327, 4431.158079964828, 4442.089371678798, 4482.8344630396, 5284.656497327738, 5434.375046869582, 5757.625754325955, 5838.795642152504, 6023.307319658208, 4048.1903619276263, 4084.959150408458, 4321.049369138785, 4408.470983482002, 4549.72607669853, 4236.347824348507, 4446.399736035593, 4594.402727645384, 4691.5308469152815, 4722.881927951828, 4130.938035929464, 4351.912961740811, 4643.605293550018, 5018.723970181675, 5059.443461219263, 5305.893882122413, 5507.0, 5507.191332420414, 5526.530244929146, 5602.299712535933, 1532.2798284806124, 1535.7849901013867, 1556.813182418117, 1566.4908904606048, 1591.3793620488018, 1051.6371851711147, 1081.6809041332826, 1111.7832022755647, 1168.9298642081503, 1190.2501424102934, 4957.975210123926, 5174.379074511083, 5249.737513124329, 5317.245309048005, 5320.733963301819, 5225.085610018232, 5259.342582177228, 5737.3688894221605, 5742.48317651405, 5758.856028599319, 4852.975735121302, 4993.725345106054, 5120.8719782005, 5191.3647201131525, 5255.5532779713385, 2662.6272321874953, 2735.931601709973, 3013.4575776360025, 3031.4998025325576, 3061.280599059219, 5718.942810571924, 6018.0, 6029.728662210211, 6084.300305464928, 6107.938920610826, 5274.235235890851, 5758.27251824451, 5804.245448091721, 5846.4153584642045, 5863.003289440805, 4602.907941841145, 4917.385326834145, 4950.955162575063, 5771.6537007778825, 5781.855453613609, 4518.570735780096, 5588.888222235451, 5840.532757379315, 5870.207521984477, 5878.0, 5008.923081537487, 5293.47065293483, 5415.967058447282, 5772.913406298993, 5788.82633520995, 5036.571891389273, 5961.731722072431, 5985.790497332572, 6045.767962426231, 6072.969635151796, 3828.5497281740268, 4281.901868084678, 4294.028024077653, 4519.367288579667, 4551.771021824023, 5365.651232669864, 5694.430556944272, 5759.1649210865025, 5802.6808525531505, 5869.589128760946, 4774.66041639601, 5174.767135478837, 5291.930680205549, 5542.418046105101, 5678.927191575128, 4758.76206189696, 5086.237064440364, 5270.154116776897, 5291.894162116659, 5669.3480249770155, 4785.928211076767, 5060.721660308718, 5253.395859476056, 5443.510084092451, 5493.642913210629, 4592.862214133581, 5082.466341034138, 5549.669138961726, 5554.472325129106, 5625.353084395183, 4308.849190278325, 4557.883318586967, 4742.818303580391, 4798.384880240347, 5542.695151766691], "value": 4223.076221958736, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.4835511851938, 787.9566623835686, 805.3154818404354, 896.6996056321132, 908.5593487158729, 16185.757213641804, 16649.167541622915, 17080.06059666718, 17706.64586708265, 17709.380171693992, 15672.216389180552, 15940.760888586681, 16063.598410039725, 16305.0, 16324.918375408106, 1006.6476733143378, 1034.7361422837166, 1034.8499467577196, 1035.9899098379058, 1038.0501840815646, 1009.8790342719575, 1022.5194158745378, 1022.5858527296419, 1026.3533973596623, 1038.3250886923522, 1075.42464781342, 1077.3051381858697, 1077.9245452818307, 1078.9946050269737, 1080.692002779208, 901.792587704825, 1072.3244356055714, 1072.3565860483686, 1074.0119090436817, 1074.2211896375104, 791.1337085890998, 791.1850793682516, 791.2799352589117, 792.5561685456163, 794.6582969323189, 17114.65770684592, 17147.05691186984, 17239.56901077483, 17726.37957671488, 18040.639187216315, 14726.63183420423, 14738.55784326472, 14833.332500037524, 14901.627459313495, 14904.0, 12044.150903835469, 12060.216085954473, 12257.816132758016, 12343.567975120828, 12532.498700051987, 3791.9810400947817, 3905.1018265798743, 3934.449177115206, 3943.3493473576827, 4074.6129117734063, 117814.99999999914, 119535.80464195333, 121033.0, 123464.0, 125662.3716881419, 10973.670789876316, 11122.55509779616, 11490.94254528722, 11593.478293476814, 11802.291862488279, 21526.354209373745, 22143.335699929026, 23855.80720963945, 23868.40328991789, 23913.880430597736, 8193.262606365455, 8291.917080829115, 8301.418900676894, 8330.958345208235, 8494.193051660082, 4576.807514548987, 4594.977025114853, 4647.674662773641, 4660.813567457264, 4699.0132072265205, 3134.623845138598, 3225.6935591118804, 3243.821589812583, 3278.344331133783, 3279.049075768048, 645.2225068792094, 645.3320812958565, 659.2089492608889, 660.0429377402785, 667.5560752099897, 584.4914924016048, 595.9553033522503, 599.886021655884, 611.1443978430233, 621.1767516636896, 10576.312539684895, 10663.73340666474, 10734.73163170927, 11194.824543422823, 11472.94263528677, 588.9322727886263, 639.7696829141505, 645.5803727577113, 676.5027704637088, 739.443277833622, 4696.725979358767, 5122.078025955289, 5131.87170320745, 5152.819651312186, 5168.819091331786, 91756.0, 97576.42344729575, 97813.25790210304, 99588.77203450991, 101180.49409752904, 42985.570144298785, 57036.71481642565, 69209.0, 85669.0, 88285.0, 68151.0, 68840.65579672069, 70934.0, 72286.63856680683, 72339.6383018092, 10698.786024279627, 10976.888407431707, 11262.38056906878, 11347.829782553434, 11364.715882103013, 39017.414738778374, 49871.75064124656, 50157.74921125371, 51344.74327628488, 51413.20053798099, 64390.71218575509, 64646.0, 65137.0, 65238.34761652323, 65286.34713652803, 12706.0, 12777.23336599807, 12876.22742635445, 13374.933125334312, 13949.651258718208, 15626.296816643051, 15634.843651563793, 15688.058716477048, 16031.919840400724, 16042.197890105681, 13820.0, 14401.42394304216, 14662.120272783666, 14683.486077987647, 15087.4719384821, 13993.650158746113, 14663.120212787268, 14721.55835324942, 14928.225874337322, 15063.246837658293, 13302.93348533251, 13911.652208694864, 14213.573592792232, 14232.35954382076, 14293.428262869786, 9642.38832093562, 9807.9019209807, 10040.899591003996, 10068.496575171066, 10342.741431464274, 10350.844737328796, 11035.889641103486, 11536.480858361227, 11746.236494627821, 11779.29324240514], "value": 7865.7619803367315, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1418.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 116.0, 117.0, 115.0, 117.0, 116.0, 114.0, 114.0, 123.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 136.0, 137.0, 142.0, 110.0, 108.0, 113.0, 94.0, 140.0, 140.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 182.0, 185.0, 181.0, 182.0, 184.0, 182.0, 187.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 107.0, 51.0, 61.0, 71.0, 53.0, 74.0, 74.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 85.0, 68.0, 69.0, 68.0, 82.0, 68.0, 68.0, 83.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 91.0, 91.0, 95.0, 86.0, 89.0, 87.0, 90.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 95.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 85.0, 85.0, 85.0, 85.0, 85.0, 83.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 61.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 68.0, 88.0, 91.0, 86.0, 56.0, 58.0, 59.0, 59.0, 58.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 85.0, 81.0, 86.0, 88.0, 116.0, 83.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 135.0, 132.0, 134.0, 133.0, 137.0, 139.0, 152.0, 135.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 163.0, 165.0, 165.0, 163.0, 161.0, 163.0, 160.0, 163.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 62.0, 63.0, 63.0, 63.0, 61.0, 62.0], "value": 63.3, "unit": "score"}]}]} 09:47:23 INFO - Return code: 0 09:47:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:23 INFO - # TBPL SUCCESS # 09:47:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:23 INFO - 2017-06-02 09:47:23.937 screenresolution[1656:9925] starting screenresolution argv=screenresolution get 09:47:23 INFO - 2017-06-02 09:47:23.939 screenresolution[1656:9925] Display 0: 1600x1200x32@60 09:47:23 INFO - 2017-06-02 09:47:23.945 screenresolution[1657:9928] starting screenresolution argv=screenresolution list 09:47:23 INFO - Available Modes on Display 0 09:47:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:24 INFO - Intel Iris: 09:47:24 INFO - Chipset Model: Intel Iris 09:47:24 INFO - Type: GPU 09:47:24 INFO - Bus: Built-In 09:47:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:24 INFO - Vendor: Intel (0x8086) 09:47:24 INFO - Device ID: 0x0a2e 09:47:24 INFO - Revision ID: 0x0009 09:47:24 INFO - Displays: 09:47:24 INFO - Display: 09:47:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:24 INFO - Display Serial Number: 1600x1200 60 09:47:24 INFO - Main Display: Yes 09:47:24 INFO - Mirror: Off 09:47:24 INFO - Online: Yes 09:47:24 INFO - Rotation: Supported 09:47:24 INFO - Return code: 0 09:47:24 INFO - Running post-action listener: _resource_record_post_action 09:47:24 INFO - [mozharness: 2017-06-02 16:47:24.180268Z] Finished run-tests step (success) 09:47:24 INFO - Running post-run listener: _resource_record_post_run 09:47:24 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 33340416; Write bytes: 426030592; Read time: 200; Write time: 1411 09:47:24 INFO - TinderboxPrint: CPU usage
53.8% 09:47:24 INFO - TinderboxPrint: I/O read bytes / time
33,340,416 / 200 09:47:24 INFO - TinderboxPrint: I/O write bytes / time
426,030,592 / 1,411 09:47:24 INFO - TinderboxPrint: CPU idle
2,203.9 (46.2%) 09:47:24 INFO - TinderboxPrint: CPU system
951.4 (19.9%) 09:47:24 INFO - TinderboxPrint: CPU user
1,618.5 (33.9%) 09:47:24 INFO - TinderboxPrint: Swap in / out
166,215,680 / 0 09:47:24 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123288064; Write bytes: 122990592; Read time: 11155; Write time: 277 09:47:24 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29105152; Write bytes: 301598208; Read time: 175; Write time: 1129 09:47:24 INFO - Running post-run listener: _upload_blobber_files 09:47:24 INFO - Blob upload gear active. 09:47:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:24 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 09:47:24 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'] 09:47:24 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 09:47:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:47:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:47:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:26 INFO - (blobuploader) - INFO - Done attempting. 09:47:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:47:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:27 INFO - (blobuploader) - INFO - Done attempting. 09:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:47:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:28 INFO - (blobuploader) - INFO - Done attempting. 09:47:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:30 INFO - (blobuploader) - INFO - Done attempting. 09:47:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:30 INFO - Return code: 0 09:47:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/50571a45d42325dbe5c11f9e2bf6cbc482d0ceb04fa6af0f21208704ca3ac64169032705003f4ee5cdc3fa8f0f727e8ff6fae9bb0e7fbfa2294e42571bff4372", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/999ed71b1e27b36cea2e392173a3e3032b2f218e46de87c6d6c2994e86e247f6beba9e91257ae49602ee3538fe02b4482a549f4aa6b37307f97e26f8bb25a57b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9eaa5091a604744e5fbc49350f26e654fead575ba53a02119d4b5b704bc68689f2762369d292f33dcd0c08879f8bb6f9a68cd70448a0830e142b23de63f1fb19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4e768cfd9bd02751eaf37c4e38c1b59ac1bd8e50aaa193643def852e0ab479fe25d3a570224743af3d988582f1a579a718bccd72f007a2be1d9524b573efc64"} 09:47:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:30 INFO - Contents: 09:47:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/50571a45d42325dbe5c11f9e2bf6cbc482d0ceb04fa6af0f21208704ca3ac64169032705003f4ee5cdc3fa8f0f727e8ff6fae9bb0e7fbfa2294e42571bff4372", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/999ed71b1e27b36cea2e392173a3e3032b2f218e46de87c6d6c2994e86e247f6beba9e91257ae49602ee3538fe02b4482a549f4aa6b37307f97e26f8bb25a57b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9eaa5091a604744e5fbc49350f26e654fead575ba53a02119d4b5b704bc68689f2762369d292f33dcd0c08879f8bb6f9a68cd70448a0830e142b23de63f1fb19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4e768cfd9bd02751eaf37c4e38c1b59ac1bd8e50aaa193643def852e0ab479fe25d3a570224743af3d988582f1a579a718bccd72f007a2be1d9524b573efc64"} 09:47:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:30 INFO - Copying logs to upload dir... 09:47:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.966865 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-06-02 09:47:30.311236) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:30.315362) ========= 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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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/50571a45d42325dbe5c11f9e2bf6cbc482d0ceb04fa6af0f21208704ca3ac64169032705003f4ee5cdc3fa8f0f727e8ff6fae9bb0e7fbfa2294e42571bff4372", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/999ed71b1e27b36cea2e392173a3e3032b2f218e46de87c6d6c2994e86e247f6beba9e91257ae49602ee3538fe02b4482a549f4aa6b37307f97e26f8bb25a57b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9eaa5091a604744e5fbc49350f26e654fead575ba53a02119d4b5b704bc68689f2762369d292f33dcd0c08879f8bb6f9a68cd70448a0830e142b23de63f1fb19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4e768cfd9bd02751eaf37c4e38c1b59ac1bd8e50aaa193643def852e0ab479fe25d3a570224743af3d988582f1a579a718bccd72f007a2be1d9524b573efc64"} build_url:https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011389 build_url: 'https://queue.taskcluster.net/v1/task/AjxA2MtTSyGU3xXwI7kvBw/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/50571a45d42325dbe5c11f9e2bf6cbc482d0ceb04fa6af0f21208704ca3ac64169032705003f4ee5cdc3fa8f0f727e8ff6fae9bb0e7fbfa2294e42571bff4372", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/999ed71b1e27b36cea2e392173a3e3032b2f218e46de87c6d6c2994e86e247f6beba9e91257ae49602ee3538fe02b4482a549f4aa6b37307f97e26f8bb25a57b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9eaa5091a604744e5fbc49350f26e654fead575ba53a02119d4b5b704bc68689f2762369d292f33dcd0c08879f8bb6f9a68cd70448a0830e142b23de63f1fb19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4e768cfd9bd02751eaf37c4e38c1b59ac1bd8e50aaa193643def852e0ab479fe25d3a570224743af3d988582f1a579a718bccd72f007a2be1d9524b573efc64"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:30.354132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:30.354518) ========= 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.rvJdOBSkUa/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.8BRGh2SB2M/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.005223 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:30.407561) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 09:47:30.408238) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 09:47:30.408584) ========= ========= Total master_lag: 0.35 =========