builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1496415380.13 results: success (0) buildid: 20170602062724 builduid: 357a4f4075a74f64b70b6ec0d150713a revision: a6a79438608114ac87218bcb467b1f8aa5d603f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.132440) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.132976) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.133261) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.158397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.158791) ========= 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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-02 07:56:20-- 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% 7.60M=0.002s 2017-06-02 07:56:20 (7.60 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.531779 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.745986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.746329) ========= 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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.020936 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:20.790704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 07:56:20.794120) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6a79438608114ac87218bcb467b1f8aa5d603f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6a79438608114ac87218bcb467b1f8aa5d603f3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-02 07:56:20,868 truncating revision to first 12 chars 2017-06-02 07:56:20,868 Setting DEBUG logging. 2017-06-02 07:56:20,868 attempt 1/10 2017-06-02 07:56:20,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6a794386081?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 07:56:21,616 unpacking tar archive at: mozilla-beta-a6a794386081/testing/mozharness/ program finished with exit code 0 elapsedTime=1.022103 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 07:56:21.839466) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:21.839790) ========= script_repo_revision: a6a79438608114ac87218bcb467b1f8aa5d603f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:21.840281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:21.840627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 07:56:21.860297) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-06-02 07:56:21.860693) ========= /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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:56:22 INFO - MultiFileLogger online at 20170602 07:56:22 in /builds/slave/test 07:56:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:56:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:56:22 INFO - {'append_to_log': False, 07:56:22 INFO - 'base_work_dir': '/builds/slave/test', 07:56:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:56:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:56:22 INFO - 'branch': 'Mozilla-Beta', 07:56:22 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:22 INFO - 'config_files': ('talos/mac_config.py',), 07:56:22 INFO - 'default_actions': ('clobber', 07:56:22 INFO - 'read-buildbot-config', 07:56:22 INFO - 'download-and-extract', 07:56:22 INFO - 'populate-webroot', 07:56:22 INFO - 'create-virtualenv', 07:56:22 INFO - 'install', 07:56:22 INFO - 'run-tests'), 07:56:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:22 INFO - 'download_minidump_stackwalk': True, 07:56:22 INFO - 'download_symbols': 'ondemand', 07:56:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:56:22 INFO - 'tooltool.py': '/tools/tooltool.py', 07:56:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:56:22 INFO - '/tools/misc-python/virtualenv.py')}, 07:56:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:22 INFO - 'gecko_profile': False, 07:56:22 INFO - 'gecko_profile_interval': 0, 07:56:22 INFO - 'installer_path': 'installer.exe', 07:56:22 INFO - 'log_level': 'info', 07:56:22 INFO - 'log_name': 'talos', 07:56:22 INFO - 'log_to_console': True, 07:56:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:56:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:56:22 INFO - 'opt_config_files': (), 07:56:22 INFO - 'pip_index': False, 07:56:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:22 INFO - 'cmd': ('bash', 07:56:22 INFO - '-c', 07:56:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:22 INFO - 'enabled': True, 07:56:22 INFO - 'halt_on_failure': False, 07:56:22 INFO - 'name': 'check_screen_resolution'},), 07:56:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:22 INFO - 'cmd': ('bash', 07:56:22 INFO - '-c', 07:56:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:22 INFO - 'enabled': True, 07:56:22 INFO - 'halt_on_failure': False, 07:56:22 INFO - 'name': 'check_screen_resolution'},), 07:56:22 INFO - 'run_cmd_checks_enabled': True, 07:56:22 INFO - 'suite': 'dromaeojs', 07:56:22 INFO - 'system_bits': '32', 07:56:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:22 INFO - 'title': 't-yosemite-r7-0016', 07:56:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:22 INFO - 'use_talos_json': True, 07:56:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:56:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:22 INFO - 'work_dir': 'build'} 07:56:22 INFO - [mozharness: 2017-06-02 14:56:22.059741Z] Running clobber step. 07:56:22 INFO - Running pre-action listener: _resource_record_pre_action 07:56:22 INFO - Running main action method: clobber 07:56:22 INFO - rmtree: /builds/slave/test/build 07:56:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:56:23 INFO - Running post-action listener: _resource_record_post_action 07:56:23 INFO - [mozharness: 2017-06-02 14:56:23.906225Z] Finished clobber step (success) 07:56:23 INFO - [mozharness: 2017-06-02 14:56:23.906420Z] Running read-buildbot-config step. 07:56:23 INFO - Running pre-action listener: _resource_record_pre_action 07:56:23 INFO - Running main action method: read_buildbot_config 07:56:23 INFO - Using buildbot properties: 07:56:23 INFO - { 07:56:23 INFO - "project": "", 07:56:23 INFO - "product": "firefox", 07:56:23 INFO - "script_repo_revision": "production", 07:56:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 07:56:23 INFO - "repository": "", 07:56:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 07:56:23 INFO - "buildid": "20170602062724", 07:56:23 INFO - "pgo_build": "False", 07:56:23 INFO - "basedir": "/builds/slave/test", 07:56:23 INFO - "buildnumber": 14, 07:56:23 INFO - "slavename": "t-yosemite-r7-0016", 07:56:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:56:23 INFO - "platform": "yosemite_r7", 07:56:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:56:23 INFO - "branch": "mozilla-beta", 07:56:23 INFO - "repo_path": "releases/mozilla-beta", 07:56:23 INFO - "revision": "a6a79438608114ac87218bcb467b1f8aa5d603f3", 07:56:23 INFO - "stage_platform": "macosx64", 07:56:23 INFO - "builduid": "357a4f4075a74f64b70b6ec0d150713a", 07:56:23 INFO - "slavebuilddir": "test" 07:56:23 INFO - } 07:56:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:56:23 INFO - Running post-action listener: _resource_record_post_action 07:56:23 INFO - [mozharness: 2017-06-02 14:56:23.912167Z] Finished read-buildbot-config step (success) 07:56:23 INFO - [mozharness: 2017-06-02 14:56:23.912396Z] Running download-and-extract step. 07:56:23 INFO - Running pre-action listener: _resource_record_pre_action 07:56:23 INFO - Running main action method: download_and_extract 07:56:23 INFO - mkdir: /builds/slave/test/build/tests 07:56: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')]} 07:56:23 INFO - trying https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:56:23 INFO - Downloading https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:56:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 07:56:25 INFO - Downloaded 1287 bytes. 07:56:25 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:56:25 INFO - Using the following test package requirements: 07:56:25 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:56:25 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:56:25 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:56:25 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:56:25 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:56:25 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:56:25 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:56:25 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:56:25 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:56:25 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:56:25 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:56:25 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:56:25 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:56:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:56:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:56:25 INFO - Fetch https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:56:27 INFO - Expected file size: 51393272 07:56:27 INFO - Obtained file size: 51393272 07:56: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 07:56:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:56:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:56:43 INFO - Fetch https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:56:44 INFO - Expected file size: 51393272 07:56:44 INFO - Obtained file size: 51393272 07:56:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:56:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:56:59 INFO - Fetch https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:57:01 INFO - Expected file size: 14404461 07:57:01 INFO - Obtained file size: 14404461 07:57:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:01 INFO - trying https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 07:57:03 INFO - Downloaded 59646849 bytes. 07:57:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:57:03 INFO - mkdir: /builds/slave/test/properties 07:57:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:57:03 INFO - Writing to file /builds/slave/test/properties/build_url 07:57:03 INFO - Contents: 07:57:03 INFO - build_url:https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:57:04 INFO - Running post-action listener: _resource_record_post_action 07:57:04 INFO - Running post-action listener: set_extra_try_arguments 07:57:04 INFO - [mozharness: 2017-06-02 14:57:04.361394Z] Finished download-and-extract step (success) 07:57:04 INFO - [mozharness: 2017-06-02 14:57:04.361514Z] Running populate-webroot step. 07:57:04 INFO - Running pre-action listener: _resource_record_pre_action 07:57:04 INFO - Running main action method: populate_webroot 07:57:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:57:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:57:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:57:04 INFO - '--tppagecycles', 07:57:04 INFO - '7'], 07:57:04 INFO - 'tests': ['tsvgm']}}, 07:57:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:57:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:57:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:57:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:57:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['damp', 'tps']}, 07:57:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'tests': ['damp', 'tps']}, 07:57:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['dromaeo_dom']}, 07:57:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:57:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['basic_compositor_video']}, 07:57:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:57:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['a11yr', 07:57:04 INFO - 'ts_paint', 07:57:04 INFO - 'tpaint', 07:57:04 INFO - 'sessionrestore', 07:57:04 INFO - 'sessionrestore_no_auto_restore', 07:57:04 INFO - 'tabpaint']}, 07:57:04 INFO - 'other-e10s': {'tests': ['a11yr', 07:57:04 INFO - 'ts_paint', 07:57:04 INFO - 'tpaint', 07:57:04 INFO - 'sessionrestore', 07:57:04 INFO - 'sessionrestore_no_auto_restore', 07:57:04 INFO - 'tabpaint']}, 07:57:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:57:04 INFO - 'ts_paint', 07:57:04 INFO - 'tpaint', 07:57:04 INFO - 'sessionrestore', 07:57:04 INFO - 'sessionrestore_no_auto_restore', 07:57:04 INFO - 'tabpaint']}, 07:57:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:57:04 INFO - 'ts_paint', 07:57:04 INFO - 'tpaint', 07:57:04 INFO - 'sessionrestore', 07:57:04 INFO - 'sessionrestore_no_auto_restore', 07:57:04 INFO - 'tabpaint']}, 07:57:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['a11yr', 07:57:04 INFO - 'ts_paint', 07:57:04 INFO - 'tpaint', 07:57:04 INFO - 'sessionrestore', 07:57:04 INFO - 'sessionrestore_no_auto_restore', 07:57:04 INFO - 'tabpaint']}, 07:57:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['a11yr', 07:57:04 INFO - 'ts_paint', 07:57:04 INFO - 'tpaint', 07:57:04 INFO - 'sessionrestore', 07:57:04 INFO - 'sessionrestore_no_auto_restore', 07:57:04 INFO - 'tabpaint']}, 07:57:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['tsvgx', 07:57:04 INFO - 'tsvgr_opacity', 07:57:04 INFO - 'tart', 07:57:04 INFO - 'tscrollx', 07:57:04 INFO - 'cart', 07:57:04 INFO - 'tsvg_static']}, 07:57:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:57:04 INFO - 'tsvgr_opacity', 07:57:04 INFO - 'tart', 07:57:04 INFO - 'tscrollx', 07:57:04 INFO - 'cart', 07:57:04 INFO - 'tsvg_static']}, 07:57:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'talos_options': ['--disable-e10s'], 07:57:04 INFO - 'tests': ['tp5o']}, 07:57:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:57:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'talos_options': ['--disable-e10s', 07:57:04 INFO - '--xperf_path', 07:57:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:04 INFO - 'tests': ['tp5n']}, 07:57:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:57:04 INFO - 'talos_options': ['--xperf_path', 07:57:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:04 INFO - 'tests': ['tp5n']}}, 07:57:04 INFO - 'talos.zip': {'path': '', 07:57:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:57:04 INFO - Running post-action listener: _resource_record_post_action 07:57:04 INFO - [mozharness: 2017-06-02 14:57:04.379642Z] Finished populate-webroot step (success) 07:57:04 INFO - [mozharness: 2017-06-02 14:57:04.379724Z] Running create-virtualenv step. 07:57:04 INFO - Running pre-action listener: _resource_record_pre_action 07:57:04 INFO - Running main action method: create_virtualenv 07:57:04 INFO - Creating virtualenv /builds/slave/test/build/venv 07:57: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 07:57:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:57:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:57:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:57:04 INFO - Using real prefix '/tools/python27' 07:57:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:57:05 INFO - Installing distribute.............................................................................................................................................................................................done. 07:57:08 INFO - Installing pip.................done. 07:57:08 INFO - Return code: 0 07:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:57:08 INFO - Reading from file tmpfile_stdout 07:57:08 INFO - Output received: 07:57: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) 07:57:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:08 INFO - 'HOME': '/Users/cltbld', 07:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:08 INFO - 'LOGNAME': 'cltbld', 07:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:08 INFO - 'MOZ_NO_REMOTE': '1', 07:57:08 INFO - 'NO_EM_RESTART': '1', 07:57:08 INFO - 'PAGER': '/bin/cat', 07:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:08 INFO - 'PWD': '/builds/slave/test', 07:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:08 INFO - 'SHELL': '/bin/bash', 07:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:08 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:08 INFO - 'USER': 'cltbld', 07:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:08 INFO - 'XPC_FLAGS': '0x0', 07:57:08 INFO - 'XPC_SERVICE_NAME': '0', 07:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57: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 07:57:08 INFO - Cleaning up... 07:57:08 INFO - Return code: 0 07:57:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:08 INFO - 'HOME': '/Users/cltbld', 07:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:08 INFO - 'LOGNAME': 'cltbld', 07:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:08 INFO - 'MOZ_NO_REMOTE': '1', 07:57:08 INFO - 'NO_EM_RESTART': '1', 07:57:08 INFO - 'PAGER': '/bin/cat', 07:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:08 INFO - 'PWD': '/builds/slave/test', 07:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:08 INFO - 'SHELL': '/bin/bash', 07:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:08 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:08 INFO - 'USER': 'cltbld', 07:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:08 INFO - 'XPC_FLAGS': '0x0', 07:57:08 INFO - 'XPC_SERVICE_NAME': '0', 07:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:08 INFO - Downloading/unpacking psutil>=3.1.1 07:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:57:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:11 INFO - Installing collected packages: psutil 07:57:11 INFO - Running setup.py install for psutil 07:57:11 INFO - building 'psutil._psutil_osx' extension 07:57: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 07:57: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:57: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:57: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 07:57:12 INFO - building 'psutil._psutil_posix' extension 07:57: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 07:57:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:57:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:57:12 INFO - ^ 07:57:12 INFO - 1 warning generated. 07:57: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 07:57:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:12 INFO - Successfully installed psutil 07:57:12 INFO - Cleaning up... 07:57:12 INFO - Return code: 0 07:57:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:12 INFO - 'HOME': '/Users/cltbld', 07:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:12 INFO - 'LOGNAME': 'cltbld', 07:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:12 INFO - 'MOZ_NO_REMOTE': '1', 07:57:12 INFO - 'NO_EM_RESTART': '1', 07:57:12 INFO - 'PAGER': '/bin/cat', 07:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:12 INFO - 'PWD': '/builds/slave/test', 07:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:12 INFO - 'SHELL': '/bin/bash', 07:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:12 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:12 INFO - 'USER': 'cltbld', 07:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:12 INFO - 'XPC_FLAGS': '0x0', 07:57:12 INFO - 'XPC_SERVICE_NAME': '0', 07:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:57:14 INFO - Installing collected packages: mozsystemmonitor 07:57:14 INFO - Running setup.py install for mozsystemmonitor 07:57:14 INFO - Successfully installed mozsystemmonitor 07:57:14 INFO - Cleaning up... 07:57:14 INFO - Return code: 0 07:57:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:14 INFO - 'HOME': '/Users/cltbld', 07:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:14 INFO - 'LOGNAME': 'cltbld', 07:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:14 INFO - 'MOZ_NO_REMOTE': '1', 07:57:14 INFO - 'NO_EM_RESTART': '1', 07:57:14 INFO - 'PAGER': '/bin/cat', 07:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:14 INFO - 'PWD': '/builds/slave/test', 07:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:14 INFO - 'SHELL': '/bin/bash', 07:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:14 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:14 INFO - 'USER': 'cltbld', 07:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:14 INFO - 'XPC_FLAGS': '0x0', 07:57:14 INFO - 'XPC_SERVICE_NAME': '0', 07:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:57:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:16 INFO - Downloading functools32-3.2.3-2.tar.gz 07:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:57:16 INFO - warning: no files found matching '*.txt' 07:57:16 INFO - no previously-included directories found matching 'build' 07:57:16 INFO - no previously-included directories found matching 'dist' 07:57:16 INFO - no previously-included directories found matching '.git*' 07:57:16 INFO - Installing collected packages: jsonschema, functools32 07:57:16 INFO - Running setup.py install for functools32 07:57:16 INFO - warning: no files found matching '*.txt' 07:57:16 INFO - no previously-included directories found matching 'build' 07:57:16 INFO - no previously-included directories found matching 'dist' 07:57:16 INFO - no previously-included directories found matching '.git*' 07:57:16 INFO - Successfully installed jsonschema functools32 07:57:16 INFO - Cleaning up... 07:57:16 INFO - Return code: 0 07:57:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:16 INFO - 'HOME': '/Users/cltbld', 07:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:16 INFO - 'LOGNAME': 'cltbld', 07:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:16 INFO - 'MOZ_NO_REMOTE': '1', 07:57:16 INFO - 'NO_EM_RESTART': '1', 07:57:16 INFO - 'PAGER': '/bin/cat', 07:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:16 INFO - 'PWD': '/builds/slave/test', 07:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:16 INFO - 'SHELL': '/bin/bash', 07:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:16 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:16 INFO - 'USER': 'cltbld', 07:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:16 INFO - 'XPC_FLAGS': '0x0', 07:57:16 INFO - 'XPC_SERVICE_NAME': '0', 07:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:57:16 INFO - Cleaning up... 07:57:16 INFO - Return code: 0 07:57:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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', '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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:57: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 blobuploader==1.2.4 07:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:16 INFO - 'HOME': '/Users/cltbld', 07:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:16 INFO - 'LOGNAME': 'cltbld', 07:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:16 INFO - 'MOZ_NO_REMOTE': '1', 07:57:16 INFO - 'NO_EM_RESTART': '1', 07:57:16 INFO - 'PAGER': '/bin/cat', 07:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:16 INFO - 'PWD': '/builds/slave/test', 07:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:16 INFO - 'SHELL': '/bin/bash', 07:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:16 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:16 INFO - 'USER': 'cltbld', 07:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:16 INFO - 'XPC_FLAGS': '0x0', 07:57:16 INFO - 'XPC_SERVICE_NAME': '0', 07:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:17 INFO - Downloading/unpacking blobuploader==1.2.4 07:57: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 07:57: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 07:57: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 07:57: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 07:57:18 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57: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 07:57: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 07:57: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 07:57: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 07:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57: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 07:57: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 07:57: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 07:57: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 07:57:19 INFO - Downloading docopt-0.6.1.tar.gz 07:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:19 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:19 INFO - Running setup.py install for blobuploader 07:57:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:19 INFO - Running setup.py install for requests 07:57:20 INFO - Running setup.py install for docopt 07:57:20 INFO - Successfully installed blobuploader requests docopt 07:57:20 INFO - Cleaning up... 07:57:20 INFO - Return code: 0 07:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:20 INFO - 'HOME': '/Users/cltbld', 07:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:20 INFO - 'LOGNAME': 'cltbld', 07:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:20 INFO - 'MOZ_NO_REMOTE': '1', 07:57:20 INFO - 'NO_EM_RESTART': '1', 07:57:20 INFO - 'PAGER': '/bin/cat', 07:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:20 INFO - 'PWD': '/builds/slave/test', 07:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:20 INFO - 'SHELL': '/bin/bash', 07:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:20 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:20 INFO - 'USER': 'cltbld', 07:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:20 INFO - 'XPC_FLAGS': '0x0', 07:57:20 INFO - 'XPC_SERVICE_NAME': '0', 07:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-cWLTv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kYIwdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9aSv89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-7isMbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-O1zgvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-jcjatM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-y27zKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-EHM3BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-0ohrJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-g02Dzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-cQh6KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-B6r4rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bkOnfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-utAOzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:21 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-CzsvJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:22 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-v0nyq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:22 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-X4Nx09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:22 INFO - Running setup.py install for manifestparser 07:57:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:22 INFO - Running setup.py install for mozcrash 07:57:22 INFO - Running setup.py install for mozdebug 07:57:22 INFO - Running setup.py install for mozdevice 07:57:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:57:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:22 INFO - Running setup.py install for mozfile 07:57:23 INFO - Running setup.py install for mozhttpd 07:57:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Running setup.py install for mozinfo 07:57:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Running setup.py install for mozInstall 07:57:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Running setup.py install for mozleak 07:57:23 INFO - Running setup.py install for mozlog 07:57:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Running setup.py install for moznetwork 07:57:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:23 INFO - Running setup.py install for mozprocess 07:57:24 INFO - Running setup.py install for mozprofile 07:57:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:24 INFO - Running setup.py install for mozrunner 07:57:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:24 INFO - Running setup.py install for mozscreenshot 07:57:24 INFO - Running setup.py install for moztest 07:57:24 INFO - Running setup.py install for mozversion 07:57:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:24 INFO - Cleaning up... 07:57:24 INFO - Return code: 0 07:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:24 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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:24 INFO - 'HOME': '/Users/cltbld', 07:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:24 INFO - 'LOGNAME': 'cltbld', 07:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:24 INFO - 'MOZ_NO_REMOTE': '1', 07:57:24 INFO - 'NO_EM_RESTART': '1', 07:57:24 INFO - 'PAGER': '/bin/cat', 07:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:24 INFO - 'PWD': '/builds/slave/test', 07:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:24 INFO - 'SHELL': '/bin/bash', 07:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:24 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:24 INFO - 'USER': 'cltbld', 07:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:24 INFO - 'XPC_FLAGS': '0x0', 07:57:24 INFO - 'XPC_SERVICE_NAME': '0', 07:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ZPLVzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57: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)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-F3jTIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57: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)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-GuK6V5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57: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)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-wHYDcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57: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)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kBQG1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-N9cg5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-er9wmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-BXzL9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:25 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-93z6MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57: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)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Ywk7N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57: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)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-x2pSAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57: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)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-O_aabi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57: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)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-6whw6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57: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)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-I6wyhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57: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)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-77Hdqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Uq9scs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:26 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-xdsYAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:57:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:28 INFO - Downloading blessings-1.6.tar.gz 07:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:57:28 INFO - Installing collected packages: blessings 07:57:28 INFO - Running setup.py install for blessings 07:57:28 INFO - Successfully installed blessings 07:57:28 INFO - Cleaning up... 07:57:28 INFO - Return code: 0 07:57:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:28 INFO - Reading from file tmpfile_stdout 07:57:28 INFO - Current package versions: 07:57:28 INFO - blessings == 1.6 07:57:28 INFO - blobuploader == 1.2.4 07:57:28 INFO - docopt == 0.6.1 07:57:28 INFO - functools32 == 3.2.3-2 07:57:28 INFO - jsonschema == 2.5.1 07:57:28 INFO - manifestparser == 1.1 07:57:28 INFO - mozInstall == 1.12 07:57:28 INFO - mozcrash == 1.0 07:57:28 INFO - mozdebug == 0.1 07:57:28 INFO - mozdevice == 0.49 07:57:28 INFO - mozfile == 1.2 07:57:28 INFO - mozhttpd == 0.7 07:57:28 INFO - mozinfo == 0.9 07:57:28 INFO - mozleak == 0.1 07:57:28 INFO - mozlog == 3.4 07:57:28 INFO - moznetwork == 0.27 07:57:28 INFO - mozprocess == 0.25 07:57:28 INFO - mozprofile == 0.28 07:57:28 INFO - mozrunner == 6.13 07:57:28 INFO - mozscreenshot == 0.1 07:57:28 INFO - mozsystemmonitor == 0.3 07:57:28 INFO - moztest == 0.8 07:57:28 INFO - mozversion == 1.4 07:57:28 INFO - psutil == 3.1.1 07:57:28 INFO - requests == 1.2.3 07:57:28 INFO - wsgiref == 0.1.2 07:57:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:28 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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:57:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:28 INFO - 'HOME': '/Users/cltbld', 07:57:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:28 INFO - 'LOGNAME': 'cltbld', 07:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:28 INFO - 'MOZ_NO_REMOTE': '1', 07:57:28 INFO - 'NO_EM_RESTART': '1', 07:57:28 INFO - 'PAGER': '/bin/cat', 07:57:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:28 INFO - 'PWD': '/builds/slave/test', 07:57:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:28 INFO - 'SHELL': '/bin/bash', 07:57:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:28 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:28 INFO - 'USER': 'cltbld', 07:57:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:28 INFO - 'XPC_FLAGS': '0x0', 07:57:28 INFO - 'XPC_SERVICE_NAME': '0', 07:57:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57: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)) 07:57:29 INFO - Cleaning up... 07:57:29 INFO - Return code: 0 07:57:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57: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 0x101aa5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aa8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9b348ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, '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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:57: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 07:57: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 07:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:29 INFO - 'HOME': '/Users/cltbld', 07:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:29 INFO - 'LOGNAME': 'cltbld', 07:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:29 INFO - 'MOZ_NO_REMOTE': '1', 07:57:29 INFO - 'NO_EM_RESTART': '1', 07:57:29 INFO - 'PAGER': '/bin/cat', 07:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:29 INFO - 'PWD': '/builds/slave/test', 07:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:29 INFO - 'SHELL': '/bin/bash', 07:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:29 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:29 INFO - 'USER': 'cltbld', 07:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:29 INFO - 'XPC_FLAGS': '0x0', 07:57:29 INFO - 'XPC_SERVICE_NAME': '0', 07:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:57:29 INFO - Cleaning up... 07:57:29 INFO - Return code: 0 07:57:29 INFO - Running post-action listener: _resource_record_post_action 07:57:29 INFO - Running post-action listener: _start_resource_monitoring 07:57:29 INFO - Starting resource monitoring. 07:57:29 INFO - [mozharness: 2017-06-02 14:57:29.550168Z] Finished create-virtualenv step (success) 07:57:29 INFO - [mozharness: 2017-06-02 14:57:29.551163Z] Running install step. 07:57:29 INFO - Running pre-action listener: _resource_record_pre_action 07:57:29 INFO - Running main action method: install 07:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:29 INFO - Reading from file tmpfile_stdout 07:57:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:57:29 INFO - Reading from file tmpfile_stdout 07:57:29 INFO - Output received: 07:57:29 INFO - Usage: mozinstall [options] installer 07:57:29 INFO - Options: 07:57:29 INFO - -h, --help show this help message and exit 07:57:29 INFO - -d DEST, --destination=DEST 07:57:29 INFO - Directory to install application into. [default: 07:57:29 INFO - "/builds/slave/test"] 07:57:29 INFO - --app=APP Application being installed. [default: firefox] 07:57:29 INFO - mkdir: /builds/slave/test/build/application 07:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:57:44 INFO - Reading from file tmpfile_stdout 07:57:44 INFO - Output received: 07:57:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:57:44 INFO - Running post-action listener: _resource_record_post_action 07:57:44 INFO - [mozharness: 2017-06-02 14:57:44.525681Z] Finished install step (success) 07:57:44 INFO - [mozharness: 2017-06-02 14:57:44.525819Z] Running run-tests step. 07:57:44 INFO - Running pre-action listener: _resource_record_pre_action 07:57:44 INFO - Running main action method: run_tests 07:57:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:57:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:57:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:57:44 INFO - 2017-06-02 07:57:44.540 screenresolution[2424:33674] starting screenresolution argv=screenresolution get 07:57:44 INFO - 2017-06-02 07:57:44.542 screenresolution[2424:33674] Display 0: 1600x1200x32@60 07:57:44 INFO - 2017-06-02 07:57:44.548 screenresolution[2425:33676] starting screenresolution argv=screenresolution list 07:57:44 INFO - Available Modes on Display 0 07:57:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:57:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:57:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:57:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:57:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:57:44 INFO - Intel Iris: 07:57:44 INFO - Chipset Model: Intel Iris 07:57:44 INFO - Type: GPU 07:57:44 INFO - Bus: Built-In 07:57:44 INFO - VRAM (Dynamic, Max): 1536 MB 07:57:44 INFO - Vendor: Intel (0x8086) 07:57:44 INFO - Device ID: 0x0a2e 07:57:44 INFO - Revision ID: 0x0009 07:57:44 INFO - Displays: 07:57:44 INFO - Display: 07:57:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:57:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:57:44 INFO - Display Serial Number: 1600x1200 60 07:57:44 INFO - Main Display: Yes 07:57:44 INFO - Mirror: Off 07:57:44 INFO - Online: Yes 07:57:44 INFO - Rotation: Supported 07:57:44 INFO - Return code: 0 07:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:57:44 INFO - Python 2.7.3 07:57:44 INFO - Return code: 0 07:57:44 INFO - grabbing minidump binary from tooltool 07:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:44 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 0x101883d30>, '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 0x1017aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101878be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:57:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:57:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:57:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:57:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:57:44 INFO - Return code: 0 07:57:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:57:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:57:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:57:44 INFO - ENV: RUST_BACKTRACE is now 1 07:57:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:57:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:57:44 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/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:57:44 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/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12Lx8Jk2I5/Render', 07:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:44 INFO - 'HOME': '/Users/cltbld', 07:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:44 INFO - 'LOGNAME': 'cltbld', 07:57:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:57:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:44 INFO - 'MOZ_NO_REMOTE': '1', 07:57:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:57:44 INFO - 'NO_EM_RESTART': '1', 07:57:44 INFO - 'PAGER': '/bin/cat', 07:57:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:44 INFO - 'PWD': '/builds/slave/test', 07:57:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:44 INFO - 'RUST_BACKTRACE': '1', 07:57:44 INFO - 'SHELL': '/bin/bash', 07:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fkFr0sQMl0/Listeners', 07:57:44 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 07:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:44 INFO - 'USER': 'cltbld', 07:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:44 INFO - 'XPC_FLAGS': '0x0', 07:57:44 INFO - 'XPC_SERVICE_NAME': '0', 07:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:44 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/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:57:45 INFO - mozversion application_buildid: 20170602062724 07:57:45 INFO - mozversion application_changeset: a6a79438608114ac87218bcb467b1f8aa5d603f3 07:57:45 INFO - mozversion application_display_name: Firefox 07:57:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:57:45 INFO - mozversion application_name: Firefox 07:57:45 INFO - mozversion application_remotingname: firefox 07:57:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:57:45 INFO - mozversion application_vendor: Mozilla 07:57:45 INFO - mozversion application_version: 54.0 07:57:45 INFO - mozversion platform_buildid: 20170602062724 07:57:45 INFO - mozversion platform_changeset: a6a79438608114ac87218bcb467b1f8aa5d603f3 07:57:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:57:45 INFO - mozversion platform_version: 54.0 07:57:45 INFO - using testdate: 1496415465 07:57:45 INFO - actual date: 1496415465 07:57:45 INFO - starting webserver on 'localhost:57768' 07:57:45 INFO - SUITE-START | Running 2 tests 07:57:45 INFO - TEST-START | dromaeo_css 07:57:45 INFO - operating with platform_type : mac_ 07:57:45 INFO - Initialising browser for dromaeo_css test... 07:57:45 INFO - TEST-INFO | started process 2435 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmps2CqXl/profile http://localhost:57768/getInfo.html) 07:57:47 INFO - PROCESS | 2435 | __metrics Screen width/height:1600/1200 07:57:47 INFO - PROCESS | 2435 | colorDepth:24 07:57:47 INFO - PROCESS | 2435 | Browser inner width/height: 1024/689 07:57:47 INFO - PROCESS | 2435 | __metrics 07:57:47 INFO - PROCESS | 2435 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:57:47 INFO - PROCESS | 2435 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:57:48 INFO - TEST-INFO | 2435: exit 0 07:57:48 INFO - Browser initialized. 07:57:48 INFO - Running cycle 1/1 for dromaeo_css test... 07:57:48 INFO - TEST-INFO | started process 2438 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmps2CqXl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:58:00 INFO - PROCESS | 2438 | RSS: Main: 165679104 07:58:00 INFO - PROCESS | 2438 | 08:01:04 INFO - PROCESS | 2438 | Cycle 1(1): loaded http://localhost:57768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57768/tests/dromaeo/cssquery-ext.html) 08:01:05 INFO - PROCESS | 2438 | RSS: Main: 294768640 08:01:05 INFO - PROCESS | 2438 | 08:04:08 INFO - PROCESS | 2438 | Cycle 1(1): loaded http://localhost:57768/tests/dromaeo/cssquery-ext.html (next: http://localhost:57768/tests/dromaeo/cssquery-jquery.html) 08:04:09 INFO - PROCESS | 2438 | RSS: Main: 302743552 08:04:09 INFO - PROCESS | 2438 | 08:07:11 INFO - PROCESS | 2438 | Cycle 1(1): loaded http://localhost:57768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57768/tests/dromaeo/cssquery-mootools.html) 08:07:12 INFO - PROCESS | 2438 | RSS: Main: 283447296 08:07:12 INFO - PROCESS | 2438 | 08:10:14 INFO - PROCESS | 2438 | Cycle 1(1): loaded http://localhost:57768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57768/tests/dromaeo/cssquery-prototype.html) 08:10:15 INFO - PROCESS | 2438 | RSS: Main: 270860288 08:10:15 INFO - PROCESS | 2438 | 08:13:18 INFO - PROCESS | 2438 | Cycle 1(1): loaded http://localhost:57768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57768/tests/dromaeo/cssquery-yui.html) 08:13:19 INFO - PROCESS | 2438 | RSS: Main: 265211904 08:13:19 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | Cycle 1(1): loaded http://localhost:57768/tests/dromaeo/cssquery-yui.html (next: http://localhost:57768/tests/dromaeo/cssquery-dojo.html) 08:16:22 INFO - PROCESS | 2438 | RSS: Main: 421335040 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | __start_tp_report 08:16:22 INFO - PROCESS | 2438 | _x_x_mozilla_page_load 08:16:22 INFO - PROCESS | 2438 | _x_x_mozilla_page_load_details 08:16:22 INFO - PROCESS | 2438 | |i|pagename|runs| 08:16:22 INFO - PROCESS | 2438 | |0;dojo.html;3565.3404120237756;3566.5006899034133;3573.0710015396;3580.713542916567;3601.8739344122946;6188.133661287423;6192.225971753531;6199.783007594735;6211.875762484748;6230.90959082161;6124.785632502863;6223.968880155604;6229.563930524865;6237.781677641284;6395.296517383096;5508.256385387981;5533.944660553383;5555.2222688823595;5600.915986260209;5638.633488823213;5443.319585051869;5456.7544460499175;5655.830325090234;5658.236138121362;5669.177969194464;5477.863053423656;5523;5533.418991005927;5571.749271282782;5576.888462230753;5602.439756024406;5609.298837645294;5627.549796016329;5630.183623374588;5638.802641907513;5430.619856610039;5498.285222921035;5569.415211402826;5570.637908535932;5576.33084029915;6030.396960303979;6097.969510152465;6113.419225173601;6114.633122012693;6136.7948077883175;6439.710213040425;6457.967710161466;6503.902441463382;6518.706658200393;6525.804225873184;6562.639054851981;6606.075149479077;6606.471482281406;6628.966855165741;6629.138212032453;6133.907991380088;6140.1492820672065;6189.164462797555;6192.752289908353;6196.783112591038;6531.477481801445;6613.3056029117215;6620.139381880421;6700.195976482853;6826.385625293748;6446.774362897276;6462.256840463407;6465.741370345134;6478.805635830932;6481;5271.947280527223;6515.902261466082;6603.504737144734;6605.504587155982;6609;6295.811125666238;6325.7785977491485;6414.775482858171;6451.161349024691;6489.318621544764;6536.967315163489;6547.050677651714;6550.639714815731;6556.836079097965;6566.080748695186;5329.333833270841;5330.25722461087;5333.919991200135;5336.279602253724;5338.626296159309;4651;4736.076465089343;4740.170470167707;4765.261384485341;4766.237402015661;6132.814448934826;6137.726793749422;6151.66165860873;6153.569250152446;6160.199174107337;6593.769218077345;6603.801885943333;6604.0093985902495;6605;6619.834504137435;6184.165137706442;6219.657918814509;6230.283517395467;6249.6875156242;6254;5633.887322253532;5685.516731077904;5711.771529138788;5726.513246374105;5742.626729262584;6546;6636.13730215069;6637.435817955527;6657.434118100015;6662.100616416916;6433.903491447726;6434.517411194086;6452.22573291208;6486.027095935458;6564.015397690194;6760.628165450751;6833.760818371333;6843.657817109025;6844.041834143124;6859.897101543381;6522.184726909137;6528.37980391862;6536.411722944958;6540.934590654223;6566.343365663395;6359.586626869239;6526.4452521536205;6563.507736919845;6603.9009414859775;6627.569208001464;6436.195475565554;6474.5791523550815;6481.773137940149;6484.902726459012;6503.154589903283;6276.9372306276355;6443.323451037761;6464.127342808849;6467.902981455188;6468.6442245676935;6176.7838125665385;6211.81364559064;6266.780662676784;6341.143945567289;6361.491080713439;6346.873062538816;6428.4214420702365;6443.774467893789;6460.644664543495;6472.385123413268;6316.494680425647;6329.050642403677;6340.71466784005;6341.143945567289;6346.587471814318 08:16:22 INFO - PROCESS | 2438 | |1;ext.html;714.371353209176;785.1285073568338;834.2200042959832;902.9774255643608;913.2100732866073;29115.854420727894;41433;42017.99999999996;42079.78960105203;42098.57901420985;41241.79379103108;41371.17257654845;41408.58591414085;42934.57065429345;42972.78513607435;7974.3221826144845;9649.855252171206;9889.060539248796;9897.059779320989;9903.306768526207;8039.115697273295;8117.512949223036;8131.105578386373;8133.796655083611;11826.704332391673;12011.339376334341;12067.215630984001;12098.939505302505;12099.395030248452;12110.757784844298;12026.51893924242;12038.39808009596;12051.457684404222;12062;12067.517299308018;11564.190506664538;11659.533618655243;11670.066394688489;11678.416079196091;11684.00685941696;40555;40738;40846.79576602128;40849.978750531176;40926.181476370606;3971.5035620547433;4000.739951903117;4006.1386801837452;4027.1744292419985;4055.047063939972;28699.56950645742;29557.408851823056;30183.396332073455;31168.688313117033;31263.37473250545;3233.321002589459;3261.673832616743;3281.0156952914267;3292.7530435217213;3295.6045274567205;361787;361931.3806861924;361952.1902390497;362698;362806;53026.469735302926;53417.732911335974;53431.46568534343;53559;53569.1964620531;56700.43299567034;56866.715666421405;56917.43082569204;56942.14586781202;56950.867702275944;15524.689506209812;15899.602509937344;15914.204289785464;15916.363345466052;15922.920385397998;13513.189208647513;13531.797023044663;13546;13552.796708049387;13573.932130339284;9540.713778586654;11283.830742538868;11413.828792568118;12972.286524241068;13039;5406.78372865081;5487.396386397493;5496.120620700669;5499.477549632778;5508.476694713996;1143.3197247637643;1162.1748558523375;1174.2308787744041;1176.264834478451;1223.7613665335352;27453.86273068662;28078;28308.150755477367;28833.279168020967;28865.7113428863;1070.8875568065241;1281.6859869331886;1282.2819221236139;1329.5014369611395;1336.4654138344545;14446.10441847338;14577.41690332396;14743.48397806093;14816.036957597722;14851.702965940836;447305.7634711806;447327;448206.7589662031;448263;448484;47624.76187619109;48188.518114819104;50244.995100097796;59019.40980590225;59129;1453.454954392103;1490.560284716033;1490.970180596382;1500.5723368839829;1502.8346881842988;29804;31225.8438707805;31294.530582040832;31618.27050643926;32018.679813202503;49336.259956099966;49636.751816240685;49681.75795605139;49723.75138124286;49724.25413618871;417494;418863;467753;476521;571579;35330.64669353273;35507.82246088753;35616.82191589026;35797.82101089478;36437.63562364342;44990.875228119556;45252.54747452615;45445.77277113593;45666.77166614146;45704.77147614241;29849.806007759445;30430.391392172474;30643.54034689526;31341.8432907834;31586.578603962418;23424.76575234294;30350;31217.375652487277;31317.8434107828;31410.528842067844;36508.087297817765;37014.814925925195;37044.629553704115;37428.43857342088;37479.25041499209;29805;30339.089827305215;31113.53329700011;31841.681583183872;31911.361772764878;23240.070397184598;24517;24808.751912481366;24838.50322993566;25213.87393063096 08:16:22 INFO - PROCESS | 2438 | |2;jquery.html;422.735790131168;427.2693693783629;427.9293916503777;433.5296203619073;446.8145719526396;19746.70379944302;21034.579308413842;22048.44878878029;22212.555748885014;22282.88858555709;22766.975486103125;23374.766252337515;23627.409314767097;24170.39574010646;24487.63268550969;6893.20728116268;6919.238883722786;7060.752873649423;7173.10336207974;7183.461240406964;5971.552133589977;6799.082123913283;6857.691403886837;6904.585724856524;6919.827004324907;6578.8684226315445;6848.417884479801;6912;6926.27274136216;6941.479389045841;6816.9318306816795;6824.249332573413;6874.793756187322;6909.654517274142;6939.479539034567;6810.046593476867;6875.656217189121;6876.553024053421;6882.5870447773295;6886.48351373649;27260.727392726018;27642.447151056866;27752.306192345353;27768.444631107468;28056.719432805614;2995.1164406500134;3067.2331917020747;3088;3093.8298393588343;3131;15881.36474541017;16313.673726525521;16719.913205641595;17326.65346693059;17373.826261737348;2088.2482306369693;2097.517570958688;2112.704221409004;2138.8075073243326;2140.176032227588;140696;145840;149907.50092499153;151185;151285;22186.223482178368;22941;23133.421664458525;23360.78072583102;23414.531709365718;39424.802875985435;39481.802590987434;40697.18605627871;40744.18511629751;40835;10603.734906627242;10800.217964035846;10983.505742241618;11007.134528350407;11233.438328083563;9118.40730352525;9133.40632858862;9144.54277286133;9273.58268877902;9335.486548239776;9565.856512152322;9714.659986900424;9802.705918822447;9816.165625921876;9819.558119884623;3615.9638403616154;3851.5763266040713;3955.367141257385;3996.3406038003986;4041.8383264669083;902.801383695586;1025.8974102589682;1046.424466543398;1066.8826429092794;1086.9076128529164;20731.92680731945;22056.33830985073;22191.779452129966;22196.223132190295;22238.332850014525;1030.2891005206534;1032.210359075308;1044.9373037617768;1056.0759335581367;1060.3002018667642;10797.730056748487;10885;11599.942000289944;11619.6514104577;11633.243839150427;746008;756276;758964;773538;774807.1259643666;153503;156098.4390156084;159764.2011789957;160141;161282;1237.8947789437818;1262.217425196384;1271.110222844013;1296.695276609996;1333.9933300333437;18105.366312179536;18623.90688046551;20997.265095721577;21171.848838197096;21266.680999785323;64862.35137648563;65111.69776604536;65750;66487.33512665005;67412.662936685;84804.15195847962;84943.0269940549;85636;92568;95732.52133739287;7821.9440375548575;10469.31949423285;11439.656810295372;11744.765104697688;12162;13114.278714670785;13146.868531314565;13478.932605336911;13573.04988650785;13687.726245475234;8975.596098175729;11110.666679999613;11153.330800152016;11614.419279035845;12045.939770301093;10785.298955567863;10927.726806829893;11119.388433636228;11311.773764524827;11328.830067549157;11742.236754610924;11784.75919122214;11913.761724765283;12059.82923073855;12159.148859579744;11856.70358241051;11908.464119114487;11949.402529873645;12024.218425802295;12201.572944946884;11573.421328933351;11582.536698531965;11703.648890533297;11855.940720296343;11867.703307417383 08:16:22 INFO - PROCESS | 2438 | |3;mootools.html;1428.1002968130076;1611.210506851643;1635.6401591649833;1650.796187090234;1671.6155284284614;9597.808043839119;10036.849447258288;11279.774404511905;11610.477528511215;11821.408929553509;11382;11486.483108260127;11489.827652585218;11493.19547631664;11519.884801151986;2427.211156374181;2471.5674756917556;2471.6045432730766;2475.678161838958;2476.170482888235;2103.1902717453813;2169.425102347879;2178.455386153462;2370.6207006878903;2401.135591187171;2473.0973194783955;2484.167803785725;2495.15164843953;2501.187114187896;2503.123906632682;2422.079609748302;2445.6698345723366;2458.6803715516867;2458.766417190364;2460.114358830828;2406.5307265083316;2434.476587533671;2436.1108195508514;2443.474652949624;2446.8654224017673;11555.075593952464;11602.419879006016;11696.590619328368;11734.11994100446;11737.295762254293;5932.703364831741;6112.877742445126;6131.264248290189;6134.263888333384;6140.907886381708;3554.3779838528153;3668.7982160981137;3720.7581507201944;3749.3438648236724;3808.6000969898314;1217.5799349224503;1222.9902358836232;1280.2638482872292;1281.116029939339;1282.1281528560576;16090.356385744557;16196;16225.51323460298;16254.268557914804;16277.755833662504;4751.95248047522;4936.703797772145;5164.896702066013;5185.22221666753;5230.476952304739;10309;12230.266184028987;12230.510779568896;12254.509819607294;12257.38713064361;6072.119542666289;6073.757049717962;6074.271087469444;6082.361352058058;6096.329403765582;5366.294242985269;5480.739429931058;5557.694326811983;5560.388357280696;5586.860328491739;3582.910427239288;3668;3802.220544788367;3830.71269654777;3856.768593884376;977.9364341317792;997.6558087459815;1058.666520046183;1061.2624226162743;1091.3124731419155;751.9962400187964;806.9475484093515;823.2056065896438;861.7716305179133;887.6316328723508;6692.364225398579;6708.798736037927;6764.019217213477;6844.863102737917;6873.278305777922;1030.6289735695143;1041.770810421706;1042.7966546523355;1056.2025670618634;1058.0953284941418;3675.7610755301353;4107.116969851496;4243.321068629005;4275.957240427618;4300.376445415397;13747.450101996008;14257.215853128178;14258.358373873201;14307.213103279211;14349;13322.533711320057;14133.222672752892;14155.29223538819;14167;14186.361613727408;10278.434686092187;10559.577616895393;10562.947185264178;10592.57629694819;10610.734731631772;5922.822315330547;6612.70242839083;6633.436157926631;6845.657717114224;7020.719171233093;12277;12407.007439405006;12674;12706.428210730724;12727.363631818187;12232.694182645506;12629.557965471164;12984.896283815982;13017.674558136123;13112.147710398793;9232.861507077265;9327.533623318943;9521.381110227812;9533.046695330411;9546.856797148188;10343.37939723619;10349.706286714161;10447.895521044693;10448.529816158443;10480.266381353233;8713.564321783759;8962.551872406484;8999.370044097112;9033.728988130366;9046.185843274137;8736.650533978569;8762.561871906251;8804.987426445785;8827.646894124164;8938.910610893809;7874.05511338643;8053.516788992679;8054.718084867001;8079.555624440713;8091.959540202261;5823.213866128188;5838.036723940502;5894.086416605433;6036.486898613667;6090.451859332681;5876.41235876409;5946.732397042057;6077.4834139098675;6206.782762603287;6326.240851097898 08:16:22 INFO - PROCESS | 2438 | |4;prototype.html;341.4400383371271;395.3180763183507;396.97221194516396;399.4527497328659;404.91901619676054;4443.933340999887;5256.316678831756;5266.841994740154;5371.355437347524;5375.381831089421;4581.717119206627;5121.231815227718;5144.04217574895;5155.355580552431;5287.682739035651;4137.400076988834;4238.385434112052;4305.536117719975;4313.719608225471;4346.631086058106;3654.524911761467;3958.4853968983994;4292.420523229355;4307.784610769449;4328.653707703376;3513.736469764765;3619.0952261934517;4276.42268293781;4289.2922667759785;4388.539203383647;3774.301754175486;4073.944541593805;4344.23975804233;4370.97814510927;4402.697976517596;3569;3731.421629647409;3897.3569361055393;4015.31739604268;4044.1911617676583;4555.886102847456;4890.9458027628125;5086.949130508685;5138.152204886189;5168.457311982263;4726.7163970161555;5040.816122618218;5050.84847454577;5055.306472331773;5067.163917953533;5385.380681221632;5557.055300598908;5580.581456390787;5585.413531579207;5591.524720398771;4060.7360521566;4466.397036400077;4488.147252022105;4491.505934347219;4512.481064677571;5096.241796580174;5356.303680521546;5672.695280085603;5984.371640977722;6053.728716969442;3616.8553257869917;4464.129494748493;4579.816807327737;4605.769711514411;4625.496713568104;4032.4354590357375;4657.836975705834;4720.811167553259;4721.622270218374;4759.571638552547;4323.48118225815;4482.565579014749;4653.488116307203;4654.371659825948;4810.368172030165;5037.496250374933;5091.937765205098;5597.6641401516035;5608.551315894719;5616.876624675081;1389.2081513537187;1488.4864721671006;1544.5752418084892;1766.3994241957796;1768.6816373052975;1273.541525050981;1330.700592366729;1340.3633274194679;1356.2811709793825;1436.9294875317955;4437.33439984005;5278.498542638443;5283.920741188886;5292.841214763564;5300.6024548158275;5275.417374787625;5489.615726899079;5733.913991290134;5735.677050794435;5815.709214539256;4828.8792780180775;4957.950420495749;5279.44565820591;5288.471152884681;5368.282149712055;2434.6104623260194;2554.7737086198727;2585.961210581843;2780.9270755814937;2880.1071667783053;4809.639277054235;5351.224072509464;5544.417836127229;5577.549837042398;5772.769089236467;4696.788644511005;4961.337951786119;5086.863517322733;5408.810691625853;5830.970845145832;5330.493603107699;5649;5801.883962320814;5856.297244330707;6026.849328766816;4403.156527819373;4899.828506002273;5242.816501422432;5425.376081750649;5868.471837534642;5116.002379535955;5485.9725701371235;5515.503604675599;5719.685417302088;5734.279716085074;5699.917015766991;5832.24180856486;5835.883282334415;5883.735231914659;5955.851103722442;3961;4231.87304380869;4403.977980110207;4616.907661846677;4632.189366860854;4551.908961820811;5257.631965762513;5280.6303558750515;5604.243427137284;5790.392008839011;4990.950090499048;5169.2941329361975;5386.380566234933;5606.046972014686;5615.18579805934;4774.618030557616;4788.473267940454;5452.59105567092;5521.613487055867;5538.889222215614;4476.731396116244;4928.20528193282;5024.382444639853;5264.209968716586;5386.245925570345;4759.6668233223345;4943.4562198157455;5300.337457817773;5585.425327790299;5644.745986430703;4891.559759621651;5385.2999110117;5400.2709634200455;5403.865757860353;5412.539934105487 08:16:22 INFO - PROCESS | 2438 | |5;yui.html;739.0946091038476;750.9924900750995;799.6241766369805;867.2888231650047;870.9390342676011;15858.048517088968;17295.30818767248;17325.74011389827;17345.479635610936;17369.21838517268;15843.920780396082;15920;16042;16065.277062532185;16069.955452895554;858.635080090962;996.476849653932;1005.6480231918842;1017.3946501831399;1039.9792004159913;1012.2256473797551;1021.1473419694564;1021.6832781837654;1029.521272608237;1031.2265800649513;1070.138538476526;1070.7911957168355;1071.2929466552077;1071.3197119828887;1071.5767271927584;1067.359584249452;1067.7063807452935;1068.9679309620685;1069.844872493492;1070.983935240968;794.8450052239758;794.996025019877;795.2762985683;795.6976348987406;795.7533164718956;15165.848341516665;16895.239714212767;17286;17306.826931730648;17407.564810879703;14663.266836658231;14789.260536973215;14917.179555124463;14947.476838310715;15143.848561514355;11768.58809941648;11885.692573816874;11912.510029252999;12049.096317776202;12206.938965305206;3610.3862343401684;3625.583057948343;3669.284489524544;3670.3576874046935;3789.5831458539537;107590.46204769005;108454.45772771165;112532;112543;112566;11478.892202132314;11487.597934072268;11544.422778861024;11703;11733.178677492664;23335.7666423337;23801.40496487567;23963.801809909433;24064.51870962571;24071.879640602034;8126.512409255463;8137.8372432552205;8205.507669539847;8217.712380066669;8236.382271329554;4437.755923424243;4503.256962601135;4515.7290562565695;4639.141758774637;4779.330893674888;3640.453931910182;3703.000189948699;3706.777593344408;3711.183539621279;3743.9438408423894;542.6499907559602;677.3159109299643;684.2302409788988;685.2564967010849;685.2941470285614;486.5815398757066;725.760499035317;745.1542499263375;750.8010377250033;781.6716978868775;9069.54652267384;9758.170555502862;10434.530446129942;10548.314359566604;10917.072048875776;589.1545632018068;598.5780025082306;620.6307247187916;663.141232104426;731.5756861020595;4450.332450132506;4755.690880092783;4881.365422495124;5180.766865491062;5183.092958732207;98248.50875745718;99718;100378;105211;108535.45732271446;83353;86716;87753.24493510222;88328;88972.5551372252;70830.58338833298;75272;80249.5987520082;80698;84268;10180.440075795606;11061.336319820837;11292.153088518558;11452.94273528627;11534.94232528832;45551;48475.75762121308;48926;49042.773930652016;49978;60694.39305607064;60950;62116;62358.688206558676;63325.68337158285;12872.613821585368;12888.162269452456;12944.676383090498;13140;13194.472221111431;12975.740485190054;15165.165915874733;15269.694606108038;16455.67088658244;16669.83330166683;13795.448182073007;14897.47858824936;14943.402263909322;15008.774868376764;15413;13716.45134194661;14247.216403097515;14600.634984125058;14642.707145857235;14947.925260373628;12665.430055647334;13880.305984700926;14255.429782808571;14851.628709282355;15023.924880375529;10133.158503816876;10184.490775461049;10309.690709278733;10383.2212584055;10667.679969600924;11385.601503947322;11487.25332853362;11742.530298787953;11826.526938922347;11827.526898923947 08:16:22 INFO - PROCESS | 2438 | __end_tp_report 08:16:22 INFO - PROCESS | 2438 | __start_cc_report 08:16:22 INFO - PROCESS | 2438 | _x_x_mozilla_cycle_collect,1315 08:16:22 INFO - PROCESS | 2438 | __end_cc_report 08:16:22 INFO - PROCESS | 2438 | __startTimestamp1496416582889__endTimestamp 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | ------- Summary: start ------- 08:16:22 INFO - PROCESS | 2438 | Number of tests: 6 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | [#0] dojo.html Cycles:165 Average:6100.06 Median:6329.05 stddev:654.31 (10.3%) stddev-sans-first:625.37 08:16:22 INFO - PROCESS | 2438 | Values: 3565.3 3566.5 3573.1 3580.7 3601.9 6188.1 6192.2 6199.8 6211.9 6230.9 6124.8 6224.0 6229.6 6237.8 6395.3 5508.3 5533.9 5555.2 5600.9 5638.6 5443.3 5456.8 5655.8 5658.2 5669.2 5477.9 5523.0 5533.4 5571.7 5576.9 5602.4 5609.3 5627.5 5630.2 5638.8 5430.6 5498.3 5569.4 5570.6 5576.3 6030.4 6098.0 6113.4 6114.6 6136.8 6439.7 6458.0 6503.9 6518.7 6525.8 6562.6 6606.1 6606.5 6629.0 6629.1 6133.9 6140.1 6189.2 6192.8 6196.8 6531.5 6613.3 6620.1 6700.2 6826.4 6446.8 6462.3 6465.7 6478.8 6481.0 5271.9 6515.9 6603.5 6605.5 6609.0 6295.8 6325.8 6414.8 6451.2 6489.3 6537.0 6547.1 6550.6 6556.8 6566.1 5329.3 5330.3 5333.9 5336.3 5338.6 4651.0 4736.1 4740.2 4765.3 4766.2 6132.8 6137.7 6151.7 6153.6 6160.2 6593.8 6603.8 6604.0 6605.0 6619.8 6184.2 6219.7 6230.3 6249.7 6254.0 5633.9 5685.5 5711.8 5726.5 5742.6 6546.0 6636.1 6637.4 6657.4 6662.1 6433.9 6434.5 6452.2 6486.0 6564.0 6760.6 6833.8 6843.7 6844.0 6859.9 6522.2 6528.4 6536.4 6540.9 6566.3 6359.6 6526.4 6563.5 6603.9 6627.6 6436.2 6474.6 6481.8 6484.9 6503.2 6276.9 6443.3 6464.1 6467.9 6468.6 6176.8 6211.8 6266.8 6341.1 6361.5 6346.9 6428.4 6443.8 6460.6 6472.4 6316.5 6329.1 6340.7 6341.1 6346.6 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | [#1] ext.html Cycles:180 Average:57403.76 Median:19313.13 stddev:114126.84 (590.9%) stddev-sans-first:114367.63 08:16:22 INFO - PROCESS | 2438 | Values: 714.4 785.1 834.2 903.0 913.2 29115.9 41433.0 42018.0 42079.8 42098.6 41241.8 41371.2 41408.6 42934.6 42972.8 7974.3 9649.9 9889.1 9897.1 9903.3 8039.1 8117.5 8131.1 8133.8 11826.7 12011.3 12067.2 12098.9 12099.4 12110.8 12026.5 12038.4 12051.5 12062.0 12067.5 11564.2 11659.5 11670.1 11678.4 11684.0 40555.0 40738.0 40846.8 40850.0 40926.2 3971.5 4000.7 4006.1 4027.2 4055.0 28699.6 29557.4 30183.4 31168.7 31263.4 3233.3 3261.7 3281.0 3292.8 3295.6 361787.0 361931.4 361952.2 362698.0 362806.0 53026.5 53417.7 53431.5 53559.0 53569.2 56700.4 56866.7 56917.4 56942.1 56950.9 15524.7 15899.6 15914.2 15916.4 15922.9 13513.2 13531.8 13546.0 13552.8 13573.9 9540.7 11283.8 11413.8 12972.3 13039.0 5406.8 5487.4 5496.1 5499.5 5508.5 1143.3 1162.2 1174.2 1176.3 1223.8 27453.9 28078.0 28308.2 28833.3 28865.7 1070.9 1281.7 1282.3 1329.5 1336.5 14446.1 14577.4 14743.5 14816.0 14851.7 447305.8 447327.0 448206.8 448263.0 448484.0 47624.8 48188.5 50245.0 59019.4 59129.0 1453.5 1490.6 1491.0 1500.6 1502.8 29804.0 31225.8 31294.5 31618.3 32018.7 49336.3 49636.8 49681.8 49723.8 49724.3 417494.0 418863.0 467753.0 476521.0 571579.0 35330.6 35507.8 35616.8 35797.8 36437.6 44990.9 45252.5 45445.8 45666.8 45704.8 29849.8 30430.4 30643.5 31341.8 31586.6 23424.8 30350.0 31217.4 31317.8 31410.5 36508.1 37014.8 37044.6 37428.4 37479.3 29805.0 30339.1 31113.5 31841.7 31911.4 23240.1 24517.0 24808.8 24838.5 25213.9 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | [#2] jquery.html Cycles:180 Average:44095.81 Median:22225.44 stddev:126901.42 (571.0%) stddev-sans-first:127215.04 08:16:22 INFO - PROCESS | 2438 | Values: 422.7 427.3 427.9 433.5 446.8 19746.7 21034.6 22048.4 22212.6 22282.9 22767.0 23374.8 23627.4 24170.4 24487.6 6893.2 6919.2 7060.8 7173.1 7183.5 5971.6 6799.1 6857.7 6904.6 6919.8 6578.9 6848.4 6912.0 6926.3 6941.5 6816.9 6824.2 6874.8 6909.7 6939.5 6810.0 6875.7 6876.6 6882.6 6886.5 27260.7 27642.4 27752.3 27768.4 28056.7 2995.1 3067.2 3088.0 3093.8 3131.0 15881.4 16313.7 16719.9 17326.7 17373.8 2088.2 2097.5 2112.7 2138.8 2140.2 140696.0 145840.0 149907.5 151185.0 151285.0 22186.2 22941.0 23133.4 23360.8 23414.5 39424.8 39481.8 40697.2 40744.2 40835.0 10603.7 10800.2 10983.5 11007.1 11233.4 9118.4 9133.4 9144.5 9273.6 9335.5 9565.9 9714.7 9802.7 9816.2 9819.6 3616.0 3851.6 3955.4 3996.3 4041.8 902.8 1025.9 1046.4 1066.9 1086.9 20731.9 22056.3 22191.8 22196.2 22238.3 1030.3 1032.2 1044.9 1056.1 1060.3 10797.7 10885.0 11599.9 11619.7 11633.2 746008.0 756276.0 758964.0 773538.0 774807.1 153503.0 156098.4 159764.2 160141.0 161282.0 1237.9 1262.2 1271.1 1296.7 1334.0 18105.4 18623.9 20997.3 21171.8 21266.7 64862.4 65111.7 65750.0 66487.3 67412.7 84804.2 84943.0 85636.0 92568.0 95732.5 7821.9 10469.3 11439.7 11744.8 12162.0 13114.3 13146.9 13478.9 13573.0 13687.7 8975.6 11110.7 11153.3 11614.4 12045.9 10785.3 10927.7 11119.4 11311.8 11328.8 11742.2 11784.8 11913.8 12059.8 12159.1 11856.7 11908.5 11949.4 12024.2 12201.6 11573.4 11582.5 11703.6 11855.9 11867.7 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | [#3] mootools.html Cycles:180 Average:6925.74 Median:2474.39 stddev:4377.75 (176.9%) stddev-sans-first:4370.54 08:16:22 INFO - PROCESS | 2438 | Values: 1428.1 1611.2 1635.6 1650.8 1671.6 9597.8 10036.8 11279.8 11610.5 11821.4 11382.0 11486.5 11489.8 11493.2 11519.9 2427.2 2471.6 2471.6 2475.7 2476.2 2103.2 2169.4 2178.5 2370.6 2401.1 2473.1 2484.2 2495.2 2501.2 2503.1 2422.1 2445.7 2458.7 2458.8 2460.1 2406.5 2434.5 2436.1 2443.5 2446.9 11555.1 11602.4 11696.6 11734.1 11737.3 5932.7 6112.9 6131.3 6134.3 6140.9 3554.4 3668.8 3720.8 3749.3 3808.6 1217.6 1223.0 1280.3 1281.1 1282.1 16090.4 16196.0 16225.5 16254.3 16277.8 4752.0 4936.7 5164.9 5185.2 5230.5 10309.0 12230.3 12230.5 12254.5 12257.4 6072.1 6073.8 6074.3 6082.4 6096.3 5366.3 5480.7 5557.7 5560.4 5586.9 3582.9 3668.0 3802.2 3830.7 3856.8 977.9 997.7 1058.7 1061.3 1091.3 752.0 806.9 823.2 861.8 887.6 6692.4 6708.8 6764.0 6844.9 6873.3 1030.6 1041.8 1042.8 1056.2 1058.1 3675.8 4107.1 4243.3 4276.0 4300.4 13747.5 14257.2 14258.4 14307.2 14349.0 13322.5 14133.2 14155.3 14167.0 14186.4 10278.4 10559.6 10562.9 10592.6 10610.7 5922.8 6612.7 6633.4 6845.7 7020.7 12277.0 12407.0 12674.0 12706.4 12727.4 12232.7 12629.6 12984.9 13017.7 13112.1 9232.9 9327.5 9521.4 9533.0 9546.9 10343.4 10349.7 10447.9 10448.5 10480.3 8713.6 8962.6 8999.4 9033.7 9046.2 8736.7 8762.6 8805.0 8827.6 8938.9 7874.1 8053.5 8054.7 8079.6 8092.0 5823.2 5838.0 5894.1 6036.5 6090.5 5876.4 5946.7 6077.5 6206.8 6326.2 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | [#4] prototype.html Cycles:180 Average:4600.36 Median:4976.14 stddev:1270.81 (25.5%) stddev-sans-first:1233.51 08:16:22 INFO - PROCESS | 2438 | Values: 341.4 395.3 397.0 399.5 404.9 4443.9 5256.3 5266.8 5371.4 5375.4 4581.7 5121.2 5144.0 5155.4 5287.7 4137.4 4238.4 4305.5 4313.7 4346.6 3654.5 3958.5 4292.4 4307.8 4328.7 3513.7 3619.1 4276.4 4289.3 4388.5 3774.3 4073.9 4344.2 4371.0 4402.7 3569.0 3731.4 3897.4 4015.3 4044.2 4555.9 4890.9 5086.9 5138.2 5168.5 4726.7 5040.8 5050.8 5055.3 5067.2 5385.4 5557.1 5580.6 5585.4 5591.5 4060.7 4466.4 4488.1 4491.5 4512.5 5096.2 5356.3 5672.7 5984.4 6053.7 3616.9 4464.1 4579.8 4605.8 4625.5 4032.4 4657.8 4720.8 4721.6 4759.6 4323.5 4482.6 4653.5 4654.4 4810.4 5037.5 5091.9 5597.7 5608.6 5616.9 1389.2 1488.5 1544.6 1766.4 1768.7 1273.5 1330.7 1340.4 1356.3 1436.9 4437.3 5278.5 5283.9 5292.8 5300.6 5275.4 5489.6 5733.9 5735.7 5815.7 4828.9 4958.0 5279.4 5288.5 5368.3 2434.6 2554.8 2586.0 2780.9 2880.1 4809.6 5351.2 5544.4 5577.5 5772.8 4696.8 4961.3 5086.9 5408.8 5831.0 5330.5 5649.0 5801.9 5856.3 6026.8 4403.2 4899.8 5242.8 5425.4 5868.5 5116.0 5486.0 5515.5 5719.7 5734.3 5699.9 5832.2 5835.9 5883.7 5955.9 3961.0 4231.9 4404.0 4616.9 4632.2 4551.9 5257.6 5280.6 5604.2 5790.4 4991.0 5169.3 5386.4 5606.0 5615.2 4774.6 4788.5 5452.6 5521.6 5538.9 4476.7 4928.2 5024.4 5264.2 5386.2 4759.7 4943.5 5300.3 5585.4 5644.7 4891.6 5385.3 5400.3 5403.9 5412.5 08:16:22 INFO - PROCESS | 2438 | 08:16:22 INFO - PROCESS | 2438 | [#5] yui.html Cycles:180 Average:20793.28 Median:16782.54 stddev:29261.98 (174.4%) stddev-sans-first:29305.32 08:16:22 INFO - PROCESS | 2438 | Values: 739.1 751.0 799.6 867.3 870.9 15858.0 17295.3 17325.7 17345.5 17369.2 15843.9 15920.0 16042.0 16065.3 16070.0 858.6 996.5 1005.6 1017.4 1040.0 1012.2 1021.1 1021.7 1029.5 1031.2 1070.1 1070.8 1071.3 1071.3 1071.6 1067.4 1067.7 1069.0 1069.8 1071.0 794.8 795.0 795.3 795.7 795.8 15165.8 16895.2 17286.0 17306.8 17407.6 14663.3 14789.3 14917.2 14947.5 15143.8 11768.6 11885.7 11912.5 12049.1 12206.9 3610.4 3625.6 3669.3 3670.4 3789.6 107590.5 108454.5 112532.0 112543.0 112566.0 11478.9 11487.6 11544.4 11703.0 11733.2 23335.8 23801.4 23963.8 24064.5 24071.9 8126.5 8137.8 8205.5 8217.7 8236.4 4437.8 4503.3 4515.7 4639.1 4779.3 3640.5 3703.0 3706.8 3711.2 3743.9 542.6 677.3 684.2 685.3 685.3 486.6 725.8 745.2 750.8 781.7 9069.5 9758.2 10434.5 10548.3 10917.1 589.2 598.6 620.6 663.1 731.6 4450.3 4755.7 4881.4 5180.8 5183.1 98248.5 99718.0 100378.0 105211.0 108535.5 83353.0 86716.0 87753.2 88328.0 88972.6 70830.6 75272.0 80249.6 80698.0 84268.0 10180.4 11061.3 11292.2 11452.9 11534.9 45551.0 48475.8 48926.0 49042.8 49978.0 60694.4 60950.0 62116.0 62358.7 63325.7 12872.6 12888.2 12944.7 13140.0 13194.5 12975.7 15165.2 15269.7 16455.7 16669.8 13795.4 14897.5 14943.4 15008.8 15413.0 13716.5 14247.2 14600.6 14642.7 14947.9 12665.4 13880.3 14255.4 14851.6 15023.9 10133.2 10184.5 10309.7 10383.2 10667.7 11385.6 11487.3 11742.5 11826.5 11827.5 08:16:22 INFO - PROCESS | 2438 | -------- Summary: end -------- 08:16:22 INFO - PROCESS | 2438 | 08:16:23 INFO - TEST-INFO | 2438: exit 0 08:16:23 INFO - TEST-OK | dromaeo_css | took 1118381ms 08:16:23 INFO - TEST-START | kraken 08:16:23 INFO - operating with platform_type : mac_ 08:16:23 INFO - Initialising browser for kraken test... 08:16:23 INFO - TEST-INFO | started process 2442 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmprelnXy/profile http://localhost:57768/getInfo.html) 08:16:25 INFO - PROCESS | 2442 | __metrics Screen width/height:1600/1200 08:16:25 INFO - PROCESS | 2442 | colorDepth:24 08:16:25 INFO - PROCESS | 2442 | Browser inner width/height: 1024/689 08:16:25 INFO - PROCESS | 2442 | __metrics 08:16:25 INFO - PROCESS | 2442 | 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" 08:16:25 INFO - PROCESS | 2442 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:16:26 INFO - TEST-INFO | 2442: exit 0 08:16:26 INFO - Browser initialized. 08:16:26 INFO - Running cycle 1/1 for kraken test... 08:16:26 INFO - TEST-INFO | started process 2444 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmprelnXy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:38 INFO - PROCESS | 2444 | RSS: Main: 165142528 08:16:38 INFO - PROCESS | 2444 | 08:16:41 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57768/tests/kraken/driver.html?testName=audio-beat-detection) 08:16:42 INFO - PROCESS | 2444 | RSS: Main: 215752704 08:16:42 INFO - PROCESS | 2444 | 08:16:44 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57768/tests/kraken/driver.html?testName=audio-dft) 08:16:45 INFO - PROCESS | 2444 | RSS: Main: 231677952 08:16:45 INFO - PROCESS | 2444 | 08:16:48 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57768/tests/kraken/driver.html?testName=audio-fft) 08:16:49 INFO - PROCESS | 2444 | RSS: Main: 328081408 08:16:49 INFO - PROCESS | 2444 | 08:16:51 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57768/tests/kraken/driver.html?testName=audio-oscillator) 08:16:51 INFO - PROCESS | 2444 | RSS: Main: 229470208 08:16:51 INFO - PROCESS | 2444 | 08:16:53 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:16:54 INFO - PROCESS | 2444 | RSS: Main: 232050688 08:16:54 INFO - PROCESS | 2444 | 08:16:57 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57768/tests/kraken/driver.html?testName=imaging-darkroom) 08:16:57 INFO - PROCESS | 2444 | RSS: Main: 272830464 08:16:57 INFO - PROCESS | 2444 | 08:17:01 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57768/tests/kraken/driver.html?testName=imaging-desaturate) 08:17:01 INFO - PROCESS | 2444 | RSS: Main: 273620992 08:17:01 INFO - PROCESS | 2444 | 08:17:04 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57768/tests/kraken/driver.html?testName=json-parse-financial) 08:17:05 INFO - PROCESS | 2444 | RSS: Main: 269561856 08:17:05 INFO - PROCESS | 2444 | 08:17:06 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:17:07 INFO - PROCESS | 2444 | RSS: Main: 247930880 08:17:07 INFO - PROCESS | 2444 | 08:17:09 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:17:09 INFO - PROCESS | 2444 | RSS: Main: 254013440 08:17:09 INFO - PROCESS | 2444 | 08:17:12 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:17:12 INFO - PROCESS | 2444 | RSS: Main: 260632576 08:17:12 INFO - PROCESS | 2444 | 08:17:15 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:17:16 INFO - PROCESS | 2444 | RSS: Main: 273117184 08:17:16 INFO - PROCESS | 2444 | 08:17:19 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:17:19 INFO - PROCESS | 2444 | RSS: Main: 265863168 08:17:19 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | Cycle 1(1): loaded http://localhost:57768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57768/tests/kraken/driver.html?testName=ai-astar) 08:17:21 INFO - PROCESS | 2444 | RSS: Main: 310624256 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | __start_tp_report 08:17:21 INFO - PROCESS | 2444 | _x_x_mozilla_page_load 08:17:21 INFO - PROCESS | 2444 | _x_x_mozilla_page_load_details 08:17:21 INFO - PROCESS | 2444 | |i|pagename|runs| 08:17:21 INFO - PROCESS | 2444 | |0;ai-astar;105;117;116;116;115;116;115;115;116;116 08:17:21 INFO - PROCESS | 2444 | |1;audio-beat-detection;144;108;107;143;111;108;107;105;128;108 08:17:21 INFO - PROCESS | 2444 | |2;audio-dft;184;181;183;186;184;197;260;207;184;185 08:17:21 INFO - PROCESS | 2444 | |3;audio-fft;82;61;60;91;59;60;59;61;75;60 08:17:21 INFO - PROCESS | 2444 | |4;audio-oscillator;112;90;116;99;69;67;81;69;74;110 08:17:21 INFO - PROCESS | 2444 | |5;imaging-gaussian-blur;95;88;87;88;87;89;89;87;88;101 08:17:21 INFO - PROCESS | 2444 | |6;imaging-darkroom;93;93;93;93;93;94;93;93;122;149 08:17:21 INFO - PROCESS | 2444 | |7;imaging-desaturate;85;83;83;85;83;86;86;84;92;85 08:17:21 INFO - PROCESS | 2444 | |8;json-parse-financial;61;57;58;85;94;93;84;63;63;63 08:17:21 INFO - PROCESS | 2444 | |9;json-stringify-tinderbox;55;67;69;62;59;58;58;57;56;58 08:17:21 INFO - PROCESS | 2444 | |10;stanford-crypto-aes;83;78;83;76;83;82;81;83;80;71 08:17:21 INFO - PROCESS | 2444 | |11;stanford-crypto-ccm;134;134;134;133;136;141;130;139;196;147 08:17:21 INFO - PROCESS | 2444 | |12;stanford-crypto-pbkdf2;159;163;161;161;161;163;160;161;161;161 08:17:21 INFO - PROCESS | 2444 | |13;stanford-crypto-sha256-iterative;65;63;63;63;64;64;63;63;64;64 08:17:21 INFO - PROCESS | 2444 | __end_tp_report 08:17:21 INFO - PROCESS | 2444 | __start_cc_report 08:17:21 INFO - PROCESS | 2444 | _x_x_mozilla_cycle_collect,2090 08:17:21 INFO - PROCESS | 2444 | __end_cc_report 08:17:21 INFO - PROCESS | 2444 | __startTimestamp1496416641661__endTimestamp 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | ------- Summary: start ------- 08:17:21 INFO - PROCESS | 2444 | Number of tests: 14 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.00 stddev:3.47 (3.0%) stddev-sans-first:0.67 08:17:21 INFO - PROCESS | 2444 | Values: 105.0 117.0 116.0 116.0 115.0 116.0 115.0 115.0 116.0 116.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:109.50 stddev:15.45 (14.1%) stddev-sans-first:12.91 08:17:21 INFO - PROCESS | 2444 | Values: 144.0 108.0 107.0 143.0 111.0 108.0 107.0 105.0 128.0 108.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#2] audio-dft Cycles:10 Average:195.10 Median:185.50 stddev:24.17 (13.0%) stddev-sans-first:25.30 08:17:21 INFO - PROCESS | 2444 | Values: 184.0 181.0 183.0 186.0 184.0 197.0 260.0 207.0 184.0 185.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.00 stddev:11.60 (19.0%) stddev-sans-first:10.93 08:17:21 INFO - PROCESS | 2444 | Values: 82.0 61.0 60.0 91.0 59.0 60.0 59.0 61.0 75.0 60.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:71.50 stddev:19.36 (27.1%) stddev-sans-first:18.60 08:17:21 INFO - PROCESS | 2444 | Values: 112.0 90.0 116.0 99.0 69.0 67.0 81.0 69.0 74.0 110.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:88.00 stddev:4.56 (5.2%) stddev-sans-first:4.44 08:17:21 INFO - PROCESS | 2444 | Values: 95.0 88.0 87.0 88.0 87.0 89.0 89.0 87.0 88.0 101.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:93.00 stddev:18.97 (20.4%) stddev-sans-first:19.86 08:17:21 INFO - PROCESS | 2444 | Values: 93.0 93.0 93.0 93.0 93.0 94.0 93.0 93.0 122.0 149.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.00 stddev:2.66 (3.1%) stddev-sans-first:2.82 08:17:21 INFO - PROCESS | 2444 | Values: 85.0 83.0 83.0 85.0 83.0 86.0 86.0 84.0 92.0 85.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#8] json-parse-financial Cycles:10 Average:72.10 Median:73.50 stddev:14.99 (20.4%) stddev-sans-first:15.35 08:17:21 INFO - PROCESS | 2444 | Values: 61.0 57.0 58.0 85.0 94.0 93.0 84.0 63.0 63.0 63.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:58.50 stddev:4.68 (8.0%) stddev-sans-first:4.61 08:17:21 INFO - PROCESS | 2444 | Values: 55.0 67.0 69.0 62.0 59.0 58.0 58.0 57.0 56.0 58.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:82.50 stddev:3.97 (4.8%) stddev-sans-first:4.06 08:17:21 INFO - PROCESS | 2444 | Values: 83.0 78.0 83.0 76.0 83.0 82.0 81.0 83.0 80.0 71.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:137.50 stddev:19.44 (14.1%) stddev-sans-first:20.38 08:17:21 INFO - PROCESS | 2444 | Values: 134.0 134.0 134.0 133.0 136.0 141.0 130.0 139.0 196.0 147.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.10 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.00 08:17:21 INFO - PROCESS | 2444 | Values: 159.0 163.0 161.0 161.0 161.0 163.0 160.0 161.0 161.0 161.0 08:17:21 INFO - PROCESS | 2444 | 08:17:21 INFO - PROCESS | 2444 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 08:17:21 INFO - PROCESS | 2444 | Values: 65.0 63.0 63.0 63.0 64.0 64.0 63.0 63.0 64.0 64.0 08:17:21 INFO - PROCESS | 2444 | -------- Summary: end -------- 08:17:21 INFO - PROCESS | 2444 | 08:17:22 INFO - TEST-INFO | 2444: exit 0 08:17:22 INFO - TEST-OK | kraken | took 58991ms 08:17:22 INFO - SUITE-END | took 1177s 08:17:22 INFO - Completed test suite (00:19:37) 08:17:22 INFO - javascript benchmark 08:17:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7944.131250696806, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3565.3404120237756, 3566.5006899034133, 3573.0710015396, 3580.713542916567, 3601.8739344122946, 6188.133661287423, 6192.225971753531, 6199.783007594735, 6211.875762484748, 6230.90959082161, 6124.785632502863, 6223.968880155604, 6229.563930524865, 6237.781677641284, 6395.296517383096, 5508.256385387981, 5533.944660553383, 5555.2222688823595, 5600.915986260209, 5638.633488823213, 5443.319585051869, 5456.7544460499175, 5655.830325090234, 5658.236138121362, 5669.177969194464, 5477.863053423656, 5523.0, 5533.418991005927, 5571.749271282782, 5576.888462230753, 5602.439756024406, 5609.298837645294, 5627.549796016329, 5630.183623374588, 5638.802641907513, 5430.619856610039, 5498.285222921035, 5569.415211402826, 5570.637908535932, 5576.33084029915, 6030.396960303979, 6097.969510152465, 6113.419225173601, 6114.633122012693, 6136.7948077883175, 6439.710213040425, 6457.967710161466, 6503.902441463382, 6518.706658200393, 6525.804225873184, 6562.639054851981, 6606.075149479077, 6606.471482281406, 6628.966855165741, 6629.138212032453, 6133.907991380088, 6140.1492820672065, 6189.164462797555, 6192.752289908353, 6196.783112591038, 6531.477481801445, 6613.3056029117215, 6620.139381880421, 6700.195976482853, 6826.385625293748, 6446.774362897276, 6462.256840463407, 6465.741370345134, 6478.805635830932, 6481.0, 5271.947280527223, 6515.902261466082, 6603.504737144734, 6605.504587155982, 6609.0, 6295.811125666238, 6325.7785977491485, 6414.775482858171, 6451.161349024691, 6489.318621544764, 6536.967315163489, 6547.050677651714, 6550.639714815731, 6556.836079097965, 6566.080748695186, 5329.333833270841, 5330.25722461087, 5333.919991200135, 5336.279602253724, 5338.626296159309, 4651.0, 4736.076465089343, 4740.170470167707, 4765.261384485341, 4766.237402015661, 6132.814448934826, 6137.726793749422, 6151.66165860873, 6153.569250152446, 6160.199174107337, 6593.769218077345, 6603.801885943333, 6604.0093985902495, 6605.0, 6619.834504137435, 6184.165137706442, 6219.657918814509, 6230.283517395467, 6249.6875156242, 6254.0, 5633.887322253532, 5685.516731077904, 5711.771529138788, 5726.513246374105, 5742.626729262584, 6546.0, 6636.13730215069, 6637.435817955527, 6657.434118100015, 6662.100616416916, 6433.903491447726, 6434.517411194086, 6452.22573291208, 6486.027095935458, 6564.015397690194, 6760.628165450751, 6833.760818371333, 6843.657817109025, 6844.041834143124, 6859.897101543381, 6522.184726909137, 6528.37980391862, 6536.411722944958, 6540.934590654223, 6566.343365663395, 6359.586626869239, 6526.4452521536205, 6563.507736919845, 6603.9009414859775, 6627.569208001464, 6436.195475565554, 6474.5791523550815, 6481.773137940149, 6484.902726459012, 6503.154589903283, 6276.9372306276355, 6443.323451037761, 6464.127342808849, 6467.902981455188, 6468.6442245676935, 6176.7838125665385, 6211.81364559064, 6266.780662676784, 6341.143945567289, 6361.491080713439, 6346.873062538816, 6428.4214420702365, 6443.774467893789, 6460.644664543495, 6472.385123413268, 6316.494680425647, 6329.050642403677, 6340.71466784005, 6341.143945567289, 6346.587471814318], "value": 6059.000697460713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.371353209176, 785.1285073568338, 834.2200042959832, 902.9774255643608, 913.2100732866073, 29115.854420727894, 41433.0, 42017.99999999996, 42079.78960105203, 42098.57901420985, 41241.79379103108, 41371.17257654845, 41408.58591414085, 42934.57065429345, 42972.78513607435, 7974.3221826144845, 9649.855252171206, 9889.060539248796, 9897.059779320989, 9903.306768526207, 8039.115697273295, 8117.512949223036, 8131.105578386373, 8133.796655083611, 11826.704332391673, 12011.339376334341, 12067.215630984001, 12098.939505302505, 12099.395030248452, 12110.757784844298, 12026.51893924242, 12038.39808009596, 12051.457684404222, 12062.0, 12067.517299308018, 11564.190506664538, 11659.533618655243, 11670.066394688489, 11678.416079196091, 11684.00685941696, 40555.0, 40738.0, 40846.79576602128, 40849.978750531176, 40926.181476370606, 3971.5035620547433, 4000.739951903117, 4006.1386801837452, 4027.1744292419985, 4055.047063939972, 28699.56950645742, 29557.408851823056, 30183.396332073455, 31168.688313117033, 31263.37473250545, 3233.321002589459, 3261.673832616743, 3281.0156952914267, 3292.7530435217213, 3295.6045274567205, 361787.0, 361931.3806861924, 361952.1902390497, 362698.0, 362806.0, 53026.469735302926, 53417.732911335974, 53431.46568534343, 53559.0, 53569.1964620531, 56700.43299567034, 56866.715666421405, 56917.43082569204, 56942.14586781202, 56950.867702275944, 15524.689506209812, 15899.602509937344, 15914.204289785464, 15916.363345466052, 15922.920385397998, 13513.189208647513, 13531.797023044663, 13546.0, 13552.796708049387, 13573.932130339284, 9540.713778586654, 11283.830742538868, 11413.828792568118, 12972.286524241068, 13039.0, 5406.78372865081, 5487.396386397493, 5496.120620700669, 5499.477549632778, 5508.476694713996, 1143.3197247637643, 1162.1748558523375, 1174.2308787744041, 1176.264834478451, 1223.7613665335352, 27453.86273068662, 28078.0, 28308.150755477367, 28833.279168020967, 28865.7113428863, 1070.8875568065241, 1281.6859869331886, 1282.2819221236139, 1329.5014369611395, 1336.4654138344545, 14446.10441847338, 14577.41690332396, 14743.48397806093, 14816.036957597722, 14851.702965940836, 447305.7634711806, 447327.0, 448206.7589662031, 448263.0, 448484.0, 47624.76187619109, 48188.518114819104, 50244.995100097796, 59019.40980590225, 59129.0, 1453.454954392103, 1490.560284716033, 1490.970180596382, 1500.5723368839829, 1502.8346881842988, 29804.0, 31225.8438707805, 31294.530582040832, 31618.27050643926, 32018.679813202503, 49336.259956099966, 49636.751816240685, 49681.75795605139, 49723.75138124286, 49724.25413618871, 417494.0, 418863.0, 467753.0, 476521.0, 571579.0, 35330.64669353273, 35507.82246088753, 35616.82191589026, 35797.82101089478, 36437.63562364342, 44990.875228119556, 45252.54747452615, 45445.77277113593, 45666.77166614146, 45704.77147614241, 29849.806007759445, 30430.391392172474, 30643.54034689526, 31341.8432907834, 31586.578603962418, 23424.76575234294, 30350.0, 31217.375652487277, 31317.8434107828, 31410.528842067844, 36508.087297817765, 37014.814925925195, 37044.629553704115, 37428.43857342088, 37479.25041499209, 29805.0, 30339.089827305215, 31113.53329700011, 31841.681583183872, 31911.361772764878, 23240.070397184598, 24517.0, 24808.751912481366, 24838.50322993566, 25213.87393063096], "value": 19567.184010503155, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.735790131168, 427.2693693783629, 427.9293916503777, 433.5296203619073, 446.8145719526396, 19746.70379944302, 21034.579308413842, 22048.44878878029, 22212.555748885014, 22282.88858555709, 22766.975486103125, 23374.766252337515, 23627.409314767097, 24170.39574010646, 24487.63268550969, 6893.20728116268, 6919.238883722786, 7060.752873649423, 7173.10336207974, 7183.461240406964, 5971.552133589977, 6799.082123913283, 6857.691403886837, 6904.585724856524, 6919.827004324907, 6578.8684226315445, 6848.417884479801, 6912.0, 6926.27274136216, 6941.479389045841, 6816.9318306816795, 6824.249332573413, 6874.793756187322, 6909.654517274142, 6939.479539034567, 6810.046593476867, 6875.656217189121, 6876.553024053421, 6882.5870447773295, 6886.48351373649, 27260.727392726018, 27642.447151056866, 27752.306192345353, 27768.444631107468, 28056.719432805614, 2995.1164406500134, 3067.2331917020747, 3088.0, 3093.8298393588343, 3131.0, 15881.36474541017, 16313.673726525521, 16719.913205641595, 17326.65346693059, 17373.826261737348, 2088.2482306369693, 2097.517570958688, 2112.704221409004, 2138.8075073243326, 2140.176032227588, 140696.0, 145840.0, 149907.50092499153, 151185.0, 151285.0, 22186.223482178368, 22941.0, 23133.421664458525, 23360.78072583102, 23414.531709365718, 39424.802875985435, 39481.802590987434, 40697.18605627871, 40744.18511629751, 40835.0, 10603.734906627242, 10800.217964035846, 10983.505742241618, 11007.134528350407, 11233.438328083563, 9118.40730352525, 9133.40632858862, 9144.54277286133, 9273.58268877902, 9335.486548239776, 9565.856512152322, 9714.659986900424, 9802.705918822447, 9816.165625921876, 9819.558119884623, 3615.9638403616154, 3851.5763266040713, 3955.367141257385, 3996.3406038003986, 4041.8383264669083, 902.801383695586, 1025.8974102589682, 1046.424466543398, 1066.8826429092794, 1086.9076128529164, 20731.92680731945, 22056.33830985073, 22191.779452129966, 22196.223132190295, 22238.332850014525, 1030.2891005206534, 1032.210359075308, 1044.9373037617768, 1056.0759335581367, 1060.3002018667642, 10797.730056748487, 10885.0, 11599.942000289944, 11619.6514104577, 11633.243839150427, 746008.0, 756276.0, 758964.0, 773538.0, 774807.1259643666, 153503.0, 156098.4390156084, 159764.2011789957, 160141.0, 161282.0, 1237.8947789437818, 1262.217425196384, 1271.110222844013, 1296.695276609996, 1333.9933300333437, 18105.366312179536, 18623.90688046551, 20997.265095721577, 21171.848838197096, 21266.680999785323, 64862.35137648563, 65111.69776604536, 65750.0, 66487.33512665005, 67412.662936685, 84804.15195847962, 84943.0269940549, 85636.0, 92568.0, 95732.52133739287, 7821.9440375548575, 10469.31949423285, 11439.656810295372, 11744.765104697688, 12162.0, 13114.278714670785, 13146.868531314565, 13478.932605336911, 13573.04988650785, 13687.726245475234, 8975.596098175729, 11110.666679999613, 11153.330800152016, 11614.419279035845, 12045.939770301093, 10785.298955567863, 10927.726806829893, 11119.388433636228, 11311.773764524827, 11328.830067549157, 11742.236754610924, 11784.75919122214, 11913.761724765283, 12059.82923073855, 12159.148859579744, 11856.70358241051, 11908.464119114487, 11949.402529873645, 12024.218425802295, 12201.572944946884, 11573.421328933351, 11582.536698531965, 11703.648890533297, 11855.940720296343, 11867.703307417383], "value": 12015.489112261488, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.1002968130076, 1611.210506851643, 1635.6401591649833, 1650.796187090234, 1671.6155284284614, 9597.808043839119, 10036.849447258288, 11279.774404511905, 11610.477528511215, 11821.408929553509, 11382.0, 11486.483108260127, 11489.827652585218, 11493.19547631664, 11519.884801151986, 2427.211156374181, 2471.5674756917556, 2471.6045432730766, 2475.678161838958, 2476.170482888235, 2103.1902717453813, 2169.425102347879, 2178.455386153462, 2370.6207006878903, 2401.135591187171, 2473.0973194783955, 2484.167803785725, 2495.15164843953, 2501.187114187896, 2503.123906632682, 2422.079609748302, 2445.6698345723366, 2458.6803715516867, 2458.766417190364, 2460.114358830828, 2406.5307265083316, 2434.476587533671, 2436.1108195508514, 2443.474652949624, 2446.8654224017673, 11555.075593952464, 11602.419879006016, 11696.590619328368, 11734.11994100446, 11737.295762254293, 5932.703364831741, 6112.877742445126, 6131.264248290189, 6134.263888333384, 6140.907886381708, 3554.3779838528153, 3668.7982160981137, 3720.7581507201944, 3749.3438648236724, 3808.6000969898314, 1217.5799349224503, 1222.9902358836232, 1280.2638482872292, 1281.116029939339, 1282.1281528560576, 16090.356385744557, 16196.0, 16225.51323460298, 16254.268557914804, 16277.755833662504, 4751.95248047522, 4936.703797772145, 5164.896702066013, 5185.22221666753, 5230.476952304739, 10309.0, 12230.266184028987, 12230.510779568896, 12254.509819607294, 12257.38713064361, 6072.119542666289, 6073.757049717962, 6074.271087469444, 6082.361352058058, 6096.329403765582, 5366.294242985269, 5480.739429931058, 5557.694326811983, 5560.388357280696, 5586.860328491739, 3582.910427239288, 3668.0, 3802.220544788367, 3830.71269654777, 3856.768593884376, 977.9364341317792, 997.6558087459815, 1058.666520046183, 1061.2624226162743, 1091.3124731419155, 751.9962400187964, 806.9475484093515, 823.2056065896438, 861.7716305179133, 887.6316328723508, 6692.364225398579, 6708.798736037927, 6764.019217213477, 6844.863102737917, 6873.278305777922, 1030.6289735695143, 1041.770810421706, 1042.7966546523355, 1056.2025670618634, 1058.0953284941418, 3675.7610755301353, 4107.116969851496, 4243.321068629005, 4275.957240427618, 4300.376445415397, 13747.450101996008, 14257.215853128178, 14258.358373873201, 14307.213103279211, 14349.0, 13322.533711320057, 14133.222672752892, 14155.29223538819, 14167.0, 14186.361613727408, 10278.434686092187, 10559.577616895393, 10562.947185264178, 10592.57629694819, 10610.734731631772, 5922.822315330547, 6612.70242839083, 6633.436157926631, 6845.657717114224, 7020.719171233093, 12277.0, 12407.007439405006, 12674.0, 12706.428210730724, 12727.363631818187, 12232.694182645506, 12629.557965471164, 12984.896283815982, 13017.674558136123, 13112.147710398793, 9232.861507077265, 9327.533623318943, 9521.381110227812, 9533.046695330411, 9546.856797148188, 10343.37939723619, 10349.706286714161, 10447.895521044693, 10448.529816158443, 10480.266381353233, 8713.564321783759, 8962.551872406484, 8999.370044097112, 9033.728988130366, 9046.185843274137, 8736.650533978569, 8762.561871906251, 8804.987426445785, 8827.646894124164, 8938.910610893809, 7874.05511338643, 8053.516788992679, 8054.718084867001, 8079.555624440713, 8091.959540202261, 5823.213866128188, 5838.036723940502, 5894.086416605433, 6036.486898613667, 6090.451859332681, 5876.41235876409, 5946.732397042057, 6077.4834139098675, 6206.782762603287, 6326.240851097898], "value": 5247.1751714868815, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.4400383371271, 395.3180763183507, 396.97221194516396, 399.4527497328659, 404.91901619676054, 4443.933340999887, 5256.316678831756, 5266.841994740154, 5371.355437347524, 5375.381831089421, 4581.717119206627, 5121.231815227718, 5144.04217574895, 5155.355580552431, 5287.682739035651, 4137.400076988834, 4238.385434112052, 4305.536117719975, 4313.719608225471, 4346.631086058106, 3654.524911761467, 3958.4853968983994, 4292.420523229355, 4307.784610769449, 4328.653707703376, 3513.736469764765, 3619.0952261934517, 4276.42268293781, 4289.2922667759785, 4388.539203383647, 3774.301754175486, 4073.944541593805, 4344.23975804233, 4370.97814510927, 4402.697976517596, 3569.0, 3731.421629647409, 3897.3569361055393, 4015.31739604268, 4044.1911617676583, 4555.886102847456, 4890.9458027628125, 5086.949130508685, 5138.152204886189, 5168.457311982263, 4726.7163970161555, 5040.816122618218, 5050.84847454577, 5055.306472331773, 5067.163917953533, 5385.380681221632, 5557.055300598908, 5580.581456390787, 5585.413531579207, 5591.524720398771, 4060.7360521566, 4466.397036400077, 4488.147252022105, 4491.505934347219, 4512.481064677571, 5096.241796580174, 5356.303680521546, 5672.695280085603, 5984.371640977722, 6053.728716969442, 3616.8553257869917, 4464.129494748493, 4579.816807327737, 4605.769711514411, 4625.496713568104, 4032.4354590357375, 4657.836975705834, 4720.811167553259, 4721.622270218374, 4759.571638552547, 4323.48118225815, 4482.565579014749, 4653.488116307203, 4654.371659825948, 4810.368172030165, 5037.496250374933, 5091.937765205098, 5597.6641401516035, 5608.551315894719, 5616.876624675081, 1389.2081513537187, 1488.4864721671006, 1544.5752418084892, 1766.3994241957796, 1768.6816373052975, 1273.541525050981, 1330.700592366729, 1340.3633274194679, 1356.2811709793825, 1436.9294875317955, 4437.33439984005, 5278.498542638443, 5283.920741188886, 5292.841214763564, 5300.6024548158275, 5275.417374787625, 5489.615726899079, 5733.913991290134, 5735.677050794435, 5815.709214539256, 4828.8792780180775, 4957.950420495749, 5279.44565820591, 5288.471152884681, 5368.282149712055, 2434.6104623260194, 2554.7737086198727, 2585.961210581843, 2780.9270755814937, 2880.1071667783053, 4809.639277054235, 5351.224072509464, 5544.417836127229, 5577.549837042398, 5772.769089236467, 4696.788644511005, 4961.337951786119, 5086.863517322733, 5408.810691625853, 5830.970845145832, 5330.493603107699, 5649.0, 5801.883962320814, 5856.297244330707, 6026.849328766816, 4403.156527819373, 4899.828506002273, 5242.816501422432, 5425.376081750649, 5868.471837534642, 5116.002379535955, 5485.9725701371235, 5515.503604675599, 5719.685417302088, 5734.279716085074, 5699.917015766991, 5832.24180856486, 5835.883282334415, 5883.735231914659, 5955.851103722442, 3961.0, 4231.87304380869, 4403.977980110207, 4616.907661846677, 4632.189366860854, 4551.908961820811, 5257.631965762513, 5280.6303558750515, 5604.243427137284, 5790.392008839011, 4990.950090499048, 5169.2941329361975, 5386.380566234933, 5606.046972014686, 5615.18579805934, 4774.618030557616, 4788.473267940454, 5452.59105567092, 5521.613487055867, 5538.889222215614, 4476.731396116244, 4928.20528193282, 5024.382444639853, 5264.209968716586, 5386.245925570345, 4759.6668233223345, 4943.4562198157455, 5300.337457817773, 5585.425327790299, 5644.745986430703, 4891.559759621651, 5385.2999110117, 5400.2709634200455, 5403.865757860353, 5412.539934105487], "value": 4242.651362435966, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.0946091038476, 750.9924900750995, 799.6241766369805, 867.2888231650047, 870.9390342676011, 15858.048517088968, 17295.30818767248, 17325.74011389827, 17345.479635610936, 17369.21838517268, 15843.920780396082, 15920.0, 16042.0, 16065.277062532185, 16069.955452895554, 858.635080090962, 996.476849653932, 1005.6480231918842, 1017.3946501831399, 1039.9792004159913, 1012.2256473797551, 1021.1473419694564, 1021.6832781837654, 1029.521272608237, 1031.2265800649513, 1070.138538476526, 1070.7911957168355, 1071.2929466552077, 1071.3197119828887, 1071.5767271927584, 1067.359584249452, 1067.7063807452935, 1068.9679309620685, 1069.844872493492, 1070.983935240968, 794.8450052239758, 794.996025019877, 795.2762985683, 795.6976348987406, 795.7533164718956, 15165.848341516665, 16895.239714212767, 17286.0, 17306.826931730648, 17407.564810879703, 14663.266836658231, 14789.260536973215, 14917.179555124463, 14947.476838310715, 15143.848561514355, 11768.58809941648, 11885.692573816874, 11912.510029252999, 12049.096317776202, 12206.938965305206, 3610.3862343401684, 3625.583057948343, 3669.284489524544, 3670.3576874046935, 3789.5831458539537, 107590.46204769005, 108454.45772771165, 112532.0, 112543.0, 112566.0, 11478.892202132314, 11487.597934072268, 11544.422778861024, 11703.0, 11733.178677492664, 23335.7666423337, 23801.40496487567, 23963.801809909433, 24064.51870962571, 24071.879640602034, 8126.512409255463, 8137.8372432552205, 8205.507669539847, 8217.712380066669, 8236.382271329554, 4437.755923424243, 4503.256962601135, 4515.7290562565695, 4639.141758774637, 4779.330893674888, 3640.453931910182, 3703.000189948699, 3706.777593344408, 3711.183539621279, 3743.9438408423894, 542.6499907559602, 677.3159109299643, 684.2302409788988, 685.2564967010849, 685.2941470285614, 486.5815398757066, 725.760499035317, 745.1542499263375, 750.8010377250033, 781.6716978868775, 9069.54652267384, 9758.170555502862, 10434.530446129942, 10548.314359566604, 10917.072048875776, 589.1545632018068, 598.5780025082306, 620.6307247187916, 663.141232104426, 731.5756861020595, 4450.332450132506, 4755.690880092783, 4881.365422495124, 5180.766865491062, 5183.092958732207, 98248.50875745718, 99718.0, 100378.0, 105211.0, 108535.45732271446, 83353.0, 86716.0, 87753.24493510222, 88328.0, 88972.5551372252, 70830.58338833298, 75272.0, 80249.5987520082, 80698.0, 84268.0, 10180.440075795606, 11061.336319820837, 11292.153088518558, 11452.94273528627, 11534.94232528832, 45551.0, 48475.75762121308, 48926.0, 49042.773930652016, 49978.0, 60694.39305607064, 60950.0, 62116.0, 62358.688206558676, 63325.68337158285, 12872.613821585368, 12888.162269452456, 12944.676383090498, 13140.0, 13194.472221111431, 12975.740485190054, 15165.165915874733, 15269.694606108038, 16455.67088658244, 16669.83330166683, 13795.448182073007, 14897.47858824936, 14943.402263909322, 15008.774868376764, 15413.0, 13716.45134194661, 14247.216403097515, 14600.634984125058, 14642.707145857235, 14947.925260373628, 12665.430055647334, 13880.305984700926, 14255.429782808571, 14851.628709282355, 15023.924880375529, 10133.158503816876, 10184.490775461049, 10309.690709278733, 10383.2212584055, 10667.679969600924, 11385.601503947322, 11487.25332853362, 11742.530298787953, 11826.526938922347, 11827.526898923947], "value": 7925.065215298463, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1438.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 116.0, 116.0, 115.0, 116.0, 115.0, 115.0, 116.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 107.0, 143.0, 111.0, 108.0, 107.0, 105.0, 128.0, 108.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 183.0, 186.0, 184.0, 197.0, 260.0, 207.0, 184.0, 185.0], "value": 195.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 60.0, 91.0, 59.0, 60.0, 59.0, 61.0, 75.0, 60.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 90.0, 116.0, 99.0, 69.0, 67.0, 81.0, 69.0, 74.0, 110.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 88.0, 87.0, 88.0, 87.0, 89.0, 89.0, 87.0, 88.0, 101.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 122.0, 149.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 85.0, 83.0, 86.0, 86.0, 84.0, 92.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 57.0, 58.0, 85.0, 94.0, 93.0, 84.0, 63.0, 63.0, 63.0], "value": 72.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 67.0, 69.0, 62.0, 59.0, 58.0, 58.0, 57.0, 56.0, 58.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 83.0, 76.0, 83.0, 82.0, 81.0, 83.0, 80.0, 71.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 134.0, 133.0, 136.0, 141.0, 130.0, 139.0, 196.0, 147.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 163.0, 161.0, 161.0, 161.0, 163.0, 160.0, 161.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 63.0, 64.0, 64.0], "value": 63.6, "unit": "score"}]}]} 08:17:22 INFO - Return code: 0 08:17:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:17:23 INFO - # TBPL SUCCESS # 08:17:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:23 INFO - 2017-06-02 08:17:23.022 screenresolution[2448:35843] starting screenresolution argv=screenresolution get 08:17:23 INFO - 2017-06-02 08:17:23.025 screenresolution[2448:35843] Display 0: 1600x1200x32@60 08:17:23 INFO - 2017-06-02 08:17:23.031 screenresolution[2449:35846] starting screenresolution argv=screenresolution list 08:17:23 INFO - Available Modes on Display 0 08:17:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:23 INFO - Intel Iris: 08:17:23 INFO - Chipset Model: Intel Iris 08:17:23 INFO - Type: GPU 08:17:23 INFO - Bus: Built-In 08:17:23 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:23 INFO - Vendor: Intel (0x8086) 08:17:23 INFO - Device ID: 0x0a2e 08:17:23 INFO - Revision ID: 0x0009 08:17:23 INFO - Displays: 08:17:23 INFO - Display: 08:17:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:23 INFO - Display Serial Number: 1600x1200 60 08:17:23 INFO - Main Display: Yes 08:17:23 INFO - Mirror: Off 08:17:23 INFO - Online: Yes 08:17:23 INFO - Rotation: Supported 08:17:23 INFO - Return code: 0 08:17:23 INFO - Running post-action listener: _resource_record_post_action 08:17:23 INFO - [mozharness: 2017-06-02 15:17:23.265370Z] Finished run-tests step (success) 08:17:23 INFO - Running post-run listener: _resource_record_post_run 08:17:23 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 1699840; Write bytes: 427681280; Read time: 22; Write time: 1469 08:17:23 INFO - TinderboxPrint: CPU usage
53.7% 08:17:23 INFO - TinderboxPrint: I/O read bytes / time
1,699,840 / 22 08:17:23 INFO - TinderboxPrint: I/O write bytes / time
427,681,280 / 1,469 08:17:23 INFO - TinderboxPrint: CPU idle
2,208.7 (46.3%) 08:17:23 INFO - TinderboxPrint: CPU system
947.7 (19.9%) 08:17:23 INFO - TinderboxPrint: CPU user
1,617.4 (33.9%) 08:17:23 INFO - TinderboxPrint: Swap in / out
123,449,344 / 0 08:17:23 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 108644864; Write bytes: 105702912; Read time: 9808; Write time: 252 08:17:23 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1699840; Write bytes: 301084672; Read time: 22; Write time: 1165 08:17:23 INFO - Running post-run listener: _upload_blobber_files 08:17:23 INFO - Blob upload gear active. 08:17:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:17:23 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 08:17:23 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'] 08:17:23 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 08:17:24 INFO - (blobuploader) - INFO - Open directory for files ... 08:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:24 INFO - (blobuploader) - INFO - Done attempting. 08:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:17:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:25 INFO - (blobuploader) - INFO - Done attempting. 08:17:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:17:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:17:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:26 INFO - (blobuploader) - INFO - Done attempting. 08:17:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:17:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:17:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:29 INFO - (blobuploader) - INFO - Done attempting. 08:17:29 INFO - (blobuploader) - INFO - Iteration through files over. 08:17:29 INFO - Return code: 0 08:17:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:17:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:17:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba56ef586b52fac054d9b25b76725071eaab67cacfc6fbb9195b97705283c7b62db519e29bc5b1f0e4b11fa6b2547dbd883a6a4df1f30c05508af6cf8cd46cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/50667e7eaedb6aee1ac26b07436ac45da6ce1d67fe52604340e83f1fe724c8940436bd6776a65922a24331c6a50c02b6b81ca74751004f9f5a8880f2faa8c680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dabd1d8ea133a813b778a55fe053e8fea59d2b91b26fd3a8091b70eda634cf0036b8e32d8cea9c6d8aeea9e67f9bc4437b949a2ac0a5c954a311218d20560805", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/738084680ea5e846bc8300ffa3ac80e772748b7a63f27190d0a539b8e287a9162e6becccadaeb1280036a255b00b2a0d88984512c234c6b45faa022e0209e517"} 08:17:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:17:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:17:29 INFO - Contents: 08:17:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba56ef586b52fac054d9b25b76725071eaab67cacfc6fbb9195b97705283c7b62db519e29bc5b1f0e4b11fa6b2547dbd883a6a4df1f30c05508af6cf8cd46cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/50667e7eaedb6aee1ac26b07436ac45da6ce1d67fe52604340e83f1fe724c8940436bd6776a65922a24331c6a50c02b6b81ca74751004f9f5a8880f2faa8c680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dabd1d8ea133a813b778a55fe053e8fea59d2b91b26fd3a8091b70eda634cf0036b8e32d8cea9c6d8aeea9e67f9bc4437b949a2ac0a5c954a311218d20560805", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/738084680ea5e846bc8300ffa3ac80e772748b7a63f27190d0a539b8e287a9162e6becccadaeb1280036a255b00b2a0d88984512c234c6b45faa022e0209e517"} 08:17:29 INFO - Running post-run listener: copy_logs_to_upload_dir 08:17:29 INFO - Copying logs to upload dir... 08:17:29 INFO - mkdir: /builds/slave/test/build/upload/logs 08:17:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.337276 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-06-02 08:17:29.236254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 08:17:29.240462) ========= 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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/ba56ef586b52fac054d9b25b76725071eaab67cacfc6fbb9195b97705283c7b62db519e29bc5b1f0e4b11fa6b2547dbd883a6a4df1f30c05508af6cf8cd46cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/50667e7eaedb6aee1ac26b07436ac45da6ce1d67fe52604340e83f1fe724c8940436bd6776a65922a24331c6a50c02b6b81ca74751004f9f5a8880f2faa8c680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dabd1d8ea133a813b778a55fe053e8fea59d2b91b26fd3a8091b70eda634cf0036b8e32d8cea9c6d8aeea9e67f9bc4437b949a2ac0a5c954a311218d20560805", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/738084680ea5e846bc8300ffa3ac80e772748b7a63f27190d0a539b8e287a9162e6becccadaeb1280036a255b00b2a0d88984512c234c6b45faa022e0209e517"} build_url:https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011210 build_url: 'https://queue.taskcluster.net/v1/task/YfkFZNrVTlC-TOYBlCrK7g/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/ba56ef586b52fac054d9b25b76725071eaab67cacfc6fbb9195b97705283c7b62db519e29bc5b1f0e4b11fa6b2547dbd883a6a4df1f30c05508af6cf8cd46cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/50667e7eaedb6aee1ac26b07436ac45da6ce1d67fe52604340e83f1fe724c8940436bd6776a65922a24331c6a50c02b6b81ca74751004f9f5a8880f2faa8c680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dabd1d8ea133a813b778a55fe053e8fea59d2b91b26fd3a8091b70eda634cf0036b8e32d8cea9c6d8aeea9e67f9bc4437b949a2ac0a5c954a311218d20560805", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/738084680ea5e846bc8300ffa3ac80e772748b7a63f27190d0a539b8e287a9162e6becccadaeb1280036a255b00b2a0d88984512c234c6b45faa022e0209e517"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 08:17:29.275292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 08:17:29.275840) ========= 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.12Lx8Jk2I5/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.fkFr0sQMl0/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005299 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 08:17:29.342730) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 08:17:29.343102) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 08:17:29.343539) ========= ========= Total master_lag: 0.23 =========