builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0117 starttime: 1496698019.98 results: success (0) buildid: 20170605125726 builduid: 208641a058e84fe3b3883ceef091d1e9 revision: 97767088522e789dd04cf06b24478f052946d1c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 14:26:59.985119) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 14:26:59.985530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 14:26:59.985819) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:00.011681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:00.012095) ========= 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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-05 14:27:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.8M=0s 2017-06-05 14:27:00 (54.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.359921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:00.396982) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:00.397475) ========= 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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.028575 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:00.447267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 14:27:00.447672) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 97767088522e789dd04cf06b24478f052946d1c9 --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 97767088522e789dd04cf06b24478f052946d1c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eeyvuUPuMF/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.MLoMOcG1SE/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-05 14:27:00,545 truncating revision to first 12 chars 2017-06-05 14:27:00,545 Setting DEBUG logging. 2017-06-05 14:27:00,545 attempt 1/10 2017-06-05 14:27:00,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/97767088522e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 14:27:01,991 unpacking tar archive at: mozilla-beta-97767088522e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.730157 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 14:27:02.199928) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:02.200245) ========= script_repo_revision: 97767088522e789dd04cf06b24478f052946d1c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:02.200624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:02.200915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 14:27:02.219359) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-05 14:27:02.219665) ========= /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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:02 INFO - MultiFileLogger online at 20170605 14:27:02 in /builds/slave/test 14:27:02 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 14:27:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:27:02 INFO - {'append_to_log': False, 14:27:02 INFO - 'base_work_dir': '/builds/slave/test', 14:27:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:27:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:27:02 INFO - 'branch': 'Mozilla-Beta', 14:27:02 INFO - 'buildbot_json_path': 'buildprops.json', 14:27:02 INFO - 'config_files': ('talos/mac_config.py',), 14:27:02 INFO - 'default_actions': ('clobber', 14:27:02 INFO - 'read-buildbot-config', 14:27:02 INFO - 'download-and-extract', 14:27:02 INFO - 'populate-webroot', 14:27:02 INFO - 'create-virtualenv', 14:27:02 INFO - 'install', 14:27:02 INFO - 'run-tests'), 14:27:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:27:02 INFO - 'download_minidump_stackwalk': True, 14:27:02 INFO - 'download_symbols': 'ondemand', 14:27:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:27:02 INFO - 'tooltool.py': '/tools/tooltool.py', 14:27:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:27:02 INFO - '/tools/misc-python/virtualenv.py')}, 14:27:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:27:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:27:02 INFO - 'gecko_profile': False, 14:27:02 INFO - 'gecko_profile_interval': 0, 14:27:02 INFO - 'installer_path': 'installer.exe', 14:27:02 INFO - 'log_level': 'info', 14:27:02 INFO - 'log_name': 'talos', 14:27:02 INFO - 'log_to_console': True, 14:27:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:27:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:27:02 INFO - 'opt_config_files': (), 14:27:02 INFO - 'pip_index': False, 14:27:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:27:02 INFO - 'cmd': ('bash', 14:27:02 INFO - '-c', 14:27:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:27:02 INFO - 'enabled': True, 14:27:02 INFO - 'halt_on_failure': False, 14:27:02 INFO - 'name': 'check_screen_resolution'},), 14:27:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:27:02 INFO - 'cmd': ('bash', 14:27:02 INFO - '-c', 14:27:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:27:02 INFO - 'enabled': True, 14:27:02 INFO - 'halt_on_failure': False, 14:27:02 INFO - 'name': 'check_screen_resolution'},), 14:27:02 INFO - 'run_cmd_checks_enabled': True, 14:27:02 INFO - 'suite': 'dromaeojs', 14:27:02 INFO - 'system_bits': '32', 14:27:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:27:02 INFO - 'title': 't-yosemite-r7-0117', 14:27:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:27:02 INFO - 'use_talos_json': True, 14:27:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:27:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:27:02 INFO - 'work_dir': 'build'} 14:27:02 INFO - [mozharness: 2017-06-05 21:27:02.431485Z] Running clobber step. 14:27:02 INFO - Running pre-action listener: _resource_record_pre_action 14:27:02 INFO - Running main action method: clobber 14:27:02 INFO - rmtree: /builds/slave/test/build 14:27:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:27:04 INFO - Running post-action listener: _resource_record_post_action 14:27:04 INFO - [mozharness: 2017-06-05 21:27:04.976760Z] Finished clobber step (success) 14:27:04 INFO - [mozharness: 2017-06-05 21:27:04.976885Z] Running read-buildbot-config step. 14:27:04 INFO - Running pre-action listener: _resource_record_pre_action 14:27:04 INFO - Running main action method: read_buildbot_config 14:27:04 INFO - Using buildbot properties: 14:27:04 INFO - { 14:27:04 INFO - "project": "", 14:27:04 INFO - "product": "firefox", 14:27:04 INFO - "script_repo_revision": "production", 14:27:04 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 14:27:04 INFO - "repository": "", 14:27:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 14:27:04 INFO - "buildid": "20170605125726", 14:27:04 INFO - "pgo_build": "False", 14:27:04 INFO - "basedir": "/builds/slave/test", 14:27:04 INFO - "buildnumber": 10, 14:27:04 INFO - "slavename": "t-yosemite-r7-0117", 14:27:04 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 14:27:04 INFO - "platform": "yosemite_r7_devedition", 14:27:04 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 14:27:04 INFO - "branch": "mozilla-beta", 14:27:04 INFO - "repo_path": "releases/mozilla-beta", 14:27:04 INFO - "revision": "97767088522e789dd04cf06b24478f052946d1c9", 14:27:04 INFO - "stage_platform": "macosx64-devedition", 14:27:04 INFO - "builduid": "208641a058e84fe3b3883ceef091d1e9", 14:27:04 INFO - "slavebuilddir": "test" 14:27:04 INFO - } 14:27:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 14:27:04 INFO - Running post-action listener: _resource_record_post_action 14:27:04 INFO - [mozharness: 2017-06-05 21:27:04.980166Z] Finished read-buildbot-config step (success) 14:27:04 INFO - [mozharness: 2017-06-05 21:27:04.980346Z] Running download-and-extract step. 14:27:04 INFO - Running pre-action listener: _resource_record_pre_action 14:27:04 INFO - Running main action method: download_and_extract 14:27:04 INFO - mkdir: /builds/slave/test/build/tests 14:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:04 INFO - trying https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 14:27:04 INFO - Downloading https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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 14:27:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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 14:27:06 INFO - Downloaded 1287 bytes. 14:27:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:27:06 INFO - Using the following test package requirements: 14:27:06 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 14:27:06 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:27:06 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 14:27:06 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 14:27:06 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:27:06 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 14:27:06 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:27:06 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 14:27:06 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 14:27:06 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 14:27:06 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:27:06 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 14:27:06 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 14:27:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:27:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:27:06 INFO - Fetch https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:27:07 INFO - Expected file size: 51964058 14:27:07 INFO - Obtained file size: 51964058 14:27:24 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 14:27:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:27:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:27:24 INFO - Fetch https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:27:25 INFO - Expected file size: 51964058 14:27:25 INFO - Obtained file size: 51964058 14:27:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 14:27:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 14:27:40 INFO - Fetch https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 14:27:42 INFO - Expected file size: 14404461 14:27:42 INFO - Obtained file size: 14404461 14:27:42 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')]} 14:27:42 INFO - trying https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:27:42 INFO - Downloading https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 14:27:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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 14:27:45 INFO - Downloaded 74889776 bytes. 14:27:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:27:45 INFO - mkdir: /builds/slave/test/properties 14:27:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:27:45 INFO - Writing to file /builds/slave/test/properties/build_url 14:27:45 INFO - Contents: 14:27:45 INFO - build_url:https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:27:45 INFO - Running post-action listener: _resource_record_post_action 14:27:45 INFO - Running post-action listener: set_extra_try_arguments 14:27:45 INFO - [mozharness: 2017-06-05 21:27:45.931395Z] Finished download-and-extract step (success) 14:27:45 INFO - [mozharness: 2017-06-05 21:27:45.931568Z] Running populate-webroot step. 14:27:45 INFO - Running pre-action listener: _resource_record_pre_action 14:27:45 INFO - Running main action method: populate_webroot 14:27:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:27:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:27:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:27:45 INFO - '--tppagecycles', 14:27:45 INFO - '7'], 14:27:45 INFO - 'tests': ['tsvgm']}}, 14:27:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:27:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:27:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:27:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:27:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['damp', 'tps']}, 14:27:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'tests': ['damp', 'tps']}, 14:27:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['dromaeo_dom']}, 14:27:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:27:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['basic_compositor_video']}, 14:27:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:27:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['a11yr', 14:27:45 INFO - 'ts_paint', 14:27:45 INFO - 'tpaint', 14:27:45 INFO - 'sessionrestore', 14:27:45 INFO - 'sessionrestore_no_auto_restore', 14:27:45 INFO - 'tabpaint']}, 14:27:45 INFO - 'other-e10s': {'tests': ['a11yr', 14:27:45 INFO - 'ts_paint', 14:27:45 INFO - 'tpaint', 14:27:45 INFO - 'sessionrestore', 14:27:45 INFO - 'sessionrestore_no_auto_restore', 14:27:45 INFO - 'tabpaint']}, 14:27:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:27:45 INFO - 'ts_paint', 14:27:45 INFO - 'tpaint', 14:27:45 INFO - 'sessionrestore', 14:27:45 INFO - 'sessionrestore_no_auto_restore', 14:27:45 INFO - 'tabpaint']}, 14:27:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:27:45 INFO - 'ts_paint', 14:27:45 INFO - 'tpaint', 14:27:45 INFO - 'sessionrestore', 14:27:45 INFO - 'sessionrestore_no_auto_restore', 14:27:45 INFO - 'tabpaint']}, 14:27:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['a11yr', 14:27:45 INFO - 'ts_paint', 14:27:45 INFO - 'tpaint', 14:27:45 INFO - 'sessionrestore', 14:27:45 INFO - 'sessionrestore_no_auto_restore', 14:27:45 INFO - 'tabpaint']}, 14:27:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['a11yr', 14:27:45 INFO - 'ts_paint', 14:27:45 INFO - 'tpaint', 14:27:45 INFO - 'sessionrestore', 14:27:45 INFO - 'sessionrestore_no_auto_restore', 14:27:45 INFO - 'tabpaint']}, 14:27:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['tsvgx', 14:27:45 INFO - 'tsvgr_opacity', 14:27:45 INFO - 'tart', 14:27:45 INFO - 'tscrollx', 14:27:45 INFO - 'cart', 14:27:45 INFO - 'tsvg_static']}, 14:27:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:27:45 INFO - 'tsvgr_opacity', 14:27:45 INFO - 'tart', 14:27:45 INFO - 'tscrollx', 14:27:45 INFO - 'cart', 14:27:45 INFO - 'tsvg_static']}, 14:27:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'talos_options': ['--disable-e10s'], 14:27:45 INFO - 'tests': ['tp5o']}, 14:27:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:27:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'talos_options': ['--disable-e10s', 14:27:45 INFO - '--xperf_path', 14:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:45 INFO - 'tests': ['tp5n']}, 14:27:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:27:45 INFO - 'talos_options': ['--xperf_path', 14:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:45 INFO - 'tests': ['tp5n']}}, 14:27:45 INFO - 'talos.zip': {'path': '', 14:27:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:27:45 INFO - Running post-action listener: _resource_record_post_action 14:27:45 INFO - [mozharness: 2017-06-05 21:27:45.949821Z] Finished populate-webroot step (success) 14:27:45 INFO - [mozharness: 2017-06-05 21:27:45.949954Z] Running create-virtualenv step. 14:27:45 INFO - Running pre-action listener: _resource_record_pre_action 14:27:45 INFO - Running main action method: create_virtualenv 14:27:45 INFO - Creating virtualenv /builds/slave/test/build/venv 14:27:45 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 14:27:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:27:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:27:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:27:46 INFO - Using real prefix '/tools/python27' 14:27:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:27:46 INFO - Installing distribute.............................................................................................................................................................................................done. 14:27:49 INFO - Installing pip.................done. 14:27:49 INFO - Return code: 0 14:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:27:50 INFO - Reading from file tmpfile_stdout 14:27:50 INFO - Output received: 14:27:50 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) 14:27:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:27:50 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')]} 14:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:50 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')]} 14:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:50 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:50 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 14:27:50 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 14:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:50 INFO - 'HOME': '/Users/cltbld', 14:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:50 INFO - 'LOGNAME': 'cltbld', 14:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:50 INFO - 'MOZ_NO_REMOTE': '1', 14:27:50 INFO - 'NO_EM_RESTART': '1', 14:27:50 INFO - 'PAGER': '/bin/cat', 14:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:50 INFO - 'PWD': '/builds/slave/test', 14:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:50 INFO - 'SHELL': '/bin/bash', 14:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:27:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:50 INFO - 'USER': 'cltbld', 14:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:50 INFO - 'XPC_FLAGS': '0x0', 14:27:50 INFO - 'XPC_SERVICE_NAME': '0', 14:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:50 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 14:27:50 INFO - Cleaning up... 14:27:50 INFO - Return code: 0 14:27:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:27:50 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')]} 14:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:50 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')]} 14:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:50 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:50 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 14:27:50 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 14:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:50 INFO - 'HOME': '/Users/cltbld', 14:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:50 INFO - 'LOGNAME': 'cltbld', 14:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:50 INFO - 'MOZ_NO_REMOTE': '1', 14:27:50 INFO - 'NO_EM_RESTART': '1', 14:27:50 INFO - 'PAGER': '/bin/cat', 14:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:50 INFO - 'PWD': '/builds/slave/test', 14:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:50 INFO - 'SHELL': '/bin/bash', 14:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:27:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:50 INFO - 'USER': 'cltbld', 14:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:50 INFO - 'XPC_FLAGS': '0x0', 14:27:50 INFO - 'XPC_SERVICE_NAME': '0', 14:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:50 INFO - Downloading/unpacking psutil>=3.1.1 14:27:50 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 14:27:50 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 14:27:50 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 14:27:50 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 14:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:52 INFO - Installing collected packages: psutil 14:27:52 INFO - Running setup.py install for psutil 14:27:52 INFO - building 'psutil._psutil_osx' extension 14:27:52 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 14:27:53 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 14:27:53 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 14:27:53 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 14:27:53 INFO - building 'psutil._psutil_posix' extension 14:27:53 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 14:27:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:27:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:27:53 INFO - ^ 14:27:53 INFO - 1 warning generated. 14:27:53 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 14:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:53 INFO - Successfully installed psutil 14:27:53 INFO - Cleaning up... 14:27:53 INFO - Return code: 0 14:27:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:27:53 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')]} 14:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:53 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')]} 14:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:53 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:53 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 14:27:53 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 14:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:53 INFO - 'HOME': '/Users/cltbld', 14:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:53 INFO - 'LOGNAME': 'cltbld', 14:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:53 INFO - 'MOZ_NO_REMOTE': '1', 14:27:53 INFO - 'NO_EM_RESTART': '1', 14:27:53 INFO - 'PAGER': '/bin/cat', 14:27:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:53 INFO - 'PWD': '/builds/slave/test', 14:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:53 INFO - 'SHELL': '/bin/bash', 14:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:27:53 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:53 INFO - 'USER': 'cltbld', 14:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:53 INFO - 'XPC_FLAGS': '0x0', 14:27:53 INFO - 'XPC_SERVICE_NAME': '0', 14:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:27:53 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 14:27:53 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 14:27:53 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 14:27:53 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 14:27:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:27:55 INFO - Installing collected packages: mozsystemmonitor 14:27:55 INFO - Running setup.py install for mozsystemmonitor 14:27:55 INFO - Successfully installed mozsystemmonitor 14:27:55 INFO - Cleaning up... 14:27:55 INFO - Return code: 0 14:27:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:55 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:55 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 14:27:55 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 14:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:55 INFO - 'HOME': '/Users/cltbld', 14:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:55 INFO - 'LOGNAME': 'cltbld', 14:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:55 INFO - 'MOZ_NO_REMOTE': '1', 14:27:55 INFO - 'NO_EM_RESTART': '1', 14:27:55 INFO - 'PAGER': '/bin/cat', 14:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:55 INFO - 'PWD': '/builds/slave/test', 14:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:55 INFO - 'SHELL': '/bin/bash', 14:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:27:55 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:55 INFO - 'USER': 'cltbld', 14:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:55 INFO - 'XPC_FLAGS': '0x0', 14:27:55 INFO - 'XPC_SERVICE_NAME': '0', 14:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:55 INFO - Downloading/unpacking jsonschema==2.5.1 14:27:55 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 14:27:55 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 14:27:55 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 14:27:55 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 14:27:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:27:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:27:57 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 14:27:57 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 14:27:57 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 14:27:57 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 14:27:57 INFO - Downloading functools32-3.2.3-2.tar.gz 14:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:27:57 INFO - warning: no files found matching '*.txt' 14:27:57 INFO - no previously-included directories found matching 'build' 14:27:57 INFO - no previously-included directories found matching 'dist' 14:27:57 INFO - no previously-included directories found matching '.git*' 14:27:57 INFO - Installing collected packages: jsonschema, functools32 14:27:57 INFO - Running setup.py install for functools32 14:27:57 INFO - warning: no files found matching '*.txt' 14:27:57 INFO - no previously-included directories found matching 'build' 14:27:57 INFO - no previously-included directories found matching 'dist' 14:27:57 INFO - no previously-included directories found matching '.git*' 14:27:57 INFO - Successfully installed jsonschema functools32 14:27:57 INFO - Cleaning up... 14:27:57 INFO - Return code: 0 14:27:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:27:57 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')]} 14:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:58 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')]} 14:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:58 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:58 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 14:27:58 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 14:27:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:27:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:58 INFO - 'HOME': '/Users/cltbld', 14:27:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:58 INFO - 'LOGNAME': 'cltbld', 14:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:58 INFO - 'MOZ_NO_REMOTE': '1', 14:27:58 INFO - 'NO_EM_RESTART': '1', 14:27:58 INFO - 'PAGER': '/bin/cat', 14:27:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:58 INFO - 'PWD': '/builds/slave/test', 14:27:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:58 INFO - 'SHELL': '/bin/bash', 14:27:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:27:58 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:27:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:58 INFO - 'USER': 'cltbld', 14:27:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:58 INFO - 'XPC_FLAGS': '0x0', 14:27:58 INFO - 'XPC_SERVICE_NAME': '0', 14:27:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:27:58 INFO - Cleaning up... 14:27:58 INFO - Return code: 0 14:27:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:27:58 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')]} 14:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:58 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')]} 14:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:59 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:27:59 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 14:27:59 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 14:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:59 INFO - 'HOME': '/Users/cltbld', 14:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:59 INFO - 'LOGNAME': 'cltbld', 14:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:59 INFO - 'MOZ_NO_REMOTE': '1', 14:27:59 INFO - 'NO_EM_RESTART': '1', 14:27:59 INFO - 'PAGER': '/bin/cat', 14:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:59 INFO - 'PWD': '/builds/slave/test', 14:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:59 INFO - 'SHELL': '/bin/bash', 14:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:27:59 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:59 INFO - 'USER': 'cltbld', 14:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:59 INFO - 'XPC_FLAGS': '0x0', 14:27:59 INFO - 'XPC_SERVICE_NAME': '0', 14:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:59 INFO - Downloading/unpacking blobuploader==1.2.4 14:27:59 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 14:27:59 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 14:27:59 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 14:27:59 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 14:28:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:28:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:28:01 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 14:28:01 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 14:28:01 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 14:28:01 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 14:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:28:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:28:01 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 14:28:01 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 14:28:01 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 14:28:01 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 14:28:02 INFO - Downloading docopt-0.6.1.tar.gz 14:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:28:02 INFO - Installing collected packages: blobuploader, requests, docopt 14:28:02 INFO - Running setup.py install for blobuploader 14:28:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:28:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:28:02 INFO - Running setup.py install for requests 14:28:02 INFO - Running setup.py install for docopt 14:28:03 INFO - Successfully installed blobuploader requests docopt 14:28:03 INFO - Cleaning up... 14:28:03 INFO - Return code: 0 14:28:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:03 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:28:03 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 14:28:03 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 14:28:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:28:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:03 INFO - 'HOME': '/Users/cltbld', 14:28:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:03 INFO - 'LOGNAME': 'cltbld', 14:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:03 INFO - 'MOZ_NO_REMOTE': '1', 14:28:03 INFO - 'NO_EM_RESTART': '1', 14:28:03 INFO - 'PAGER': '/bin/cat', 14:28:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:03 INFO - 'PWD': '/builds/slave/test', 14:28:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:03 INFO - 'SHELL': '/bin/bash', 14:28:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:28:03 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:28:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:03 INFO - 'USER': 'cltbld', 14:28:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:03 INFO - 'XPC_FLAGS': '0x0', 14:28:03 INFO - 'XPC_SERVICE_NAME': '0', 14:28:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-vwK8Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-KkXVH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Yclmbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-LM4OBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-gTrdFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-mO4myX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2fs9Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-6I0vfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-9AjV01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3zIEa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3lwm9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-8Qj8ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-mfmBN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-kr7qWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-sR2RMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:04 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-BKNLfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:05 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-_sIcry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:28:05 INFO - Running setup.py install for manifestparser 14:28:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:28:05 INFO - Running setup.py install for mozcrash 14:28:05 INFO - Running setup.py install for mozdebug 14:28:05 INFO - Running setup.py install for mozdevice 14:28:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:28:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:28:05 INFO - Running setup.py install for mozfile 14:28:05 INFO - Running setup.py install for mozhttpd 14:28:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozinfo 14:28:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozInstall 14:28:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozleak 14:28:06 INFO - Running setup.py install for mozlog 14:28:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for moznetwork 14:28:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:28:06 INFO - Running setup.py install for mozprocess 14:28:06 INFO - Running setup.py install for mozprofile 14:28:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for mozrunner 14:28:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Running setup.py install for mozscreenshot 14:28:07 INFO - Running setup.py install for moztest 14:28:07 INFO - Running setup.py install for mozversion 14:28:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:28:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:28:07 INFO - Cleaning up... 14:28:07 INFO - Return code: 0 14:28:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:07 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:28:07 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 14:28:07 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 14:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:07 INFO - 'HOME': '/Users/cltbld', 14:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:07 INFO - 'LOGNAME': 'cltbld', 14:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:07 INFO - 'MOZ_NO_REMOTE': '1', 14:28:07 INFO - 'NO_EM_RESTART': '1', 14:28:07 INFO - 'PAGER': '/bin/cat', 14:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:07 INFO - 'PWD': '/builds/slave/test', 14:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:07 INFO - 'SHELL': '/bin/bash', 14:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:28:07 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:07 INFO - 'USER': 'cltbld', 14:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:07 INFO - 'XPC_FLAGS': '0x0', 14:28:07 INFO - 'XPC_SERVICE_NAME': '0', 14:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-JPMPWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UdLdii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Vzmeu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3LNPtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-xelN8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-NkivRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-moMV1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Tkuzuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-_cjx6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:08 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)) 14:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FTmzIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-aZNcoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-bg_Kx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-tW6ssV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-nwuEcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UCMPrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fkN9FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:09 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)) 14:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:09 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-YwJYqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:09 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)) 14:28:09 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)) 14:28:09 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)) 14:28:09 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)) 14:28:09 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)) 14:28:09 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)) 14:28:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:28:09 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 14:28:09 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 14:28:09 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 14:28:09 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 14:28:11 INFO - Downloading blessings-1.6.tar.gz 14:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:28:11 INFO - Installing collected packages: blessings 14:28:11 INFO - Running setup.py install for blessings 14:28:11 INFO - Successfully installed blessings 14:28:11 INFO - Cleaning up... 14:28:11 INFO - Return code: 0 14:28:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:11 INFO - Reading from file tmpfile_stdout 14:28:11 INFO - Current package versions: 14:28:11 INFO - blessings == 1.6 14:28:11 INFO - blobuploader == 1.2.4 14:28:11 INFO - docopt == 0.6.1 14:28:11 INFO - functools32 == 3.2.3-2 14:28:11 INFO - jsonschema == 2.5.1 14:28:11 INFO - manifestparser == 1.1 14:28:11 INFO - mozInstall == 1.12 14:28:11 INFO - mozcrash == 1.0 14:28:11 INFO - mozdebug == 0.1 14:28:11 INFO - mozdevice == 0.49 14:28:11 INFO - mozfile == 1.2 14:28:11 INFO - mozhttpd == 0.7 14:28:11 INFO - mozinfo == 0.9 14:28:11 INFO - mozleak == 0.1 14:28:11 INFO - mozlog == 3.4 14:28:11 INFO - moznetwork == 0.27 14:28:11 INFO - mozprocess == 0.25 14:28:11 INFO - mozprofile == 0.28 14:28:11 INFO - mozrunner == 6.13 14:28:11 INFO - mozscreenshot == 0.1 14:28:11 INFO - mozsystemmonitor == 0.3 14:28:11 INFO - moztest == 0.8 14:28:11 INFO - mozversion == 1.4 14:28:11 INFO - psutil == 3.1.1 14:28:11 INFO - requests == 1.2.3 14:28:11 INFO - wsgiref == 0.1.2 14:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28:11 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')]} 14:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:11 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')]} 14:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:11 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 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:28:11 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 14:28:11 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 14:28:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:28:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:11 INFO - 'HOME': '/Users/cltbld', 14:28:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:11 INFO - 'LOGNAME': 'cltbld', 14:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:11 INFO - 'MOZ_NO_REMOTE': '1', 14:28:11 INFO - 'NO_EM_RESTART': '1', 14:28:11 INFO - 'PAGER': '/bin/cat', 14:28:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:11 INFO - 'PWD': '/builds/slave/test', 14:28:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:11 INFO - 'SHELL': '/bin/bash', 14:28:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:28:11 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:28:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:11 INFO - 'USER': 'cltbld', 14:28:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:11 INFO - 'XPC_FLAGS': '0x0', 14:28:11 INFO - 'XPC_SERVICE_NAME': '0', 14:28:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 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)) 14:28:12 INFO - Cleaning up... 14:28:12 INFO - Return code: 0 14:28:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28: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')]} 14:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba0475f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, '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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 14:28: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', 'jsonschema'] in /builds/slave/test/build 14:28: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 jsonschema 14:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:12 INFO - 'HOME': '/Users/cltbld', 14:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:12 INFO - 'LOGNAME': 'cltbld', 14:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:12 INFO - 'MOZ_NO_REMOTE': '1', 14:28:12 INFO - 'NO_EM_RESTART': '1', 14:28:12 INFO - 'PAGER': '/bin/cat', 14:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:12 INFO - 'PWD': '/builds/slave/test', 14:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:12 INFO - 'SHELL': '/bin/bash', 14:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:28:12 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:12 INFO - 'USER': 'cltbld', 14:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:12 INFO - 'XPC_FLAGS': '0x0', 14:28:12 INFO - 'XPC_SERVICE_NAME': '0', 14:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:28:12 INFO - Cleaning up... 14:28:12 INFO - Return code: 0 14:28:12 INFO - Running post-action listener: _resource_record_post_action 14:28:12 INFO - Running post-action listener: _start_resource_monitoring 14:28:12 INFO - Starting resource monitoring. 14:28:12 INFO - [mozharness: 2017-06-05 21:28:12.401576Z] Finished create-virtualenv step (success) 14:28:12 INFO - [mozharness: 2017-06-05 21:28:12.402548Z] Running install step. 14:28:12 INFO - Running pre-action listener: _resource_record_pre_action 14:28:12 INFO - Running main action method: install 14:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:28:12 INFO - Reading from file tmpfile_stdout 14:28:12 INFO - Detecting whether we're running mozinstall >=1.0... 14:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:28:12 INFO - Reading from file tmpfile_stdout 14:28:12 INFO - Output received: 14:28:12 INFO - Usage: mozinstall [options] installer 14:28:12 INFO - Options: 14:28:12 INFO - -h, --help show this help message and exit 14:28:12 INFO - -d DEST, --destination=DEST 14:28:12 INFO - Directory to install application into. [default: 14:28:12 INFO - "/builds/slave/test"] 14:28:12 INFO - --app=APP Application being installed. [default: firefox] 14:28:12 INFO - mkdir: /builds/slave/test/build/application 14:28:12 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'] 14:28:12 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 14:28:33 INFO - Reading from file tmpfile_stdout 14:28:33 INFO - Output received: 14:28:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:28:33 INFO - Running post-action listener: _resource_record_post_action 14:28:33 INFO - [mozharness: 2017-06-05 21:28:33.221114Z] Finished install step (success) 14:28:33 INFO - [mozharness: 2017-06-05 21:28:33.221287Z] Running run-tests step. 14:28:33 INFO - Running pre-action listener: _resource_record_pre_action 14:28:33 INFO - Running main action method: run_tests 14:28:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:28:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:28:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:28:33 INFO - 2017-06-05 14:28:33.235 screenresolution[1624:7366] starting screenresolution argv=screenresolution get 14:28:33 INFO - 2017-06-05 14:28:33.238 screenresolution[1624:7366] Display 0: 1600x1200x32@60 14:28:33 INFO - 2017-06-05 14:28:33.243 screenresolution[1625:7368] starting screenresolution argv=screenresolution list 14:28:33 INFO - Available Modes on Display 0 14:28:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:28:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:28:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:28:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:28:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:28:33 INFO - Intel Iris: 14:28:33 INFO - Chipset Model: Intel Iris 14:28:33 INFO - Type: GPU 14:28:33 INFO - Bus: Built-In 14:28:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:28:33 INFO - Vendor: Intel (0x8086) 14:28:33 INFO - Device ID: 0x0a2e 14:28:33 INFO - Revision ID: 0x0009 14:28:33 INFO - Displays: 14:28:33 INFO - Display: 14:28:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:28:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:28:33 INFO - Display Serial Number: 1600x1200 60 14:28:33 INFO - Main Display: Yes 14:28:33 INFO - Mirror: Off 14:28:33 INFO - Online: Yes 14:28:33 INFO - Rotation: Supported 14:28:33 INFO - Return code: 0 14:28:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:28:33 INFO - Python 2.7.3 14:28:33 INFO - Return code: 0 14:28:33 INFO - grabbing minidump binary from tooltool 14:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:33 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 0x10eec1d30>, '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 0x10ede8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:28:33 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 14:28:33 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 14:28:33 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 14:28:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:28:33 INFO - Return code: 0 14:28:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:28:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:28:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:28:33 INFO - ENV: RUST_BACKTRACE is now 1 14:28:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:28:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:28:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 14:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 14:28:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eeyvuUPuMF/Render', 14:28:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:33 INFO - 'HOME': '/Users/cltbld', 14:28:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:33 INFO - 'LOGNAME': 'cltbld', 14:28:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:28:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:33 INFO - 'MOZ_NO_REMOTE': '1', 14:28:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:28:33 INFO - 'NO_EM_RESTART': '1', 14:28:33 INFO - 'PAGER': '/bin/cat', 14:28:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:33 INFO - 'PWD': '/builds/slave/test', 14:28:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:28:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:33 INFO - 'RUST_BACKTRACE': '1', 14:28:33 INFO - 'SHELL': '/bin/bash', 14:28:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MLoMOcG1SE/Listeners', 14:28:33 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 14:28:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:33 INFO - 'USER': 'cltbld', 14:28:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:33 INFO - 'XPC_FLAGS': '0x0', 14:28:33 INFO - 'XPC_SERVICE_NAME': '0', 14:28:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 14:28:33 INFO - mozversion application_buildid: 20170605125726 14:28:33 INFO - mozversion application_changeset: 97767088522e789dd04cf06b24478f052946d1c9 14:28:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:28:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:28:33 INFO - mozversion application_name: Firefox 14:28:33 INFO - mozversion application_remotingname: firefox-dev 14:28:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:28:33 INFO - mozversion application_vendor: Mozilla 14:28:33 INFO - mozversion application_version: 54.0 14:28:33 INFO - mozversion platform_buildid: 20170605125726 14:28:33 INFO - mozversion platform_changeset: 97767088522e789dd04cf06b24478f052946d1c9 14:28:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:28:33 INFO - mozversion platform_version: 54.0 14:28:33 INFO - using testdate: 1496698113 14:28:33 INFO - actual date: 1496698113 14:28:33 INFO - starting webserver on 'localhost:49224' 14:28:33 INFO - SUITE-START | Running 2 tests 14:28:33 INFO - TEST-START | dromaeo_css 14:28:33 INFO - operating with platform_type : mac_ 14:28:33 INFO - Initialising browser for dromaeo_css test... 14:28:33 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpf7CgXz/profile http://localhost:49224/getInfo.html) 14:28:36 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 14:28:36 INFO - PROCESS | 1635 | colorDepth:24 14:28:36 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 14:28:36 INFO - PROCESS | 1635 | __metrics 14:28:36 INFO - PROCESS | 1635 | 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" 14:28:36 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:28:37 INFO - TEST-INFO | 1635: exit 0 14:28:37 INFO - Browser initialized. 14:28:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:28:37 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpf7CgXz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:28:49 INFO - PROCESS | 1638 | RSS: Main: 193753088 14:28:49 INFO - PROCESS | 1638 | 14:31:52 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 14:31:55 INFO - PROCESS | 1638 | RSS: Main: 415399936 14:31:55 INFO - PROCESS | 1638 | 14:34:58 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 14:34:59 INFO - PROCESS | 1638 | RSS: Main: 331816960 14:34:59 INFO - PROCESS | 1638 | 14:38:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 14:38:01 INFO - PROCESS | 1638 | RSS: Main: 312446976 14:38:01 INFO - PROCESS | 1638 | 14:41:04 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 14:41:04 INFO - PROCESS | 1638 | RSS: Main: 299966464 14:41:04 INFO - PROCESS | 1638 | 14:44:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 14:44:08 INFO - PROCESS | 1638 | RSS: Main: 305451008 14:44:08 INFO - PROCESS | 1638 | 14:47:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 14:47:12 INFO - PROCESS | 1638 | RSS: Main: 373514240 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | __start_tp_report 14:47:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:47:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:47:12 INFO - PROCESS | 1638 | |i|pagename|runs| 14:47:12 INFO - PROCESS | 1638 | |0;dojo.html;2894.3777087926096;3273.6562660920604;3422.828858557073;3438.65613438656;3451.6203217646053;5889.057750759879;5944.732487038083;5959.672218028007;5966.642001479914;5986.2517185351835;5908;5982.6410415375;6005.819825405245;6010.008348622472;6017.458428741424;5308.761105750251;5344.652597581145;5409.107497262947;5423.484768946943;5430.348358197002;5258;5258;5273.446288139748;5459.290292262001;5474.562035037207;5349.384820745625;5365.195220716904;5367.624266301341;5372.328458942632;5374.247605335257;5345.1714984177515;5359.383670877841;5362.490563396491;5363.088274993261;5370.409254981988;5172.534471897509;5306.469353064701;5306.6550674206055;5322.866928326784;5343.225232341328;5940.970295148497;5954.583179177459;5956.0172571525645;5959.10613407988;5971.850703732398;6129.786714141072;6134.130457564626;6148.477379422754;6159.568830181889;6166.568340216187;6205.503559715212;6217.502599792006;6273.278572964122;6281.466075383598;6289.150964619764;5962.761489540457;6036.396360363972;6046.697665116727;6080.847978800522;6083.117947897575;5874.265716785402;6440.259370172444;6480.060391243291;6491.383318584727;6496.415322620986;6108.858142493628;6115.696722738819;6148.1085242640465;6162.753489860355;6176.845578860474;6354.078658594478;6357.078223657544;6362.809115726535;6375.776847810304;6378.362163783585;6075.5747097703625;6242.781502647476;6248.187735594343;6250.874982500324;6270.372962703785;6139.201903752573;6145.539084568675;6179.443850053517;6214.751409943552;6294.905576416266;4694.920168361227;4704.388429504143;4781.569658730731;4881.975590122027;4897.559219670246;4396.714213576107;4398.450193725785;4412.624926881251;4431.8448854289945;4448.599626033608;5808.79669211584;5810.157527158538;5844.561657875676;5859.5019423349495;5890.410958904075;6213.875722485525;6259.18630578031;6301.653409062454;6334.3032266450655;6369.426751592379;5876.030454974882;5886;5889.823305300848;5897.174395584621;5900.970495147582;5518.392976772552;5522.696251706115;5526.557875369961;5539.196816461671;5542.91685624716;6211.192544969125;6250.937490625127;6442.484601231797;6455.548111632141;6531.477481801349;6324.810255692337;6373.2989371168205;6378.52161087911;6384.840378990562;6388.425041746079;6528.412442879973;6535.901961470677;6549.6070235786;6550.312217217314;6556.409923106943;6167.167432396748;6178.258608966953;6301.40136687014;6310.148130002391;6311.873762524816;6288.088227207121;6288.654124023223;6289.937100628935;6327.715252813727;6335.873282534416;6208.906866397098;6228.626282423069;6253.062040693932;6303.842403939938;6325.430711236011;4631.814727411001;5707.286589176353;6237.5009999200865;6280.089387038763;6298.590591611531;5945.137954996415;6176.629402235881;6183.505319574513;6187.3503282154725;6188.81433556994;5236.869078273074;6146.907796383147;6176.629402235881;6230;6245.6252624842655;5960.4635582797755;5988.580799344179;6015.608985416109;6110.022396416552;6166.876662466815 14:47:12 INFO - PROCESS | 1638 | |1;ext.html;767.658392015553;812.7480481050873;876.5398165962869;890.9287257019444;892.7589550821283;40998.18003639925;41202.58797412025;41388.96527586808;41418.17163656725;43555.56444435555;42287.577124228825;42512.362314565304;42527.3620895686;42597.36103958436;42629.14741705164;7474.140473845496;9509.28680348975;9661.806763864719;9674.129328360446;9674.516274186297;7472.476926615139;11698;11915.582954596592;12095.455704493275;12118.939405302961;11824.113191510629;11829.526818927232;11840.703982400466;11873.465694043789;11929.522819087226;10102.141317987905;11771.470283837249;11780.823287650692;11792.646220613395;11801.232919860182;11367.147463940237;11428.599999000078;11446;11454.140939429493;11464.598739044093;39820;39964.00089997744;39987;40090.398644020366;40163;3193.1218914798383;3226.2418331692033;3249.333886553251;3278.6721327867263;3299.15871452778;27930.301742456602;28001;29844.701552984625;30026.099217023526;30173.245668858235;3047.405755877605;3059.8011129276524;3109.673484284163;3125.3905488429764;3126.983730287666;350949;352103.99999999744;352186.99999999744;352550;352870.23564882524;52874.206886896725;52932.47067529275;52987.47012529903;53093;53104.73447632737;55512;55582.16626750602;55699;55787.44212557822;55793.442065579635;15105.3202605883;15244.618884527976;15251;15261.23693815327;15264.694706105816;13016.739665206833;13031.02267329954;13043.934780326037;13067.411966461343;13085.411156497981;10807;12265.938670306592;12274.570390036306;12315.014798816073;12407.44166512509;5664.26364572603;5741.483266506035;5746.367899531048;5749.655020698771;5758.654480731169;1020.8570800087994;1105.784372047443;1107.363266121983;1192.7316353820322;1195.2290772451893;25093.121740738985;27287.044953426535;27502.724972750417;27560.310992225353;27735.861320693268;1073.6993641780314;1274.9107562470724;1279.9872001280055;1289.2135797163724;1290.4256806695817;10856.074172921397;12669.556565520346;12849.565455586971;13003.804942925864;13183.604491865259;432982;434952;435084;435207;435344;46387;46409.767951160706;46465.767671161426;46501.53498464972;46536.30195547069;1446.7034257977089;1455.3814628782725;1469.926503674812;1478.3051965576162;1480.711261304057;22555.5488890218;27459.450810984068;28139;28216.576751349156;28901.132966010213;45454;45619.85950351268;45639;45799.542004580864;45802.08395831998;455659;457347;458616;459010;459106;23974.880125599262;24468.632970505812;28349.574756378257;28682.42635147327;29685.85157074201;36976;37667.81166094152;37712;37897;38225.4266186013;27423.725762742117;27526.724732752416;27568.310792230353;27874.721252787214;28668.713312866603;26416;26421.867890660425;27423.314417139733;28387.006454773982;28562.714372856837;30471.93348232801;32112.518312225802;32551.860684875915;32706.182345441553;32824.83587582047;27516.449671006867;27766.722332776415;28065.859670701517;28205.71794282113;28821.42357152887;19562.924039177982;20185.6972145415;21966.560668786853;22623.622471103696;22803.54392912099 14:47:12 INFO - PROCESS | 1638 | |2;jquery.html;413.3489753638021;417.38019041723055;424.5372543927119;425.3131193123106;448.54472710199155;19922.800771992275;21180.470488237803;22320.21879234227;22669.319920402373;22669.54660906783;21774.564508709856;23159.536809263845;23220.838958052114;23296.534069318644;23576.528469430643;6668.699908504104;6683.13119294491;7043.859122817541;7056.964715176443;7063.328983746562;6829.692663830139;6914.239433662293;6993.790186294419;6994;7001.964990175042;6352.4918006559365;6990;7004.929950700479;7404.666789994436;7600.125985511681;7291.708331666727;7455.142658594277;7486.887696684554;7545.962270188641;7550.509216900911;7293.52592081513;7297.635118244067;7302.56184628924;7304.926950730478;7307.89038164428;24234.151804686928;25178;26036.34909127268;26505.602415963775;26711.465770684477;2842.6345644150465;2961.511350627144;2993.3863557970562;3022.2142243016756;3037.9392412151633;15761.684766304725;16218.513444596681;16261.268242928978;16520.6695866082;16548.255328510248;2056.148281647507;2075.616011037963;2080.3238947342174;2081.687746837971;2127.617028934791;140910.2954485221;160897.37867832955;164838;173714.26285737235;177508;15916.602084947968;18923.90538047301;19878.60242795165;20055;20146.294879679142;31099;31121.732218117224;31160;31225;31245.218869527987;9111;9800.55897484615;10051.849222261671;10601.363918164934;10624.946875265574;8767.386282960259;8789.428687135316;8818.294536437197;8955.820883582423;8984.281257499384;8229.506229626242;8804.427712198687;9008.324375671851;9414.199793017533;9432.90567094334;3182.8090314580736;3451.162512581818;3855.8843234703004;3913.5108111486065;4009.4787677602308;1028.2545154762738;1038.657243109772;1049.2969710294121;1069.8693209757039;1084.7017070305571;21558.568828623655;21963.560728785655;22196.445088872908;22220.55558888813;22424.327270181922;1055.171690223171;1064.084886997182;1085.8316960871077;1088.553692985872;1101.5373543111912;10708.303960242714;10845.783084338269;11541.307521548733;11570.537178512934;11653.6503904883;791290;800708;801483;802469;816768;118319;136217;155159.22420388053;156213;159366;1268.657462485124;1284.101129209557;1330.8935285177163;1331.0815537279245;1331.42083193811;19612.411627651192;20848.895755521124;20867.1653133879;20872.956352182027;20899.686504702746;61090.08364874442;61330;61477.69261153666;61960;62639.43401414819;87246.38260852185;87610.56194718985;87984.56007720176;90098;90909.54190520645;11213.71965700864;11268.830967535658;11467;11760.823587646364;11801.881981180079;12095.455704493495;12125.69685757863;12371.195872268274;12514.87485125137;12521.186122901981;10813.51339189754;10932.180086493703;10962.835557466486;10966.835497467704;11198.608048718255;10184.338018028804;10252.794944101226;10298.588056477656;10448.268621196443;10555.736106597396;11225.045871101049;11226.438678066228;11373.374464404536;11397.829032564352;11490.195686302213;11328.376939268419;11412.48643811047;11444.370559619301;11540.307581545134;11683.941580292045;10749.623763168564;10779.568817247222;11002.889971100187;11008.339499630049;11193.048590869541 14:47:12 INFO - PROCESS | 1638 | |3;mootools.html;1593.3546913500022;1597.08965889443;1620.206099011484;1620.643458439143;1623.3100932103857;10646.946765266173;11224.438778061094;11228.214025018253;11242.6627201184;11242.831357529643;10767.784644307128;11155.832662510069;11282.323060616369;11293.20947533671;11309.377984210885;2295.8737269450216;2357.57563638545;2362.4802543440414;2362.9527409451803;2364.2670772060715;2002.6295135399926;2262.1177740681146;2325.825563082767;2378.3816207786012;2383.201627454796;2303.101790301775;2349.9530009399887;2357.823163262762;2369.1707902234252;2371.3834403055157;2289.9313020609243;2295.4605667668084;2317.2932255662017;2325.8139348852137;2328.7438381777993;2340.5435939991708;2346.554154710617;2346.77705617966;2348.988255058731;2357.6934998450265;11076.224664273504;11163.609273675464;11169.609063682732;11178.888211117948;11184.552617895275;5420;5584.106542953149;5592.5805564582815;5603.187537807036;5611.466910643482;3214.244652506659;3321.9501707474406;3481.5125882376233;3481.651834816523;3499.8075105869166;1102.871458085894;1107.5126944144552;1127.8364637127652;1138.5161306444727;1163.883611638838;13611.795823062663;13896.861031389657;14260.215688137252;15302.999999999889;15312.234388280542;4282.850100246476;4432.601065904084;4460.6431485481735;4462.219111655448;4464.799084041226;11486;11512.19414640967;11522.884771152349;11524;11544.76910461786;4708.882277943078;5484.725763711798;5485.094959331665;5488.902219556105;5489.039418101896;4095.28332541804;4700.55344742249;5094.821681241213;5149.201873709581;5152.227165925066;3734.57052438973;3809.8857034289017;3905.4922860028582;3935.0555866591812;3954.1498577805332;1001.5042553935848;1008.0826447932346;1008.9899101009042;1021.0606242257142;1022.9028242316969;981.7496538382667;988.357567581078;989.6776619854982;1004.4124187350336;1033.0960409641564;6639.701213445406;6669.63317017559;6732.798016059526;6903.309669033157;6905.689243983932;994.4778991029767;1008.3848852200152;1014.6753039027587;1021.6781713760141;1041.5885725138528;4085.104689513119;4087.325591277406;4125.608067233608;4156.688248381383;4229.894252643709;13049.478020879058;13635.659108522366;13650;13659.38532766008;13680.179189248676;13565.525206617722;13586.184828910296;13588.116772409761;13588.524401646093;13589.728205435837;8745.693900713444;9856.901430985741;9892.901070989343;10090.899091009143;10274.434906080238;6297.6463392194555;6351;6403.391677790602;6475.514336424692;6564.179477565304;11229.775404492028;11889.048876089719;11941.044716422492;12253.794317023021;12406.069544784357;9605.279604029865;12298.262104273772;12372.4432400544;12448.066395020229;12506.874931250572;9167.633294668405;9216.170544651013;9230.538473076185;9251.259899208182;9345.532723363669;8676.175763302475;8905.33210009239;10353.378797272188;10355.430451324948;10367.32612380193;8600.440971336842;8875.689350872688;8923.33075019389;8983.955080224558;9029.322800789836;7494;8683.435576687496;8715.172058654418;8777.780555486164;8813.735587932117;7841.1766764491185;7978.760637180894;7985.08171560278;7990;7993.040835099825;5799.594028418139;5803.912941305799;5856.209411729362;5988.550858685703;6028.216331876828;5904.996150654313;5916.763329466773;6109.327973922776;6139.447449729546;6252.437280644764 14:47:12 INFO - PROCESS | 1638 | |4;prototype.html;352.5892335429225;371.1982118623772;378.8730775190311;379.1298968866452;388.2758655108224;4274.63665588425;4597.379353787238;4726.810927562893;4975.8258460953875;4979.576735977446;4559;4606.815727370909;4615.607673347761;4855.465898751143;4864.902701945959;3627.8004709740953;4084.121913788535;4091.9156423547784;4131.648809851166;4149.875503734886;3519.9472007919903;4056.930965207736;4110.403991421242;4155.210510003089;4168.8332466701295;3746.007308063352;3885.057471264368;4130.586941305875;4188.267053265681;4203.201391735576;3898.5126859142747;4040.434339192486;4086;4097.89755256118;4185.367706594416;3548.4677298405445;3617.4625784041677;3784.6026167252594;3791.4881490998637;3870.1872606752618;4460.33095035744;4839;4843.709377437365;4880.902381952376;4898.044881248158;4448.977755111204;4456.55434456555;4710.293455981596;4734.147853386389;4777.593904518086;5145.614078944094;5194.896067092834;5204.089284375257;5213.897730850138;5267;3863.208042351311;3982.4424580558743;4240.897366684653;4241.70947688248;4310.267254566731;4685.367475390847;5012.368506534099;5224.92162617561;5296.337957755319;5492.203630473559;3902.590228026073;4030.7783071931326;4121.876343709693;4276.786160691953;4289.463817022872;3995.400689896481;4254.129301563884;4374.737515749065;4429.468463784367;4451.265541185733;4020;4122.2786012447705;4380.218561213588;4717.740524271129;4762.880927976828;4393.495707446725;5010.498950105033;5012.170557746469;5230.476952304739;5243.003829272426;1278.8529319128234;1397.902146849731;1478.268257212665;1479.7632378819337;1490.9254537273093;940.9153176214173;1024.5850430575629;1050.422267752733;1058.3226734889677;1135.9886401136048;4162.375643653476;4595.977020114878;4666.090112428112;4701.647376446712;4712.175369310357;4903.803847846118;5319.148936170194;5393.6763794172475;5424.83725488236;5471.699056551848;4370.8033138508845;4747.95252047482;4748.525147485293;4749.978754567715;4836.613070954315;2289.1530133850247;2492.7382624824495;2697.720426239824;2778.9301119069305;2829.3775369418695;4706.62347012245;5083.339165908482;5089.803896084417;5176.767045482887;5563.415841336682;4910.361652985161;4923.163062279349;5077.441481437039;5391.711056255681;5549.889002219933;4833.709977401367;4835.927461088017;5013.423456302499;5194.662346947436;5283.6037297202865;4566.109608626286;4793.640476964172;4805;5402.486763757437;5434.483724046209;5022.623303252344;5085.5931525477135;5157.974210128926;5258.737063146751;5389.407165211902;4707.364505791675;4813.652177390336;5183.740812959412;5520.641158324696;5523.475269849358;3955.674848925642;3991.529486974607;4049.6962727794944;4158.26159899337;4216.388623649493;4583.9312410313205;4789.640776941674;4906.754662266943;5164.870878228075;5400.812670868305;5037.596992240685;5064.898702026013;5145.434002259673;5264.52619264268;5268.947310526999;4956.801727930842;5127.512886275797;5283.339582552181;5290.338707661543;5302.761375738024;4357.474883790648;4656.348111264493;4890.9021819562695;5148.06947394727;5194.584433245407;4412.271975124068;5278.60410469224;5293.7353132344;5294.867628309323;5315.574754019592;4753;4856.39295088114;5184.429712731672;5258.316418865676;5328.8276579152525 14:47:12 INFO - PROCESS | 1638 | |5;yui.html;743.9553626782397;763.709790279694;791.9762407127785;827.6978902700514;860.948343099414;15855.84144158558;16575.83424165758;16598.66802663946;16607.169641517936;16634.66730665386;14831.110133391978;14858.257087145654;14887.18120503372;14904.105753654761;14921.328540215689;962.6197651927502;973.2846357926918;975.2977855943709;983.8081574093055;993.5677980078689;999.5502024089153;1001.1190152665644;1001.2590682894642;1001.329109496639;1001.6043662753244;1032.4992378696327;1034.8395998620226;1034.9275550711454;1035.244271681673;1037.5123691864812;1030.798994196132;1030.99484502577;1032.746976990642;1032.8192566300943;1033.8914413986536;766.2529034191674;766.6894907562446;766.861964846333;767.620028086101;768.5388766739967;16590.83409165917;16670.499885003468;16798.58003549909;17418.651626967516;17645.55886102845;14467;14471.42114315426;14618.561443156723;14623.70752584953;14688.632784180481;11701;11824.408779561074;11885.940570297093;11924.46339914706;12548.686282842911;3705.207291594466;3723.1436769543157;3821.5032045833864;3903.1217975955465;3947.210557888434;115426;118703.81296187014;121844.39077804644;122240;123434;9932.851007234898;10024.599016039278;10124.898751012543;10417.218708596885;10615.946920265349;19661.213551457782;19766.110525026408;19798.90100549488;19830;19957.002149892447;7947.682092716343;7954.642041108164;7957.641906114239;8096.987876515435;8224.342052635775;4384.674443371214;4455.26488129455;4509.940164061443;4570.81716731327;4736.597389221886;3434.845431955568;3493.2314890724;3523;3538.840752166107;3546.2021045265124;563.6956043736336;599.4155698194226;614.6895817612167;624.1854380034008;626.2610120058284;554.5535843645823;629.5750368501243;713.6467448612866;740.6222826358596;766.9654865531064;9979.251556133318;10497.422641754623;10520.24156559978;10644.520996555173;10855.348679079281;684.5789839248827;705.6612825843624;714.8891921752137;722.989155162673;729.8759210934154;4440.149656829925;4679.789409476513;5108.310378098983;5156.690598564098;5199.402068762065;91443;91866.16267674742;110450;111161;113121;93217.53391233001;95150;96425;96734.5163274179;98022;81015.379692407;81671;82952.58523707344;84725.57637211775;85242.14757852466;9835.360701554377;9857.260705446977;9952.054554817281;9999.450030248407;10038.498075096362;41928;48960.75519622379;49768.75115624399;53068.469315306356;54583.72708136434;59046.704766475894;68250.3174968244;69314.30685693078;70420;70969;9356.4386136832;11480.368579728196;11703.2978021319;11823.82264266054;11847.526098955945;13050.10601162905;13064;13424.261665608485;13622.795658064939;13841.723165536432;11866.228695134789;12157.209781364536;12353.046845372477;12451.564195253122;12865.871341286467;10775.784484310427;12127.939360303142;12197.878021220029;12307.753844922872;12358.196717213352;11703.590374336858;11901.345426001306;11933.641990740292;12071.155019148575;12071.818922715991;9092.128601451845;9130.315226358183;9277.48973806447;9445.338826282094;9635.373700709431;8911.50986695712;9838.819341679044;10069.446180460145;10203.94898025505;10330.586776528855 14:47:12 INFO - PROCESS | 1638 | __end_tp_report 14:47:12 INFO - PROCESS | 1638 | __start_cc_report 14:47:12 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2549 14:47:12 INFO - PROCESS | 1638 | __end_cc_report 14:47:12 INFO - PROCESS | 1638 | __startTimestamp1496699231996__endTimestamp 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:47:12 INFO - PROCESS | 1638 | Number of tests: 6 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5835.09 Median:6080.85 stddev:674.00 (11.1%) stddev-sans-first:635.36 14:47:12 INFO - PROCESS | 1638 | Values: 2894.4 3273.7 3422.8 3438.7 3451.6 5889.1 5944.7 5959.7 5966.6 5986.3 5908.0 5982.6 6005.8 6010.0 6017.5 5308.8 5344.7 5409.1 5423.5 5430.3 5258.0 5258.0 5273.4 5459.3 5474.6 5349.4 5365.2 5367.6 5372.3 5374.2 5345.2 5359.4 5362.5 5363.1 5370.4 5172.5 5306.5 5306.7 5322.9 5343.2 5941.0 5954.6 5956.0 5959.1 5971.9 6129.8 6134.1 6148.5 6159.6 6166.6 6205.5 6217.5 6273.3 6281.5 6289.2 5962.8 6036.4 6046.7 6080.8 6083.1 5874.3 6440.3 6480.1 6491.4 6496.4 6108.9 6115.7 6148.1 6162.8 6176.8 6354.1 6357.1 6362.8 6375.8 6378.4 6075.6 6242.8 6248.2 6250.9 6270.4 6139.2 6145.5 6179.4 6214.8 6294.9 4694.9 4704.4 4781.6 4882.0 4897.6 4396.7 4398.5 4412.6 4431.8 4448.6 5808.8 5810.2 5844.6 5859.5 5890.4 6213.9 6259.2 6301.7 6334.3 6369.4 5876.0 5886.0 5889.8 5897.2 5901.0 5518.4 5522.7 5526.6 5539.2 5542.9 6211.2 6250.9 6442.5 6455.5 6531.5 6324.8 6373.3 6378.5 6384.8 6388.4 6528.4 6535.9 6549.6 6550.3 6556.4 6167.2 6178.3 6301.4 6310.1 6311.9 6288.1 6288.7 6289.9 6327.7 6335.9 6208.9 6228.6 6253.1 6303.8 6325.4 4631.8 5707.3 6237.5 6280.1 6298.6 5945.1 6176.6 6183.5 6187.4 6188.8 5236.9 6146.9 6176.6 6230.0 6245.6 5960.5 5988.6 6015.6 6110.0 6166.9 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:54979.98 Median:29765.28 stddev:110716.12 (372.0%) stddev-sans-first:110951.89 14:47:12 INFO - PROCESS | 1638 | Values: 767.7 812.7 876.5 890.9 892.8 40998.2 41202.6 41389.0 41418.2 43555.6 42287.6 42512.4 42527.4 42597.4 42629.1 7474.1 9509.3 9661.8 9674.1 9674.5 7472.5 11698.0 11915.6 12095.5 12118.9 11824.1 11829.5 11840.7 11873.5 11929.5 10102.1 11771.5 11780.8 11792.6 11801.2 11367.1 11428.6 11446.0 11454.1 11464.6 39820.0 39964.0 39987.0 40090.4 40163.0 3193.1 3226.2 3249.3 3278.7 3299.2 27930.3 28001.0 29844.7 30026.1 30173.2 3047.4 3059.8 3109.7 3125.4 3127.0 350949.0 352104.0 352187.0 352550.0 352870.2 52874.2 52932.5 52987.5 53093.0 53104.7 55512.0 55582.2 55699.0 55787.4 55793.4 15105.3 15244.6 15251.0 15261.2 15264.7 13016.7 13031.0 13043.9 13067.4 13085.4 10807.0 12265.9 12274.6 12315.0 12407.4 5664.3 5741.5 5746.4 5749.7 5758.7 1020.9 1105.8 1107.4 1192.7 1195.2 25093.1 27287.0 27502.7 27560.3 27735.9 1073.7 1274.9 1280.0 1289.2 1290.4 10856.1 12669.6 12849.6 13003.8 13183.6 432982.0 434952.0 435084.0 435207.0 435344.0 46387.0 46409.8 46465.8 46501.5 46536.3 1446.7 1455.4 1469.9 1478.3 1480.7 22555.5 27459.5 28139.0 28216.6 28901.1 45454.0 45619.9 45639.0 45799.5 45802.1 455659.0 457347.0 458616.0 459010.0 459106.0 23974.9 24468.6 28349.6 28682.4 29685.9 36976.0 37667.8 37712.0 37897.0 38225.4 27423.7 27526.7 27568.3 27874.7 28668.7 26416.0 26421.9 27423.3 28387.0 28562.7 30471.9 32112.5 32551.9 32706.2 32824.8 27516.4 27766.7 28065.9 28205.7 28821.4 19562.9 20185.7 21966.6 22623.6 22803.5 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:44655.58 Median:21666.57 stddev:133495.61 (616.1%) stddev-sans-first:133828.76 14:47:12 INFO - PROCESS | 1638 | Values: 413.3 417.4 424.5 425.3 448.5 19922.8 21180.5 22320.2 22669.3 22669.5 21774.6 23159.5 23220.8 23296.5 23576.5 6668.7 6683.1 7043.9 7057.0 7063.3 6829.7 6914.2 6993.8 6994.0 7002.0 6352.5 6990.0 7004.9 7404.7 7600.1 7291.7 7455.1 7486.9 7546.0 7550.5 7293.5 7297.6 7302.6 7304.9 7307.9 24234.2 25178.0 26036.3 26505.6 26711.5 2842.6 2961.5 2993.4 3022.2 3037.9 15761.7 16218.5 16261.3 16520.7 16548.3 2056.1 2075.6 2080.3 2081.7 2127.6 140910.3 160897.4 164838.0 173714.3 177508.0 15916.6 18923.9 19878.6 20055.0 20146.3 31099.0 31121.7 31160.0 31225.0 31245.2 9111.0 9800.6 10051.8 10601.4 10624.9 8767.4 8789.4 8818.3 8955.8 8984.3 8229.5 8804.4 9008.3 9414.2 9432.9 3182.8 3451.2 3855.9 3913.5 4009.5 1028.3 1038.7 1049.3 1069.9 1084.7 21558.6 21963.6 22196.4 22220.6 22424.3 1055.2 1064.1 1085.8 1088.6 1101.5 10708.3 10845.8 11541.3 11570.5 11653.7 791290.0 800708.0 801483.0 802469.0 816768.0 118319.0 136217.0 155159.2 156213.0 159366.0 1268.7 1284.1 1330.9 1331.1 1331.4 19612.4 20848.9 20867.2 20873.0 20899.7 61090.1 61330.0 61477.7 61960.0 62639.4 87246.4 87610.6 87984.6 90098.0 90909.5 11213.7 11268.8 11467.0 11760.8 11801.9 12095.5 12125.7 12371.2 12514.9 12521.2 10813.5 10932.2 10962.8 10966.8 11198.6 10184.3 10252.8 10298.6 10448.3 10555.7 11225.0 11226.4 11373.4 11397.8 11490.2 11328.4 11412.5 11444.4 11540.3 11683.9 10749.6 10779.6 11002.9 11008.3 11193.0 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6625.60 Median:2366.72 stddev:4152.58 (175.5%) stddev-sans-first:4147.01 14:47:12 INFO - PROCESS | 1638 | Values: 1593.4 1597.1 1620.2 1620.6 1623.3 10646.9 11224.4 11228.2 11242.7 11242.8 10767.8 11155.8 11282.3 11293.2 11309.4 2295.9 2357.6 2362.5 2363.0 2364.3 2002.6 2262.1 2325.8 2378.4 2383.2 2303.1 2350.0 2357.8 2369.2 2371.4 2289.9 2295.5 2317.3 2325.8 2328.7 2340.5 2346.6 2346.8 2349.0 2357.7 11076.2 11163.6 11169.6 11178.9 11184.6 5420.0 5584.1 5592.6 5603.2 5611.5 3214.2 3322.0 3481.5 3481.7 3499.8 1102.9 1107.5 1127.8 1138.5 1163.9 13611.8 13896.9 14260.2 15303.0 15312.2 4282.9 4432.6 4460.6 4462.2 4464.8 11486.0 11512.2 11522.9 11524.0 11544.8 4708.9 5484.7 5485.1 5488.9 5489.0 4095.3 4700.6 5094.8 5149.2 5152.2 3734.6 3809.9 3905.5 3935.1 3954.1 1001.5 1008.1 1009.0 1021.1 1022.9 981.7 988.4 989.7 1004.4 1033.1 6639.7 6669.6 6732.8 6903.3 6905.7 994.5 1008.4 1014.7 1021.7 1041.6 4085.1 4087.3 4125.6 4156.7 4229.9 13049.5 13635.7 13650.0 13659.4 13680.2 13565.5 13586.2 13588.1 13588.5 13589.7 8745.7 9856.9 9892.9 10090.9 10274.4 6297.6 6351.0 6403.4 6475.5 6564.2 11229.8 11889.0 11941.0 12253.8 12406.1 9605.3 12298.3 12372.4 12448.1 12506.9 9167.6 9216.2 9230.5 9251.3 9345.5 8676.2 8905.3 10353.4 10355.4 10367.3 8600.4 8875.7 8923.3 8984.0 9029.3 7494.0 8683.4 8715.2 8777.8 8813.7 7841.2 7978.8 7985.1 7990.0 7993.0 5799.6 5803.9 5856.2 5988.6 6028.2 5905.0 5916.8 6109.3 6139.4 6252.4 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4334.73 Median:4714.96 stddev:1191.90 (25.3%) stddev-sans-first:1157.16 14:47:12 INFO - PROCESS | 1638 | Values: 352.6 371.2 378.9 379.1 388.3 4274.6 4597.4 4726.8 4975.8 4979.6 4559.0 4606.8 4615.6 4855.5 4864.9 3627.8 4084.1 4091.9 4131.6 4149.9 3519.9 4056.9 4110.4 4155.2 4168.8 3746.0 3885.1 4130.6 4188.3 4203.2 3898.5 4040.4 4086.0 4097.9 4185.4 3548.5 3617.5 3784.6 3791.5 3870.2 4460.3 4839.0 4843.7 4880.9 4898.0 4449.0 4456.6 4710.3 4734.1 4777.6 5145.6 5194.9 5204.1 5213.9 5267.0 3863.2 3982.4 4240.9 4241.7 4310.3 4685.4 5012.4 5224.9 5296.3 5492.2 3902.6 4030.8 4121.9 4276.8 4289.5 3995.4 4254.1 4374.7 4429.5 4451.3 4020.0 4122.3 4380.2 4717.7 4762.9 4393.5 5010.5 5012.2 5230.5 5243.0 1278.9 1397.9 1478.3 1479.8 1490.9 940.9 1024.6 1050.4 1058.3 1136.0 4162.4 4596.0 4666.1 4701.6 4712.2 4903.8 5319.1 5393.7 5424.8 5471.7 4370.8 4748.0 4748.5 4750.0 4836.6 2289.2 2492.7 2697.7 2778.9 2829.4 4706.6 5083.3 5089.8 5176.8 5563.4 4910.4 4923.2 5077.4 5391.7 5549.9 4833.7 4835.9 5013.4 5194.7 5283.6 4566.1 4793.6 4805.0 5402.5 5434.5 5022.6 5085.6 5158.0 5258.7 5389.4 4707.4 4813.7 5183.7 5520.6 5523.5 3955.7 3991.5 4049.7 4158.3 4216.4 4583.9 4789.6 4906.8 5164.9 5400.8 5037.6 5064.9 5145.4 5264.5 5268.9 4956.8 5127.5 5283.3 5290.3 5302.8 4357.5 4656.3 4890.9 5148.1 5194.6 4412.3 5278.6 5293.7 5294.9 5315.6 4753.0 4856.4 5184.4 5258.3 5328.8 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21057.85 Median:3530.92 stddev:31471.84 (891.3%) stddev-sans-first:31523.17 14:47:12 INFO - PROCESS | 1638 | Values: 744.0 763.7 792.0 827.7 860.9 15855.8 16575.8 16598.7 16607.2 16634.7 14831.1 14858.3 14887.2 14904.1 14921.3 962.6 973.3 975.3 983.8 993.6 999.6 1001.1 1001.3 1001.3 1001.6 1032.5 1034.8 1034.9 1035.2 1037.5 1030.8 1031.0 1032.7 1032.8 1033.9 766.3 766.7 766.9 767.6 768.5 16590.8 16670.5 16798.6 17418.7 17645.6 14467.0 14471.4 14618.6 14623.7 14688.6 11701.0 11824.4 11885.9 11924.5 12548.7 3705.2 3723.1 3821.5 3903.1 3947.2 115426.0 118703.8 121844.4 122240.0 123434.0 9932.9 10024.6 10124.9 10417.2 10615.9 19661.2 19766.1 19798.9 19830.0 19957.0 7947.7 7954.6 7957.6 8097.0 8224.3 4384.7 4455.3 4509.9 4570.8 4736.6 3434.8 3493.2 3523.0 3538.8 3546.2 563.7 599.4 614.7 624.2 626.3 554.6 629.6 713.6 740.6 767.0 9979.3 10497.4 10520.2 10644.5 10855.3 684.6 705.7 714.9 723.0 729.9 4440.1 4679.8 5108.3 5156.7 5199.4 91443.0 91866.2 110450.0 111161.0 113121.0 93217.5 95150.0 96425.0 96734.5 98022.0 81015.4 81671.0 82952.6 84725.6 85242.1 9835.4 9857.3 9952.1 9999.5 10038.5 41928.0 48960.8 49768.8 53068.5 54583.7 59046.7 68250.3 69314.3 70420.0 70969.0 9356.4 11480.4 11703.3 11823.8 11847.5 13050.1 13064.0 13424.3 13622.8 13841.7 11866.2 12157.2 12353.0 12451.6 12865.9 10775.8 12127.9 12197.9 12307.8 12358.2 11703.6 11901.3 11933.6 12071.2 12071.8 9092.1 9130.3 9277.5 9445.3 9635.4 8911.5 9838.8 10069.4 10203.9 10330.6 14:47:12 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:47:12 INFO - PROCESS | 1638 | 14:47:12 INFO - TEST-INFO | 1638: exit 0 14:47:12 INFO - TEST-OK | dromaeo_css | took 1118697ms 14:47:12 INFO - TEST-START | kraken 14:47:12 INFO - operating with platform_type : mac_ 14:47:12 INFO - Initialising browser for kraken test... 14:47:12 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpxmcG0z/profile http://localhost:49224/getInfo.html) 14:47:14 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 14:47:14 INFO - PROCESS | 1649 | colorDepth:24 14:47:14 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 14:47:14 INFO - PROCESS | 1649 | __metrics 14:47:14 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:47:14 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:47:15 INFO - TEST-INFO | 1649: exit 0 14:47:15 INFO - Browser initialized. 14:47:15 INFO - Running cycle 1/1 for kraken test... 14:47:15 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpxmcG0z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:27 INFO - PROCESS | 1651 | RSS: Main: 193744896 14:47:27 INFO - PROCESS | 1651 | 14:47:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 14:47:31 INFO - PROCESS | 1651 | RSS: Main: 244637696 14:47:31 INFO - PROCESS | 1651 | 14:47:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 14:47:34 INFO - PROCESS | 1651 | RSS: Main: 259252224 14:47:34 INFO - PROCESS | 1651 | 14:47:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 14:47:38 INFO - PROCESS | 1651 | RSS: Main: 355123200 14:47:38 INFO - PROCESS | 1651 | 14:47:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 14:47:40 INFO - PROCESS | 1651 | RSS: Main: 257798144 14:47:40 INFO - PROCESS | 1651 | 14:47:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:47:43 INFO - PROCESS | 1651 | RSS: Main: 258908160 14:47:43 INFO - PROCESS | 1651 | 14:47:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 14:47:47 INFO - PROCESS | 1651 | RSS: Main: 300249088 14:47:47 INFO - PROCESS | 1651 | 14:47:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 14:47:51 INFO - PROCESS | 1651 | RSS: Main: 300314624 14:47:51 INFO - PROCESS | 1651 | 14:47:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 14:47:54 INFO - PROCESS | 1651 | RSS: Main: 297824256 14:47:54 INFO - PROCESS | 1651 | 14:47:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:47:56 INFO - PROCESS | 1651 | RSS: Main: 275697664 14:47:56 INFO - PROCESS | 1651 | 14:47:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:47:59 INFO - PROCESS | 1651 | RSS: Main: 280068096 14:47:59 INFO - PROCESS | 1651 | 14:48:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:48:02 INFO - PROCESS | 1651 | RSS: Main: 286244864 14:48:02 INFO - PROCESS | 1651 | 14:48:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:48:05 INFO - PROCESS | 1651 | RSS: Main: 299048960 14:48:05 INFO - PROCESS | 1651 | 14:48:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:48:09 INFO - PROCESS | 1651 | RSS: Main: 292585472 14:48:09 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 14:48:11 INFO - PROCESS | 1651 | RSS: Main: 337633280 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | __start_tp_report 14:48:11 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 14:48:11 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 14:48:11 INFO - PROCESS | 1651 | |i|pagename|runs| 14:48:11 INFO - PROCESS | 1651 | |0;ai-astar;103;161;171;116;116;116;116;115;117;114 14:48:11 INFO - PROCESS | 1651 | |1;audio-beat-detection;143;111;112;147;111;133;136;116;128;111 14:48:11 INFO - PROCESS | 1651 | |2;audio-dft;189;184;185;185;185;184;184;185;185;185 14:48:11 INFO - PROCESS | 1651 | |3;audio-fft;83;63;62;90;64;58;60;60;75;60 14:48:11 INFO - PROCESS | 1651 | |4;audio-oscillator;89;70;111;97;97;69;83;69;69;83 14:48:11 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;90;89;90;89;89;90;90;89;90;89 14:48:11 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;96;97;107;149;148;96;97;97;99 14:48:11 INFO - PROCESS | 1651 | |7;imaging-desaturate;92;127;82;85;86;87;83;85;83;84 14:48:11 INFO - PROCESS | 1651 | |8;json-parse-financial;63;60;60;60;63;63;65;63;64;65 14:48:11 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;62;60;62;89;84;80;57;58;59;58 14:48:11 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;88;81;88;85;86;80;80;85;81;89 14:48:11 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;138;136;135;135;136;137;139;149;175;167 14:48:11 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;167;161;166;164;165;170;165;164;163;164 14:48:11 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;64;65;64;65;64;63;64;63;64;63 14:48:11 INFO - PROCESS | 1651 | __end_tp_report 14:48:11 INFO - PROCESS | 1651 | __start_cc_report 14:48:11 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2169 14:48:11 INFO - PROCESS | 1651 | __end_cc_report 14:48:11 INFO - PROCESS | 1651 | __startTimestamp1496699291296__endTimestamp 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | ------- Summary: start ------- 14:48:11 INFO - PROCESS | 1651 | Number of tests: 14 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:124.50 Median:116.00 stddev:22.37 (19.3%) stddev-sans-first:22.33 14:48:11 INFO - PROCESS | 1651 | Values: 103.0 161.0 171.0 116.0 116.0 116.0 116.0 115.0 117.0 114.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:130.50 stddev:14.30 (11.0%) stddev-sans-first:13.56 14:48:11 INFO - PROCESS | 1651 | Values: 143.0 111.0 112.0 147.0 111.0 133.0 136.0 116.0 128.0 111.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.00 stddev:1.45 (0.8%) stddev-sans-first:0.50 14:48:11 INFO - PROCESS | 1651 | Values: 189.0 184.0 185.0 185.0 185.0 184.0 184.0 185.0 185.0 185.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.50 Median:63.50 stddev:11.18 (17.6%) stddev-sans-first:10.35 14:48:11 INFO - PROCESS | 1651 | Values: 83.0 63.0 62.0 90.0 64.0 58.0 60.0 60.0 75.0 60.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:83.00 stddev:14.77 (17.8%) stddev-sans-first:15.54 14:48:11 INFO - PROCESS | 1651 | Values: 89.0 70.0 111.0 97.0 97.0 69.0 83.0 69.0 69.0 83.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 14:48:11 INFO - PROCESS | 1651 | Values: 90.0 89.0 90.0 89.0 89.0 90.0 90.0 89.0 90.0 89.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:96.50 stddev:21.49 (22.3%) stddev-sans-first:22.34 14:48:11 INFO - PROCESS | 1651 | Values: 96.0 96.0 97.0 107.0 149.0 148.0 96.0 97.0 97.0 99.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:85.00 stddev:13.51 (15.9%) stddev-sans-first:14.30 14:48:11 INFO - PROCESS | 1651 | Values: 92.0 127.0 82.0 85.0 86.0 87.0 83.0 85.0 83.0 84.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:1.96 (3.1%) stddev-sans-first:2.07 14:48:11 INFO - PROCESS | 1651 | Values: 63.0 60.0 60.0 60.0 63.0 63.0 65.0 63.0 64.0 65.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:62.00 stddev:12.32 (19.9%) stddev-sans-first:12.94 14:48:11 INFO - PROCESS | 1651 | Values: 62.0 60.0 62.0 89.0 84.0 80.0 57.0 58.0 59.0 58.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.50 stddev:3.53 (4.1%) stddev-sans-first:3.48 14:48:11 INFO - PROCESS | 1651 | Values: 88.0 81.0 88.0 85.0 86.0 80.0 80.0 85.0 81.0 89.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:138.50 stddev:14.57 (10.5%) stddev-sans-first:15.25 14:48:11 INFO - PROCESS | 1651 | Values: 138.0 136.0 135.0 135.0 136.0 137.0 139.0 149.0 175.0 167.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.00 stddev:2.42 (1.5%) stddev-sans-first:2.45 14:48:11 INFO - PROCESS | 1651 | Values: 167.0 161.0 166.0 164.0 165.0 170.0 165.0 164.0 163.0 164.0 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 14:48:11 INFO - PROCESS | 1651 | Values: 64.0 65.0 64.0 65.0 64.0 63.0 64.0 63.0 64.0 63.0 14:48:11 INFO - PROCESS | 1651 | -------- Summary: end -------- 14:48:11 INFO - PROCESS | 1651 | 14:48:11 INFO - TEST-INFO | 1651: exit 0 14:48:12 INFO - TEST-OK | kraken | took 59485ms 14:48:12 INFO - SUITE-END | took 1178s 14:48:12 INFO - Completed test suite (00:19:38) 14:48:12 INFO - javascript benchmark 14:48:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7608.077052182029, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2894.3777087926096, 3273.6562660920604, 3422.828858557073, 3438.65613438656, 3451.6203217646053, 5889.057750759879, 5944.732487038083, 5959.672218028007, 5966.642001479914, 5986.2517185351835, 5908.0, 5982.6410415375, 6005.819825405245, 6010.008348622472, 6017.458428741424, 5308.761105750251, 5344.652597581145, 5409.107497262947, 5423.484768946943, 5430.348358197002, 5258.0, 5258.0, 5273.446288139748, 5459.290292262001, 5474.562035037207, 5349.384820745625, 5365.195220716904, 5367.624266301341, 5372.328458942632, 5374.247605335257, 5345.1714984177515, 5359.383670877841, 5362.490563396491, 5363.088274993261, 5370.409254981988, 5172.534471897509, 5306.469353064701, 5306.6550674206055, 5322.866928326784, 5343.225232341328, 5940.970295148497, 5954.583179177459, 5956.0172571525645, 5959.10613407988, 5971.850703732398, 6129.786714141072, 6134.130457564626, 6148.477379422754, 6159.568830181889, 6166.568340216187, 6205.503559715212, 6217.502599792006, 6273.278572964122, 6281.466075383598, 6289.150964619764, 5962.761489540457, 6036.396360363972, 6046.697665116727, 6080.847978800522, 6083.117947897575, 5874.265716785402, 6440.259370172444, 6480.060391243291, 6491.383318584727, 6496.415322620986, 6108.858142493628, 6115.696722738819, 6148.1085242640465, 6162.753489860355, 6176.845578860474, 6354.078658594478, 6357.078223657544, 6362.809115726535, 6375.776847810304, 6378.362163783585, 6075.5747097703625, 6242.781502647476, 6248.187735594343, 6250.874982500324, 6270.372962703785, 6139.201903752573, 6145.539084568675, 6179.443850053517, 6214.751409943552, 6294.905576416266, 4694.920168361227, 4704.388429504143, 4781.569658730731, 4881.975590122027, 4897.559219670246, 4396.714213576107, 4398.450193725785, 4412.624926881251, 4431.8448854289945, 4448.599626033608, 5808.79669211584, 5810.157527158538, 5844.561657875676, 5859.5019423349495, 5890.410958904075, 6213.875722485525, 6259.18630578031, 6301.653409062454, 6334.3032266450655, 6369.426751592379, 5876.030454974882, 5886.0, 5889.823305300848, 5897.174395584621, 5900.970495147582, 5518.392976772552, 5522.696251706115, 5526.557875369961, 5539.196816461671, 5542.91685624716, 6211.192544969125, 6250.937490625127, 6442.484601231797, 6455.548111632141, 6531.477481801349, 6324.810255692337, 6373.2989371168205, 6378.52161087911, 6384.840378990562, 6388.425041746079, 6528.412442879973, 6535.901961470677, 6549.6070235786, 6550.312217217314, 6556.409923106943, 6167.167432396748, 6178.258608966953, 6301.40136687014, 6310.148130002391, 6311.873762524816, 6288.088227207121, 6288.654124023223, 6289.937100628935, 6327.715252813727, 6335.873282534416, 6208.906866397098, 6228.626282423069, 6253.062040693932, 6303.842403939938, 6325.430711236011, 4631.814727411001, 5707.286589176353, 6237.5009999200865, 6280.089387038763, 6298.590591611531, 5945.137954996415, 6176.629402235881, 6183.505319574513, 6187.3503282154725, 6188.81433556994, 5236.869078273074, 6146.907796383147, 6176.629402235881, 6230.0, 6245.6252624842655, 5960.4635582797755, 5988.580799344179, 6015.608985416109, 6110.022396416552, 6166.876662466815], "value": 5790.028947814817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.658392015553, 812.7480481050873, 876.5398165962869, 890.9287257019444, 892.7589550821283, 40998.18003639925, 41202.58797412025, 41388.96527586808, 41418.17163656725, 43555.56444435555, 42287.577124228825, 42512.362314565304, 42527.3620895686, 42597.36103958436, 42629.14741705164, 7474.140473845496, 9509.28680348975, 9661.806763864719, 9674.129328360446, 9674.516274186297, 7472.476926615139, 11698.0, 11915.582954596592, 12095.455704493275, 12118.939405302961, 11824.113191510629, 11829.526818927232, 11840.703982400466, 11873.465694043789, 11929.522819087226, 10102.141317987905, 11771.470283837249, 11780.823287650692, 11792.646220613395, 11801.232919860182, 11367.147463940237, 11428.599999000078, 11446.0, 11454.140939429493, 11464.598739044093, 39820.0, 39964.00089997744, 39987.0, 40090.398644020366, 40163.0, 3193.1218914798383, 3226.2418331692033, 3249.333886553251, 3278.6721327867263, 3299.15871452778, 27930.301742456602, 28001.0, 29844.701552984625, 30026.099217023526, 30173.245668858235, 3047.405755877605, 3059.8011129276524, 3109.673484284163, 3125.3905488429764, 3126.983730287666, 350949.0, 352103.99999999744, 352186.99999999744, 352550.0, 352870.23564882524, 52874.206886896725, 52932.47067529275, 52987.47012529903, 53093.0, 53104.73447632737, 55512.0, 55582.16626750602, 55699.0, 55787.44212557822, 55793.442065579635, 15105.3202605883, 15244.618884527976, 15251.0, 15261.23693815327, 15264.694706105816, 13016.739665206833, 13031.02267329954, 13043.934780326037, 13067.411966461343, 13085.411156497981, 10807.0, 12265.938670306592, 12274.570390036306, 12315.014798816073, 12407.44166512509, 5664.26364572603, 5741.483266506035, 5746.367899531048, 5749.655020698771, 5758.654480731169, 1020.8570800087994, 1105.784372047443, 1107.363266121983, 1192.7316353820322, 1195.2290772451893, 25093.121740738985, 27287.044953426535, 27502.724972750417, 27560.310992225353, 27735.861320693268, 1073.6993641780314, 1274.9107562470724, 1279.9872001280055, 1289.2135797163724, 1290.4256806695817, 10856.074172921397, 12669.556565520346, 12849.565455586971, 13003.804942925864, 13183.604491865259, 432982.0, 434952.0, 435084.0, 435207.0, 435344.0, 46387.0, 46409.767951160706, 46465.767671161426, 46501.53498464972, 46536.30195547069, 1446.7034257977089, 1455.3814628782725, 1469.926503674812, 1478.3051965576162, 1480.711261304057, 22555.5488890218, 27459.450810984068, 28139.0, 28216.576751349156, 28901.132966010213, 45454.0, 45619.85950351268, 45639.0, 45799.542004580864, 45802.08395831998, 455659.0, 457347.0, 458616.0, 459010.0, 459106.0, 23974.880125599262, 24468.632970505812, 28349.574756378257, 28682.42635147327, 29685.85157074201, 36976.0, 37667.81166094152, 37712.0, 37897.0, 38225.4266186013, 27423.725762742117, 27526.724732752416, 27568.310792230353, 27874.721252787214, 28668.713312866603, 26416.0, 26421.867890660425, 27423.314417139733, 28387.006454773982, 28562.714372856837, 30471.93348232801, 32112.518312225802, 32551.860684875915, 32706.182345441553, 32824.83587582047, 27516.449671006867, 27766.722332776415, 28065.859670701517, 28205.71794282113, 28821.42357152887, 19562.924039177982, 20185.6972145415, 21966.560668786853, 22623.622471103696, 22803.54392912099], "value": 18567.576713256683, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.3489753638021, 417.38019041723055, 424.5372543927119, 425.3131193123106, 448.54472710199155, 19922.800771992275, 21180.470488237803, 22320.21879234227, 22669.319920402373, 22669.54660906783, 21774.564508709856, 23159.536809263845, 23220.838958052114, 23296.534069318644, 23576.528469430643, 6668.699908504104, 6683.13119294491, 7043.859122817541, 7056.964715176443, 7063.328983746562, 6829.692663830139, 6914.239433662293, 6993.790186294419, 6994.0, 7001.964990175042, 6352.4918006559365, 6990.0, 7004.929950700479, 7404.666789994436, 7600.125985511681, 7291.708331666727, 7455.142658594277, 7486.887696684554, 7545.962270188641, 7550.509216900911, 7293.52592081513, 7297.635118244067, 7302.56184628924, 7304.926950730478, 7307.89038164428, 24234.151804686928, 25178.0, 26036.34909127268, 26505.602415963775, 26711.465770684477, 2842.6345644150465, 2961.511350627144, 2993.3863557970562, 3022.2142243016756, 3037.9392412151633, 15761.684766304725, 16218.513444596681, 16261.268242928978, 16520.6695866082, 16548.255328510248, 2056.148281647507, 2075.616011037963, 2080.3238947342174, 2081.687746837971, 2127.617028934791, 140910.2954485221, 160897.37867832955, 164838.0, 173714.26285737235, 177508.0, 15916.602084947968, 18923.90538047301, 19878.60242795165, 20055.0, 20146.294879679142, 31099.0, 31121.732218117224, 31160.0, 31225.0, 31245.218869527987, 9111.0, 9800.55897484615, 10051.849222261671, 10601.363918164934, 10624.946875265574, 8767.386282960259, 8789.428687135316, 8818.294536437197, 8955.820883582423, 8984.281257499384, 8229.506229626242, 8804.427712198687, 9008.324375671851, 9414.199793017533, 9432.90567094334, 3182.8090314580736, 3451.162512581818, 3855.8843234703004, 3913.5108111486065, 4009.4787677602308, 1028.2545154762738, 1038.657243109772, 1049.2969710294121, 1069.8693209757039, 1084.7017070305571, 21558.568828623655, 21963.560728785655, 22196.445088872908, 22220.55558888813, 22424.327270181922, 1055.171690223171, 1064.084886997182, 1085.8316960871077, 1088.553692985872, 1101.5373543111912, 10708.303960242714, 10845.783084338269, 11541.307521548733, 11570.537178512934, 11653.6503904883, 791290.0, 800708.0, 801483.0, 802469.0, 816768.0, 118319.0, 136217.0, 155159.22420388053, 156213.0, 159366.0, 1268.657462485124, 1284.101129209557, 1330.8935285177163, 1331.0815537279245, 1331.42083193811, 19612.411627651192, 20848.895755521124, 20867.1653133879, 20872.956352182027, 20899.686504702746, 61090.08364874442, 61330.0, 61477.69261153666, 61960.0, 62639.43401414819, 87246.38260852185, 87610.56194718985, 87984.56007720176, 90098.0, 90909.54190520645, 11213.71965700864, 11268.830967535658, 11467.0, 11760.823587646364, 11801.881981180079, 12095.455704493495, 12125.69685757863, 12371.195872268274, 12514.87485125137, 12521.186122901981, 10813.51339189754, 10932.180086493703, 10962.835557466486, 10966.835497467704, 11198.608048718255, 10184.338018028804, 10252.794944101226, 10298.588056477656, 10448.268621196443, 10555.736106597396, 11225.045871101049, 11226.438678066228, 11373.374464404536, 11397.829032564352, 11490.195686302213, 11328.376939268419, 11412.48643811047, 11444.370559619301, 11540.307581545134, 11683.941580292045, 10749.623763168564, 10779.568817247222, 11002.889971100187, 11008.339499630049, 11193.048590869541], "value": 11744.303662633094, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1593.3546913500022, 1597.08965889443, 1620.206099011484, 1620.643458439143, 1623.3100932103857, 10646.946765266173, 11224.438778061094, 11228.214025018253, 11242.6627201184, 11242.831357529643, 10767.784644307128, 11155.832662510069, 11282.323060616369, 11293.20947533671, 11309.377984210885, 2295.8737269450216, 2357.57563638545, 2362.4802543440414, 2362.9527409451803, 2364.2670772060715, 2002.6295135399926, 2262.1177740681146, 2325.825563082767, 2378.3816207786012, 2383.201627454796, 2303.101790301775, 2349.9530009399887, 2357.823163262762, 2369.1707902234252, 2371.3834403055157, 2289.9313020609243, 2295.4605667668084, 2317.2932255662017, 2325.8139348852137, 2328.7438381777993, 2340.5435939991708, 2346.554154710617, 2346.77705617966, 2348.988255058731, 2357.6934998450265, 11076.224664273504, 11163.609273675464, 11169.609063682732, 11178.888211117948, 11184.552617895275, 5420.0, 5584.106542953149, 5592.5805564582815, 5603.187537807036, 5611.466910643482, 3214.244652506659, 3321.9501707474406, 3481.5125882376233, 3481.651834816523, 3499.8075105869166, 1102.871458085894, 1107.5126944144552, 1127.8364637127652, 1138.5161306444727, 1163.883611638838, 13611.795823062663, 13896.861031389657, 14260.215688137252, 15302.999999999889, 15312.234388280542, 4282.850100246476, 4432.601065904084, 4460.6431485481735, 4462.219111655448, 4464.799084041226, 11486.0, 11512.19414640967, 11522.884771152349, 11524.0, 11544.76910461786, 4708.882277943078, 5484.725763711798, 5485.094959331665, 5488.902219556105, 5489.039418101896, 4095.28332541804, 4700.55344742249, 5094.821681241213, 5149.201873709581, 5152.227165925066, 3734.57052438973, 3809.8857034289017, 3905.4922860028582, 3935.0555866591812, 3954.1498577805332, 1001.5042553935848, 1008.0826447932346, 1008.9899101009042, 1021.0606242257142, 1022.9028242316969, 981.7496538382667, 988.357567581078, 989.6776619854982, 1004.4124187350336, 1033.0960409641564, 6639.701213445406, 6669.63317017559, 6732.798016059526, 6903.309669033157, 6905.689243983932, 994.4778991029767, 1008.3848852200152, 1014.6753039027587, 1021.6781713760141, 1041.5885725138528, 4085.104689513119, 4087.325591277406, 4125.608067233608, 4156.688248381383, 4229.894252643709, 13049.478020879058, 13635.659108522366, 13650.0, 13659.38532766008, 13680.179189248676, 13565.525206617722, 13586.184828910296, 13588.116772409761, 13588.524401646093, 13589.728205435837, 8745.693900713444, 9856.901430985741, 9892.901070989343, 10090.899091009143, 10274.434906080238, 6297.6463392194555, 6351.0, 6403.391677790602, 6475.514336424692, 6564.179477565304, 11229.775404492028, 11889.048876089719, 11941.044716422492, 12253.794317023021, 12406.069544784357, 9605.279604029865, 12298.262104273772, 12372.4432400544, 12448.066395020229, 12506.874931250572, 9167.633294668405, 9216.170544651013, 9230.538473076185, 9251.259899208182, 9345.532723363669, 8676.175763302475, 8905.33210009239, 10353.378797272188, 10355.430451324948, 10367.32612380193, 8600.440971336842, 8875.689350872688, 8923.33075019389, 8983.955080224558, 9029.322800789836, 7494.0, 8683.435576687496, 8715.172058654418, 8777.780555486164, 8813.735587932117, 7841.1766764491185, 7978.760637180894, 7985.08171560278, 7990.0, 7993.040835099825, 5799.594028418139, 5803.912941305799, 5856.209411729362, 5988.550858685703, 6028.216331876828, 5904.996150654313, 5916.763329466773, 6109.327973922776, 6139.447449729546, 6252.437280644764], "value": 5055.57065383402, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.5892335429225, 371.1982118623772, 378.8730775190311, 379.1298968866452, 388.2758655108224, 4274.63665588425, 4597.379353787238, 4726.810927562893, 4975.8258460953875, 4979.576735977446, 4559.0, 4606.815727370909, 4615.607673347761, 4855.465898751143, 4864.902701945959, 3627.8004709740953, 4084.121913788535, 4091.9156423547784, 4131.648809851166, 4149.875503734886, 3519.9472007919903, 4056.930965207736, 4110.403991421242, 4155.210510003089, 4168.8332466701295, 3746.007308063352, 3885.057471264368, 4130.586941305875, 4188.267053265681, 4203.201391735576, 3898.5126859142747, 4040.434339192486, 4086.0, 4097.89755256118, 4185.367706594416, 3548.4677298405445, 3617.4625784041677, 3784.6026167252594, 3791.4881490998637, 3870.1872606752618, 4460.33095035744, 4839.0, 4843.709377437365, 4880.902381952376, 4898.044881248158, 4448.977755111204, 4456.55434456555, 4710.293455981596, 4734.147853386389, 4777.593904518086, 5145.614078944094, 5194.896067092834, 5204.089284375257, 5213.897730850138, 5267.0, 3863.208042351311, 3982.4424580558743, 4240.897366684653, 4241.70947688248, 4310.267254566731, 4685.367475390847, 5012.368506534099, 5224.92162617561, 5296.337957755319, 5492.203630473559, 3902.590228026073, 4030.7783071931326, 4121.876343709693, 4276.786160691953, 4289.463817022872, 3995.400689896481, 4254.129301563884, 4374.737515749065, 4429.468463784367, 4451.265541185733, 4020.0, 4122.2786012447705, 4380.218561213588, 4717.740524271129, 4762.880927976828, 4393.495707446725, 5010.498950105033, 5012.170557746469, 5230.476952304739, 5243.003829272426, 1278.8529319128234, 1397.902146849731, 1478.268257212665, 1479.7632378819337, 1490.9254537273093, 940.9153176214173, 1024.5850430575629, 1050.422267752733, 1058.3226734889677, 1135.9886401136048, 4162.375643653476, 4595.977020114878, 4666.090112428112, 4701.647376446712, 4712.175369310357, 4903.803847846118, 5319.148936170194, 5393.6763794172475, 5424.83725488236, 5471.699056551848, 4370.8033138508845, 4747.95252047482, 4748.525147485293, 4749.978754567715, 4836.613070954315, 2289.1530133850247, 2492.7382624824495, 2697.720426239824, 2778.9301119069305, 2829.3775369418695, 4706.62347012245, 5083.339165908482, 5089.803896084417, 5176.767045482887, 5563.415841336682, 4910.361652985161, 4923.163062279349, 5077.441481437039, 5391.711056255681, 5549.889002219933, 4833.709977401367, 4835.927461088017, 5013.423456302499, 5194.662346947436, 5283.6037297202865, 4566.109608626286, 4793.640476964172, 4805.0, 5402.486763757437, 5434.483724046209, 5022.623303252344, 5085.5931525477135, 5157.974210128926, 5258.737063146751, 5389.407165211902, 4707.364505791675, 4813.652177390336, 5183.740812959412, 5520.641158324696, 5523.475269849358, 3955.674848925642, 3991.529486974607, 4049.6962727794944, 4158.26159899337, 4216.388623649493, 4583.9312410313205, 4789.640776941674, 4906.754662266943, 5164.870878228075, 5400.812670868305, 5037.596992240685, 5064.898702026013, 5145.434002259673, 5264.52619264268, 5268.947310526999, 4956.801727930842, 5127.512886275797, 5283.339582552181, 5290.338707661543, 5302.761375738024, 4357.474883790648, 4656.348111264493, 4890.9021819562695, 5148.06947394727, 5194.584433245407, 4412.271975124068, 5278.60410469224, 5293.7353132344, 5294.867628309323, 5315.574754019592, 4753.0, 4856.39295088114, 5184.429712731672, 5258.316418865676, 5328.8276579152525], "value": 3985.62833138955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9553626782397, 763.709790279694, 791.9762407127785, 827.6978902700514, 860.948343099414, 15855.84144158558, 16575.83424165758, 16598.66802663946, 16607.169641517936, 16634.66730665386, 14831.110133391978, 14858.257087145654, 14887.18120503372, 14904.105753654761, 14921.328540215689, 962.6197651927502, 973.2846357926918, 975.2977855943709, 983.8081574093055, 993.5677980078689, 999.5502024089153, 1001.1190152665644, 1001.2590682894642, 1001.329109496639, 1001.6043662753244, 1032.4992378696327, 1034.8395998620226, 1034.9275550711454, 1035.244271681673, 1037.5123691864812, 1030.798994196132, 1030.99484502577, 1032.746976990642, 1032.8192566300943, 1033.8914413986536, 766.2529034191674, 766.6894907562446, 766.861964846333, 767.620028086101, 768.5388766739967, 16590.83409165917, 16670.499885003468, 16798.58003549909, 17418.651626967516, 17645.55886102845, 14467.0, 14471.42114315426, 14618.561443156723, 14623.70752584953, 14688.632784180481, 11701.0, 11824.408779561074, 11885.940570297093, 11924.46339914706, 12548.686282842911, 3705.207291594466, 3723.1436769543157, 3821.5032045833864, 3903.1217975955465, 3947.210557888434, 115426.0, 118703.81296187014, 121844.39077804644, 122240.0, 123434.0, 9932.851007234898, 10024.599016039278, 10124.898751012543, 10417.218708596885, 10615.946920265349, 19661.213551457782, 19766.110525026408, 19798.90100549488, 19830.0, 19957.002149892447, 7947.682092716343, 7954.642041108164, 7957.641906114239, 8096.987876515435, 8224.342052635775, 4384.674443371214, 4455.26488129455, 4509.940164061443, 4570.81716731327, 4736.597389221886, 3434.845431955568, 3493.2314890724, 3523.0, 3538.840752166107, 3546.2021045265124, 563.6956043736336, 599.4155698194226, 614.6895817612167, 624.1854380034008, 626.2610120058284, 554.5535843645823, 629.5750368501243, 713.6467448612866, 740.6222826358596, 766.9654865531064, 9979.251556133318, 10497.422641754623, 10520.24156559978, 10644.520996555173, 10855.348679079281, 684.5789839248827, 705.6612825843624, 714.8891921752137, 722.989155162673, 729.8759210934154, 4440.149656829925, 4679.789409476513, 5108.310378098983, 5156.690598564098, 5199.402068762065, 91443.0, 91866.16267674742, 110450.0, 111161.0, 113121.0, 93217.53391233001, 95150.0, 96425.0, 96734.5163274179, 98022.0, 81015.379692407, 81671.0, 82952.58523707344, 84725.57637211775, 85242.14757852466, 9835.360701554377, 9857.260705446977, 9952.054554817281, 9999.450030248407, 10038.498075096362, 41928.0, 48960.75519622379, 49768.75115624399, 53068.469315306356, 54583.72708136434, 59046.704766475894, 68250.3174968244, 69314.30685693078, 70420.0, 70969.0, 9356.4386136832, 11480.368579728196, 11703.2978021319, 11823.82264266054, 11847.526098955945, 13050.10601162905, 13064.0, 13424.261665608485, 13622.795658064939, 13841.723165536432, 11866.228695134789, 12157.209781364536, 12353.046845372477, 12451.564195253122, 12865.871341286467, 10775.784484310427, 12127.939360303142, 12197.878021220029, 12307.753844922872, 12358.196717213352, 11703.590374336858, 11901.345426001306, 11933.641990740292, 12071.155019148575, 12071.818922715991, 9092.128601451845, 9130.315226358183, 9277.48973806447, 9445.338826282094, 9635.373700709431, 8911.50986695712, 9838.819341679044, 10069.446180460145, 10203.94898025505, 10330.586776528855], "value": 7622.0972707691435, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 161.0, 171.0, 116.0, 116.0, 116.0, 116.0, 115.0, 117.0, 114.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 112.0, 147.0, 111.0, 133.0, 136.0, 116.0, 128.0, 111.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 184.0, 185.0, 185.0, 185.0, 184.0, 184.0, 185.0, 185.0, 185.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 63.0, 62.0, 90.0, 64.0, 58.0, 60.0, 60.0, 75.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 111.0, 97.0, 97.0, 69.0, 83.0, 69.0, 69.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 89.0, 89.0, 90.0, 90.0, 89.0, 90.0, 89.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 107.0, 149.0, 148.0, 96.0, 97.0, 97.0, 99.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 127.0, 82.0, 85.0, 86.0, 87.0, 83.0, 85.0, 83.0, 84.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 63.0, 63.0, 65.0, 63.0, 64.0, 65.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 62.0, 89.0, 84.0, 80.0, 57.0, 58.0, 59.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 88.0, 85.0, 86.0, 80.0, 80.0, 85.0, 81.0, 89.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 135.0, 135.0, 136.0, 137.0, 139.0, 149.0, 175.0, 167.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 161.0, 166.0, 164.0, 165.0, 170.0, 165.0, 164.0, 163.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 64.0, 63.0, 64.0, 63.0, 64.0, 63.0], "value": 63.9, "unit": "score"}]}]} 14:48:12 INFO - Return code: 0 14:48:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:48:12 INFO - # TBPL SUCCESS # 14:48:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:48:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:48:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:48:12 INFO - 2017-06-05 14:48:12.635 screenresolution[1655:9717] starting screenresolution argv=screenresolution get 14:48:12 INFO - 2017-06-05 14:48:12.638 screenresolution[1655:9717] Display 0: 1600x1200x32@60 14:48:12 INFO - 2017-06-05 14:48:12.644 screenresolution[1656:9720] starting screenresolution argv=screenresolution list 14:48:12 INFO - Available Modes on Display 0 14:48:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:48:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:48:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:48:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:48:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:48:12 INFO - Intel Iris: 14:48:12 INFO - Chipset Model: Intel Iris 14:48:12 INFO - Type: GPU 14:48:12 INFO - Bus: Built-In 14:48:12 INFO - VRAM (Dynamic, Max): 1536 MB 14:48:12 INFO - Vendor: Intel (0x8086) 14:48:12 INFO - Device ID: 0x0a2e 14:48:12 INFO - Revision ID: 0x0009 14:48:12 INFO - Displays: 14:48:12 INFO - Display: 14:48:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:48:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:48:12 INFO - Display Serial Number: 1600x1200 60 14:48:12 INFO - Main Display: Yes 14:48:12 INFO - Mirror: Off 14:48:12 INFO - Online: Yes 14:48:12 INFO - Rotation: Supported 14:48:12 INFO - Return code: 0 14:48:12 INFO - Running post-action listener: _resource_record_post_action 14:48:12 INFO - [mozharness: 2017-06-05 21:48:12.879815Z] Finished run-tests step (success) 14:48:12 INFO - Running post-run listener: _resource_record_post_run 14:48:12 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39748608; Write bytes: 509250560; Read time: 380; Write time: 1676 14:48:12 INFO - TinderboxPrint: CPU usage
53.8% 14:48:12 INFO - TinderboxPrint: I/O read bytes / time
39,748,608 / 380 14:48:12 INFO - TinderboxPrint: I/O write bytes / time
509,250,560 / 1,676 14:48:12 INFO - TinderboxPrint: CPU idle
2,217.7 (46.2%) 14:48:12 INFO - TinderboxPrint: CPU system
958.2 (20.0%) 14:48:12 INFO - TinderboxPrint: CPU user
1,625.8 (33.9%) 14:48:12 INFO - TinderboxPrint: Swap in / out
248,303,616 / 0 14:48:12 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200620544; Write bytes: 202610688; Read time: 15459; Write time: 425 14:48:12 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 38777856; Write bytes: 296711168; Read time: 366; Write time: 1218 14:48:13 INFO - Running post-run listener: _upload_blobber_files 14:48:13 INFO - Blob upload gear active. 14:48:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:48:13 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 14:48:13 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'] 14:48:13 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 14:48:13 INFO - (blobuploader) - INFO - Open directory for files ... 14:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:48:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:14 INFO - (blobuploader) - INFO - Done attempting. 14:48:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:48:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:48:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:15 INFO - (blobuploader) - INFO - Done attempting. 14:48:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:48:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:48:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:16 INFO - (blobuploader) - INFO - Done attempting. 14:48:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:48:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:48:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:19 INFO - (blobuploader) - INFO - Done attempting. 14:48:19 INFO - (blobuploader) - INFO - Iteration through files over. 14:48:19 INFO - Return code: 0 14:48:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:48:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:48:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/addda3935e13b85d77d0fa7d330dfaeca63e3a2dacbdedf6d88d5d12cb40ade001b4d936b40ee026b354d387f15038b2cfaaaeed4567b784a98d0c4e4173f99c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b33e6f600a25649f818ff68b8ac7eb115d6cc1ac1c1fcd3b10cc821f9eaa73b44afd6bef5999bbcc114574db44ade9f305089d5d6a1b137503aa26a6f257ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52b031e2a803b97015bf19ab115318aaeb354610dcf8c9d4bae6bd4f2d0b28a2e78fa129fd44ba791bb628ae34002861f1f4de835f2d113d3865370f3975b7d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7c520f417990f8bcaac9c8ef20233adcf38eb83ff53cba311d545247fb1a2c5ed7316d8c2015d38b238bb32f20b9e350e4235e791e94a43c03453fba41b6443"} 14:48:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:48:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:48:19 INFO - Contents: 14:48:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/addda3935e13b85d77d0fa7d330dfaeca63e3a2dacbdedf6d88d5d12cb40ade001b4d936b40ee026b354d387f15038b2cfaaaeed4567b784a98d0c4e4173f99c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b33e6f600a25649f818ff68b8ac7eb115d6cc1ac1c1fcd3b10cc821f9eaa73b44afd6bef5999bbcc114574db44ade9f305089d5d6a1b137503aa26a6f257ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52b031e2a803b97015bf19ab115318aaeb354610dcf8c9d4bae6bd4f2d0b28a2e78fa129fd44ba791bb628ae34002861f1f4de835f2d113d3865370f3975b7d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7c520f417990f8bcaac9c8ef20233adcf38eb83ff53cba311d545247fb1a2c5ed7316d8c2015d38b238bb32f20b9e350e4235e791e94a43c03453fba41b6443"} 14:48:19 INFO - Running post-run listener: copy_logs_to_upload_dir 14:48:19 INFO - Copying logs to upload dir... 14:48:19 INFO - mkdir: /builds/slave/test/build/upload/logs 14:48:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.903896 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-05 14:48:19.161733) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 14:48:19.167404) ========= 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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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/addda3935e13b85d77d0fa7d330dfaeca63e3a2dacbdedf6d88d5d12cb40ade001b4d936b40ee026b354d387f15038b2cfaaaeed4567b784a98d0c4e4173f99c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b33e6f600a25649f818ff68b8ac7eb115d6cc1ac1c1fcd3b10cc821f9eaa73b44afd6bef5999bbcc114574db44ade9f305089d5d6a1b137503aa26a6f257ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52b031e2a803b97015bf19ab115318aaeb354610dcf8c9d4bae6bd4f2d0b28a2e78fa129fd44ba791bb628ae34002861f1f4de835f2d113d3865370f3975b7d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7c520f417990f8bcaac9c8ef20233adcf38eb83ff53cba311d545247fb1a2c5ed7316d8c2015d38b238bb32f20b9e350e4235e791e94a43c03453fba41b6443"} build_url:https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011054 build_url: 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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/addda3935e13b85d77d0fa7d330dfaeca63e3a2dacbdedf6d88d5d12cb40ade001b4d936b40ee026b354d387f15038b2cfaaaeed4567b784a98d0c4e4173f99c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b33e6f600a25649f818ff68b8ac7eb115d6cc1ac1c1fcd3b10cc821f9eaa73b44afd6bef5999bbcc114574db44ade9f305089d5d6a1b137503aa26a6f257ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/52b031e2a803b97015bf19ab115318aaeb354610dcf8c9d4bae6bd4f2d0b28a2e78fa129fd44ba791bb628ae34002861f1f4de835f2d113d3865370f3975b7d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f7c520f417990f8bcaac9c8ef20233adcf38eb83ff53cba311d545247fb1a2c5ed7316d8c2015d38b238bb32f20b9e350e4235e791e94a43c03453fba41b6443"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 14:48:19.200814) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:48:19.201187) ========= 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.eeyvuUPuMF/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.MLoMOcG1SE/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.005059 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:48:19.250058) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 14:48:19.250376) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 14:48:19.250713) ========= ========= Total master_lag: 0.17 =========