builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0148 starttime: 1496055498.04 results: success (0) buildid: 20170529024916 builduid: 5ecc714c79cb4746852bc1d6e6780ea3 revision: 6fb66467485a8beee11dad38a08768c2a9955fe7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.044490) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.045047) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.045332) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.071771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.072188) ========= 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.y0zB0kzeKC/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.gVmaCHavEL/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-29 03:58:18-- 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% 12.5M=0.001s 2017-05-29 03:58:18 (12.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.190905 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.354285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.358626) ========= 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.y0zB0kzeKC/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.gVmaCHavEL/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.029158 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.423670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-29 03:58:18.424015) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6fb66467485a8beee11dad38a08768c2a9955fe7 --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 6fb66467485a8beee11dad38a08768c2a9955fe7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y0zB0kzeKC/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.gVmaCHavEL/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-29 03:58:18,522 truncating revision to first 12 chars 2017-05-29 03:58:18,522 Setting DEBUG logging. 2017-05-29 03:58:18,522 attempt 1/10 2017-05-29 03:58:18,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6fb66467485a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 03:58:19,281 unpacking tar archive at: mozilla-beta-6fb66467485a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.049230 ========= master_lag: 1.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-29 03:58:21.247055) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:21.247379) ========= script_repo_revision: 6fb66467485a8beee11dad38a08768c2a9955fe7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:21.247827) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:21.248101) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:21.291847) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-29 03:58:21.292232) ========= /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.y0zB0kzeKC/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.gVmaCHavEL/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:58:21 INFO - MultiFileLogger online at 20170529 03:58:21 in /builds/slave/test 03:58:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:58:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:58:21 INFO - {'append_to_log': False, 03:58:21 INFO - 'base_work_dir': '/builds/slave/test', 03:58:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:58:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:58:21 INFO - 'branch': 'Mozilla-Beta', 03:58:21 INFO - 'buildbot_json_path': 'buildprops.json', 03:58:21 INFO - 'config_files': ('talos/mac_config.py',), 03:58:21 INFO - 'default_actions': ('clobber', 03:58:21 INFO - 'read-buildbot-config', 03:58:21 INFO - 'download-and-extract', 03:58:21 INFO - 'populate-webroot', 03:58:21 INFO - 'create-virtualenv', 03:58:21 INFO - 'install', 03:58:21 INFO - 'run-tests'), 03:58:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:58:21 INFO - 'download_minidump_stackwalk': True, 03:58:21 INFO - 'download_symbols': 'ondemand', 03:58:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:58:21 INFO - 'tooltool.py': '/tools/tooltool.py', 03:58:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:58:21 INFO - '/tools/misc-python/virtualenv.py')}, 03:58:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:58:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:58:21 INFO - 'gecko_profile': False, 03:58:21 INFO - 'gecko_profile_interval': 0, 03:58:21 INFO - 'installer_path': 'installer.exe', 03:58:21 INFO - 'log_level': 'info', 03:58:21 INFO - 'log_name': 'talos', 03:58:21 INFO - 'log_to_console': True, 03:58:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:58:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:58:21 INFO - 'opt_config_files': (), 03:58:21 INFO - 'pip_index': False, 03:58:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:21 INFO - 'cmd': ('bash', 03:58:21 INFO - '-c', 03:58:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:21 INFO - 'enabled': True, 03:58:21 INFO - 'halt_on_failure': False, 03:58:21 INFO - 'name': 'check_screen_resolution'},), 03:58:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:21 INFO - 'cmd': ('bash', 03:58:21 INFO - '-c', 03:58:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:21 INFO - 'enabled': True, 03:58:21 INFO - 'halt_on_failure': False, 03:58:21 INFO - 'name': 'check_screen_resolution'},), 03:58:21 INFO - 'run_cmd_checks_enabled': True, 03:58:21 INFO - 'suite': 'dromaeojs', 03:58:21 INFO - 'system_bits': '32', 03:58:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:58:21 INFO - 'title': 't-yosemite-r7-0148', 03:58:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:58:21 INFO - 'use_talos_json': True, 03:58:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:58:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:58:21 INFO - 'work_dir': 'build'} 03:58:21 INFO - [mozharness: 2017-05-29 10:58:21.500168Z] Running clobber step. 03:58:21 INFO - Running pre-action listener: _resource_record_pre_action 03:58:21 INFO - Running main action method: clobber 03:58:21 INFO - rmtree: /builds/slave/test/build 03:58:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:58:23 INFO - Running post-action listener: _resource_record_post_action 03:58:23 INFO - [mozharness: 2017-05-29 10:58:23.947625Z] Finished clobber step (success) 03:58:23 INFO - [mozharness: 2017-05-29 10:58:23.947767Z] Running read-buildbot-config step. 03:58:23 INFO - Running pre-action listener: _resource_record_pre_action 03:58:23 INFO - Running main action method: read_buildbot_config 03:58:23 INFO - Using buildbot properties: 03:58:23 INFO - { 03:58:23 INFO - "project": "", 03:58:23 INFO - "product": "firefox", 03:58:23 INFO - "script_repo_revision": "production", 03:58:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 03:58:23 INFO - "repository": "", 03:58:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 03:58:23 INFO - "buildid": "20170529024916", 03:58:23 INFO - "pgo_build": "False", 03:58:23 INFO - "basedir": "/builds/slave/test", 03:58:23 INFO - "buildnumber": 9, 03:58:23 INFO - "slavename": "t-yosemite-r7-0148", 03:58:23 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:58:23 INFO - "platform": "yosemite_r7", 03:58:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:58:23 INFO - "branch": "mozilla-beta", 03:58:23 INFO - "repo_path": "releases/mozilla-beta", 03:58:23 INFO - "revision": "6fb66467485a8beee11dad38a08768c2a9955fe7", 03:58:23 INFO - "stage_platform": "macosx64", 03:58:23 INFO - "builduid": "5ecc714c79cb4746852bc1d6e6780ea3", 03:58:23 INFO - "slavebuilddir": "test" 03:58:23 INFO - } 03:58:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 03:58:23 INFO - Running post-action listener: _resource_record_post_action 03:58:23 INFO - [mozharness: 2017-05-29 10:58:23.951100Z] Finished read-buildbot-config step (success) 03:58:23 INFO - [mozharness: 2017-05-29 10:58:23.951230Z] Running download-and-extract step. 03:58:23 INFO - Running pre-action listener: _resource_record_pre_action 03:58:23 INFO - Running main action method: download_and_extract 03:58:23 INFO - mkdir: /builds/slave/test/build/tests 03:58:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:23 INFO - trying https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 03:58:23 INFO - Downloading https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/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 03:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/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 03:58:25 INFO - Downloaded 1287 bytes. 03:58:25 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 03:58:25 INFO - Using the following test package requirements: 03:58:25 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 03:58:25 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 03:58:25 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 03:58:25 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 03:58:25 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:58:25 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 03:58:25 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 03:58:25 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 03:58:25 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 03:58:25 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 03:58:25 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:25 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 03:58:25 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 03:58:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 03:58:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 03:58:25 INFO - Fetch https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 03:58:27 INFO - Expected file size: 51393214 03:58:27 INFO - Obtained file size: 51393214 03:58:43 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:58:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 03:58:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 03:58:43 INFO - Fetch https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 03:58:45 INFO - Expected file size: 51393214 03:58:45 INFO - Obtained file size: 51393214 03:59:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 03:59:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 03:59:00 INFO - Fetch https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 03:59:01 INFO - Expected file size: 14404461 03:59:01 INFO - Obtained file size: 14404461 03:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:01 INFO - trying https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:59:01 INFO - Downloading https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 03:59:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/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 03:59:03 INFO - Downloaded 59645581 bytes. 03:59:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:59:03 INFO - mkdir: /builds/slave/test/properties 03:59:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:59:03 INFO - Writing to file /builds/slave/test/properties/build_url 03:59:03 INFO - Contents: 03:59:03 INFO - build_url:https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:59:04 INFO - Running post-action listener: _resource_record_post_action 03:59:04 INFO - Running post-action listener: set_extra_try_arguments 03:59:04 INFO - [mozharness: 2017-05-29 10:59:04.755223Z] Finished download-and-extract step (success) 03:59:04 INFO - [mozharness: 2017-05-29 10:59:04.755383Z] Running populate-webroot step. 03:59:04 INFO - Running pre-action listener: _resource_record_pre_action 03:59:04 INFO - Running main action method: populate_webroot 03:59:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:59:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:59:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:59:04 INFO - '--tppagecycles', 03:59:04 INFO - '7'], 03:59:04 INFO - 'tests': ['tsvgm']}}, 03:59:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:59:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:59:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:59:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:59:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['damp', 'tps']}, 03:59:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'tests': ['damp', 'tps']}, 03:59:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['dromaeo_dom']}, 03:59:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:59:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['basic_compositor_video']}, 03:59:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:59:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['a11yr', 03:59:04 INFO - 'ts_paint', 03:59:04 INFO - 'tpaint', 03:59:04 INFO - 'sessionrestore', 03:59:04 INFO - 'sessionrestore_no_auto_restore', 03:59:04 INFO - 'tabpaint']}, 03:59:04 INFO - 'other-e10s': {'tests': ['a11yr', 03:59:04 INFO - 'ts_paint', 03:59:04 INFO - 'tpaint', 03:59:04 INFO - 'sessionrestore', 03:59:04 INFO - 'sessionrestore_no_auto_restore', 03:59:04 INFO - 'tabpaint']}, 03:59:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:59:04 INFO - 'ts_paint', 03:59:04 INFO - 'tpaint', 03:59:04 INFO - 'sessionrestore', 03:59:04 INFO - 'sessionrestore_no_auto_restore', 03:59:04 INFO - 'tabpaint']}, 03:59:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:59:04 INFO - 'ts_paint', 03:59:04 INFO - 'tpaint', 03:59:04 INFO - 'sessionrestore', 03:59:04 INFO - 'sessionrestore_no_auto_restore', 03:59:04 INFO - 'tabpaint']}, 03:59:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['a11yr', 03:59:04 INFO - 'ts_paint', 03:59:04 INFO - 'tpaint', 03:59:04 INFO - 'sessionrestore', 03:59:04 INFO - 'sessionrestore_no_auto_restore', 03:59:04 INFO - 'tabpaint']}, 03:59:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['a11yr', 03:59:04 INFO - 'ts_paint', 03:59:04 INFO - 'tpaint', 03:59:04 INFO - 'sessionrestore', 03:59:04 INFO - 'sessionrestore_no_auto_restore', 03:59:04 INFO - 'tabpaint']}, 03:59:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['tsvgx', 03:59:04 INFO - 'tsvgr_opacity', 03:59:04 INFO - 'tart', 03:59:04 INFO - 'tscrollx', 03:59:04 INFO - 'cart', 03:59:04 INFO - 'tsvg_static']}, 03:59:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:59:04 INFO - 'tsvgr_opacity', 03:59:04 INFO - 'tart', 03:59:04 INFO - 'tscrollx', 03:59:04 INFO - 'cart', 03:59:04 INFO - 'tsvg_static']}, 03:59:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'talos_options': ['--disable-e10s'], 03:59:04 INFO - 'tests': ['tp5o']}, 03:59:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:59:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'talos_options': ['--disable-e10s', 03:59:04 INFO - '--xperf_path', 03:59:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:04 INFO - 'tests': ['tp5n']}, 03:59:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:59:04 INFO - 'talos_options': ['--xperf_path', 03:59:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:04 INFO - 'tests': ['tp5n']}}, 03:59:04 INFO - 'talos.zip': {'path': '', 03:59:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:59:04 INFO - Running post-action listener: _resource_record_post_action 03:59:04 INFO - [mozharness: 2017-05-29 10:59:04.773508Z] Finished populate-webroot step (success) 03:59:04 INFO - [mozharness: 2017-05-29 10:59:04.773584Z] Running create-virtualenv step. 03:59:04 INFO - Running pre-action listener: _resource_record_pre_action 03:59:04 INFO - Running main action method: create_virtualenv 03:59:04 INFO - Creating virtualenv /builds/slave/test/build/venv 03:59:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:59:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:59:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:59:04 INFO - Using real prefix '/tools/python27' 03:59:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:59:05 INFO - Installing distribute.............................................................................................................................................................................................done. 03:59:08 INFO - Installing pip.................done. 03:59:08 INFO - Return code: 0 03:59:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:59:08 INFO - Reading from file tmpfile_stdout 03:59:08 INFO - Output received: 03:59:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:59:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:59:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:08 INFO - 'HOME': '/Users/cltbld', 03:59:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:08 INFO - 'LOGNAME': 'cltbld', 03:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:08 INFO - 'MOZ_NO_REMOTE': '1', 03:59:08 INFO - 'NO_EM_RESTART': '1', 03:59:08 INFO - 'PAGER': '/bin/cat', 03:59:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:08 INFO - 'PWD': '/builds/slave/test', 03:59:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:08 INFO - 'SHELL': '/bin/bash', 03:59:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:08 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:08 INFO - 'USER': 'cltbld', 03:59:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:08 INFO - 'XPC_FLAGS': '0x0', 03:59:08 INFO - 'XPC_SERVICE_NAME': '0', 03:59:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:09 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 03:59:09 INFO - Cleaning up... 03:59:09 INFO - Return code: 0 03:59:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:59:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:09 INFO - 'HOME': '/Users/cltbld', 03:59:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:09 INFO - 'LOGNAME': 'cltbld', 03:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:09 INFO - 'MOZ_NO_REMOTE': '1', 03:59:09 INFO - 'NO_EM_RESTART': '1', 03:59:09 INFO - 'PAGER': '/bin/cat', 03:59:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:09 INFO - 'PWD': '/builds/slave/test', 03:59:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:09 INFO - 'SHELL': '/bin/bash', 03:59:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:09 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:09 INFO - 'USER': 'cltbld', 03:59:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:09 INFO - 'XPC_FLAGS': '0x0', 03:59:09 INFO - 'XPC_SERVICE_NAME': '0', 03:59:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:09 INFO - Downloading/unpacking psutil>=3.1.1 03:59: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 03:59: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 03:59: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 03:59: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 03:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:59:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:11 INFO - Installing collected packages: psutil 03:59:11 INFO - Running setup.py install for psutil 03:59:11 INFO - building 'psutil._psutil_osx' extension 03:59:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:59:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:59:12 INFO - building 'psutil._psutil_posix' extension 03:59:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:59:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:59:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:59:12 INFO - ^ 03:59:12 INFO - 1 warning generated. 03:59:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:59:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:12 INFO - Successfully installed psutil 03:59:12 INFO - Cleaning up... 03:59:12 INFO - Return code: 0 03:59:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:59: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')]} 03:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59: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')]} 03:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:59:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:12 INFO - 'HOME': '/Users/cltbld', 03:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:12 INFO - 'LOGNAME': 'cltbld', 03:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:12 INFO - 'MOZ_NO_REMOTE': '1', 03:59:12 INFO - 'NO_EM_RESTART': '1', 03:59:12 INFO - 'PAGER': '/bin/cat', 03:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:12 INFO - 'PWD': '/builds/slave/test', 03:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:12 INFO - 'SHELL': '/bin/bash', 03:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:12 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:12 INFO - 'USER': 'cltbld', 03:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:12 INFO - 'XPC_FLAGS': '0x0', 03:59:12 INFO - 'XPC_SERVICE_NAME': '0', 03:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:59:13 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 03:59:13 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 03:59:13 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 03:59:13 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 03:59:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:59:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:59:14 INFO - Installing collected packages: mozsystemmonitor 03:59:14 INFO - Running setup.py install for mozsystemmonitor 03:59:14 INFO - Successfully installed mozsystemmonitor 03:59:14 INFO - Cleaning up... 03:59:14 INFO - Return code: 0 03:59:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:59:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:14 INFO - 'HOME': '/Users/cltbld', 03:59:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:14 INFO - 'LOGNAME': 'cltbld', 03:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:14 INFO - 'MOZ_NO_REMOTE': '1', 03:59:14 INFO - 'NO_EM_RESTART': '1', 03:59:14 INFO - 'PAGER': '/bin/cat', 03:59:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:14 INFO - 'PWD': '/builds/slave/test', 03:59:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:14 INFO - 'SHELL': '/bin/bash', 03:59:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:14 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:14 INFO - 'USER': 'cltbld', 03:59:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:14 INFO - 'XPC_FLAGS': '0x0', 03:59:14 INFO - 'XPC_SERVICE_NAME': '0', 03:59:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:15 INFO - Downloading/unpacking jsonschema==2.5.1 03:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:59:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:59:16 INFO - warning: no files found matching '*.txt' 03:59:16 INFO - no previously-included directories found matching 'build' 03:59:16 INFO - no previously-included directories found matching 'dist' 03:59:16 INFO - no previously-included directories found matching '.git*' 03:59:16 INFO - Installing collected packages: jsonschema, functools32 03:59:16 INFO - Running setup.py install for functools32 03:59:16 INFO - warning: no files found matching '*.txt' 03:59:16 INFO - no previously-included directories found matching 'build' 03:59:16 INFO - no previously-included directories found matching 'dist' 03:59:16 INFO - no previously-included directories found matching '.git*' 03:59:16 INFO - Successfully installed jsonschema functools32 03:59:16 INFO - Cleaning up... 03:59:17 INFO - Return code: 0 03:59:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:17 INFO - 'HOME': '/Users/cltbld', 03:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:17 INFO - 'LOGNAME': 'cltbld', 03:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:17 INFO - 'MOZ_NO_REMOTE': '1', 03:59:17 INFO - 'NO_EM_RESTART': '1', 03:59:17 INFO - 'PAGER': '/bin/cat', 03:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:17 INFO - 'PWD': '/builds/slave/test', 03:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:17 INFO - 'SHELL': '/bin/bash', 03:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:17 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:17 INFO - 'USER': 'cltbld', 03:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:17 INFO - 'XPC_FLAGS': '0x0', 03:59:17 INFO - 'XPC_SERVICE_NAME': '0', 03:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:59:17 INFO - Cleaning up... 03:59:17 INFO - Return code: 0 03:59:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:17 INFO - 'HOME': '/Users/cltbld', 03:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:17 INFO - 'LOGNAME': 'cltbld', 03:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:17 INFO - 'MOZ_NO_REMOTE': '1', 03:59:17 INFO - 'NO_EM_RESTART': '1', 03:59:17 INFO - 'PAGER': '/bin/cat', 03:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:17 INFO - 'PWD': '/builds/slave/test', 03:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:17 INFO - 'SHELL': '/bin/bash', 03:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:17 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:17 INFO - 'USER': 'cltbld', 03:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:17 INFO - 'XPC_FLAGS': '0x0', 03:59:17 INFO - 'XPC_SERVICE_NAME': '0', 03:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:17 INFO - Downloading/unpacking blobuploader==1.2.4 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:18 INFO - Downloading blobuploader-1.2.4.tar.gz 03:59:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:59:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:59:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:19 INFO - Downloading docopt-0.6.1.tar.gz 03:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:59:19 INFO - Installing collected packages: blobuploader, requests, docopt 03:59:19 INFO - Running setup.py install for blobuploader 03:59:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:59:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:59:20 INFO - Running setup.py install for requests 03:59:20 INFO - Running setup.py install for docopt 03:59:20 INFO - Successfully installed blobuploader requests docopt 03:59:20 INFO - Cleaning up... 03:59:20 INFO - Return code: 0 03:59:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:20 INFO - 'HOME': '/Users/cltbld', 03:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:20 INFO - 'LOGNAME': 'cltbld', 03:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:20 INFO - 'MOZ_NO_REMOTE': '1', 03:59:20 INFO - 'NO_EM_RESTART': '1', 03:59:20 INFO - 'PAGER': '/bin/cat', 03:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:20 INFO - 'PWD': '/builds/slave/test', 03:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:20 INFO - 'SHELL': '/bin/bash', 03:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:20 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:20 INFO - 'USER': 'cltbld', 03:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:20 INFO - 'XPC_FLAGS': '0x0', 03:59:20 INFO - 'XPC_SERVICE_NAME': '0', 03:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-VHtdht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-JN0vgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-pCd_gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-UuVRvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-y1Ab5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-CqgVUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-AYjSlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-7AxvY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-8WljEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:21 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Rj27xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-NxooRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-_ZAeLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-cd54VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-XAwbiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6mOf8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-whSZuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:22 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-3g9T1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:59:22 INFO - Running setup.py install for manifestparser 03:59:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:59:22 INFO - Running setup.py install for mozcrash 03:59:23 INFO - Running setup.py install for mozdebug 03:59:23 INFO - Running setup.py install for mozdevice 03:59:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Running setup.py install for mozfile 03:59:23 INFO - Running setup.py install for mozhttpd 03:59:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Running setup.py install for mozinfo 03:59:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Running setup.py install for mozInstall 03:59:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Running setup.py install for mozleak 03:59:24 INFO - Running setup.py install for mozlog 03:59:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Running setup.py install for moznetwork 03:59:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Running setup.py install for mozprocess 03:59:24 INFO - Running setup.py install for mozprofile 03:59:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Running setup.py install for mozrunner 03:59:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Running setup.py install for mozscreenshot 03:59:25 INFO - Running setup.py install for moztest 03:59:25 INFO - Running setup.py install for mozversion 03:59:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:59:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:59:25 INFO - Cleaning up... 03:59:25 INFO - Return code: 0 03:59:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:59:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:25 INFO - 'HOME': '/Users/cltbld', 03:59:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:25 INFO - 'LOGNAME': 'cltbld', 03:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:25 INFO - 'MOZ_NO_REMOTE': '1', 03:59:25 INFO - 'NO_EM_RESTART': '1', 03:59:25 INFO - 'PAGER': '/bin/cat', 03:59:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:25 INFO - 'PWD': '/builds/slave/test', 03:59:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:25 INFO - 'SHELL': '/bin/bash', 03:59:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:25 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:25 INFO - 'USER': 'cltbld', 03:59:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:25 INFO - 'XPC_FLAGS': '0x0', 03:59:25 INFO - 'XPC_SERVICE_NAME': '0', 03:59:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ZaznIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-G5y_NI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Swx0BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:26 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)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-301PGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:26 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)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-i5cilY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-FzndMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-3PJGxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Apjxpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-LJFOVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-LxvWVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-114eSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-eN7V0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:26 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-L64v3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:27 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)) 03:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:27 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-LwHxh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:27 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)) 03:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:27 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-lYkB4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:27 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-SojuaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:27 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-AXhxC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:28 INFO - Downloading blessings-1.6.tar.gz 03:59:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:59:29 INFO - Installing collected packages: blessings 03:59:29 INFO - Running setup.py install for blessings 03:59:29 INFO - Successfully installed blessings 03:59:29 INFO - Cleaning up... 03:59:29 INFO - Return code: 0 03:59:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:29 INFO - Reading from file tmpfile_stdout 03:59:29 INFO - Current package versions: 03:59:29 INFO - blessings == 1.6 03:59:29 INFO - blobuploader == 1.2.4 03:59:29 INFO - docopt == 0.6.1 03:59:29 INFO - functools32 == 3.2.3-2 03:59:29 INFO - jsonschema == 2.5.1 03:59:29 INFO - manifestparser == 1.1 03:59:29 INFO - mozInstall == 1.12 03:59:29 INFO - mozcrash == 1.0 03:59:29 INFO - mozdebug == 0.1 03:59:29 INFO - mozdevice == 0.49 03:59:29 INFO - mozfile == 1.2 03:59:29 INFO - mozhttpd == 0.7 03:59:29 INFO - mozinfo == 0.9 03:59:29 INFO - mozleak == 0.1 03:59:29 INFO - mozlog == 3.4 03:59:29 INFO - moznetwork == 0.27 03:59:29 INFO - mozprocess == 0.25 03:59:29 INFO - mozprofile == 0.28 03:59:29 INFO - mozrunner == 6.13 03:59:29 INFO - mozscreenshot == 0.1 03:59:29 INFO - mozsystemmonitor == 0.3 03:59:29 INFO - moztest == 0.8 03:59:29 INFO - mozversion == 1.4 03:59:29 INFO - psutil == 3.1.1 03:59:29 INFO - requests == 1.2.3 03:59:29 INFO - wsgiref == 0.1.2 03:59:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:29 INFO - 'HOME': '/Users/cltbld', 03:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:29 INFO - 'LOGNAME': 'cltbld', 03:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:29 INFO - 'MOZ_NO_REMOTE': '1', 03:59:29 INFO - 'NO_EM_RESTART': '1', 03:59:29 INFO - 'PAGER': '/bin/cat', 03:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:29 INFO - 'PWD': '/builds/slave/test', 03:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:29 INFO - 'SHELL': '/bin/bash', 03:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:29 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:29 INFO - 'USER': 'cltbld', 03:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:29 INFO - 'XPC_FLAGS': '0x0', 03:59:29 INFO - 'XPC_SERVICE_NAME': '0', 03:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:59:29 INFO - Cleaning up... 03:59:29 INFO - Return code: 0 03:59:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4015b41f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, '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.y0zB0kzeKC/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.gVmaCHavEL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 03:59:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:29 INFO - 'HOME': '/Users/cltbld', 03:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:29 INFO - 'LOGNAME': 'cltbld', 03:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:29 INFO - 'MOZ_NO_REMOTE': '1', 03:59:29 INFO - 'NO_EM_RESTART': '1', 03:59:29 INFO - 'PAGER': '/bin/cat', 03:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:29 INFO - 'PWD': '/builds/slave/test', 03:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:29 INFO - 'SHELL': '/bin/bash', 03:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:29 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:29 INFO - 'USER': 'cltbld', 03:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:29 INFO - 'XPC_FLAGS': '0x0', 03:59:29 INFO - 'XPC_SERVICE_NAME': '0', 03:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:59:30 INFO - Cleaning up... 03:59:30 INFO - Return code: 0 03:59:30 INFO - Running post-action listener: _resource_record_post_action 03:59:30 INFO - Running post-action listener: _start_resource_monitoring 03:59:30 INFO - Starting resource monitoring. 03:59:30 INFO - [mozharness: 2017-05-29 10:59:30.057446Z] Finished create-virtualenv step (success) 03:59:30 INFO - [mozharness: 2017-05-29 10:59:30.058520Z] Running install step. 03:59:30 INFO - Running pre-action listener: _resource_record_pre_action 03:59:30 INFO - Running main action method: install 03:59:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:30 INFO - Reading from file tmpfile_stdout 03:59:30 INFO - Detecting whether we're running mozinstall >=1.0... 03:59:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:59:30 INFO - Reading from file tmpfile_stdout 03:59:30 INFO - Output received: 03:59:30 INFO - Usage: mozinstall [options] installer 03:59:30 INFO - Options: 03:59:30 INFO - -h, --help show this help message and exit 03:59:30 INFO - -d DEST, --destination=DEST 03:59:30 INFO - Directory to install application into. [default: 03:59:30 INFO - "/builds/slave/test"] 03:59:30 INFO - --app=APP Application being installed. [default: firefox] 03:59:30 INFO - mkdir: /builds/slave/test/build/application 03:59:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 03:59:45 INFO - Reading from file tmpfile_stdout 03:59:45 INFO - Output received: 03:59:45 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:59:45 INFO - Running post-action listener: _resource_record_post_action 03:59:45 INFO - [mozharness: 2017-05-29 10:59:45.210754Z] Finished install step (success) 03:59:45 INFO - [mozharness: 2017-05-29 10:59:45.210883Z] Running run-tests step. 03:59:45 INFO - Running pre-action listener: _resource_record_pre_action 03:59:45 INFO - Running main action method: run_tests 03:59:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:59:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:59:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:59:45 INFO - 2017-05-29 03:59:45.225 screenresolution[1629:8217] starting screenresolution argv=screenresolution get 03:59:45 INFO - 2017-05-29 03:59:45.228 screenresolution[1629:8217] Display 0: 1600x1200x32@60 03:59:45 INFO - 2017-05-29 03:59:45.233 screenresolution[1630:8219] starting screenresolution argv=screenresolution list 03:59:45 INFO - Available Modes on Display 0 03:59:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:59:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:59:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:59:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:59:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:59:45 INFO - Intel Iris: 03:59:45 INFO - Chipset Model: Intel Iris 03:59:45 INFO - Type: GPU 03:59:45 INFO - Bus: Built-In 03:59:45 INFO - VRAM (Dynamic, Max): 1536 MB 03:59:45 INFO - Vendor: Intel (0x8086) 03:59:45 INFO - Device ID: 0x0a2e 03:59:45 INFO - Revision ID: 0x0009 03:59:45 INFO - Displays: 03:59:45 INFO - Display: 03:59:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:59:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:59:45 INFO - Display Serial Number: 1600x1200 60 03:59:45 INFO - Main Display: Yes 03:59:45 INFO - Mirror: Off 03:59:45 INFO - Online: Yes 03:59:45 INFO - Rotation: Supported 03:59:45 INFO - Return code: 0 03:59:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:59:45 INFO - Python 2.7.3 03:59:45 INFO - Return code: 0 03:59:45 INFO - grabbing minidump binary from tooltool 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108943d30>, '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 0x10886adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108938be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:59:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:59:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:59:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:59:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:59:45 INFO - Return code: 0 03:59:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:59:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:59:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:59:45 INFO - ENV: RUST_BACKTRACE is now 1 03:59:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:59:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:59:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--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 03:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0148 --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 03:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0zB0kzeKC/Render', 03:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:45 INFO - 'HOME': '/Users/cltbld', 03:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:45 INFO - 'LOGNAME': 'cltbld', 03:59:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:59:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:45 INFO - 'MOZ_NO_REMOTE': '1', 03:59:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:59:45 INFO - 'NO_EM_RESTART': '1', 03:59:45 INFO - 'PAGER': '/bin/cat', 03:59:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:45 INFO - 'PWD': '/builds/slave/test', 03:59:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:45 INFO - 'RUST_BACKTRACE': '1', 03:59:45 INFO - 'SHELL': '/bin/bash', 03:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gVmaCHavEL/Listeners', 03:59:45 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 03:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:45 INFO - 'USER': 'cltbld', 03:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:45 INFO - 'XPC_FLAGS': '0x0', 03:59:45 INFO - 'XPC_SERVICE_NAME': '0', 03:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--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 03:59:45 INFO - mozversion application_buildid: 20170529024916 03:59:45 INFO - mozversion application_changeset: 6fb66467485a8beee11dad38a08768c2a9955fe7 03:59:45 INFO - mozversion application_display_name: Firefox 03:59:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:59:45 INFO - mozversion application_name: Firefox 03:59:45 INFO - mozversion application_remotingname: firefox 03:59:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:59:45 INFO - mozversion application_vendor: Mozilla 03:59:45 INFO - mozversion application_version: 54.0 03:59:45 INFO - mozversion platform_buildid: 20170529024916 03:59:45 INFO - mozversion platform_changeset: 6fb66467485a8beee11dad38a08768c2a9955fe7 03:59:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:59:45 INFO - mozversion platform_version: 54.0 03:59:45 INFO - using testdate: 1496055585 03:59:45 INFO - actual date: 1496055585 03:59:45 INFO - starting webserver on 'localhost:49225' 03:59:45 INFO - SUITE-START | Running 2 tests 03:59:45 INFO - TEST-START | dromaeo_css 03:59:45 INFO - operating with platform_type : mac_ 03:59:45 INFO - Initialising browser for dromaeo_css test... 03:59:45 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmppQzhat/profile http://localhost:49225/getInfo.html) 03:59:48 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 03:59:48 INFO - PROCESS | 1640 | colorDepth:24 03:59:48 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 03:59:48 INFO - PROCESS | 1640 | __metrics 03:59:48 INFO - PROCESS | 1640 | 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" 03:59:48 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:59:48 INFO - TEST-INFO | 1640: exit 0 03:59:48 INFO - Browser initialized. 03:59:48 INFO - Running cycle 1/1 for dromaeo_css test... 03:59:48 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmppQzhat/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:00 INFO - PROCESS | 1644 | RSS: Main: 164990976 04:00:00 INFO - PROCESS | 1644 | 04:03:04 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 04:03:06 INFO - PROCESS | 1644 | RSS: Main: 355815424 04:03:06 INFO - PROCESS | 1644 | 04:06:09 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 04:06:10 INFO - PROCESS | 1644 | RSS: Main: 300892160 04:06:10 INFO - PROCESS | 1644 | 04:09:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 04:09:13 INFO - PROCESS | 1644 | RSS: Main: 273051648 04:09:13 INFO - PROCESS | 1644 | 04:12:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 04:12:16 INFO - PROCESS | 1644 | RSS: Main: 269967360 04:12:16 INFO - PROCESS | 1644 | 04:15:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 04:15:20 INFO - PROCESS | 1644 | RSS: Main: 270999552 04:15:20 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 04:18:23 INFO - PROCESS | 1644 | RSS: Main: 401686528 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | __start_tp_report 04:18:23 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 04:18:23 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 04:18:23 INFO - PROCESS | 1644 | |i|pagename|runs| 04:18:23 INFO - PROCESS | 1644 | |0;dojo.html;3279.8360081995897;3577.3203091412615;3598.5681718193814;3606.2607165531067;3646.7811931284123;5146.305248791412;6010.12853136295;6153.630782153074;6177.073438984149;6182.814515564541;6129.295131059929;6207.317195108534;6211.534134939875;6216.16081828953;6225.470834979021;5509.972450137744;5525.171224316365;5537.390887002426;5543.1408131739645;5606.971965140168;5400.83797486076;5458.208559758833;5496.587755918302;5641.802536911209;5653.1237658162845;5508.531774799147;5529.778808847662;5531;5538.806141785039;5544.667319960815;5570.693611851347;5582.106862901936;5609.551235901118;5615.326160860723;5637.238972738669;5469.972650136724;5532.336119665626;5547.750351234205;5557.5831812613815;5595.18869763882;5991.2510936132985;6006.12911127884;6039.365866583989;6046.758129674808;6231.065340198961;5359.410464848864;5966.105084237356;6143.047827586731;6350.587211831263;6483.610983340967;6576.177977752781;6588.703508342136;6628.635425051621;6645.534812563123;6651.334866513358;6328.240611126648;6335.303116657164;6340.778072767477;6344.206974128234;6346.333634968354;6462;6536.411722944958;6553.311902250195;6601.5378923475855;6779.2881747416795;6351.555391122577;6354.364563543609;6370.522210834206;6373.71318290678;6392.520560957947;6583.572067815577;6609.438197753245;6646.734130634721;6648.16897887764;6652.866942661217;6544.083828264047;6557.606543607399;6558.147440832661;6559.967200164064;6588.8023359299295;6329.430351268408;6348.111264422984;6354.491640668735;6558.606483610999;6562.442192413699;5272.525472707474;5297;5319.414864364917;5338.439463856317;5362.302900622972;4688.156131896291;4722.315264286659;4738.360321356642;4738.715677059318;4767.4517430495935;6096.756129754849;6281.748730050746;6288.591241569284;6297.811065668037;6299.149614802035;6445.4199122079235;6490.675466226669;6523.7064332105165;6556.967215163893;6610.636414997125;6280.654563998933;6287.717052732638;6299.811005669837;6300.243970723543;6320.462760665395;5766.443060373759;5784.652920824764;5786.421357864264;5807.680577568274;5836.270466191726;5576.776928922797;6521.217453905561;6592.736290548324;6643.667816609247;6651.102101216274;6430.421262086435;6499.28507864144;6520.315366886409;6597.1423714916755;6603.108580341785;6763.323667633197;6816.693248803915;6830.795076147723;6835.89746153818;6853.725850965906;6486.254080780771;6514.934850651623;6522.119513865568;6526.67366631676;6536.248331441945;5286;5786.537077033743;6045.244344456943;6240.375962403723;6434.356564343529;6421.486281097407;6445.484361250995;6454.160959075478;6457.580257283262;6465.903011454738;6406.519511036784;6439.549231553746;6457.5479716419395;6461.321561236003;6468.191476065492;6182.381761823962;6222.222222222223;6266.060090986389;6292;6326.051092336186;6444.645544494911;6451.677416129269;6459.386358295955;6462.256840463407;6473.417392434703;6196.287426945959;6256.155419018374;6276.623402595859;6300.464460520724;6361.236651601837 04:18:23 INFO - PROCESS | 1644 | |1;ext.html;823.4894365493393;841.0706169682503;914.51530688735;921.6912334367983;998.9650362237323;39371;39975.00062498439;39981.600183998155;40001.59998400015;40013.79993100034;39566.406503902464;39848.60151398485;39877.40183897244;39888.60111398885;39924.20151596966;7916.564588947605;8076.515409075445;8155.918440815575;8213.630386632602;8355.95822020889;7928.326092282163;8013.278804907557;11551.595694150707;11700.882991170107;11718.648440546755;11763.000144987644;11831;11864.52541898323;11876.703082422922;11884.168108232427;9765.462899540522;11795.705107372298;11796.587119450864;11798.056155507538;11857.22928009681;11487;11567.595134170264;11580.594679186272;11610;11610.303381797119;35787.28425431477;39133.434662613465;40980;41212.58787412148;41261.587384126375;4034.798260086984;4084.244414783275;4084.65280451162;4086.652634526069;4122.793860307002;28184.436311273865;28742.856285718437;29576.55635165474;30343.848280758677;30456.8477157615;3273.5253388258807;3275.6396796352383;3305.190228394035;3417.9316413671836;3420.9144771380666;347700;351902.2404887985;352272;352851.3574321299;353257;44959.32561011588;45759.77120114378;47693;52083.73958130185;53847.73076134594;56418.43581564214;56717.71641141768;56718;56745.71627141838;56831.715841420526;14790.630234244014;15252;15279.770803437958;15300.464483743015;15313.46402875894;13076.673083172996;13081.280529570775;13108.475660973454;13128.868711312765;13251.933740331237;12052.517899284107;12071.155019148751;12183.086268529718;12190.451429685685;12198.90209881115;5427.158790387496;5457.590680698963;5461.672299662033;5467.0705979983495;5474.5894057945015;1172.5778719660916;1175.400545721688;1190.2443895254603;1193.6777070191;1200.771853347861;23040.65439018416;26893.386396816255;27152.778124984423;27278;27336.726632733815;1288.9806652900213;1296.7925131979027;1306.1705816806395;1330.5875178694635;1336.104809777432;13900.721985560232;14041.368138433794;14164.433422663184;14206.857931420553;14253.50112746049;460352;460914;461660.69169653935;461666;461935;57454.85090298254;57487.56281092922;57521.849563009346;57636;57658;1457.1402872304966;1467.222372142766;1501.737195990719;1513.6215946013497;1515.9317830697646;26285.868570657025;27143.18570442811;27565.58651620187;27568.724312756614;27750.722492774814;39762.40356394714;39801.20397591974;39857;39898.80050599844;39914;410884;411577;412582;412785;413375.9331203325;24290.514189715752;24715.62926556067;27119.72880271251;28297.43405131927;28972.39248467648;32740.836295818368;37137.814310928276;37359;37671.81164094162;37824.62175378211;26976.70697990693;27561.173164804284;27664.861675691493;28224.71775282221;28345.007924722537;22110.22614208495;22315.888420557792;22428.887855561272;22429.439264017878;22558.323250302517;30938.845305773328;31289.374212515166;31762.682373176896;32153.35693286168;32182.517262240617;21636.45908852291;26924.730752693005;27115.322116947234;27118.72881271162;27728.861355693094;21718.348449546538;22295.554088918456;22765.88617056904;22842;22867 04:18:23 INFO - PROCESS | 1644 | |2;jquery.html;358.430096147126;411.44454985769204;418.6310763802366;423.5129600958897;455.6559797353;19967.90016049921;21273.893630531846;22213.777862221374;22360.664590031163;22404.439889002784;21703.891480542574;22124.446888827788;22819.31542053737;23101.768982310172;23781.881090594565;5918.674472903988;5946.256717910262;6684.398404143614;7039.225685174627;7045.11936007999;6514.869702605945;6787.185537735454;6847.691853866588;6850.075239842632;6850.212225594046;6761.695723692421;6769.796906092825;6839.179298484189;6844.007618895257;6860.691268892887;6741.966290168542;6827.863442731143;6883.174019117688;6913.10129683143;6920.134983127135;6469.676516174172;6681.599104053772;6762.425193858478;6768.086308348359;6770.661466926683;26416.207513774618;26523.33691657724;26959.32601684954;27061.45877082467;27248.04631837896;3020.8489575521135;3079.3995170941675;3095.241665791873;3118.688131186886;3128.702773236539;15704.607384815357;15883.444079457164;15895.29024756265;16652.66694666112;16831.905926114763;2105.221068204759;2134.1676746069047;2166.0361139293022;2194.188150384352;2234.754177040524;158418.88198757765;159001.20499397544;164635;170515;175476.24523754726;19871.801281987286;20229.08969096394;20432.795672043387;20436.489087772923;20476.897615512124;31675.841620791747;31958.680413196034;32235.516467253008;32333.35333293321;32404.83797580997;10416.489609006803;10468.63359782404;10684.305520141166;10685.57257709683;10850.457477126112;8942.776430589158;8953.194212520904;8976.730698079067;9140.314476414296;9165.58754856033;9195.229955718123;9266.397684150508;9380.906190938003;9384.202342800801;9397.76505587352;2997.9100626981226;3643.4534819777246;3741.875533929145;3780.9621903781162;3848.5958974307855;1027.6505987963978;1060.3319908457622;1075.800976819291;1090.3839330778085;1130.5873356224918;22272.55454890893;22295.857282667308;22532.21137260188;22546.436339091324;22594.661080083813;1091.7216109892086;1103.2167161315394;1114.2645853736494;1145.4215621111287;1146.0831334932188;9856.112949834405;11152.386618736047;11494.930912435842;11716.472758725879;12034.939825300993;785502.0724896339;801752;808559;816052;816262;127243.72756272319;151643;155882;163826;173150;1344.1330341929597;1344.227069435059;1381.2955392749568;1386.3899884050986;1386.4246337769914;16111.905452291126;19094.427167185007;19664;20148.697769533177;20392;64834.675826620565;64845;65042.674786625765;65941.67029164823;66321.66839165773;93986;94406;95122.04877951131;95383;96166.5417471196;10691.893081069089;10914;11113.44432778374;11495.597654082067;11591.478383472933;12705;12732.87267127317;13053.477860885458;13060.80408793888;13414.865851341361;11083.334999900031;11148.665540033811;11267.436628168723;11274.549018039186;11635.185537012328;10063.194944404284;10716.35701857891;10947.56209751601;11063.834042489209;11084.889151108386;11119.054880335261;11258.66224013281;11605.941970290432;11982.58060967862;12046.216995895238;11326.37704926237;11475.540978360772;11756.177067605186;11873.940630296793;11875.821862671894;11185.608503702331;11278.436078195893;11438.142139339749;11484.794096619735;11552.826707599224 04:18:23 INFO - PROCESS | 1644 | |3;mootools.html;1616.2969108437824;1621;1642.0065860154614;1655.263407783536;1662.0941586835174;10897.182711296646;11602.709932251699;11609.071274298058;11617.825732614017;11637.952584267403;10987.340759554432;11375.976162145405;11577.65267041988;11586.131040171978;11586.478608462618;2301.5051763870765;2339.508703172336;2341.613633750429;2346.7418583955837;2349.929502114939;2275.7496675365696;2344.589696803061;2371.205646108547;2371.67982322386;2376.5603363377745;2285.0517035430275;2310.838241323108;2316.4324740438615;2331.428799944016;2348.941276468085;2318.953620927589;2322;2335.4161459635093;2343.1915988983774;2348.3189874936334;1948.824605785486;2357.139644029934;2365.0539784086504;2366.7514910934447;2369.9407514812096;11401.942990285079;11482.138839587064;11494.655160345203;11505.712357191054;11521.308721476738;5232.947670523284;5401.837944861661;5406.729663516809;5421.376541697716;5422.701751403672;3157.4790159623635;3225.661305562929;3364.0916952422954;3407.028996735943;3421.674940880612;1157.6584907452323;1169.5555688838188;1899.5441094137445;1931.304730297092;1953.6946403244167;15668.529944101696;15700.214989250606;15873.761893571605;15899.364025439085;15901.761473577906;4301.376300436419;4329.740215587075;4439.66702497314;4446.1330040642415;4447.755373454426;12057.035437165006;12065.698357540956;12067.336296503776;12070.577529786415;12073.939630301793;5522.364928033247;5523.696196709219;5528.668279903219;5532.917006244909;5539.25220095282;4140.296149654566;5073.594112470993;5088.898222035538;5154.5103215194495;5158.92261616076;2969.732724054845;3612.422012477991;3632.600413954463;3676.0809797550614;3687.5574931007914;915.3089417489753;915.9679411220575;928.7167413938813;929.5538141692026;929.9302552308604;945.8628498867625;957.7701351675547;959.9424034557949;966.6520052780993;1007.6145300212208;6445.1943507061615;6470.6441145737435;6644.867102658017;6690.130283063268;6741.764038258638;922.727795300388;930.3998920696113;963.816874793787;996.0935548650838;1008.9495525223709;3528.964710352915;4085.1829634073306;4094.5700701426517;4227.3447615619625;4256.978715106405;13511.256880871646;14309.427622894967;14311.9981601289;14317.64205894861;14319.713605727828;12064.457099430547;12501.93749031249;13919.640012742968;13951.511697090757;14003.579892603238;8359.95820020896;9160.17558419732;9452.95273523628;10476.895231047592;10772.515236814363;5549.917766035745;6419.582727122722;6785.287544807724;6900.689468974008;6905.75829845953;10506;12336.691582710504;12463.31451770125;12569.748605028031;12584.685382865502;10740.946295268473;12707.237565746085;13015.414306356426;13038.348082595643;13058.608241752763;9355.06449355059;9407.529623518934;9424.246060315296;9518.524073796421;9612.134907858326;10410.895891041295;10489;10603.840942386025;10608.310459820086;10609.575616975235;8932.910670893469;8991.910080899108;9018;9154.359194856295;9210.677626283048;7369.926300737138;8741.606627701896;8905.73282801517;8909.509976951329;9030.23243024324;7700.152983171734;7911.129775724781;8025.719099831478;8027.719029833927;8066.193380661887;5967.492763115184;6007.309159446545;6026.216591843032;6027.728752206073;6079.787207447718;5964.522838172849;6002.399760024137;6056.757729690939;6163.476104531165;6183.227096612924 04:18:23 INFO - PROCESS | 1644 | |4;prototype.html;349.9020274323189;378.5362930410248;392.786290362779;392.96463318301346;393.21553500765975;4537.591616754487;4822.083804077227;5024.748762561871;5108.540231379176;5129.948700512994;4401.40581021562;4865.440474345452;4991.301217829507;5008.49915008499;5024.271480635306;4169.228692691861;4245.448091748076;4247.426597409341;4284.800255928345;4291.485021797389;3478.9826050869838;3896.2597106549806;3919.960800391988;4237.8516751913685;4260.169266992938;3681.502997095398;4241.066965267636;4252.744835309876;4254.383334332949;4284.443022407083;3715.5541335039698;3823.6941044716355;4064.012950481394;4259.16946195492;4320.719153255044;3456.792592444436;3936.7637941723588;3970.940435893464;3996.760194388317;4025.7383270087353;4524.977375113136;5012.448630650625;5024.899502009976;5103.23451482277;5149.948500515022;4775.379200703921;4835.718534588337;4876.244551961291;4927.285543596195;4977.601791856679;4601.631869450436;5409.702466364349;5422.240886275925;5423.078076726926;5435.755391007375;3809.847606095753;4380.956190438119;4387.27609944362;4414.3378493225955;4423.496011356155;5022.824201152942;5632.971835140839;5756.510696590753;5835.35811060783;5853.239078919756;3632.3098611263777;4475.7985890635;4475.843345482957;4478.432548608026;4504.707194032378;3761.7022127366563;3810.256999884996;4017.1478250442515;4639.976800115978;4661.254199328091;4327.913441731148;4539.591436770706;4841.419029716386;4914.36113305268;4937.49406431042;5173.534381905646;5369.865753356197;5390.1999090641;5404.7567859445635;5407.917487032354;1417.3551034279378;1421.8933579981542;1439.8920080993967;1539.599704076948;1565.2956169723443;1085.92398532102;1166.4284500594647;1181.4033912873945;1197.808350663907;1213.2114125818111;4584.951007579922;4683.484816670163;4780.091782561302;5105.795768169306;5119.334486516729;4706.364435915898;5597.969461987363;5602.663840169603;5604.971975140098;5641.364004438748;4366.039471316305;4469.9553004469535;4590.816367345269;5051.444341122474;5080.23796430531;2482.74070595757;2719.428919926798;2842.0905310300504;2910.8544572771275;2925.765938724897;4885.363403259877;5160.329157209539;5749.607731438471;5762.74067666956;5774.509166720875;4881.731504767201;5324.840254792362;5493.533049690741;5687.232223649837;5767.548791763484;5590.580706446952;5661.348944871393;5762.510186634099;5788.363280039192;5824.3593204748295;5060.8987820242655;5295.816305118913;5305.628605997543;5330.7877230948725;5355.410904800391;4659.813607455664;4938.432080310667;5174.818881339135;5474.616776825584;5604.691741954069;5696.1455781633085;5777.566682498746;5800.76796928135;5845;5981.6372532850155;4460.3755474234285;4460.821567137408;4487.5288094749585;4505.863315820034;4506;5017.899642007213;5034.899302014012;5532.695701736443;5629.986602411442;5689.260396148474;5399.973000134974;5484.808031718871;5503.367112782082;5533.750981205936;5597.608167428404;4554.817807287672;4992.503748125937;4995.976629465073;5297.477177676802;5521.901677362576;4452.443444569429;5290.7354632269;5345.518903298722;5347.786088556414;5361.544268737045;4665.757298677387;4706.552877476771;4866.29438731389;5260.605454590845;5492.528362237089;4810.989692164685;4939.802407903788;5331.680099194061;5406.567474601944;5628.887422251614 04:18:23 INFO - PROCESS | 1644 | |5;yui.html;764.3808515102767;776.1229810314344;801.8035581282587;891.8528442806937;901.6917880577914;15093;15952.202389880504;16177.757333639991;17420.738688919646;17921.014344211064;15793.60515987101;16033.518994430156;16048.59878503038;16106.919465402685;16124.435644752435;1014.3862962907446;1043.9373637581768;1045.0542259255349;1047.9266451348408;1052.221356196413;1013.3463915774321;1031.4687935713114;1036.0364860679565;1036.2953191829554;1051.2115913065202;1087.162884578874;1089.0688461365537;1089.8528698625703;1090.0026475774696;1092.1644941619627;1085.7557049663762;1086.456771614197;1086.934783912964;1086.9673909782719;1090.1333439915286;810.0643756461296;811.6996711216888;811.9837603247961;812.1350761439107;812.3785304242259;17064.573385665335;17168.48494545153;17216.483505494856;17881.195346209453;18134.45596632103;14629.853701462956;14691.706165876623;14715.92642036783;14716.926415367854;14719.70560588793;12016.699582510508;12055.21641093326;12109.455074521667;12213.572524961584;12343.444544995496;3715.5169827922196;3800.2399520095814;3840.635139661728;3859.1316953685478;3946.3685810270217;105532.41701374562;112528;113611;114764;115402.42298788537;10792.298500597437;10911.454427278763;10998.175136864767;11471;11558.653240402802;22868.65697014546;23133;23252.53494930092;23260.41848953756;23775;8070.636821343053;8076.353891688768;8149.429539932148;8223.05434874985;8255.752327430188;3720.404735242348;4488.281874900001;4538.795754191069;4581.79381927814;4733.982193828343;3029.4849875521213;3458.9308213835584;3526.6473352665043;3542.875999340011;3549.467579863041;612.7763366371242;614.0972770028052;624.4099326136757;627.6767464755654;630.82967598749;654.8068319845657;699.5418001209216;725.8294300839299;741.8812989921587;765.3035737478867;10313.226508011929;10718.303310284962;10764.730881727863;10866;11211.831822522669;686.8111269400955;695.7043256615918;746.216472703659;750.5271678842404;767.7121079595152;4523.2988886722615;4530.6602004849765;4698.989717210816;5300.44345343741;5300.496452836975;85990.57004714936;108715;111814.44092779484;112116;114447;67268;96608.51695741617;97078.5146074265;98204;101101.49449252707;62416;70745.64627176934;78879;86001.83988960682;86251.56874215588;8313.960754905636;10065.798684026426;10066;10086.747831304276;10090.545925433225;55032.9999999984;55217.171742423096;55274.44725552854;56301.71849140728;56475.87048258929;69965.60068798697;70329.37365010889;71017.6449117751;71743.28256717575;71821;11506.07951363872;11664.475098620413;11771.941140294244;11858.703532411759;12116.454759535665;13212.405441754952;13411.006567908471;13457;13463.192208467523;13502.86052279723;12307.630771076882;12313;12431.21137636938;12579;12617.306048167071;12029.398530073637;12355.382230888239;12497.14007182163;12500.437480313225;12968.221906685629;11648.592299269485;12120.696982575506;12454.626361209179;12506.937465312616;12522.248665080124;9031.277497800029;9436.339456237998;9468.857967130636;9502.23982081418;9555.761105972406;8688.78278043054;9414.811703765748;10517.526711298164;10607.787844242917;10621.734456638645 04:18:23 INFO - PROCESS | 1644 | __end_tp_report 04:18:23 INFO - PROCESS | 1644 | __start_cc_report 04:18:23 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,2155 04:18:23 INFO - PROCESS | 1644 | __end_cc_report 04:18:23 INFO - PROCESS | 1644 | __startTimestamp1496056703699__endTimestamp 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | ------- Summary: start ------- 04:18:23 INFO - PROCESS | 1644 | Number of tests: 6 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:6064.02 Median:6299.81 stddev:659.37 (10.5%) stddev-sans-first:624.17 04:18:23 INFO - PROCESS | 1644 | Values: 3279.8 3577.3 3598.6 3606.3 3646.8 5146.3 6010.1 6153.6 6177.1 6182.8 6129.3 6207.3 6211.5 6216.2 6225.5 5510.0 5525.2 5537.4 5543.1 5607.0 5400.8 5458.2 5496.6 5641.8 5653.1 5508.5 5529.8 5531.0 5538.8 5544.7 5570.7 5582.1 5609.6 5615.3 5637.2 5470.0 5532.3 5547.8 5557.6 5595.2 5991.3 6006.1 6039.4 6046.8 6231.1 5359.4 5966.1 6143.0 6350.6 6483.6 6576.2 6588.7 6628.6 6645.5 6651.3 6328.2 6335.3 6340.8 6344.2 6346.3 6462.0 6536.4 6553.3 6601.5 6779.3 6351.6 6354.4 6370.5 6373.7 6392.5 6583.6 6609.4 6646.7 6648.2 6652.9 6544.1 6557.6 6558.1 6560.0 6588.8 6329.4 6348.1 6354.5 6558.6 6562.4 5272.5 5297.0 5319.4 5338.4 5362.3 4688.2 4722.3 4738.4 4738.7 4767.5 6096.8 6281.7 6288.6 6297.8 6299.1 6445.4 6490.7 6523.7 6557.0 6610.6 6280.7 6287.7 6299.8 6300.2 6320.5 5766.4 5784.7 5786.4 5807.7 5836.3 5576.8 6521.2 6592.7 6643.7 6651.1 6430.4 6499.3 6520.3 6597.1 6603.1 6763.3 6816.7 6830.8 6835.9 6853.7 6486.3 6514.9 6522.1 6526.7 6536.2 5286.0 5786.5 6045.2 6240.4 6434.4 6421.5 6445.5 6454.2 6457.6 6465.9 6406.5 6439.5 6457.5 6461.3 6468.2 6182.4 6222.2 6266.1 6292.0 6326.1 6444.6 6451.7 6459.4 6462.3 6473.4 6196.3 6256.2 6276.6 6300.5 6361.2 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:54156.20 Median:30400.35 stddev:108991.79 (358.5%) stddev-sans-first:109223.98 04:18:23 INFO - PROCESS | 1644 | Values: 823.5 841.1 914.5 921.7 999.0 39371.0 39975.0 39981.6 40001.6 40013.8 39566.4 39848.6 39877.4 39888.6 39924.2 7916.6 8076.5 8155.9 8213.6 8356.0 7928.3 8013.3 11551.6 11700.9 11718.6 11763.0 11831.0 11864.5 11876.7 11884.2 9765.5 11795.7 11796.6 11798.1 11857.2 11487.0 11567.6 11580.6 11610.0 11610.3 35787.3 39133.4 40980.0 41212.6 41261.6 4034.8 4084.2 4084.7 4086.7 4122.8 28184.4 28742.9 29576.6 30343.8 30456.8 3273.5 3275.6 3305.2 3417.9 3420.9 347700.0 351902.2 352272.0 352851.4 353257.0 44959.3 45759.8 47693.0 52083.7 53847.7 56418.4 56717.7 56718.0 56745.7 56831.7 14790.6 15252.0 15279.8 15300.5 15313.5 13076.7 13081.3 13108.5 13128.9 13251.9 12052.5 12071.2 12183.1 12190.5 12198.9 5427.2 5457.6 5461.7 5467.1 5474.6 1172.6 1175.4 1190.2 1193.7 1200.8 23040.7 26893.4 27152.8 27278.0 27336.7 1289.0 1296.8 1306.2 1330.6 1336.1 13900.7 14041.4 14164.4 14206.9 14253.5 460352.0 460914.0 461660.7 461666.0 461935.0 57454.9 57487.6 57521.8 57636.0 57658.0 1457.1 1467.2 1501.7 1513.6 1515.9 26285.9 27143.2 27565.6 27568.7 27750.7 39762.4 39801.2 39857.0 39898.8 39914.0 410884.0 411577.0 412582.0 412785.0 413375.9 24290.5 24715.6 27119.7 28297.4 28972.4 32740.8 37137.8 37359.0 37671.8 37824.6 26976.7 27561.2 27664.9 28224.7 28345.0 22110.2 22315.9 22428.9 22429.4 22558.3 30938.8 31289.4 31762.7 32153.4 32182.5 21636.5 26924.7 27115.3 27118.7 27728.9 21718.3 22295.6 22765.9 22842.0 22867.0 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:45414.27 Median:12159.32 stddev:134292.87 (1104.4%) stddev-sans-first:134626.98 04:18:23 INFO - PROCESS | 1644 | Values: 358.4 411.4 418.6 423.5 455.7 19967.9 21273.9 22213.8 22360.7 22404.4 21703.9 22124.4 22819.3 23101.8 23781.9 5918.7 5946.3 6684.4 7039.2 7045.1 6514.9 6787.2 6847.7 6850.1 6850.2 6761.7 6769.8 6839.2 6844.0 6860.7 6742.0 6827.9 6883.2 6913.1 6920.1 6469.7 6681.6 6762.4 6768.1 6770.7 26416.2 26523.3 26959.3 27061.5 27248.0 3020.8 3079.4 3095.2 3118.7 3128.7 15704.6 15883.4 15895.3 16652.7 16831.9 2105.2 2134.2 2166.0 2194.2 2234.8 158418.9 159001.2 164635.0 170515.0 175476.2 19871.8 20229.1 20432.8 20436.5 20476.9 31675.8 31958.7 32235.5 32333.4 32404.8 10416.5 10468.6 10684.3 10685.6 10850.5 8942.8 8953.2 8976.7 9140.3 9165.6 9195.2 9266.4 9380.9 9384.2 9397.8 2997.9 3643.5 3741.9 3781.0 3848.6 1027.7 1060.3 1075.8 1090.4 1130.6 22272.6 22295.9 22532.2 22546.4 22594.7 1091.7 1103.2 1114.3 1145.4 1146.1 9856.1 11152.4 11494.9 11716.5 12034.9 785502.1 801752.0 808559.0 816052.0 816262.0 127243.7 151643.0 155882.0 163826.0 173150.0 1344.1 1344.2 1381.3 1386.4 1386.4 16111.9 19094.4 19664.0 20148.7 20392.0 64834.7 64845.0 65042.7 65941.7 66321.7 93986.0 94406.0 95122.0 95383.0 96166.5 10691.9 10914.0 11113.4 11495.6 11591.5 12705.0 12732.9 13053.5 13060.8 13414.9 11083.3 11148.7 11267.4 11274.5 11635.2 10063.2 10716.4 10947.6 11063.8 11084.9 11119.1 11258.7 11605.9 11982.6 12046.2 11326.4 11475.5 11756.2 11873.9 11875.8 11185.6 11278.4 11438.1 11484.8 11552.8 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6766.22 Median:3191.57 stddev:4342.21 (136.1%) stddev-sans-first:4337.15 04:18:23 INFO - PROCESS | 1644 | Values: 1616.3 1621.0 1642.0 1655.3 1662.1 10897.2 11602.7 11609.1 11617.8 11638.0 10987.3 11376.0 11577.7 11586.1 11586.5 2301.5 2339.5 2341.6 2346.7 2349.9 2275.7 2344.6 2371.2 2371.7 2376.6 2285.1 2310.8 2316.4 2331.4 2348.9 2319.0 2322.0 2335.4 2343.2 2348.3 1948.8 2357.1 2365.1 2366.8 2369.9 11401.9 11482.1 11494.7 11505.7 11521.3 5232.9 5401.8 5406.7 5421.4 5422.7 3157.5 3225.7 3364.1 3407.0 3421.7 1157.7 1169.6 1899.5 1931.3 1953.7 15668.5 15700.2 15873.8 15899.4 15901.8 4301.4 4329.7 4439.7 4446.1 4447.8 12057.0 12065.7 12067.3 12070.6 12073.9 5522.4 5523.7 5528.7 5532.9 5539.3 4140.3 5073.6 5088.9 5154.5 5158.9 2969.7 3612.4 3632.6 3676.1 3687.6 915.3 916.0 928.7 929.6 929.9 945.9 957.8 959.9 966.7 1007.6 6445.2 6470.6 6644.9 6690.1 6741.8 922.7 930.4 963.8 996.1 1008.9 3529.0 4085.2 4094.6 4227.3 4257.0 13511.3 14309.4 14312.0 14317.6 14319.7 12064.5 12501.9 13919.6 13951.5 14003.6 8360.0 9160.2 9453.0 10476.9 10772.5 5549.9 6419.6 6785.3 6900.7 6905.8 10506.0 12336.7 12463.3 12569.7 12584.7 10740.9 12707.2 13015.4 13038.3 13058.6 9355.1 9407.5 9424.2 9518.5 9612.1 10410.9 10489.0 10603.8 10608.3 10609.6 8932.9 8991.9 9018.0 9154.4 9210.7 7369.9 8741.6 8905.7 8909.5 9030.2 7700.2 7911.1 8025.7 8027.7 8066.2 5967.5 6007.3 6026.2 6027.7 6079.8 5964.5 6002.4 6056.8 6163.5 6183.2 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4530.81 Median:4878.99 stddev:1265.28 (25.9%) stddev-sans-first:1229.30 04:18:23 INFO - PROCESS | 1644 | Values: 349.9 378.5 392.8 393.0 393.2 4537.6 4822.1 5024.7 5108.5 5129.9 4401.4 4865.4 4991.3 5008.5 5024.3 4169.2 4245.4 4247.4 4284.8 4291.5 3479.0 3896.3 3920.0 4237.9 4260.2 3681.5 4241.1 4252.7 4254.4 4284.4 3715.6 3823.7 4064.0 4259.2 4320.7 3456.8 3936.8 3970.9 3996.8 4025.7 4525.0 5012.4 5024.9 5103.2 5149.9 4775.4 4835.7 4876.2 4927.3 4977.6 4601.6 5409.7 5422.2 5423.1 5435.8 3809.8 4381.0 4387.3 4414.3 4423.5 5022.8 5633.0 5756.5 5835.4 5853.2 3632.3 4475.8 4475.8 4478.4 4504.7 3761.7 3810.3 4017.1 4640.0 4661.3 4327.9 4539.6 4841.4 4914.4 4937.5 5173.5 5369.9 5390.2 5404.8 5407.9 1417.4 1421.9 1439.9 1539.6 1565.3 1085.9 1166.4 1181.4 1197.8 1213.2 4585.0 4683.5 4780.1 5105.8 5119.3 4706.4 5598.0 5602.7 5605.0 5641.4 4366.0 4470.0 4590.8 5051.4 5080.2 2482.7 2719.4 2842.1 2910.9 2925.8 4885.4 5160.3 5749.6 5762.7 5774.5 4881.7 5324.8 5493.5 5687.2 5767.5 5590.6 5661.3 5762.5 5788.4 5824.4 5060.9 5295.8 5305.6 5330.8 5355.4 4659.8 4938.4 5174.8 5474.6 5604.7 5696.1 5777.6 5800.8 5845.0 5981.6 4460.4 4460.8 4487.5 4505.9 4506.0 5017.9 5034.9 5532.7 5630.0 5689.3 5400.0 5484.8 5503.4 5533.8 5597.6 4554.8 4992.5 4996.0 5297.5 5521.9 4452.4 5290.7 5345.5 5347.8 5361.5 4665.8 4706.6 4866.3 5260.6 5492.5 4811.0 4939.8 5331.7 5406.6 5628.9 04:18:23 INFO - PROCESS | 1644 | 04:18:23 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21130.22 Median:17116.53 stddev:30812.32 (180.0%) stddev-sans-first:30860.81 04:18:23 INFO - PROCESS | 1644 | Values: 764.4 776.1 801.8 891.9 901.7 15093.0 15952.2 16177.8 17420.7 17921.0 15793.6 16033.5 16048.6 16106.9 16124.4 1014.4 1043.9 1045.1 1047.9 1052.2 1013.3 1031.5 1036.0 1036.3 1051.2 1087.2 1089.1 1089.9 1090.0 1092.2 1085.8 1086.5 1086.9 1087.0 1090.1 810.1 811.7 812.0 812.1 812.4 17064.6 17168.5 17216.5 17881.2 18134.5 14629.9 14691.7 14715.9 14716.9 14719.7 12016.7 12055.2 12109.5 12213.6 12343.4 3715.5 3800.2 3840.6 3859.1 3946.4 105532.4 112528.0 113611.0 114764.0 115402.4 10792.3 10911.5 10998.2 11471.0 11558.7 22868.7 23133.0 23252.5 23260.4 23775.0 8070.6 8076.4 8149.4 8223.1 8255.8 3720.4 4488.3 4538.8 4581.8 4734.0 3029.5 3458.9 3526.6 3542.9 3549.5 612.8 614.1 624.4 627.7 630.8 654.8 699.5 725.8 741.9 765.3 10313.2 10718.3 10764.7 10866.0 11211.8 686.8 695.7 746.2 750.5 767.7 4523.3 4530.7 4699.0 5300.4 5300.5 85990.6 108715.0 111814.4 112116.0 114447.0 67268.0 96608.5 97078.5 98204.0 101101.5 62416.0 70745.6 78879.0 86001.8 86251.6 8314.0 10065.8 10066.0 10086.7 10090.5 55033.0 55217.2 55274.4 56301.7 56475.9 69965.6 70329.4 71017.6 71743.3 71821.0 11506.1 11664.5 11771.9 11858.7 12116.5 13212.4 13411.0 13457.0 13463.2 13502.9 12307.6 12313.0 12431.2 12579.0 12617.3 12029.4 12355.4 12497.1 12500.4 12968.2 11648.6 12120.7 12454.6 12506.9 12522.2 9031.3 9436.3 9468.9 9502.2 9555.8 8688.8 9414.8 10517.5 10607.8 10621.7 04:18:23 INFO - PROCESS | 1644 | -------- Summary: end -------- 04:18:23 INFO - PROCESS | 1644 | 04:18:24 INFO - TEST-INFO | 1644: exit 0 04:18:24 INFO - TEST-OK | dromaeo_css | took 1118458ms 04:18:24 INFO - TEST-START | kraken 04:18:24 INFO - operating with platform_type : mac_ 04:18:24 INFO - Initialising browser for kraken test... 04:18:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpSTHDPr/profile http://localhost:49225/getInfo.html) 04:18:26 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 04:18:26 INFO - PROCESS | 1650 | colorDepth:24 04:18:26 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 04:18:26 INFO - PROCESS | 1650 | __metrics 04:18:26 INFO - PROCESS | 1650 | 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" 04:18:26 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:27 INFO - TEST-INFO | 1650: exit 0 04:18:27 INFO - Browser initialized. 04:18:27 INFO - Running cycle 1/1 for kraken test... 04:18:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpSTHDPr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:39 INFO - PROCESS | 1652 | RSS: Main: 164966400 04:18:39 INFO - PROCESS | 1652 | 04:18:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 04:18:43 INFO - PROCESS | 1652 | RSS: Main: 215883776 04:18:43 INFO - PROCESS | 1652 | 04:18:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 04:18:46 INFO - PROCESS | 1652 | RSS: Main: 231559168 04:18:46 INFO - PROCESS | 1652 | 04:18:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 04:18:50 INFO - PROCESS | 1652 | RSS: Main: 329121792 04:18:50 INFO - PROCESS | 1652 | 04:18:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 04:18:52 INFO - PROCESS | 1652 | RSS: Main: 229474304 04:18:52 INFO - PROCESS | 1652 | 04:18:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:18:55 INFO - PROCESS | 1652 | RSS: Main: 230789120 04:18:55 INFO - PROCESS | 1652 | 04:18:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 04:18:58 INFO - PROCESS | 1652 | RSS: Main: 272953344 04:18:58 INFO - PROCESS | 1652 | 04:19:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 04:19:02 INFO - PROCESS | 1652 | RSS: Main: 273436672 04:19:02 INFO - PROCESS | 1652 | 04:19:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 04:19:05 INFO - PROCESS | 1652 | RSS: Main: 267874304 04:19:05 INFO - PROCESS | 1652 | 04:19:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:19:07 INFO - PROCESS | 1652 | RSS: Main: 246956032 04:19:07 INFO - PROCESS | 1652 | 04:19:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:19:10 INFO - PROCESS | 1652 | RSS: Main: 253698048 04:19:10 INFO - PROCESS | 1652 | 04:19:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:19:13 INFO - PROCESS | 1652 | RSS: Main: 257753088 04:19:13 INFO - PROCESS | 1652 | 04:19:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:19:16 INFO - PROCESS | 1652 | RSS: Main: 262868992 04:19:16 INFO - PROCESS | 1652 | 04:19:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:19:19 INFO - PROCESS | 1652 | RSS: Main: 264044544 04:19:19 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 04:19:21 INFO - PROCESS | 1652 | RSS: Main: 308563968 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | __start_tp_report 04:19:21 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 04:19:21 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 04:19:21 INFO - PROCESS | 1652 | |i|pagename|runs| 04:19:21 INFO - PROCESS | 1652 | |0;ai-astar;104;112;138;171;142;113;112;113;113;113 04:19:21 INFO - PROCESS | 1652 | |1;audio-beat-detection;143;109;108;143;109;109;108;107;127;107 04:19:21 INFO - PROCESS | 1652 | |2;audio-dft;184;199;233;212;185;185;183;182;187;183 04:19:21 INFO - PROCESS | 1652 | |3;audio-fft;82;62;60;90;60;59;61;60;76;58 04:19:21 INFO - PROCESS | 1652 | |4;audio-oscillator;81;69;86;68;69;69;82;69;69;82 04:19:21 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;87;86;87;87;86;89;88;86;87;85 04:19:21 INFO - PROCESS | 1652 | |6;imaging-darkroom;93;93;94;92;93;92;93;93;93;93 04:19:21 INFO - PROCESS | 1652 | |7;imaging-desaturate;83;83;84;82;82;82;82;84;82;83 04:19:21 INFO - PROCESS | 1652 | |8;json-parse-financial;60;59;57;60;60;61;61;62;62;63 04:19:21 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;53;62;57;64;59;57;57;57;57;58 04:19:21 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;84;83;85;82;83;84;81;83;79;105 04:19:21 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;136;134;133;133;148;132;139;129;149;134 04:19:21 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;164;161;162;164;210;162;162;163;160;160 04:19:21 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;64;63;64;63;64;63;64;62;63;62 04:19:21 INFO - PROCESS | 1652 | __end_tp_report 04:19:21 INFO - PROCESS | 1652 | __start_cc_report 04:19:21 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1998 04:19:21 INFO - PROCESS | 1652 | __end_cc_report 04:19:21 INFO - PROCESS | 1652 | __startTimestamp1496056761885__endTimestamp 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | ------- Summary: start ------- 04:19:21 INFO - PROCESS | 1652 | Number of tests: 14 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:123.10 Median:113.00 stddev:20.80 (18.4%) stddev-sans-first:20.88 04:19:21 INFO - PROCESS | 1652 | Values: 104.0 112.0 138.0 171.0 142.0 113.0 112.0 113.0 113.0 113.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:109.00 stddev:14.93 (13.7%) stddev-sans-first:12.52 04:19:21 INFO - PROCESS | 1652 | Values: 143.0 109.0 108.0 143.0 109.0 109.0 108.0 107.0 127.0 107.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:193.30 Median:186.00 stddev:16.87 (9.1%) stddev-sans-first:17.56 04:19:21 INFO - PROCESS | 1652 | Values: 184.0 199.0 233.0 212.0 185.0 185.0 183.0 182.0 187.0 183.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.50 stddev:11.49 (18.7%) stddev-sans-first:10.79 04:19:21 INFO - PROCESS | 1652 | Values: 82.0 62.0 60.0 90.0 60.0 59.0 61.0 60.0 76.0 58.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:74.40 Median:75.00 stddev:7.31 (9.7%) stddev-sans-first:7.35 04:19:21 INFO - PROCESS | 1652 | Values: 81.0 69.0 86.0 68.0 69.0 69.0 82.0 69.0 69.0 82.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:1.14 (1.3%) stddev-sans-first:1.20 04:19:21 INFO - PROCESS | 1652 | Values: 87.0 86.0 87.0 87.0 86.0 89.0 88.0 86.0 87.0 85.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:19:21 INFO - PROCESS | 1652 | Values: 93.0 93.0 94.0 92.0 93.0 92.0 93.0 93.0 93.0 93.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:82.70 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 04:19:21 INFO - PROCESS | 1652 | Values: 83.0 83.0 84.0 82.0 82.0 82.0 82.0 84.0 82.0 83.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:61.00 stddev:1.72 (2.8%) stddev-sans-first:1.81 04:19:21 INFO - PROCESS | 1652 | Values: 60.0 59.0 57.0 60.0 60.0 61.0 61.0 62.0 62.0 63.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:57.50 stddev:3.03 (5.3%) stddev-sans-first:2.60 04:19:21 INFO - PROCESS | 1652 | Values: 53.0 62.0 57.0 64.0 59.0 57.0 57.0 57.0 57.0 58.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:83.00 stddev:7.26 (8.8%) stddev-sans-first:7.70 04:19:21 INFO - PROCESS | 1652 | Values: 84.0 83.0 85.0 82.0 83.0 84.0 81.0 83.0 79.0 105.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:135.00 stddev:6.73 (5.0%) stddev-sans-first:7.14 04:19:21 INFO - PROCESS | 1652 | Values: 136.0 134.0 133.0 133.0 148.0 132.0 139.0 129.0 149.0 134.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:162.50 stddev:15.24 (9.4%) stddev-sans-first:16.14 04:19:21 INFO - PROCESS | 1652 | Values: 164.0 161.0 162.0 164.0 210.0 162.0 162.0 163.0 160.0 160.0 04:19:21 INFO - PROCESS | 1652 | 04:19:21 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 04:19:21 INFO - PROCESS | 1652 | Values: 64.0 63.0 64.0 63.0 64.0 63.0 64.0 62.0 63.0 62.0 04:19:21 INFO - PROCESS | 1652 | -------- Summary: end -------- 04:19:21 INFO - PROCESS | 1652 | 04:19:22 INFO - TEST-INFO | 1652: exit 0 04:19:22 INFO - TEST-OK | kraken | took 58447ms 04:19:22 INFO - SUITE-END | took 1176s 04:19:23 INFO - Completed test suite (00:19:37) 04:19:23 INFO - javascript benchmark 04:19:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7775.306512283129, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3279.8360081995897, 3577.3203091412615, 3598.5681718193814, 3606.2607165531067, 3646.7811931284123, 5146.305248791412, 6010.12853136295, 6153.630782153074, 6177.073438984149, 6182.814515564541, 6129.295131059929, 6207.317195108534, 6211.534134939875, 6216.16081828953, 6225.470834979021, 5509.972450137744, 5525.171224316365, 5537.390887002426, 5543.1408131739645, 5606.971965140168, 5400.83797486076, 5458.208559758833, 5496.587755918302, 5641.802536911209, 5653.1237658162845, 5508.531774799147, 5529.778808847662, 5531.0, 5538.806141785039, 5544.667319960815, 5570.693611851347, 5582.106862901936, 5609.551235901118, 5615.326160860723, 5637.238972738669, 5469.972650136724, 5532.336119665626, 5547.750351234205, 5557.5831812613815, 5595.18869763882, 5991.2510936132985, 6006.12911127884, 6039.365866583989, 6046.758129674808, 6231.065340198961, 5359.410464848864, 5966.105084237356, 6143.047827586731, 6350.587211831263, 6483.610983340967, 6576.177977752781, 6588.703508342136, 6628.635425051621, 6645.534812563123, 6651.334866513358, 6328.240611126648, 6335.303116657164, 6340.778072767477, 6344.206974128234, 6346.333634968354, 6462.0, 6536.411722944958, 6553.311902250195, 6601.5378923475855, 6779.2881747416795, 6351.555391122577, 6354.364563543609, 6370.522210834206, 6373.71318290678, 6392.520560957947, 6583.572067815577, 6609.438197753245, 6646.734130634721, 6648.16897887764, 6652.866942661217, 6544.083828264047, 6557.606543607399, 6558.147440832661, 6559.967200164064, 6588.8023359299295, 6329.430351268408, 6348.111264422984, 6354.491640668735, 6558.606483610999, 6562.442192413699, 5272.525472707474, 5297.0, 5319.414864364917, 5338.439463856317, 5362.302900622972, 4688.156131896291, 4722.315264286659, 4738.360321356642, 4738.715677059318, 4767.4517430495935, 6096.756129754849, 6281.748730050746, 6288.591241569284, 6297.811065668037, 6299.149614802035, 6445.4199122079235, 6490.675466226669, 6523.7064332105165, 6556.967215163893, 6610.636414997125, 6280.654563998933, 6287.717052732638, 6299.811005669837, 6300.243970723543, 6320.462760665395, 5766.443060373759, 5784.652920824764, 5786.421357864264, 5807.680577568274, 5836.270466191726, 5576.776928922797, 6521.217453905561, 6592.736290548324, 6643.667816609247, 6651.102101216274, 6430.421262086435, 6499.28507864144, 6520.315366886409, 6597.1423714916755, 6603.108580341785, 6763.323667633197, 6816.693248803915, 6830.795076147723, 6835.89746153818, 6853.725850965906, 6486.254080780771, 6514.934850651623, 6522.119513865568, 6526.67366631676, 6536.248331441945, 5286.0, 5786.537077033743, 6045.244344456943, 6240.375962403723, 6434.356564343529, 6421.486281097407, 6445.484361250995, 6454.160959075478, 6457.580257283262, 6465.903011454738, 6406.519511036784, 6439.549231553746, 6457.5479716419395, 6461.321561236003, 6468.191476065492, 6182.381761823962, 6222.222222222223, 6266.060090986389, 6292.0, 6326.051092336186, 6444.645544494911, 6451.677416129269, 6459.386358295955, 6462.256840463407, 6473.417392434703, 6196.287426945959, 6256.155419018374, 6276.623402595859, 6300.464460520724, 6361.236651601837], "value": 6023.1896347512, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.4894365493393, 841.0706169682503, 914.51530688735, 921.6912334367983, 998.9650362237323, 39371.0, 39975.00062498439, 39981.600183998155, 40001.59998400015, 40013.79993100034, 39566.406503902464, 39848.60151398485, 39877.40183897244, 39888.60111398885, 39924.20151596966, 7916.564588947605, 8076.515409075445, 8155.918440815575, 8213.630386632602, 8355.95822020889, 7928.326092282163, 8013.278804907557, 11551.595694150707, 11700.882991170107, 11718.648440546755, 11763.000144987644, 11831.0, 11864.52541898323, 11876.703082422922, 11884.168108232427, 9765.462899540522, 11795.705107372298, 11796.587119450864, 11798.056155507538, 11857.22928009681, 11487.0, 11567.595134170264, 11580.594679186272, 11610.0, 11610.303381797119, 35787.28425431477, 39133.434662613465, 40980.0, 41212.58787412148, 41261.587384126375, 4034.798260086984, 4084.244414783275, 4084.65280451162, 4086.652634526069, 4122.793860307002, 28184.436311273865, 28742.856285718437, 29576.55635165474, 30343.848280758677, 30456.8477157615, 3273.5253388258807, 3275.6396796352383, 3305.190228394035, 3417.9316413671836, 3420.9144771380666, 347700.0, 351902.2404887985, 352272.0, 352851.3574321299, 353257.0, 44959.32561011588, 45759.77120114378, 47693.0, 52083.73958130185, 53847.73076134594, 56418.43581564214, 56717.71641141768, 56718.0, 56745.71627141838, 56831.715841420526, 14790.630234244014, 15252.0, 15279.770803437958, 15300.464483743015, 15313.46402875894, 13076.673083172996, 13081.280529570775, 13108.475660973454, 13128.868711312765, 13251.933740331237, 12052.517899284107, 12071.155019148751, 12183.086268529718, 12190.451429685685, 12198.90209881115, 5427.158790387496, 5457.590680698963, 5461.672299662033, 5467.0705979983495, 5474.5894057945015, 1172.5778719660916, 1175.400545721688, 1190.2443895254603, 1193.6777070191, 1200.771853347861, 23040.65439018416, 26893.386396816255, 27152.778124984423, 27278.0, 27336.726632733815, 1288.9806652900213, 1296.7925131979027, 1306.1705816806395, 1330.5875178694635, 1336.104809777432, 13900.721985560232, 14041.368138433794, 14164.433422663184, 14206.857931420553, 14253.50112746049, 460352.0, 460914.0, 461660.69169653935, 461666.0, 461935.0, 57454.85090298254, 57487.56281092922, 57521.849563009346, 57636.0, 57658.0, 1457.1402872304966, 1467.222372142766, 1501.737195990719, 1513.6215946013497, 1515.9317830697646, 26285.868570657025, 27143.18570442811, 27565.58651620187, 27568.724312756614, 27750.722492774814, 39762.40356394714, 39801.20397591974, 39857.0, 39898.80050599844, 39914.0, 410884.0, 411577.0, 412582.0, 412785.0, 413375.9331203325, 24290.514189715752, 24715.62926556067, 27119.72880271251, 28297.43405131927, 28972.39248467648, 32740.836295818368, 37137.814310928276, 37359.0, 37671.81164094162, 37824.62175378211, 26976.70697990693, 27561.173164804284, 27664.861675691493, 28224.71775282221, 28345.007924722537, 22110.22614208495, 22315.888420557792, 22428.887855561272, 22429.439264017878, 22558.323250302517, 30938.845305773328, 31289.374212515166, 31762.682373176896, 32153.35693286168, 32182.517262240617, 21636.45908852291, 26924.730752693005, 27115.322116947234, 27118.72881271162, 27728.861355693094, 21718.348449546538, 22295.554088918456, 22765.88617056904, 22842.0, 22867.0], "value": 18529.69366485172, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.430096147126, 411.44454985769204, 418.6310763802366, 423.5129600958897, 455.6559797353, 19967.90016049921, 21273.893630531846, 22213.777862221374, 22360.664590031163, 22404.439889002784, 21703.891480542574, 22124.446888827788, 22819.31542053737, 23101.768982310172, 23781.881090594565, 5918.674472903988, 5946.256717910262, 6684.398404143614, 7039.225685174627, 7045.11936007999, 6514.869702605945, 6787.185537735454, 6847.691853866588, 6850.075239842632, 6850.212225594046, 6761.695723692421, 6769.796906092825, 6839.179298484189, 6844.007618895257, 6860.691268892887, 6741.966290168542, 6827.863442731143, 6883.174019117688, 6913.10129683143, 6920.134983127135, 6469.676516174172, 6681.599104053772, 6762.425193858478, 6768.086308348359, 6770.661466926683, 26416.207513774618, 26523.33691657724, 26959.32601684954, 27061.45877082467, 27248.04631837896, 3020.8489575521135, 3079.3995170941675, 3095.241665791873, 3118.688131186886, 3128.702773236539, 15704.607384815357, 15883.444079457164, 15895.29024756265, 16652.66694666112, 16831.905926114763, 2105.221068204759, 2134.1676746069047, 2166.0361139293022, 2194.188150384352, 2234.754177040524, 158418.88198757765, 159001.20499397544, 164635.0, 170515.0, 175476.24523754726, 19871.801281987286, 20229.08969096394, 20432.795672043387, 20436.489087772923, 20476.897615512124, 31675.841620791747, 31958.680413196034, 32235.516467253008, 32333.35333293321, 32404.83797580997, 10416.489609006803, 10468.63359782404, 10684.305520141166, 10685.57257709683, 10850.457477126112, 8942.776430589158, 8953.194212520904, 8976.730698079067, 9140.314476414296, 9165.58754856033, 9195.229955718123, 9266.397684150508, 9380.906190938003, 9384.202342800801, 9397.76505587352, 2997.9100626981226, 3643.4534819777246, 3741.875533929145, 3780.9621903781162, 3848.5958974307855, 1027.6505987963978, 1060.3319908457622, 1075.800976819291, 1090.3839330778085, 1130.5873356224918, 22272.55454890893, 22295.857282667308, 22532.21137260188, 22546.436339091324, 22594.661080083813, 1091.7216109892086, 1103.2167161315394, 1114.2645853736494, 1145.4215621111287, 1146.0831334932188, 9856.112949834405, 11152.386618736047, 11494.930912435842, 11716.472758725879, 12034.939825300993, 785502.0724896339, 801752.0, 808559.0, 816052.0, 816262.0, 127243.72756272319, 151643.0, 155882.0, 163826.0, 173150.0, 1344.1330341929597, 1344.227069435059, 1381.2955392749568, 1386.3899884050986, 1386.4246337769914, 16111.905452291126, 19094.427167185007, 19664.0, 20148.697769533177, 20392.0, 64834.675826620565, 64845.0, 65042.674786625765, 65941.67029164823, 66321.66839165773, 93986.0, 94406.0, 95122.04877951131, 95383.0, 96166.5417471196, 10691.893081069089, 10914.0, 11113.44432778374, 11495.597654082067, 11591.478383472933, 12705.0, 12732.87267127317, 13053.477860885458, 13060.80408793888, 13414.865851341361, 11083.334999900031, 11148.665540033811, 11267.436628168723, 11274.549018039186, 11635.185537012328, 10063.194944404284, 10716.35701857891, 10947.56209751601, 11063.834042489209, 11084.889151108386, 11119.054880335261, 11258.66224013281, 11605.941970290432, 11982.58060967862, 12046.216995895238, 11326.37704926237, 11475.540978360772, 11756.177067605186, 11873.940630296793, 11875.821862671894, 11185.608503702331, 11278.436078195893, 11438.142139339749, 11484.794096619735, 11552.826707599224], "value": 11868.7431316507, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1616.2969108437824, 1621.0, 1642.0065860154614, 1655.263407783536, 1662.0941586835174, 10897.182711296646, 11602.709932251699, 11609.071274298058, 11617.825732614017, 11637.952584267403, 10987.340759554432, 11375.976162145405, 11577.65267041988, 11586.131040171978, 11586.478608462618, 2301.5051763870765, 2339.508703172336, 2341.613633750429, 2346.7418583955837, 2349.929502114939, 2275.7496675365696, 2344.589696803061, 2371.205646108547, 2371.67982322386, 2376.5603363377745, 2285.0517035430275, 2310.838241323108, 2316.4324740438615, 2331.428799944016, 2348.941276468085, 2318.953620927589, 2322.0, 2335.4161459635093, 2343.1915988983774, 2348.3189874936334, 1948.824605785486, 2357.139644029934, 2365.0539784086504, 2366.7514910934447, 2369.9407514812096, 11401.942990285079, 11482.138839587064, 11494.655160345203, 11505.712357191054, 11521.308721476738, 5232.947670523284, 5401.837944861661, 5406.729663516809, 5421.376541697716, 5422.701751403672, 3157.4790159623635, 3225.661305562929, 3364.0916952422954, 3407.028996735943, 3421.674940880612, 1157.6584907452323, 1169.5555688838188, 1899.5441094137445, 1931.304730297092, 1953.6946403244167, 15668.529944101696, 15700.214989250606, 15873.761893571605, 15899.364025439085, 15901.761473577906, 4301.376300436419, 4329.740215587075, 4439.66702497314, 4446.1330040642415, 4447.755373454426, 12057.035437165006, 12065.698357540956, 12067.336296503776, 12070.577529786415, 12073.939630301793, 5522.364928033247, 5523.696196709219, 5528.668279903219, 5532.917006244909, 5539.25220095282, 4140.296149654566, 5073.594112470993, 5088.898222035538, 5154.5103215194495, 5158.92261616076, 2969.732724054845, 3612.422012477991, 3632.600413954463, 3676.0809797550614, 3687.5574931007914, 915.3089417489753, 915.9679411220575, 928.7167413938813, 929.5538141692026, 929.9302552308604, 945.8628498867625, 957.7701351675547, 959.9424034557949, 966.6520052780993, 1007.6145300212208, 6445.1943507061615, 6470.6441145737435, 6644.867102658017, 6690.130283063268, 6741.764038258638, 922.727795300388, 930.3998920696113, 963.816874793787, 996.0935548650838, 1008.9495525223709, 3528.964710352915, 4085.1829634073306, 4094.5700701426517, 4227.3447615619625, 4256.978715106405, 13511.256880871646, 14309.427622894967, 14311.9981601289, 14317.64205894861, 14319.713605727828, 12064.457099430547, 12501.93749031249, 13919.640012742968, 13951.511697090757, 14003.579892603238, 8359.95820020896, 9160.17558419732, 9452.95273523628, 10476.895231047592, 10772.515236814363, 5549.917766035745, 6419.582727122722, 6785.287544807724, 6900.689468974008, 6905.75829845953, 10506.0, 12336.691582710504, 12463.31451770125, 12569.748605028031, 12584.685382865502, 10740.946295268473, 12707.237565746085, 13015.414306356426, 13038.348082595643, 13058.608241752763, 9355.06449355059, 9407.529623518934, 9424.246060315296, 9518.524073796421, 9612.134907858326, 10410.895891041295, 10489.0, 10603.840942386025, 10608.310459820086, 10609.575616975235, 8932.910670893469, 8991.910080899108, 9018.0, 9154.359194856295, 9210.677626283048, 7369.926300737138, 8741.606627701896, 8905.73282801517, 8909.509976951329, 9030.23243024324, 7700.152983171734, 7911.129775724781, 8025.719099831478, 8027.719029833927, 8066.193380661887, 5967.492763115184, 6007.309159446545, 6026.216591843032, 6027.728752206073, 6079.787207447718, 5964.522838172849, 6002.399760024137, 6056.757729690939, 6163.476104531165, 6183.227096612924], "value": 5127.491368105241, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.9020274323189, 378.5362930410248, 392.786290362779, 392.96463318301346, 393.21553500765975, 4537.591616754487, 4822.083804077227, 5024.748762561871, 5108.540231379176, 5129.948700512994, 4401.40581021562, 4865.440474345452, 4991.301217829507, 5008.49915008499, 5024.271480635306, 4169.228692691861, 4245.448091748076, 4247.426597409341, 4284.800255928345, 4291.485021797389, 3478.9826050869838, 3896.2597106549806, 3919.960800391988, 4237.8516751913685, 4260.169266992938, 3681.502997095398, 4241.066965267636, 4252.744835309876, 4254.383334332949, 4284.443022407083, 3715.5541335039698, 3823.6941044716355, 4064.012950481394, 4259.16946195492, 4320.719153255044, 3456.792592444436, 3936.7637941723588, 3970.940435893464, 3996.760194388317, 4025.7383270087353, 4524.977375113136, 5012.448630650625, 5024.899502009976, 5103.23451482277, 5149.948500515022, 4775.379200703921, 4835.718534588337, 4876.244551961291, 4927.285543596195, 4977.601791856679, 4601.631869450436, 5409.702466364349, 5422.240886275925, 5423.078076726926, 5435.755391007375, 3809.847606095753, 4380.956190438119, 4387.27609944362, 4414.3378493225955, 4423.496011356155, 5022.824201152942, 5632.971835140839, 5756.510696590753, 5835.35811060783, 5853.239078919756, 3632.3098611263777, 4475.7985890635, 4475.843345482957, 4478.432548608026, 4504.707194032378, 3761.7022127366563, 3810.256999884996, 4017.1478250442515, 4639.976800115978, 4661.254199328091, 4327.913441731148, 4539.591436770706, 4841.419029716386, 4914.36113305268, 4937.49406431042, 5173.534381905646, 5369.865753356197, 5390.1999090641, 5404.7567859445635, 5407.917487032354, 1417.3551034279378, 1421.8933579981542, 1439.8920080993967, 1539.599704076948, 1565.2956169723443, 1085.92398532102, 1166.4284500594647, 1181.4033912873945, 1197.808350663907, 1213.2114125818111, 4584.951007579922, 4683.484816670163, 4780.091782561302, 5105.795768169306, 5119.334486516729, 4706.364435915898, 5597.969461987363, 5602.663840169603, 5604.971975140098, 5641.364004438748, 4366.039471316305, 4469.9553004469535, 4590.816367345269, 5051.444341122474, 5080.23796430531, 2482.74070595757, 2719.428919926798, 2842.0905310300504, 2910.8544572771275, 2925.765938724897, 4885.363403259877, 5160.329157209539, 5749.607731438471, 5762.74067666956, 5774.509166720875, 4881.731504767201, 5324.840254792362, 5493.533049690741, 5687.232223649837, 5767.548791763484, 5590.580706446952, 5661.348944871393, 5762.510186634099, 5788.363280039192, 5824.3593204748295, 5060.8987820242655, 5295.816305118913, 5305.628605997543, 5330.7877230948725, 5355.410904800391, 4659.813607455664, 4938.432080310667, 5174.818881339135, 5474.616776825584, 5604.691741954069, 5696.1455781633085, 5777.566682498746, 5800.76796928135, 5845.0, 5981.6372532850155, 4460.3755474234285, 4460.821567137408, 4487.5288094749585, 4505.863315820034, 4506.0, 5017.899642007213, 5034.899302014012, 5532.695701736443, 5629.986602411442, 5689.260396148474, 5399.973000134974, 5484.808031718871, 5503.367112782082, 5533.750981205936, 5597.608167428404, 4554.817807287672, 4992.503748125937, 4995.976629465073, 5297.477177676802, 5521.901677362576, 4452.443444569429, 5290.7354632269, 5345.518903298722, 5347.786088556414, 5361.544268737045, 4665.757298677387, 4706.552877476771, 4866.29438731389, 5260.605454590845, 5492.528362237089, 4810.989692164685, 4939.802407903788, 5331.680099194061, 5406.567474601944, 5628.887422251614], "value": 4164.9419638084255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.3808515102767, 776.1229810314344, 801.8035581282587, 891.8528442806937, 901.6917880577914, 15093.0, 15952.202389880504, 16177.757333639991, 17420.738688919646, 17921.014344211064, 15793.60515987101, 16033.518994430156, 16048.59878503038, 16106.919465402685, 16124.435644752435, 1014.3862962907446, 1043.9373637581768, 1045.0542259255349, 1047.9266451348408, 1052.221356196413, 1013.3463915774321, 1031.4687935713114, 1036.0364860679565, 1036.2953191829554, 1051.2115913065202, 1087.162884578874, 1089.0688461365537, 1089.8528698625703, 1090.0026475774696, 1092.1644941619627, 1085.7557049663762, 1086.456771614197, 1086.934783912964, 1086.9673909782719, 1090.1333439915286, 810.0643756461296, 811.6996711216888, 811.9837603247961, 812.1350761439107, 812.3785304242259, 17064.573385665335, 17168.48494545153, 17216.483505494856, 17881.195346209453, 18134.45596632103, 14629.853701462956, 14691.706165876623, 14715.92642036783, 14716.926415367854, 14719.70560588793, 12016.699582510508, 12055.21641093326, 12109.455074521667, 12213.572524961584, 12343.444544995496, 3715.5169827922196, 3800.2399520095814, 3840.635139661728, 3859.1316953685478, 3946.3685810270217, 105532.41701374562, 112528.0, 113611.0, 114764.0, 115402.42298788537, 10792.298500597437, 10911.454427278763, 10998.175136864767, 11471.0, 11558.653240402802, 22868.65697014546, 23133.0, 23252.53494930092, 23260.41848953756, 23775.0, 8070.636821343053, 8076.353891688768, 8149.429539932148, 8223.05434874985, 8255.752327430188, 3720.404735242348, 4488.281874900001, 4538.795754191069, 4581.79381927814, 4733.982193828343, 3029.4849875521213, 3458.9308213835584, 3526.6473352665043, 3542.875999340011, 3549.467579863041, 612.7763366371242, 614.0972770028052, 624.4099326136757, 627.6767464755654, 630.82967598749, 654.8068319845657, 699.5418001209216, 725.8294300839299, 741.8812989921587, 765.3035737478867, 10313.226508011929, 10718.303310284962, 10764.730881727863, 10866.0, 11211.831822522669, 686.8111269400955, 695.7043256615918, 746.216472703659, 750.5271678842404, 767.7121079595152, 4523.2988886722615, 4530.6602004849765, 4698.989717210816, 5300.44345343741, 5300.496452836975, 85990.57004714936, 108715.0, 111814.44092779484, 112116.0, 114447.0, 67268.0, 96608.51695741617, 97078.5146074265, 98204.0, 101101.49449252707, 62416.0, 70745.64627176934, 78879.0, 86001.83988960682, 86251.56874215588, 8313.960754905636, 10065.798684026426, 10066.0, 10086.747831304276, 10090.545925433225, 55032.9999999984, 55217.171742423096, 55274.44725552854, 56301.71849140728, 56475.87048258929, 69965.60068798697, 70329.37365010889, 71017.6449117751, 71743.28256717575, 71821.0, 11506.07951363872, 11664.475098620413, 11771.941140294244, 11858.703532411759, 12116.454759535665, 13212.405441754952, 13411.006567908471, 13457.0, 13463.192208467523, 13502.86052279723, 12307.630771076882, 12313.0, 12431.21137636938, 12579.0, 12617.306048167071, 12029.398530073637, 12355.382230888239, 12497.14007182163, 12500.437480313225, 12968.221906685629, 11648.592299269485, 12120.696982575506, 12454.626361209179, 12506.937465312616, 12522.248665080124, 9031.277497800029, 9436.339456237998, 9468.857967130636, 9502.23982081418, 9555.761105972406, 8688.78278043054, 9414.811703765748, 10517.526711298164, 10607.787844242917, 10621.734456638645], "value": 7809.983973112235, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1407.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 138.0, 171.0, 142.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 108.0, 143.0, 109.0, 109.0, 108.0, 107.0, 127.0, 107.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 199.0, 233.0, 212.0, 185.0, 185.0, 183.0, 182.0, 187.0, 183.0], "value": 193.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 60.0, 90.0, 60.0, 59.0, 61.0, 60.0, 76.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 86.0, 68.0, 69.0, 69.0, 82.0, 69.0, 69.0, 82.0], "value": 74.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 87.0, 86.0, 89.0, 88.0, 86.0, 87.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 92.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 82.0, 82.0, 82.0, 82.0, 84.0, 82.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 57.0, 60.0, 60.0, 61.0, 61.0, 62.0, 62.0, 63.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 62.0, 57.0, 64.0, 59.0, 57.0, 57.0, 57.0, 57.0, 58.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 82.0, 83.0, 84.0, 81.0, 83.0, 79.0, 105.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 133.0, 133.0, 148.0, 132.0, 139.0, 129.0, 149.0, 134.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 164.0, 210.0, 162.0, 162.0, 163.0, 160.0, 160.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 64.0, 63.0, 64.0, 62.0, 63.0, 62.0], "value": 63.2, "unit": "score"}]}]} 04:19:23 INFO - Return code: 0 04:19:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:19:23 INFO - # TBPL SUCCESS # 04:19:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:19:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:19:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:19:23 INFO - 2017-05-29 04:19:23.203 screenresolution[1656:10496] starting screenresolution argv=screenresolution get 04:19:23 INFO - 2017-05-29 04:19:23.206 screenresolution[1656:10496] Display 0: 1600x1200x32@60 04:19:23 INFO - 2017-05-29 04:19:23.212 screenresolution[1657:10499] starting screenresolution argv=screenresolution list 04:19:23 INFO - Available Modes on Display 0 04:19:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:19:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:19:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:19:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:19:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:19:23 INFO - Intel Iris: 04:19:23 INFO - Chipset Model: Intel Iris 04:19:23 INFO - Type: GPU 04:19:23 INFO - Bus: Built-In 04:19:23 INFO - VRAM (Dynamic, Max): 1536 MB 04:19:23 INFO - Vendor: Intel (0x8086) 04:19:23 INFO - Device ID: 0x0a2e 04:19:23 INFO - Revision ID: 0x0009 04:19:23 INFO - Displays: 04:19:23 INFO - Display: 04:19:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:19:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:19:23 INFO - Display Serial Number: 1600x1200 60 04:19:23 INFO - Main Display: Yes 04:19:23 INFO - Mirror: Off 04:19:23 INFO - Online: Yes 04:19:23 INFO - Rotation: Supported 04:19:23 INFO - Return code: 0 04:19:23 INFO - Running post-action listener: _resource_record_post_action 04:19:23 INFO - [mozharness: 2017-05-29 11:19:23.444789Z] Finished run-tests step (success) 04:19:23 INFO - Running post-run listener: _resource_record_post_run 04:19:23 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 34442240; Write bytes: 425313792; Read time: 317; Write time: 1531 04:19:23 INFO - TinderboxPrint: CPU usage
53.7% 04:19:23 INFO - TinderboxPrint: I/O read bytes / time
34,442,240 / 317 04:19:23 INFO - TinderboxPrint: I/O write bytes / time
425,313,792 / 1,531 04:19:23 INFO - TinderboxPrint: CPU idle
2,208.0 (46.3%) 04:19:23 INFO - TinderboxPrint: CPU system
950.0 (19.9%) 04:19:23 INFO - TinderboxPrint: CPU user
1,611.7 (33.8%) 04:19:23 INFO - TinderboxPrint: Swap in / out
152,985,600 / 0 04:19:23 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123259392; Write bytes: 127940608; Read time: 11101; Write time: 322 04:19:23 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29244416; Write bytes: 295861760; Read time: 283; Write time: 1202 04:19:23 INFO - Running post-run listener: _upload_blobber_files 04:19:23 INFO - Blob upload gear active. 04:19:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:19:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:19:24 INFO - (blobuploader) - INFO - Open directory for files ... 04:19:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:19:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:19:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:25 INFO - (blobuploader) - INFO - Done attempting. 04:19:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:19:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:19:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:26 INFO - (blobuploader) - INFO - Done attempting. 04:19:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:19:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:19:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:27 INFO - (blobuploader) - INFO - Done attempting. 04:19:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:19:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:19:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:29 INFO - (blobuploader) - INFO - Done attempting. 04:19:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:19:29 INFO - Return code: 0 04:19:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:19:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:19:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7890d2f50af92af75fdaf8676d2c197138e6758d9d63cebaffb91f66c148edf22994402ad591fb47225af439f29cf0be509df811a119ce2038d49cc446175925", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54fcce7206c622c7321ddab3dfe72929575bb512ca822adab0bb2861e6a283c88523fefb11aaab9c654eb693e45ddebd97b180205fa6872a512ba019953a6594", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a434f4e6ba6973a7a841dda7c35bd708da2662adb9cc99c5f31bbb8a72b3138d5ab763763e302432b0dbefeadc3cd80ac26a21f6dc7b4a09befb7fc91d0de81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/708807bb5c698629da37b1a9b3b62dbf84292c8abbea846d6bf034ab6f7b7860ca9c6aa4b635c2bf11f2fa29585b463b1896b220bb19874f5083dacfedb105ae"} 04:19:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:19:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:19:29 INFO - Contents: 04:19:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7890d2f50af92af75fdaf8676d2c197138e6758d9d63cebaffb91f66c148edf22994402ad591fb47225af439f29cf0be509df811a119ce2038d49cc446175925", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54fcce7206c622c7321ddab3dfe72929575bb512ca822adab0bb2861e6a283c88523fefb11aaab9c654eb693e45ddebd97b180205fa6872a512ba019953a6594", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a434f4e6ba6973a7a841dda7c35bd708da2662adb9cc99c5f31bbb8a72b3138d5ab763763e302432b0dbefeadc3cd80ac26a21f6dc7b4a09befb7fc91d0de81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/708807bb5c698629da37b1a9b3b62dbf84292c8abbea846d6bf034ab6f7b7860ca9c6aa4b635c2bf11f2fa29585b463b1896b220bb19874f5083dacfedb105ae"} 04:19:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:19:29 INFO - Copying logs to upload dir... 04:19:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:19:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.946748 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-29 04:19:29.275251) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:29.278990) ========= 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.y0zB0kzeKC/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.gVmaCHavEL/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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/7890d2f50af92af75fdaf8676d2c197138e6758d9d63cebaffb91f66c148edf22994402ad591fb47225af439f29cf0be509df811a119ce2038d49cc446175925", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54fcce7206c622c7321ddab3dfe72929575bb512ca822adab0bb2861e6a283c88523fefb11aaab9c654eb693e45ddebd97b180205fa6872a512ba019953a6594", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a434f4e6ba6973a7a841dda7c35bd708da2662adb9cc99c5f31bbb8a72b3138d5ab763763e302432b0dbefeadc3cd80ac26a21f6dc7b4a09befb7fc91d0de81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/708807bb5c698629da37b1a9b3b62dbf84292c8abbea846d6bf034ab6f7b7860ca9c6aa4b635c2bf11f2fa29585b463b1896b220bb19874f5083dacfedb105ae"} build_url:https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/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/7890d2f50af92af75fdaf8676d2c197138e6758d9d63cebaffb91f66c148edf22994402ad591fb47225af439f29cf0be509df811a119ce2038d49cc446175925", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54fcce7206c622c7321ddab3dfe72929575bb512ca822adab0bb2861e6a283c88523fefb11aaab9c654eb693e45ddebd97b180205fa6872a512ba019953a6594", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a434f4e6ba6973a7a841dda7c35bd708da2662adb9cc99c5f31bbb8a72b3138d5ab763763e302432b0dbefeadc3cd80ac26a21f6dc7b4a09befb7fc91d0de81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/708807bb5c698629da37b1a9b3b62dbf84292c8abbea846d6bf034ab6f7b7860ca9c6aa4b635c2bf11f2fa29585b463b1896b220bb19874f5083dacfedb105ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:29.314469) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:29.314817) ========= 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.y0zB0kzeKC/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.gVmaCHavEL/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.005154 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:29.363755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:19:29.364091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:19:29.364426) ========= ========= Total master_lag: 2.01 =========