builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0137 starttime: 1495099672.98 results: success (0) buildid: 20170518010620 builduid: 54e22d892463456187fa29de05278853 revision: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:52.981092) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:52.981539) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:52.981827) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:53.006923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:53.007273) ========= 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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-18 02:27:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.8M=0s 2017-05-18 02:27:53 (54.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148144 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:53.379551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:53.379880) ========= 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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.028207 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:53.429035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 02:27:53.429381) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 --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 a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-18 02:27:53,522 truncating revision to first 12 chars 2017-05-18 02:27:53,522 Setting DEBUG logging. 2017-05-18 02:27:53,522 attempt 1/10 2017-05-18 02:27:53,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a3bc13b60d41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 02:27:54,267 unpacking tar archive at: mozilla-beta-a3bc13b60d41/testing/mozharness/ program finished with exit code 0 elapsedTime=1.018840 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 02:27:54.541537) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:54.541822) ========= script_repo_revision: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:54.542180) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:54.542443) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 02:27:54.561491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-18 02:27:54.561900) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:27:54 INFO - MultiFileLogger online at 20170518 02:27:54 in /builds/slave/test 02:27:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 02:27:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:54 INFO - {'append_to_log': False, 02:27:54 INFO - 'base_work_dir': '/builds/slave/test', 02:27:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:27:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:54 INFO - 'branch': 'Mozilla-Beta', 02:27:54 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:54 INFO - 'config_files': ('talos/mac_config.py',), 02:27:54 INFO - 'default_actions': ('clobber', 02:27:54 INFO - 'read-buildbot-config', 02:27:54 INFO - 'download-and-extract', 02:27:54 INFO - 'populate-webroot', 02:27:54 INFO - 'create-virtualenv', 02:27:54 INFO - 'install', 02:27:54 INFO - 'run-tests'), 02:27:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:54 INFO - 'download_minidump_stackwalk': True, 02:27:54 INFO - 'download_symbols': 'ondemand', 02:27:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:54 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:54 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:54 INFO - 'gecko_profile': False, 02:27:54 INFO - 'gecko_profile_interval': 0, 02:27:54 INFO - 'installer_path': 'installer.exe', 02:27:54 INFO - 'log_level': 'info', 02:27:54 INFO - 'log_name': 'talos', 02:27:54 INFO - 'log_to_console': True, 02:27:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:54 INFO - 'opt_config_files': (), 02:27:54 INFO - 'pip_index': False, 02:27:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:54 INFO - 'cmd': ('bash', 02:27:54 INFO - '-c', 02:27:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:54 INFO - 'enabled': True, 02:27:54 INFO - 'halt_on_failure': False, 02:27:54 INFO - 'name': 'check_screen_resolution'},), 02:27:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:54 INFO - 'cmd': ('bash', 02:27:54 INFO - '-c', 02:27:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:54 INFO - 'enabled': True, 02:27:54 INFO - 'halt_on_failure': False, 02:27:54 INFO - 'name': 'check_screen_resolution'},), 02:27:54 INFO - 'run_cmd_checks_enabled': True, 02:27:54 INFO - 'suite': 'dromaeojs-e10s', 02:27:54 INFO - 'system_bits': '32', 02:27:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:54 INFO - 'title': 't-yosemite-r7-0137', 02:27:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:54 INFO - 'use_talos_json': True, 02:27:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:54 INFO - 'work_dir': 'build'} 02:27:54 INFO - [mozharness: 2017-05-18 09:27:54.772031Z] Running clobber step. 02:27:54 INFO - Running pre-action listener: _resource_record_pre_action 02:27:54 INFO - Running main action method: clobber 02:27:54 INFO - rmtree: /builds/slave/test/build 02:27:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:57 INFO - Running post-action listener: _resource_record_post_action 02:27:57 INFO - [mozharness: 2017-05-18 09:27:57.467532Z] Finished clobber step (success) 02:27:57 INFO - [mozharness: 2017-05-18 09:27:57.467793Z] Running read-buildbot-config step. 02:27:57 INFO - Running pre-action listener: _resource_record_pre_action 02:27:57 INFO - Running main action method: read_buildbot_config 02:27:57 INFO - Using buildbot properties: 02:27:57 INFO - { 02:27:57 INFO - "project": "", 02:27:57 INFO - "product": "firefox", 02:27:57 INFO - "script_repo_revision": "production", 02:27:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 02:27:57 INFO - "repository": "", 02:27:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 02:27:57 INFO - "buildid": "20170518010620", 02:27:57 INFO - "pgo_build": "False", 02:27:57 INFO - "basedir": "/builds/slave/test", 02:27:57 INFO - "buildnumber": 35, 02:27:57 INFO - "slavename": "t-yosemite-r7-0137", 02:27:57 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:27:57 INFO - "platform": "yosemite_r7", 02:27:57 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 02:27:57 INFO - "branch": "mozilla-beta", 02:27:57 INFO - "repo_path": "releases/mozilla-beta", 02:27:57 INFO - "revision": "a3bc13b60d416ad4a0dd42820ede5c0d69c288a0", 02:27:57 INFO - "stage_platform": "macosx64", 02:27:57 INFO - "builduid": "54e22d892463456187fa29de05278853", 02:27:57 INFO - "slavebuilddir": "test" 02:27:57 INFO - } 02:27:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 02:27:57 INFO - Running post-action listener: _resource_record_post_action 02:27:57 INFO - [mozharness: 2017-05-18 09:27:57.473419Z] Finished read-buildbot-config step (success) 02:27:57 INFO - [mozharness: 2017-05-18 09:27:57.473626Z] Running download-and-extract step. 02:27:57 INFO - Running pre-action listener: _resource_record_pre_action 02:27:57 INFO - Running main action method: download_and_extract 02:27:57 INFO - mkdir: /builds/slave/test/build/tests 02:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:57 INFO - trying https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 02:27:57 INFO - Downloading https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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 02:27:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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 02:27:58 INFO - Downloaded 1287 bytes. 02:27:58 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:27:58 INFO - Using the following test package requirements: 02:27:58 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 02:27:58 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:27:58 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 02:27:58 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 02:27:58 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:27:58 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 02:27:58 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:27:58 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 02:27:58 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 02:27:58 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 02:27:58 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:27:58 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 02:27:58 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 02:27:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:27:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:27:58 INFO - Fetch https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:28:00 INFO - Expected file size: 51225509 02:28:00 INFO - Obtained file size: 51225509 02:28:16 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 02:28:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:28:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:28:16 INFO - Fetch https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:28:18 INFO - Expected file size: 51225509 02:28:18 INFO - Obtained file size: 51225509 02:28:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 02:28:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 02:28:33 INFO - Fetch https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 02:28:34 INFO - Expected file size: 14404449 02:28:34 INFO - Obtained file size: 14404449 02:28:35 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')]} 02:28:35 INFO - trying https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:28:35 INFO - Downloading https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 02:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/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 02:28:37 INFO - Downloaded 59542855 bytes. 02:28:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:28:37 INFO - mkdir: /builds/slave/test/properties 02:28:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:37 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:37 INFO - Contents: 02:28:37 INFO - build_url:https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:28:37 INFO - Running post-action listener: _resource_record_post_action 02:28:37 INFO - Running post-action listener: set_extra_try_arguments 02:28:37 INFO - [mozharness: 2017-05-18 09:28:37.648154Z] Finished download-and-extract step (success) 02:28:37 INFO - [mozharness: 2017-05-18 09:28:37.648319Z] Running populate-webroot step. 02:28:37 INFO - Running pre-action listener: _resource_record_pre_action 02:28:37 INFO - Running main action method: populate_webroot 02:28:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:37 INFO - '--tppagecycles', 02:28:37 INFO - '7'], 02:28:37 INFO - 'tests': ['tsvgm']}}, 02:28:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['damp', 'tps']}, 02:28:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'tests': ['damp', 'tps']}, 02:28:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['dromaeo_dom']}, 02:28:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['basic_compositor_video']}, 02:28:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:28:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['a11yr', 02:28:37 INFO - 'ts_paint', 02:28:37 INFO - 'tpaint', 02:28:37 INFO - 'sessionrestore', 02:28:37 INFO - 'sessionrestore_no_auto_restore', 02:28:37 INFO - 'tabpaint']}, 02:28:37 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:37 INFO - 'ts_paint', 02:28:37 INFO - 'tpaint', 02:28:37 INFO - 'sessionrestore', 02:28:37 INFO - 'sessionrestore_no_auto_restore', 02:28:37 INFO - 'tabpaint']}, 02:28:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:37 INFO - 'ts_paint', 02:28:37 INFO - 'tpaint', 02:28:37 INFO - 'sessionrestore', 02:28:37 INFO - 'sessionrestore_no_auto_restore', 02:28:37 INFO - 'tabpaint']}, 02:28:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:37 INFO - 'ts_paint', 02:28:37 INFO - 'tpaint', 02:28:37 INFO - 'sessionrestore', 02:28:37 INFO - 'sessionrestore_no_auto_restore', 02:28:37 INFO - 'tabpaint']}, 02:28:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['a11yr', 02:28:37 INFO - 'ts_paint', 02:28:37 INFO - 'tpaint', 02:28:37 INFO - 'sessionrestore', 02:28:37 INFO - 'sessionrestore_no_auto_restore', 02:28:37 INFO - 'tabpaint']}, 02:28:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['a11yr', 02:28:37 INFO - 'ts_paint', 02:28:37 INFO - 'tpaint', 02:28:37 INFO - 'sessionrestore', 02:28:37 INFO - 'sessionrestore_no_auto_restore', 02:28:37 INFO - 'tabpaint']}, 02:28:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['tsvgx', 02:28:37 INFO - 'tsvgr_opacity', 02:28:37 INFO - 'tart', 02:28:37 INFO - 'tscrollx', 02:28:37 INFO - 'cart', 02:28:37 INFO - 'tsvg_static']}, 02:28:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:37 INFO - 'tsvgr_opacity', 02:28:37 INFO - 'tart', 02:28:37 INFO - 'tscrollx', 02:28:37 INFO - 'cart', 02:28:37 INFO - 'tsvg_static']}, 02:28:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'talos_options': ['--disable-e10s'], 02:28:37 INFO - 'tests': ['tp5o']}, 02:28:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:28:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'talos_options': ['--disable-e10s', 02:28:37 INFO - '--xperf_path', 02:28:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:37 INFO - 'tests': ['tp5n']}, 02:28:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:28:37 INFO - 'talos_options': ['--xperf_path', 02:28:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:37 INFO - 'tests': ['tp5n']}}, 02:28:37 INFO - 'talos.zip': {'path': '', 02:28:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:37 INFO - Running post-action listener: _resource_record_post_action 02:28:37 INFO - [mozharness: 2017-05-18 09:28:37.668960Z] Finished populate-webroot step (success) 02:28:37 INFO - [mozharness: 2017-05-18 09:28:37.669098Z] Running create-virtualenv step. 02:28:37 INFO - Running pre-action listener: _resource_record_pre_action 02:28:37 INFO - Running main action method: create_virtualenv 02:28:37 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:37 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 02:28:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:37 INFO - Using real prefix '/tools/python27' 02:28:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:38 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:41 INFO - Installing pip.................done. 02:28:41 INFO - Return code: 0 02:28:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:28:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:28:41 INFO - Reading from file tmpfile_stdout 02:28:41 INFO - Output received: 02:28:41 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) 02:28:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28:41 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')]} 02:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:41 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')]} 02:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:41 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:41 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 02:28:41 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 02:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:41 INFO - 'HOME': '/Users/cltbld', 02:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:41 INFO - 'LOGNAME': 'cltbld', 02:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:41 INFO - 'MOZ_NO_REMOTE': '1', 02:28:41 INFO - 'NO_EM_RESTART': '1', 02:28:41 INFO - 'PAGER': '/bin/cat', 02:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:41 INFO - 'PWD': '/builds/slave/test', 02:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:41 INFO - 'SHELL': '/bin/bash', 02:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:41 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:41 INFO - 'USER': 'cltbld', 02:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:41 INFO - 'XPC_FLAGS': '0x0', 02:28:41 INFO - 'XPC_SERVICE_NAME': '0', 02:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:42 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 02:28:42 INFO - Cleaning up... 02:28:42 INFO - Return code: 0 02:28:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:42 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:42 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 02:28:42 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 02:28:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:42 INFO - 'HOME': '/Users/cltbld', 02:28:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:42 INFO - 'LOGNAME': 'cltbld', 02:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:42 INFO - 'MOZ_NO_REMOTE': '1', 02:28:42 INFO - 'NO_EM_RESTART': '1', 02:28:42 INFO - 'PAGER': '/bin/cat', 02:28:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:42 INFO - 'PWD': '/builds/slave/test', 02:28:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:42 INFO - 'SHELL': '/bin/bash', 02:28:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:42 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:42 INFO - 'USER': 'cltbld', 02:28:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:42 INFO - 'XPC_FLAGS': '0x0', 02:28:42 INFO - 'XPC_SERVICE_NAME': '0', 02:28:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:42 INFO - Downloading/unpacking psutil>=3.1.1 02:28:42 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 02:28:42 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 02:28:42 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 02:28:42 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 02:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:44 INFO - Installing collected packages: psutil 02:28:44 INFO - Running setup.py install for psutil 02:28:44 INFO - building 'psutil._psutil_osx' extension 02:28:44 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 02:28:44 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 02:28:44 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 02:28:45 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 02:28:45 INFO - building 'psutil._psutil_posix' extension 02:28:45 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 02:28:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:45 INFO - ^ 02:28:45 INFO - 1 warning generated. 02:28:45 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 02:28:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:45 INFO - Successfully installed psutil 02:28:45 INFO - Cleaning up... 02:28:45 INFO - Return code: 0 02:28:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28: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')]} 02:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:45 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:45 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 02:28:45 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 02:28:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:45 INFO - 'HOME': '/Users/cltbld', 02:28:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:45 INFO - 'LOGNAME': 'cltbld', 02:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:45 INFO - 'MOZ_NO_REMOTE': '1', 02:28:45 INFO - 'NO_EM_RESTART': '1', 02:28:45 INFO - 'PAGER': '/bin/cat', 02:28:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:45 INFO - 'PWD': '/builds/slave/test', 02:28:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:45 INFO - 'SHELL': '/bin/bash', 02:28:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:45 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:45 INFO - 'USER': 'cltbld', 02:28:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:45 INFO - 'XPC_FLAGS': '0x0', 02:28:45 INFO - 'XPC_SERVICE_NAME': '0', 02:28:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:28:45 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 02:28:45 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 02:28:45 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 02:28:45 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 02:28:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:28:47 INFO - Installing collected packages: mozsystemmonitor 02:28:47 INFO - Running setup.py install for mozsystemmonitor 02:28:47 INFO - Successfully installed mozsystemmonitor 02:28:47 INFO - Cleaning up... 02:28:47 INFO - Return code: 0 02:28:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:28:47 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')]} 02:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:47 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')]} 02:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:47 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:47 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 02:28:47 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 02:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:47 INFO - 'HOME': '/Users/cltbld', 02:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:47 INFO - 'LOGNAME': 'cltbld', 02:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:47 INFO - 'MOZ_NO_REMOTE': '1', 02:28:47 INFO - 'NO_EM_RESTART': '1', 02:28:47 INFO - 'PAGER': '/bin/cat', 02:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:47 INFO - 'PWD': '/builds/slave/test', 02:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:47 INFO - 'SHELL': '/bin/bash', 02:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:47 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:47 INFO - 'USER': 'cltbld', 02:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:47 INFO - 'XPC_FLAGS': '0x0', 02:28:47 INFO - 'XPC_SERVICE_NAME': '0', 02:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:47 INFO - Downloading/unpacking jsonschema==2.5.1 02:28:47 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 02:28:47 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 02:28:47 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 02:28:47 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 02:28:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:28:49 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 02:28:49 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 02:28:49 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 02:28:49 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 02:28:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:49 INFO - warning: no files found matching '*.txt' 02:28:49 INFO - no previously-included directories found matching 'build' 02:28:49 INFO - no previously-included directories found matching 'dist' 02:28:49 INFO - no previously-included directories found matching '.git*' 02:28:49 INFO - Installing collected packages: jsonschema, functools32 02:28:49 INFO - Running setup.py install for functools32 02:28:49 INFO - warning: no files found matching '*.txt' 02:28:49 INFO - no previously-included directories found matching 'build' 02:28:49 INFO - no previously-included directories found matching 'dist' 02:28:49 INFO - no previously-included directories found matching '.git*' 02:28:49 INFO - Successfully installed jsonschema functools32 02:28:49 INFO - Cleaning up... 02:28:49 INFO - Return code: 0 02:28:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:28:49 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')]} 02:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:50 INFO - 'HOME': '/Users/cltbld', 02:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:50 INFO - 'LOGNAME': 'cltbld', 02:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:50 INFO - 'MOZ_NO_REMOTE': '1', 02:28:50 INFO - 'NO_EM_RESTART': '1', 02:28:50 INFO - 'PAGER': '/bin/cat', 02:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:50 INFO - 'PWD': '/builds/slave/test', 02:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:50 INFO - 'SHELL': '/bin/bash', 02:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:50 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:50 INFO - 'USER': 'cltbld', 02:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:50 INFO - 'XPC_FLAGS': '0x0', 02:28:50 INFO - 'XPC_SERVICE_NAME': '0', 02:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:28:50 INFO - Cleaning up... 02:28:50 INFO - Return code: 0 02:28:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:51 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:51 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 02:28:51 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 02:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:51 INFO - 'HOME': '/Users/cltbld', 02:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:51 INFO - 'LOGNAME': 'cltbld', 02:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:51 INFO - 'MOZ_NO_REMOTE': '1', 02:28:51 INFO - 'NO_EM_RESTART': '1', 02:28:51 INFO - 'PAGER': '/bin/cat', 02:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:51 INFO - 'PWD': '/builds/slave/test', 02:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:51 INFO - 'SHELL': '/bin/bash', 02:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:51 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:51 INFO - 'USER': 'cltbld', 02:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:51 INFO - 'XPC_FLAGS': '0x0', 02:28:51 INFO - 'XPC_SERVICE_NAME': '0', 02:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:51 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:51 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 02:28:51 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 02:28:51 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 02:28:51 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 02:28:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:53 INFO - Downloading docopt-0.6.1.tar.gz 02:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:54 INFO - Running setup.py install for blobuploader 02:28:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:54 INFO - Running setup.py install for requests 02:28:54 INFO - Running setup.py install for docopt 02:28:54 INFO - Successfully installed blobuploader requests docopt 02:28:54 INFO - Cleaning up... 02:28:54 INFO - Return code: 0 02:28:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:54 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')]} 02:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:54 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')]} 02:28:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:54 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:54 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 02:28:54 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 02:28:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:54 INFO - 'HOME': '/Users/cltbld', 02:28:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:54 INFO - 'LOGNAME': 'cltbld', 02:28:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:54 INFO - 'MOZ_NO_REMOTE': '1', 02:28:54 INFO - 'NO_EM_RESTART': '1', 02:28:54 INFO - 'PAGER': '/bin/cat', 02:28:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:54 INFO - 'PWD': '/builds/slave/test', 02:28:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:54 INFO - 'SHELL': '/bin/bash', 02:28:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:54 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:54 INFO - 'USER': 'cltbld', 02:28:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:54 INFO - 'XPC_FLAGS': '0x0', 02:28:54 INFO - 'XPC_SERVICE_NAME': '0', 02:28:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-RsMfmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-93x9XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VTnwCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-CYqj0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Zt3xH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-X22zyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-NktW7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kjlp5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:55 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-MdAX8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-t59hLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-3orwAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-OVuBY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-U7VVOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rgVCT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-UCdVVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-WaROh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:56 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-k2WgKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:56 INFO - Running setup.py install for manifestparser 02:28:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:57 INFO - Running setup.py install for mozcrash 02:28:57 INFO - Running setup.py install for mozdebug 02:28:57 INFO - Running setup.py install for mozdevice 02:28:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:57 INFO - Running setup.py install for mozfile 02:28:57 INFO - Running setup.py install for mozhttpd 02:28:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:57 INFO - Running setup.py install for mozinfo 02:28:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:57 INFO - Running setup.py install for mozInstall 02:28:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Running setup.py install for mozleak 02:28:58 INFO - Running setup.py install for mozlog 02:28:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Running setup.py install for moznetwork 02:28:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Running setup.py install for mozprocess 02:28:58 INFO - Running setup.py install for mozprofile 02:28:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:58 INFO - Running setup.py install for mozrunner 02:28:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:59 INFO - Running setup.py install for mozscreenshot 02:28:59 INFO - Running setup.py install for moztest 02:28:59 INFO - Running setup.py install for mozversion 02:28:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:59 INFO - Cleaning up... 02:28:59 INFO - Return code: 0 02:28:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:59 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')]} 02:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:59 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')]} 02:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:59 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:28:59 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 02:28:59 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 02:28:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:28:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:59 INFO - 'HOME': '/Users/cltbld', 02:28:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:59 INFO - 'LOGNAME': 'cltbld', 02:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:59 INFO - 'MOZ_NO_REMOTE': '1', 02:28:59 INFO - 'NO_EM_RESTART': '1', 02:28:59 INFO - 'PAGER': '/bin/cat', 02:28:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:59 INFO - 'PWD': '/builds/slave/test', 02:28:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:59 INFO - 'SHELL': '/bin/bash', 02:28:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:28:59 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:28:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:59 INFO - 'USER': 'cltbld', 02:28:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:59 INFO - 'XPC_FLAGS': '0x0', 02:28:59 INFO - 'XPC_SERVICE_NAME': '0', 02:28:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:59 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-y7h6eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:59 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)) 02:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:59 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-pfegdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-qMhGU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rkW5WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-FDZ36G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-sGr9oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-J0dIz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-PFYPAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-qJOhAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Og91XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kMl3kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:00 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)) 02:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:00 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-iS0gIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:01 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)) 02:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:01 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-OBAFJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:01 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)) 02:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:01 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ta_Esj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:01 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)) 02:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:01 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-sLUcla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:01 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)) 02:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:01 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-5gcUXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:01 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)) 02:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:01 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Jgyi6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:01 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)) 02:29:01 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)) 02:29:01 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)) 02:29:01 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)) 02:29:01 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)) 02:29:01 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)) 02:29:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:02 INFO - Downloading blessings-1.6.tar.gz 02:29:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:03 INFO - Installing collected packages: blessings 02:29:03 INFO - Running setup.py install for blessings 02:29:03 INFO - Successfully installed blessings 02:29:03 INFO - Cleaning up... 02:29:03 INFO - Return code: 0 02:29:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:03 INFO - Reading from file tmpfile_stdout 02:29:03 INFO - Current package versions: 02:29:03 INFO - blessings == 1.6 02:29:03 INFO - blobuploader == 1.2.4 02:29:03 INFO - docopt == 0.6.1 02:29:03 INFO - functools32 == 3.2.3-2 02:29:03 INFO - jsonschema == 2.5.1 02:29:03 INFO - manifestparser == 1.1 02:29:03 INFO - mozInstall == 1.12 02:29:03 INFO - mozcrash == 1.0 02:29:03 INFO - mozdebug == 0.1 02:29:03 INFO - mozdevice == 0.49 02:29:03 INFO - mozfile == 1.2 02:29:03 INFO - mozhttpd == 0.7 02:29:03 INFO - mozinfo == 0.9 02:29:03 INFO - mozleak == 0.1 02:29:03 INFO - mozlog == 3.4 02:29:03 INFO - moznetwork == 0.27 02:29:03 INFO - mozprocess == 0.25 02:29:03 INFO - mozprofile == 0.28 02:29:03 INFO - mozrunner == 6.13 02:29:03 INFO - mozscreenshot == 0.1 02:29:03 INFO - mozsystemmonitor == 0.3 02:29:03 INFO - moztest == 0.8 02:29:03 INFO - mozversion == 1.4 02:29:03 INFO - psutil == 3.1.1 02:29:03 INFO - requests == 1.2.3 02:29:03 INFO - wsgiref == 0.1.2 02:29:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:03 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 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:29:03 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 02:29:03 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 02:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:03 INFO - 'HOME': '/Users/cltbld', 02:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:03 INFO - 'LOGNAME': 'cltbld', 02:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:03 INFO - 'MOZ_NO_REMOTE': '1', 02:29:03 INFO - 'NO_EM_RESTART': '1', 02:29:03 INFO - 'PAGER': '/bin/cat', 02:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:03 INFO - 'PWD': '/builds/slave/test', 02:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:03 INFO - 'SHELL': '/bin/bash', 02:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:29:03 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:03 INFO - 'USER': 'cltbld', 02:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:03 INFO - 'XPC_FLAGS': '0x0', 02:29:03 INFO - 'XPC_SERVICE_NAME': '0', 02:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 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)) 02:29:03 INFO - Cleaning up... 02:29:03 INFO - Return code: 0 02:29:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae334733d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, '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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 02:29:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:03 INFO - 'HOME': '/Users/cltbld', 02:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:03 INFO - 'LOGNAME': 'cltbld', 02:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:03 INFO - 'MOZ_NO_REMOTE': '1', 02:29:03 INFO - 'NO_EM_RESTART': '1', 02:29:03 INFO - 'PAGER': '/bin/cat', 02:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:03 INFO - 'PWD': '/builds/slave/test', 02:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:03 INFO - 'SHELL': '/bin/bash', 02:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:29:03 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:03 INFO - 'USER': 'cltbld', 02:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:03 INFO - 'XPC_FLAGS': '0x0', 02:29:03 INFO - 'XPC_SERVICE_NAME': '0', 02:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:04 INFO - Cleaning up... 02:29:04 INFO - Return code: 0 02:29:04 INFO - Running post-action listener: _resource_record_post_action 02:29:04 INFO - Running post-action listener: _start_resource_monitoring 02:29:04 INFO - Starting resource monitoring. 02:29:04 INFO - [mozharness: 2017-05-18 09:29:04.179440Z] Finished create-virtualenv step (success) 02:29:04 INFO - [mozharness: 2017-05-18 09:29:04.180403Z] Running install step. 02:29:04 INFO - Running pre-action listener: _resource_record_pre_action 02:29:04 INFO - Running main action method: install 02:29:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:04 INFO - Reading from file tmpfile_stdout 02:29:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:04 INFO - Reading from file tmpfile_stdout 02:29:04 INFO - Output received: 02:29:04 INFO - Usage: mozinstall [options] installer 02:29:04 INFO - Options: 02:29:04 INFO - -h, --help show this help message and exit 02:29:04 INFO - -d DEST, --destination=DEST 02:29:04 INFO - Directory to install application into. [default: 02:29:04 INFO - "/builds/slave/test"] 02:29:04 INFO - --app=APP Application being installed. [default: firefox] 02:29:04 INFO - mkdir: /builds/slave/test/build/application 02:29:04 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'] 02:29:04 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 02:29:19 INFO - Reading from file tmpfile_stdout 02:29:19 INFO - Output received: 02:29:19 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 02:29:19 INFO - Running post-action listener: _resource_record_post_action 02:29:19 INFO - [mozharness: 2017-05-18 09:29:19.399138Z] Finished install step (success) 02:29:19 INFO - [mozharness: 2017-05-18 09:29:19.399293Z] Running run-tests step. 02:29:19 INFO - Running pre-action listener: _resource_record_pre_action 02:29:19 INFO - Running main action method: run_tests 02:29:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:19 INFO - 2017-05-18 02:29:19.413 screenresolution[1625:7467] starting screenresolution argv=screenresolution get 02:29:19 INFO - 2017-05-18 02:29:19.416 screenresolution[1625:7467] Display 0: 1600x1200x32@60 02:29:19 INFO - 2017-05-18 02:29:19.422 screenresolution[1626:7469] starting screenresolution argv=screenresolution list 02:29:19 INFO - Available Modes on Display 0 02:29:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:19 INFO - Intel Iris: 02:29:19 INFO - Chipset Model: Intel Iris 02:29:19 INFO - Type: GPU 02:29:19 INFO - Bus: Built-In 02:29:19 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:19 INFO - Vendor: Intel (0x8086) 02:29:19 INFO - Device ID: 0x0a2e 02:29:19 INFO - Revision ID: 0x0009 02:29:19 INFO - Displays: 02:29:19 INFO - Display: 02:29:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:19 INFO - Display Serial Number: 1600x1200 60 02:29:19 INFO - Main Display: Yes 02:29:19 INFO - Mirror: Off 02:29:19 INFO - Online: Yes 02:29:19 INFO - Rotation: Supported 02:29:19 INFO - Return code: 0 02:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:19 INFO - Python 2.7.3 02:29:19 INFO - Return code: 0 02:29:19 INFO - grabbing minidump binary from tooltool 02:29:19 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')]} 02:29:19 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 0x1018bdd30>, '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 0x1017e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:19 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 02:29:19 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 02:29:19 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 02:29:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:19 INFO - Return code: 0 02:29:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:29:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:19 INFO - ENV: RUST_BACKTRACE is now 1 02:29:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:29:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:19 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:29:19 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBSRGSxSh6/Render', 02:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:19 INFO - 'HOME': '/Users/cltbld', 02:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:19 INFO - 'LOGNAME': 'cltbld', 02:29:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:19 INFO - 'MOZ_NO_REMOTE': '1', 02:29:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:19 INFO - 'NO_EM_RESTART': '1', 02:29:19 INFO - 'PAGER': '/bin/cat', 02:29:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:19 INFO - 'PWD': '/builds/slave/test', 02:29:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:19 INFO - 'RUST_BACKTRACE': '1', 02:29:19 INFO - 'SHELL': '/bin/bash', 02:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wMu9Gu4z0Z/Listeners', 02:29:19 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 02:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:19 INFO - 'USER': 'cltbld', 02:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:19 INFO - 'XPC_FLAGS': '0x0', 02:29:19 INFO - 'XPC_SERVICE_NAME': '0', 02:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:29:20 INFO - mozversion application_buildid: 20170518010620 02:29:20 INFO - mozversion application_changeset: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 02:29:20 INFO - mozversion application_display_name: Firefox 02:29:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:20 INFO - mozversion application_name: Firefox 02:29:20 INFO - mozversion application_remotingname: firefox 02:29:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:29:20 INFO - mozversion application_vendor: Mozilla 02:29:20 INFO - mozversion application_version: 54.0 02:29:20 INFO - mozversion platform_buildid: 20170518010620 02:29:20 INFO - mozversion platform_changeset: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 02:29:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:29:20 INFO - mozversion platform_version: 54.0 02:29:20 INFO - using testdate: 1495099760 02:29:20 INFO - actual date: 1495099760 02:29:20 INFO - starting webserver on 'localhost:49224' 02:29:20 INFO - SUITE-START | Running 2 tests 02:29:20 INFO - TEST-START | dromaeo_css 02:29:20 INFO - operating with platform_type : mac_ 02:29:20 INFO - Initialising browser for dromaeo_css test... 02:29:20 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpbVKsHp/profile http://localhost:49224/getInfo.html) 02:29:22 INFO - PROCESS | 1636 | 2017-05-18 02:29:22.823 plugin-container[1639:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 02:29:22 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:22 INFO - PROCESS | 1636 | 2017-05-18 02:29:22.823 plugin-container[1639:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 02:29:22 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:22 INFO - PROCESS | 1636 | 2017-05-18 02:29:22.824 plugin-container[1639:7637] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:22 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 02:29:22 INFO - PROCESS | 1636 | colorDepth:24 02:29:22 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 02:29:22 INFO - PROCESS | 1636 | __metrics 02:29:22 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 02:29:23 INFO - TEST-INFO | 1636: exit 0 02:29:23 INFO - Browser initialized. 02:29:23 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:23 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpbVKsHp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:25 INFO - PROCESS | 1640 | 2017-05-18 02:29:25.772 plugin-container[1641:7767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x823f, name = 'com.apple.tsm.portname' 02:29:25 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:25 INFO - PROCESS | 1640 | 2017-05-18 02:29:25.773 plugin-container[1641:7767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 02:29:25 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:25 INFO - PROCESS | 1640 | 2017-05-18 02:29:25.773 plugin-container[1641:7767] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:35 INFO - PROCESS | 1640 | RSS: Main: 174301184 02:29:35 INFO - PROCESS | 1640 | 02:32:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 02:32:40 INFO - PROCESS | 1640 | RSS: Main: 192192512 02:32:40 INFO - PROCESS | 1640 | 02:35:42 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 02:35:43 INFO - PROCESS | 1640 | RSS: Main: 203812864 02:35:43 INFO - PROCESS | 1640 | 02:38:45 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 02:38:46 INFO - PROCESS | 1640 | RSS: Main: 205332480 02:38:46 INFO - PROCESS | 1640 | 02:41:47 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 02:41:48 INFO - PROCESS | 1640 | RSS: Main: 211546112 02:41:48 INFO - PROCESS | 1640 | 02:44:51 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 02:44:52 INFO - PROCESS | 1640 | RSS: Main: 214540288 02:44:52 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 02:47:54 INFO - PROCESS | 1640 | RSS: Main: 210325504 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | __start_tp_report 02:47:54 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 02:47:54 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 02:47:54 INFO - PROCESS | 1640 | |i|pagename|runs| 02:47:54 INFO - PROCESS | 1640 | |0;dojo.html;3577.9284414311696;3660.6705396514317;3688.317661232672;3716.646918542738;3731.291054699607;4696.389469368982;5113.974430127844;6251;6275.341089185638;6317.463015643664;6249;6265.7493700251935;6270.623762574237;6306.490859035788;6324.241091069078;5647.124695672177;5690.374058853523;5699.401562835905;5739.253896993406;5808.796692115777;5762.250907382035;5767.769289228426;5784.768609255625;5797.449242321994;5822.854428639297;5469.507744303032;5478.041342765;5694.345150307706;5801.017164653543;5853.736581853806;5768.336641286242;5806.65160090396;5822.214001109838;5845.678487683176;5883.352831188566;5610.523105536035;5732.140178973145;5733.627314224562;5774.797882074107;5780.855478613026;5959.135925290809;6014.007688731354;6021.849453763691;6335.11308416822;6369.554131210817;6444.516661250425;6457.999010153434;6468.67656617172;6597.47220222381;6600.306967768412;6791.490638202105;6846.691898864563;6855.862882742317;6867.3476019778045;6875.278095799919;6329.461995730322;6356.809295721089;6384.3934826191435;6402.455791257796;6407.423331900151;6613.074169616258;6702.798916032527;6949.826254343682;6950.374466297956;6992.265812089658;6430.614163150226;6506.934930650727;6522.249941256816;6585.604863708097;6597.010448432678;6733.629650369178;6746.460283177334;6776.15298087739;6780.525363224527;6784.219814721271;6635.568688035262;6673.833154171185;6681.7327306908155;6684.766033188815;6703.798886033427;6463.515236357292;6491.221053473613;6597.571157874819;6598.967005165039;6703.698333575001;5374.5700343972385;5379.946200537945;5390.9191362129595;5395.649282796606;5420.810271640474;4007.1184339445276;4207.789610519461;4577.679562430664;4785.545373189541;4816.084943860656;5403.324584426947;6122.295935967331;6271.843203919939;6301.936980630227;6305.716242769086;6805.863882722417;6845.383915447534;6849.554778939353;6860.759873404507;6865.897011544831;6385.425311721874;6401.55189136756;6425.678716064178;6434.64609446485;6436.420722135031;5685.914711279334;5796.710164491758;5813.09896965971;6029.035354343372;6053.969730151409;6865.588064716033;6872.931270687229;6996.405305549085;7005.299470052954;7009.894851577333;5584;6781.423578995643;6810.489213308922;6812.8978065328065;6816.48876334267;5767.5674324425;6662.200535935719;6915.239323674291;6962;6978.755743548952;6487.643179625166;6597.2742998271115;6601.207855057424;6695.732170713117;6711.46308295345;6788.151481064867;6788.456923446014;6792.422644075309;6793.354631310018;6799.6600169990315;5246.816361427332;6181.598196117238;6315.652639104894;6336;6623.072769812326;6664.200295964515;6666.066750655009;6672.633005184762;6679.4990375721045;6688.097106890507;6168.444839964424;6308.463780578702;6339.96830015847;6393.904091438539;6480.57876238043;6615.073889655549;6671.066050752802;6677.499187560855;6688.732450702112;6710;6479;6491.383318584727;6527.51043671717;6542.953127499577;6553.180852393451 02:47:54 INFO - PROCESS | 1640 | |1;ext.html;787.4369825574713;829.0548774397187;851.0468275531406;959.7312752429323;968.5883499512705;40525.189496210056;42415.93960150998;43077.56922430775;43125.568744312586;43146.78426607871;40936.38595421071;41221.58778412215;41281.79359103208;41310.58689413105;41311.17377652453;8165.755027349174;8261.669533218665;11377.488013039432;11541.826872596917;11879.703007424798;11536.826947595793;11618.186726929118;11630.534778608846;11656.358900260482;11666.766664666702;11623.302601843874;11628.011619012394;11654.359010254433;11671;11700.18098733089;11508.482118304655;11581.768364632702;11595.884041159648;11613.593524226697;11626.534938602446;11336.149788765793;11411.771764564663;11412.972832445119;11433.142514311377;11448;36383.454248186295;36544.45183322252;36646.633533664586;36668;36713.08217294562;3912.080661044652;3925.391564307537;3951.8616848410165;3984.621460961233;4033.475648165749;26638.73361266382;26833.463330733277;27143.1857044289;28414.715852841415;28522.857385713145;3213.5822343095483;3277.1643230976188;3311.5021698451237;3334.316465124668;3447.3450044491465;388493;388773;390540;390703;390998;37024;42812;47039.29441058387;47127.76436117797;47229.76385118121;48094;48214.75892620585;48261;48271.75864120657;48401.51598484041;12037.87962120385;14494.565163045125;14927.477538286324;14941.028833125813;15001.099934003776;12831.486740530461;12834.614961551168;12836.358182090858;12838.67903302425;12845.229286242855;12368.1960672556;12378;12390;12402.19385739924;12482.375881206084;5122.974385128051;5397.9460205397445;5756.65460072397;5859.150423188614;5922.940770592325;965.2278177458146;975.9707208783748;980.4656462228103;990.7374545745383;992.5186284651938;23999.80000999943;28953.565696514568;29564.852175739416;29931;30162.84918575393;911.5852287209303;986.4771671014374;1038.8597539332245;1067.2049323254225;1081.5727787523883;14956.700865982619;15239.61900952463;15345.46290879814;15398.615034624225;15533.533993980198;480712;485134;486555;487357;489971;49120.77198070077;59450;60699.48251293753;61183;61190.69404653037;1293.4438191577524;1304.2826445454962;1337.2845527642664;1504.9172295523633;1528.7019031289014;25203;32358.514622280214;32459.86390476322;32660.673393265763;33679.83160084184;45997;46525.76737116428;46606.76696616495;46659.53340466552;46787.29819052785;335477;448809;449603;450050;452596;33369;33646.831765841016;34089.82955085209;34126.65873341235;34222.14444638904;47299;47543;47829.521704782506;48845.511544884095;48923;26165.345866353495;30286.84856575703;30692.925747598725;31880.5217921736;32507.837460812545;23676.644850327604;31853;32291.35417291594;32776;32900.835495822364;37038.62961370352;37476.812615936746;38213;38354.61645383622;38660.6133938657;32089.679103209604;32419.675803241666;32719.345613087127;33366;33568.49647255342;22501.43746406353;25615.23154305374;25887.352816179748;26202.4759504805;26324.078657246904 02:47:54 INFO - PROCESS | 1640 | |2;jquery.html;407.6555310762405;429.8624440179142;431.53394334119156;431.67408606502084;432.31767629248986;20957.16171353146;21785.128594856207;22344.106235750573;22642.320730378073;22984.77015229847;22612.773872261274;23153.536929261405;23586.64620030701;23728.40678983022;23957.281281561536;6866.931330686704;6880.483963702717;6894.896576551356;7156;7178.676959536833;6900.3444672756;6978.371946524811;7034.3317384848615;7038.1554213494455;7052.294770522958;6756.358145976124;6926.930730692704;6951.443884489228;7003.754868579601;7021.719131234744;6850.554713943628;6864.347886950732;6920.826979325507;6944.548604340727;6997.580145191279;6722.932770672329;6737.124173857367;6745.662716864137;6762.661866906684;6782.5252232343755;27719.445611087664;27985.440291194267;28056.859715701496;28123.0156944508;28244;3006.113196607006;3078.9846050769825;3085.1670049086847;3092.690730926912;3095.2107212660853;17125.229364678496;17182.39861604838;17241.39655112065;17306.048167350917;17414.738778918327;2110.4512826665023;2120.3850883243995;2268.3648578398074;2290.7022087128735;2292.885355732207;112354;137859;138967;139681.99999999898;141417;19299.903500482687;22583.322500325015;22588.435289117573;22623.66064509034;23004;35479.64520354763;35487.4676879847;36145.638543614754;36159.27681446409;36292.637073628925;11297.774044519063;11564.537418503334;11777.941110294565;11804.704882377837;11986.940065299617;9549.9045009549;9603.951980240054;9641.180499657608;9710.951445242728;9828.508574571386;10707;11056.613018544313;11091.44542772858;11136.33182009082;11198.328100314007;4428.712133711363;4469.173202957463;4476.529964353761;4481.731096134243;4501.167284052461;770.9498882572615;931.5807886450989;965.1217392173089;971.3249291742171;992.8808542974889;22704.205352812573;23390.18134365289;23719.407014824767;24097.879510602335;24194.274171774876;979.2264111351952;983.3460748602099;984.9359791613522;1020.4744556553381;1022.1516141602452;12057.457414416373;12665.240085594894;12873.935630321788;12886.871131288568;12976.416061277265;755888;773858;785495;792831;795768;125785.37107314589;128393;128485.35757321154;132148.33925830503;134087;1274.2609286613742;1290.851552071505;1304.1262354222692;1307.2940612069376;1311.0756916373934;20484.795152048886;20681.58636827225;20924.05841737095;20959;21017.844018578515;57019.42980570141;57583.42416575781;58502.12246816386;58549.414505854395;58937.115943260025;76511.61744191243;77525.449491011;79425.60287198528;79690.00774980673;88343;11789.528418863492;12114.15200935926;12316.876831231932;12447.688807779878;12487.375631218585;11384.804595517351;12808.231506109663;13105.14816536922;13563.525276615666;13588.86411135876;9594.808103838024;9649.179819715402;10261.435621040915;11879.703007424883;11919.821202681793;10463.94768026155;10936.50785714629;11001.724956876142;11192.608258710907;11273.943630282125;11780.823287650863;11883.524659013889;11952.282863028246;12135.211211271238;12180.756384872077;10708.89291107079;12081.335526546125;12359.62921112368;12471.628120906511;12480.625581232578;10493.160547156056;11630.6510804676;11754.529818807152;11778.939895409454;11948.641540753792 02:47:54 INFO - PROCESS | 1640 | |3;mootools.html;1623.2370785730702;1641.8342976486695;1655.3544117794063;1658.5770628489734;1668.4660908509275;9735.026497350262;10006.649767258148;11034.558617655284;11774.529018839236;11792.528298868046;11043.83434248487;11485.483153258103;11660.125490588218;11667.883321166786;11697.181197316191;2450.779429851313;2485.6644353012293;2489.788367988723;2490.2778194323623;2494.3639371960217;2369.1115831563166;2444.2422848916804;2496.051500429834;2496.7253602103847;2501.874906254689;2448.3511869354634;2453.2027091195346;2456.349067497115;2462.852228866265;2463.15021317646;2417.516496700658;2423.164008417093;2428.2351059416405;2431.6960379952507;2436.8903399347073;1997.350860970176;2397.0891061396733;2405.7714517120844;2406.362313986795;2420.854748715065;10656.147508199403;11684.357360345177;11694.181407301492;11699.7075073123;11702.122340824388;5940.495057920039;6122.846928826771;6131.172291740602;6145.5083593312875;6149.29283132441;3571.196480791801;3604.1350075981836;3661.286049220403;3676.669099781006;3704.944425833615;1118.558169523034;1256.760033051804;1263.4693428759851;1296.7406518696205;1326.4031185966353;14628.780568291484;14824.925875370662;16119.597010074842;16220.918895405448;16228.837711622851;4984.77568509418;5059.418166910852;5102.36220472441;5132.666376685504;5133.589312855037;13472.932635336956;13543.119697219847;13568.321583920764;13588.524401645895;13603.183808971493;6124.908126378108;6164.198654174928;6167.105769663373;6175.59858609189;6177.135201071854;4569.908601827945;4744.620430365562;5266.4470230626;5550;5551.056320425538;4172.916541669149;4252;4255.234057869551;4327.8485253016;4364.192624364503;1086.750047489082;1112.7607564373698;1155.7977353963022;1174.5536696055444;1186.8753780852885;944.2776276148752;961.2550273538064;966.3090890013674;975.7024107647094;997.4115271989562;6533.8693226136165;6750.3249675033085;6767.661616919135;6878.828029299208;6974.686139123752;1031.1647565471992;1061.8194906865851;1070.384528895888;1075.134516714037;1077.272840832435;4122.660135455989;4201.411802347674;4235.512916014698;4380.8685739427865;4431.645468362523;13642.795358069638;14179.078359906573;14179.22014289224;14180.432782688576;14195.574132776033;13292.800607990888;13826.170429774245;14087.647808804863;14097.78853317201;14109;10376.377417354983;10626.787464250672;10683.145348372267;10684.786304273872;10708.625198117872;6431.22825260953;6684.732610695518;7073.575585464889;7076.540024898365;7112.431005519649;12585.748285034431;12879.935600321938;13026.674333141747;13033.674158146123;13036.869631303565;12952.352382381032;13240.337983100999;13356.398962046536;13358.332083395597;13459.461621535029;9705.175060119687;9773.609055637979;9862.112409883144;9881.703548893545;9920;10729.839052414376;10759.46202689878;10808.18938579594;10856.945715271373;10879.728006799893;7313.963430182815;9172.587233574639;9261;9492.193163581172;9549.28380371461;7533.472656913963;9061.818763624822;9105.043970383014;9177.036411176998;9346.439213647203;7920;8110.351171906352;8137.877931830909;8214.712485062993;8225.83548329018;5939.881202376015;6032.366601506954;6076.270847498328;6099.512039036955;6216.160818289473;5136.434992150786;6043.397692629446;6255;6310.368963103653;6316.431521163117 02:47:54 INFO - PROCESS | 1640 | |4;prototype.html;359.60802725029714;400.93384591542394;401.1055346577133;401.3057410679526;405.3979839937693;4964.578010869081;5318.351311093559;5587.38538760736;5724.4561766632205;5733.509287585227;4425.911481770363;4619.884502887429;4800.687955282904;5045.445001049881;5375.838724838261;3724.9383925581214;4085;4258.212230737308;4317;4542.227821270376;4478.171538265431;4637.420322459692;4641.930371044437;4675.532446755315;4699;3723.90144907253;4086.4483294755123;4313.935290970638;4586.128635559233;4627.699199552018;3776.168558249247;4385.894770510141;4519.480259770103;4570.840020599297;4592.311153327011;3263.624683161443;3615.891523254307;3983.64147226751;4179.874603761892;4194.5386007539455;4797.30439086334;4986.6562715524005;5410.377806552257;5454.045542030169;5456.944111297686;4386.605205531518;5079.90781981872;5143.331366922276;5255.842324730264;5287.709175995319;5275.709835959021;5474.698891560963;5538.778448862041;5656.91514627281;5924.940750592482;4051.372037334218;4539.795709193094;4555.954440455619;4735.916196873364;4774.40319960005;5917.940820591782;5961.374055724174;5979.730912108966;6058.2132219179675;6235.131839353902;3962.2669806085964;4426.782634775463;4577.992841574848;4595.540445955377;4797.472278049412;3910.2179564087296;4193.496780386312;4887.877803054952;4936.7284799336385;4941.431735350408;4446.843820606601;4558.176729308237;4641.466231383367;5230.607704422184;5243.041503664785;4866.926996095061;5040.430324491384;5748.189320364072;5871.412858714094;5886;1458.5186888326828;1543.8456154384696;1639.4262008297;1654.5532706169267;1661.3686799016539;1134.6709454258337;1165.7551914098135;1231.8337024501757;1239;1268.4165283969294;5113.693178409308;5268.578513718893;5515.751791169407;5546.500814926676;5553.916691249635;5366.551031221548;6136.011592464844;6141.662208578482;6152.876942461216;6183.690815459299;4735.408073990751;5029.949700503022;5160.767765450564;5263.7104959226845;5562.527185189223;2853.444872544468;3114.8786436882706;3186.824724640167;3216.726578240829;3232.6444091149956;5334.573234141337;5478.178273259043;5762.020456522401;6018.337982821886;6337.904931426032;5175.767090480937;5427.620066595379;5476.863078422992;5653.575981801381;6297.622142671455;5852.385499522574;6303.896818056822;6348.396902294275;6371.776987805404;6373.426391624776;5943.7028148591535;5986.371430999682;6105.877882442415;6113.2969708482315;6114.633122012693;5108.412532558803;5959.582829201914;6022.608530445507;6042.8791424170395;6072.939270607238;5072.898542029065;5422.9186562202385;5655.688937108334;6156.5998210116195;6397.520185985976;4064.4800223861394;4517.644706587945;4769.499202583817;4780.498047704941;4831.7041267686745;4653.649906797181;4976.751162441791;5161.200013997836;5291.59772660253;6086.72178842448;5364;5425.83722488326;5869.589128761117;5930.495907847881;5944.8811023780145;5016.172331565251;5584;5586.944130558642;5891.440313170242;5922.890482496724;5024;5258.133362656202;5307.99148161848;5401.9202606995805;5827.912581311369;5132.230165475208;5137.845864624067;5675.8864822704145;5757.683327417032;5990.910136348046;5246.475352464876;5458.836234912959;5481.397046324981;5585.832425027255;5846.503047241032 02:47:54 INFO - PROCESS | 1640 | |5;yui.html;715.8854583266678;728.2280782370688;762.8398036412353;831.3598529132568;849.46908182386;15609.687806243883;16277.51167464975;17002.82997170028;17057.91471042643;17068;15584.532464026097;15595.1422671753;15757.763633545506;15773.842261577382;15780.447684331051;981.1169947047661;1010.3584224017728;1027.5170669785189;1028.7942411517727;1029.6396261308557;981.7545613596601;1019.9133073688745;1022.6829682798319;1022.7136401807505;1023.9795204095914;1072.7371793910463;1080.0117892128692;1081.621432498627;1082.0694202985464;1083.5232497701027;1075.0861767497643;1075.7633320669518;1075.9193060520493;1076.6985244131656;1077.7251800790827;790.4071946040469;799.6521513141802;809.6761295481855;809.6801763303521;810.2828996338225;14707.411703531845;16067.517974460785;16885.66228675432;17034.82965170357;17454.12729363527;12174.634760957186;14570.562883113524;14593.343299551545;14628.634284142876;14929.626759331104;12051.096167787451;12095.334756588383;12109.515619375214;12152.338890582008;12179.208351457128;3747.906302342436;3778.6032466590887;3823.9151168335807;3848.807559622008;3996.3805610130476;101295.98704012939;112794.30808537878;120390.39804801007;121033.39483302528;121440.39279803632;10941.671749847517;11086.334819910831;11147.777044459066;11243.887561124448;11293.265937714186;21820.45448863791;22382.888085559793;22389.664155037688;22409.66385504219;22485;8158.551279679675;8241.464304820287;8257.42198046143;8275.04836943747;8324.167583241628;4025.496812898388;4530.320451932171;4556.703814252063;4592.127495775792;4750.192467280571;2998.310388610632;3458.4985177149174;3641.326354624403;3650.653187947141;3653.8538458461894;658.499540349338;669.0466085827676;670.7618795327686;674.305465370669;681.819317880762;658.6048370977376;660.7687309441657;687.88993760998;697.3653974882832;709.202147583968;10702.785944281071;10753.89246107529;10769.25364700282;10807.945960270148;10940;652.7128063651978;690.7478770248823;694.1080711285973;695.8086526205334;696.3809143740823;4827.300041493963;5113.667611605234;5121.743912804345;5129.743512824344;5154.948450515447;110072;111248.33127503094;113294;113388;114417;92591;94893;95812.52093739486;98317;98942;63625.681871591274;66779;67563;68865;78494;10932.836007459735;11150.553977840795;11457.770844583229;11505.482253298454;11529.942350288195;46172.769136155446;46262;49390;50791.74604126956;51228.48771512386;57637.847243055745;67397.66301168462;69595.60808783898;69976.6501167491;72411;12971.416286266953;13089.149205301625;13330.26683532415;13658.658533536742;13912.51306204278;15171.544853654408;15195.468158614838;15357.155356455502;15411.152386618844;15624.84375156234;14003.369848356644;14004.579862604138;14014.229217393142;14376.28118594087;14416.567502974507;12744.745105097662;12910.612681619567;13769.31153442344;14202.644933876736;14246.501372451914;13495.987800915167;13757.269329922676;13824;14246.786298205328;14459.204743738774;9712.951435242778;9798;10089.798204036026;10333.896661033294;10478.318909270873;9988.653800976754;11047.937403312124;11406.14453915943;11650.41747912618;11899.226550274205 02:47:54 INFO - PROCESS | 1640 | __end_tp_report 02:47:54 INFO - PROCESS | 1640 | __start_cc_report 02:47:54 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,395 02:47:54 INFO - PROCESS | 1640 | __end_cc_report 02:47:54 INFO - PROCESS | 1640 | __startTimestamp1495100874743__endTimestamp 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | ------- Summary: start ------- 02:47:54 INFO - PROCESS | 1640 | Number of tests: 6 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:6202.31 Median:6425.68 stddev:722.80 (11.2%) stddev-sans-first:695.07 02:47:54 INFO - PROCESS | 1640 | Values: 3577.9 3660.7 3688.3 3716.6 3731.3 4696.4 5114.0 6251.0 6275.3 6317.5 6249.0 6265.7 6270.6 6306.5 6324.2 5647.1 5690.4 5699.4 5739.3 5808.8 5762.3 5767.8 5784.8 5797.4 5822.9 5469.5 5478.0 5694.3 5801.0 5853.7 5768.3 5806.7 5822.2 5845.7 5883.4 5610.5 5732.1 5733.6 5774.8 5780.9 5959.1 6014.0 6021.8 6335.1 6369.6 6444.5 6458.0 6468.7 6597.5 6600.3 6791.5 6846.7 6855.9 6867.3 6875.3 6329.5 6356.8 6384.4 6402.5 6407.4 6613.1 6702.8 6949.8 6950.4 6992.3 6430.6 6506.9 6522.2 6585.6 6597.0 6733.6 6746.5 6776.2 6780.5 6784.2 6635.6 6673.8 6681.7 6684.8 6703.8 6463.5 6491.2 6597.6 6599.0 6703.7 5374.6 5379.9 5390.9 5395.6 5420.8 4007.1 4207.8 4577.7 4785.5 4816.1 5403.3 6122.3 6271.8 6301.9 6305.7 6805.9 6845.4 6849.6 6860.8 6865.9 6385.4 6401.6 6425.7 6434.6 6436.4 5685.9 5796.7 5813.1 6029.0 6054.0 6865.6 6872.9 6996.4 7005.3 7009.9 5584.0 6781.4 6810.5 6812.9 6816.5 5767.6 6662.2 6915.2 6962.0 6978.8 6487.6 6597.3 6601.2 6695.7 6711.5 6788.2 6788.5 6792.4 6793.4 6799.7 5246.8 6181.6 6315.7 6336.0 6623.1 6664.2 6666.1 6672.6 6679.5 6688.1 6168.4 6308.5 6340.0 6393.9 6480.6 6615.1 6671.1 6677.5 6688.7 6710.0 6479.0 6491.4 6527.5 6543.0 6553.2 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:57656.31 Median:33607.66 stddev:115848.09 (344.7%) stddev-sans-first:116094.39 02:47:54 INFO - PROCESS | 1640 | Values: 787.4 829.1 851.0 959.7 968.6 40525.2 42415.9 43077.6 43125.6 43146.8 40936.4 41221.6 41281.8 41310.6 41311.2 8165.8 8261.7 11377.5 11541.8 11879.7 11536.8 11618.2 11630.5 11656.4 11666.8 11623.3 11628.0 11654.4 11671.0 11700.2 11508.5 11581.8 11595.9 11613.6 11626.5 11336.1 11411.8 11413.0 11433.1 11448.0 36383.5 36544.5 36646.6 36668.0 36713.1 3912.1 3925.4 3951.9 3984.6 4033.5 26638.7 26833.5 27143.2 28414.7 28522.9 3213.6 3277.2 3311.5 3334.3 3447.3 388493.0 388773.0 390540.0 390703.0 390998.0 37024.0 42812.0 47039.3 47127.8 47229.8 48094.0 48214.8 48261.0 48271.8 48401.5 12037.9 14494.6 14927.5 14941.0 15001.1 12831.5 12834.6 12836.4 12838.7 12845.2 12368.2 12378.0 12390.0 12402.2 12482.4 5123.0 5397.9 5756.7 5859.2 5922.9 965.2 976.0 980.5 990.7 992.5 23999.8 28953.6 29564.9 29931.0 30162.8 911.6 986.5 1038.9 1067.2 1081.6 14956.7 15239.6 15345.5 15398.6 15533.5 480712.0 485134.0 486555.0 487357.0 489971.0 49120.8 59450.0 60699.5 61183.0 61190.7 1293.4 1304.3 1337.3 1504.9 1528.7 25203.0 32358.5 32459.9 32660.7 33679.8 45997.0 46525.8 46606.8 46659.5 46787.3 335477.0 448809.0 449603.0 450050.0 452596.0 33369.0 33646.8 34089.8 34126.7 34222.1 47299.0 47543.0 47829.5 48845.5 48923.0 26165.3 30286.8 30692.9 31880.5 32507.8 23676.6 31853.0 32291.4 32776.0 32900.8 37038.6 37476.8 38213.0 38354.6 38660.6 32089.7 32419.7 32719.3 33366.0 33568.5 22501.4 25615.2 25887.4 26202.5 26324.1 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:43099.67 Median:22994.39 stddev:128851.53 (560.4%) stddev-sans-first:129173.12 02:47:54 INFO - PROCESS | 1640 | Values: 407.7 429.9 431.5 431.7 432.3 20957.2 21785.1 22344.1 22642.3 22984.8 22612.8 23153.5 23586.6 23728.4 23957.3 6866.9 6880.5 6894.9 7156.0 7178.7 6900.3 6978.4 7034.3 7038.2 7052.3 6756.4 6926.9 6951.4 7003.8 7021.7 6850.6 6864.3 6920.8 6944.5 6997.6 6722.9 6737.1 6745.7 6762.7 6782.5 27719.4 27985.4 28056.9 28123.0 28244.0 3006.1 3079.0 3085.2 3092.7 3095.2 17125.2 17182.4 17241.4 17306.0 17414.7 2110.5 2120.4 2268.4 2290.7 2292.9 112354.0 137859.0 138967.0 139682.0 141417.0 19299.9 22583.3 22588.4 22623.7 23004.0 35479.6 35487.5 36145.6 36159.3 36292.6 11297.8 11564.5 11777.9 11804.7 11986.9 9549.9 9604.0 9641.2 9711.0 9828.5 10707.0 11056.6 11091.4 11136.3 11198.3 4428.7 4469.2 4476.5 4481.7 4501.2 770.9 931.6 965.1 971.3 992.9 22704.2 23390.2 23719.4 24097.9 24194.3 979.2 983.3 984.9 1020.5 1022.2 12057.5 12665.2 12873.9 12886.9 12976.4 755888.0 773858.0 785495.0 792831.0 795768.0 125785.4 128393.0 128485.4 132148.3 134087.0 1274.3 1290.9 1304.1 1307.3 1311.1 20484.8 20681.6 20924.1 20959.0 21017.8 57019.4 57583.4 58502.1 58549.4 58937.1 76511.6 77525.4 79425.6 79690.0 88343.0 11789.5 12114.2 12316.9 12447.7 12487.4 11384.8 12808.2 13105.1 13563.5 13588.9 9594.8 9649.2 10261.4 11879.7 11919.8 10463.9 10936.5 11001.7 11192.6 11273.9 11780.8 11883.5 11952.3 12135.2 12180.8 10708.9 12081.3 12359.6 12471.6 12480.6 10493.2 11630.7 11754.5 11778.9 11948.6 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:7027.51 Median:2463.00 stddev:4422.95 (179.6%) stddev-sans-first:4416.71 02:47:54 INFO - PROCESS | 1640 | Values: 1623.2 1641.8 1655.4 1658.6 1668.5 9735.0 10006.6 11034.6 11774.5 11792.5 11043.8 11485.5 11660.1 11667.9 11697.2 2450.8 2485.7 2489.8 2490.3 2494.4 2369.1 2444.2 2496.1 2496.7 2501.9 2448.4 2453.2 2456.3 2462.9 2463.2 2417.5 2423.2 2428.2 2431.7 2436.9 1997.4 2397.1 2405.8 2406.4 2420.9 10656.1 11684.4 11694.2 11699.7 11702.1 5940.5 6122.8 6131.2 6145.5 6149.3 3571.2 3604.1 3661.3 3676.7 3704.9 1118.6 1256.8 1263.5 1296.7 1326.4 14628.8 14824.9 16119.6 16220.9 16228.8 4984.8 5059.4 5102.4 5132.7 5133.6 13472.9 13543.1 13568.3 13588.5 13603.2 6124.9 6164.2 6167.1 6175.6 6177.1 4569.9 4744.6 5266.4 5550.0 5551.1 4172.9 4252.0 4255.2 4327.8 4364.2 1086.8 1112.8 1155.8 1174.6 1186.9 944.3 961.3 966.3 975.7 997.4 6533.9 6750.3 6767.7 6878.8 6974.7 1031.2 1061.8 1070.4 1075.1 1077.3 4122.7 4201.4 4235.5 4380.9 4431.6 13642.8 14179.1 14179.2 14180.4 14195.6 13292.8 13826.2 14087.6 14097.8 14109.0 10376.4 10626.8 10683.1 10684.8 10708.6 6431.2 6684.7 7073.6 7076.5 7112.4 12585.7 12879.9 13026.7 13033.7 13036.9 12952.4 13240.3 13356.4 13358.3 13459.5 9705.2 9773.6 9862.1 9881.7 9920.0 10729.8 10759.5 10808.2 10856.9 10879.7 7314.0 9172.6 9261.0 9492.2 9549.3 7533.5 9061.8 9105.0 9177.0 9346.4 7920.0 8110.4 8137.9 8214.7 8225.8 5939.9 6032.4 6076.3 6099.5 6216.2 5136.4 6043.4 6255.0 6310.4 6316.4 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4807.07 Median:5152.05 stddev:1358.70 (26.4%) stddev-sans-first:1320.87 02:47:54 INFO - PROCESS | 1640 | Values: 359.6 400.9 401.1 401.3 405.4 4964.6 5318.4 5587.4 5724.5 5733.5 4425.9 4619.9 4800.7 5045.4 5375.8 3724.9 4085.0 4258.2 4317.0 4542.2 4478.2 4637.4 4641.9 4675.5 4699.0 3723.9 4086.4 4313.9 4586.1 4627.7 3776.2 4385.9 4519.5 4570.8 4592.3 3263.6 3615.9 3983.6 4179.9 4194.5 4797.3 4986.7 5410.4 5454.0 5456.9 4386.6 5079.9 5143.3 5255.8 5287.7 5275.7 5474.7 5538.8 5656.9 5924.9 4051.4 4539.8 4556.0 4735.9 4774.4 5917.9 5961.4 5979.7 6058.2 6235.1 3962.3 4426.8 4578.0 4595.5 4797.5 3910.2 4193.5 4887.9 4936.7 4941.4 4446.8 4558.2 4641.5 5230.6 5243.0 4866.9 5040.4 5748.2 5871.4 5886.0 1458.5 1543.8 1639.4 1654.6 1661.4 1134.7 1165.8 1231.8 1239.0 1268.4 5113.7 5268.6 5515.8 5546.5 5553.9 5366.6 6136.0 6141.7 6152.9 6183.7 4735.4 5029.9 5160.8 5263.7 5562.5 2853.4 3114.9 3186.8 3216.7 3232.6 5334.6 5478.2 5762.0 6018.3 6337.9 5175.8 5427.6 5476.9 5653.6 6297.6 5852.4 6303.9 6348.4 6371.8 6373.4 5943.7 5986.4 6105.9 6113.3 6114.6 5108.4 5959.6 6022.6 6042.9 6072.9 5072.9 5422.9 5655.7 6156.6 6397.5 4064.5 4517.6 4769.5 4780.5 4831.7 4653.6 4976.8 5161.2 5291.6 6086.7 5364.0 5425.8 5869.6 5930.5 5944.9 5016.2 5584.0 5586.9 5891.4 5922.9 5024.0 5258.1 5308.0 5401.9 5827.9 5132.2 5137.8 5675.9 5757.7 5990.9 5246.5 5458.8 5481.4 5585.8 5846.5 02:47:54 INFO - PROCESS | 1640 | 02:47:54 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:21235.02 Median:15777.14 stddev:30892.94 (195.8%) stddev-sans-first:30941.18 02:47:54 INFO - PROCESS | 1640 | Values: 715.9 728.2 762.8 831.4 849.5 15609.7 16277.5 17002.8 17057.9 17068.0 15584.5 15595.1 15757.8 15773.8 15780.4 981.1 1010.4 1027.5 1028.8 1029.6 981.8 1019.9 1022.7 1022.7 1024.0 1072.7 1080.0 1081.6 1082.1 1083.5 1075.1 1075.8 1075.9 1076.7 1077.7 790.4 799.7 809.7 809.7 810.3 14707.4 16067.5 16885.7 17034.8 17454.1 12174.6 14570.6 14593.3 14628.6 14929.6 12051.1 12095.3 12109.5 12152.3 12179.2 3747.9 3778.6 3823.9 3848.8 3996.4 101296.0 112794.3 120390.4 121033.4 121440.4 10941.7 11086.3 11147.8 11243.9 11293.3 21820.5 22382.9 22389.7 22409.7 22485.0 8158.6 8241.5 8257.4 8275.0 8324.2 4025.5 4530.3 4556.7 4592.1 4750.2 2998.3 3458.5 3641.3 3650.7 3653.9 658.5 669.0 670.8 674.3 681.8 658.6 660.8 687.9 697.4 709.2 10702.8 10753.9 10769.3 10807.9 10940.0 652.7 690.7 694.1 695.8 696.4 4827.3 5113.7 5121.7 5129.7 5154.9 110072.0 111248.3 113294.0 113388.0 114417.0 92591.0 94893.0 95812.5 98317.0 98942.0 63625.7 66779.0 67563.0 68865.0 78494.0 10932.8 11150.6 11457.8 11505.5 11529.9 46172.8 46262.0 49390.0 50791.7 51228.5 57637.8 67397.7 69595.6 69976.7 72411.0 12971.4 13089.1 13330.3 13658.7 13912.5 15171.5 15195.5 15357.2 15411.2 15624.8 14003.4 14004.6 14014.2 14376.3 14416.6 12744.7 12910.6 13769.3 14202.6 14246.5 13496.0 13757.3 13824.0 14246.8 14459.2 9713.0 9798.0 10089.8 10333.9 10478.3 9988.7 11047.9 11406.1 11650.4 11899.2 02:47:54 INFO - PROCESS | 1640 | -------- Summary: end -------- 02:47:54 INFO - PROCESS | 1640 | 02:47:55 INFO - TEST-INFO | 1640: exit 0 02:47:55 INFO - TEST-OK | dromaeo_css | took 1115377ms 02:47:55 INFO - TEST-START | kraken 02:47:55 INFO - operating with platform_type : mac_ 02:47:55 INFO - Initialising browser for kraken test... 02:47:55 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpRdvOGA/profile http://localhost:49224/getInfo.html) 02:47:57 INFO - PROCESS | 1652 | 2017-05-18 02:47:57.895 plugin-container[1654:10274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:47:57 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:57 INFO - PROCESS | 1652 | 2017-05-18 02:47:57.895 plugin-container[1654:10274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.CFPasteboardClient' 02:47:57 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:57 INFO - PROCESS | 1652 | 2017-05-18 02:47:57.895 plugin-container[1654:10274] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:47:57 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:47:57 INFO - PROCESS | 1652 | colorDepth:24 02:47:57 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:47:57 INFO - PROCESS | 1652 | __metrics 02:47:58 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 02:47:58 INFO - TEST-INFO | 1652: exit 0 02:47:58 INFO - Browser initialized. 02:47:58 INFO - Running cycle 1/1 for kraken test... 02:47:58 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpRdvOGA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:01 INFO - PROCESS | 1655 | 2017-05-18 02:48:01.055 plugin-container[1656:10397] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 02:48:01 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:48:01 INFO - PROCESS | 1655 | 2017-05-18 02:48:01.056 plugin-container[1656:10397] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 02:48:01 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:48:01 INFO - PROCESS | 1655 | 2017-05-18 02:48:01.056 plugin-container[1656:10397] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:48:10 INFO - PROCESS | 1655 | RSS: Main: 174362624 02:48:10 INFO - PROCESS | 1655 | 02:48:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:14 INFO - PROCESS | 1655 | RSS: Main: 187924480 02:48:14 INFO - PROCESS | 1655 | 02:48:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 02:48:18 INFO - PROCESS | 1655 | RSS: Main: 195866624 02:48:18 INFO - PROCESS | 1655 | 02:48:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 02:48:21 INFO - PROCESS | 1655 | RSS: Main: 199589888 02:48:21 INFO - PROCESS | 1655 | 02:48:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 02:48:24 INFO - PROCESS | 1655 | RSS: Main: 200945664 02:48:24 INFO - PROCESS | 1655 | 02:48:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:27 INFO - PROCESS | 1655 | RSS: Main: 200921088 02:48:27 INFO - PROCESS | 1655 | 02:48:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:30 INFO - PROCESS | 1655 | RSS: Main: 205185024 02:48:30 INFO - PROCESS | 1655 | 02:48:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:34 INFO - PROCESS | 1655 | RSS: Main: 206098432 02:48:34 INFO - PROCESS | 1655 | 02:48:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 02:48:37 INFO - PROCESS | 1655 | RSS: Main: 205422592 02:48:37 INFO - PROCESS | 1655 | 02:48:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:40 INFO - PROCESS | 1655 | RSS: Main: 204824576 02:48:40 INFO - PROCESS | 1655 | 02:48:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:42 INFO - PROCESS | 1655 | RSS: Main: 204918784 02:48:42 INFO - PROCESS | 1655 | 02:48:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:45 INFO - PROCESS | 1655 | RSS: Main: 204857344 02:48:45 INFO - PROCESS | 1655 | 02:48:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:48 INFO - PROCESS | 1655 | RSS: Main: 204935168 02:48:48 INFO - PROCESS | 1655 | 02:48:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:52 INFO - PROCESS | 1655 | RSS: Main: 205258752 02:48:52 INFO - PROCESS | 1655 | 02:48:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 02:48:54 INFO - PROCESS | 1655 | RSS: Main: 207310848 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | __start_tp_report 02:48:54 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 02:48:54 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 02:48:54 INFO - PROCESS | 1655 | |i|pagename|runs| 02:48:54 INFO - PROCESS | 1655 | |0;ai-astar;107;157;178;118;119;119;119;120;117;141 02:48:54 INFO - PROCESS | 1655 | |1;audio-beat-detection;143;109;108;144;110;109;142;147;159;110 02:48:54 INFO - PROCESS | 1655 | |2;audio-dft;197;197;195;194;194;196;197;193;198;193 02:48:54 INFO - PROCESS | 1655 | |3;audio-fft;80;57;56;109;87;52;72;78;97;87 02:48:54 INFO - PROCESS | 1655 | |4;audio-oscillator;85;69;84;69;72;69;81;69;70;81 02:48:54 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;91;90;103;90;90;104;89;91;89 02:48:54 INFO - PROCESS | 1655 | |6;imaging-darkroom;96;97;97;97;98;100;121;97;96;100 02:48:54 INFO - PROCESS | 1655 | |7;imaging-desaturate;97;129;92;91;93;91;92;91;91;91 02:48:54 INFO - PROCESS | 1655 | |8;json-parse-financial;65;65;65;64;68;69;68;70;69;68 02:48:54 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;59;65;57;62;65;67;61;59;61;60 02:48:54 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;81;83;82;83;85;87;81;86;83;87 02:48:54 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;134;135;137;133;137;134;133;138;180;176 02:48:54 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;163;164;160;163;166;163;163;163;162;161 02:48:54 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;64;65;63;63;71;59;64;73;82;84 02:48:54 INFO - PROCESS | 1655 | __end_tp_report 02:48:54 INFO - PROCESS | 1655 | __start_cc_report 02:48:54 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,980 02:48:54 INFO - PROCESS | 1655 | __end_cc_report 02:48:54 INFO - PROCESS | 1655 | __startTimestamp1495100934122__endTimestamp 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | ------- Summary: start ------- 02:48:54 INFO - PROCESS | 1655 | Number of tests: 14 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:129.50 Median:119.50 stddev:22.25 (18.6%) stddev-sans-first:22.06 02:48:54 INFO - PROCESS | 1655 | Values: 107.0 157.0 178.0 118.0 119.0 119.0 119.0 120.0 117.0 141.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:142.50 stddev:20.46 (14.4%) stddev-sans-first:20.98 02:48:54 INFO - PROCESS | 1655 | Values: 143.0 109.0 108.0 144.0 110.0 109.0 142.0 147.0 159.0 110.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:195.40 Median:196.50 stddev:1.84 (0.9%) stddev-sans-first:1.86 02:48:54 INFO - PROCESS | 1655 | Values: 197.0 197.0 195.0 194.0 194.0 196.0 197.0 193.0 198.0 193.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:77.50 Median:79.00 stddev:18.63 (23.6%) stddev-sans-first:19.73 02:48:54 INFO - PROCESS | 1655 | Values: 80.0 57.0 56.0 109.0 87.0 52.0 72.0 78.0 97.0 87.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:74.90 Median:76.50 stddev:6.92 (9.0%) stddev-sans-first:6.30 02:48:54 INFO - PROCESS | 1655 | Values: 85.0 69.0 84.0 69.0 72.0 69.0 81.0 69.0 70.0 81.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:90.00 stddev:5.74 (6.4%) stddev-sans-first:6.00 02:48:54 INFO - PROCESS | 1655 | Values: 90.0 91.0 90.0 103.0 90.0 90.0 104.0 89.0 91.0 89.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:97.00 stddev:7.55 (7.8%) stddev-sans-first:7.87 02:48:54 INFO - PROCESS | 1655 | Values: 96.0 97.0 97.0 97.0 98.0 100.0 121.0 97.0 96.0 100.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:91.50 stddev:11.81 (12.9%) stddev-sans-first:12.52 02:48:54 INFO - PROCESS | 1655 | Values: 97.0 129.0 92.0 91.0 93.0 91.0 92.0 91.0 91.0 91.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:2.13 (3.1%) stddev-sans-first:2.12 02:48:54 INFO - PROCESS | 1655 | Values: 65.0 65.0 65.0 64.0 68.0 69.0 68.0 70.0 69.0 68.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:61.50 stddev:3.17 (5.2%) stddev-sans-first:3.22 02:48:54 INFO - PROCESS | 1655 | Values: 59.0 65.0 57.0 62.0 65.0 67.0 61.0 59.0 61.0 60.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:2.30 (2.7%) stddev-sans-first:2.20 02:48:54 INFO - PROCESS | 1655 | Values: 81.0 83.0 82.0 83.0 85.0 87.0 81.0 86.0 83.0 87.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:137.00 stddev:18.18 (13.3%) stddev-sans-first:18.95 02:48:54 INFO - PROCESS | 1655 | Values: 134.0 135.0 137.0 133.0 137.0 134.0 133.0 138.0 180.0 176.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:163.00 stddev:1.62 (1.0%) stddev-sans-first:1.72 02:48:54 INFO - PROCESS | 1655 | Values: 163.0 164.0 160.0 163.0 166.0 163.0 163.0 163.0 162.0 161.0 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:68.00 stddev:8.51 (12.5%) stddev-sans-first:8.85 02:48:54 INFO - PROCESS | 1655 | Values: 64.0 65.0 63.0 63.0 71.0 59.0 64.0 73.0 82.0 84.0 02:48:54 INFO - PROCESS | 1655 | -------- Summary: end -------- 02:48:54 INFO - PROCESS | 1655 | 02:48:54 INFO - TEST-INFO | 1655: exit 0 02:48:54 INFO - TEST-OK | kraken | took 59528ms 02:48:54 INFO - SUITE-END | took 1174s 02:48:55 INFO - Completed test suite (00:19:35) 02:48:55 INFO - javascript benchmark 02:48:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8029.321299643088, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3577.9284414311696, 3660.6705396514317, 3688.317661232672, 3716.646918542738, 3731.291054699607, 4696.389469368982, 5113.974430127844, 6251.0, 6275.341089185638, 6317.463015643664, 6249.0, 6265.7493700251935, 6270.623762574237, 6306.490859035788, 6324.241091069078, 5647.124695672177, 5690.374058853523, 5699.401562835905, 5739.253896993406, 5808.796692115777, 5762.250907382035, 5767.769289228426, 5784.768609255625, 5797.449242321994, 5822.854428639297, 5469.507744303032, 5478.041342765, 5694.345150307706, 5801.017164653543, 5853.736581853806, 5768.336641286242, 5806.65160090396, 5822.214001109838, 5845.678487683176, 5883.352831188566, 5610.523105536035, 5732.140178973145, 5733.627314224562, 5774.797882074107, 5780.855478613026, 5959.135925290809, 6014.007688731354, 6021.849453763691, 6335.11308416822, 6369.554131210817, 6444.516661250425, 6457.999010153434, 6468.67656617172, 6597.47220222381, 6600.306967768412, 6791.490638202105, 6846.691898864563, 6855.862882742317, 6867.3476019778045, 6875.278095799919, 6329.461995730322, 6356.809295721089, 6384.3934826191435, 6402.455791257796, 6407.423331900151, 6613.074169616258, 6702.798916032527, 6949.826254343682, 6950.374466297956, 6992.265812089658, 6430.614163150226, 6506.934930650727, 6522.249941256816, 6585.604863708097, 6597.010448432678, 6733.629650369178, 6746.460283177334, 6776.15298087739, 6780.525363224527, 6784.219814721271, 6635.568688035262, 6673.833154171185, 6681.7327306908155, 6684.766033188815, 6703.798886033427, 6463.515236357292, 6491.221053473613, 6597.571157874819, 6598.967005165039, 6703.698333575001, 5374.5700343972385, 5379.946200537945, 5390.9191362129595, 5395.649282796606, 5420.810271640474, 4007.1184339445276, 4207.789610519461, 4577.679562430664, 4785.545373189541, 4816.084943860656, 5403.324584426947, 6122.295935967331, 6271.843203919939, 6301.936980630227, 6305.716242769086, 6805.863882722417, 6845.383915447534, 6849.554778939353, 6860.759873404507, 6865.897011544831, 6385.425311721874, 6401.55189136756, 6425.678716064178, 6434.64609446485, 6436.420722135031, 5685.914711279334, 5796.710164491758, 5813.09896965971, 6029.035354343372, 6053.969730151409, 6865.588064716033, 6872.931270687229, 6996.405305549085, 7005.299470052954, 7009.894851577333, 5584.0, 6781.423578995643, 6810.489213308922, 6812.8978065328065, 6816.48876334267, 5767.5674324425, 6662.200535935719, 6915.239323674291, 6962.0, 6978.755743548952, 6487.643179625166, 6597.2742998271115, 6601.207855057424, 6695.732170713117, 6711.46308295345, 6788.151481064867, 6788.456923446014, 6792.422644075309, 6793.354631310018, 6799.6600169990315, 5246.816361427332, 6181.598196117238, 6315.652639104894, 6336.0, 6623.072769812326, 6664.200295964515, 6666.066750655009, 6672.633005184762, 6679.4990375721045, 6688.097106890507, 6168.444839964424, 6308.463780578702, 6339.96830015847, 6393.904091438539, 6480.57876238043, 6615.073889655549, 6671.066050752802, 6677.499187560855, 6688.732450702112, 6710.0, 6479.0, 6491.383318584727, 6527.51043671717, 6542.953127499577, 6553.180852393451], "value": 6156.68386837044, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.4369825574713, 829.0548774397187, 851.0468275531406, 959.7312752429323, 968.5883499512705, 40525.189496210056, 42415.93960150998, 43077.56922430775, 43125.568744312586, 43146.78426607871, 40936.38595421071, 41221.58778412215, 41281.79359103208, 41310.58689413105, 41311.17377652453, 8165.755027349174, 8261.669533218665, 11377.488013039432, 11541.826872596917, 11879.703007424798, 11536.826947595793, 11618.186726929118, 11630.534778608846, 11656.358900260482, 11666.766664666702, 11623.302601843874, 11628.011619012394, 11654.359010254433, 11671.0, 11700.18098733089, 11508.482118304655, 11581.768364632702, 11595.884041159648, 11613.593524226697, 11626.534938602446, 11336.149788765793, 11411.771764564663, 11412.972832445119, 11433.142514311377, 11448.0, 36383.454248186295, 36544.45183322252, 36646.633533664586, 36668.0, 36713.08217294562, 3912.080661044652, 3925.391564307537, 3951.8616848410165, 3984.621460961233, 4033.475648165749, 26638.73361266382, 26833.463330733277, 27143.1857044289, 28414.715852841415, 28522.857385713145, 3213.5822343095483, 3277.1643230976188, 3311.5021698451237, 3334.316465124668, 3447.3450044491465, 388493.0, 388773.0, 390540.0, 390703.0, 390998.0, 37024.0, 42812.0, 47039.29441058387, 47127.76436117797, 47229.76385118121, 48094.0, 48214.75892620585, 48261.0, 48271.75864120657, 48401.51598484041, 12037.87962120385, 14494.565163045125, 14927.477538286324, 14941.028833125813, 15001.099934003776, 12831.486740530461, 12834.614961551168, 12836.358182090858, 12838.67903302425, 12845.229286242855, 12368.1960672556, 12378.0, 12390.0, 12402.19385739924, 12482.375881206084, 5122.974385128051, 5397.9460205397445, 5756.65460072397, 5859.150423188614, 5922.940770592325, 965.2278177458146, 975.9707208783748, 980.4656462228103, 990.7374545745383, 992.5186284651938, 23999.80000999943, 28953.565696514568, 29564.852175739416, 29931.0, 30162.84918575393, 911.5852287209303, 986.4771671014374, 1038.8597539332245, 1067.2049323254225, 1081.5727787523883, 14956.700865982619, 15239.61900952463, 15345.46290879814, 15398.615034624225, 15533.533993980198, 480712.0, 485134.0, 486555.0, 487357.0, 489971.0, 49120.77198070077, 59450.0, 60699.48251293753, 61183.0, 61190.69404653037, 1293.4438191577524, 1304.2826445454962, 1337.2845527642664, 1504.9172295523633, 1528.7019031289014, 25203.0, 32358.514622280214, 32459.86390476322, 32660.673393265763, 33679.83160084184, 45997.0, 46525.76737116428, 46606.76696616495, 46659.53340466552, 46787.29819052785, 335477.0, 448809.0, 449603.0, 450050.0, 452596.0, 33369.0, 33646.831765841016, 34089.82955085209, 34126.65873341235, 34222.14444638904, 47299.0, 47543.0, 47829.521704782506, 48845.511544884095, 48923.0, 26165.345866353495, 30286.84856575703, 30692.925747598725, 31880.5217921736, 32507.837460812545, 23676.644850327604, 31853.0, 32291.35417291594, 32776.0, 32900.835495822364, 37038.62961370352, 37476.812615936746, 38213.0, 38354.61645383622, 38660.6133938657, 32089.679103209604, 32419.675803241666, 32719.345613087127, 33366.0, 33568.49647255342, 22501.43746406353, 25615.23154305374, 25887.352816179748, 26202.4759504805, 26324.078657246904], "value": 19334.919879574983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.6555310762405, 429.8624440179142, 431.53394334119156, 431.67408606502084, 432.31767629248986, 20957.16171353146, 21785.128594856207, 22344.106235750573, 22642.320730378073, 22984.77015229847, 22612.773872261274, 23153.536929261405, 23586.64620030701, 23728.40678983022, 23957.281281561536, 6866.931330686704, 6880.483963702717, 6894.896576551356, 7156.0, 7178.676959536833, 6900.3444672756, 6978.371946524811, 7034.3317384848615, 7038.1554213494455, 7052.294770522958, 6756.358145976124, 6926.930730692704, 6951.443884489228, 7003.754868579601, 7021.719131234744, 6850.554713943628, 6864.347886950732, 6920.826979325507, 6944.548604340727, 6997.580145191279, 6722.932770672329, 6737.124173857367, 6745.662716864137, 6762.661866906684, 6782.5252232343755, 27719.445611087664, 27985.440291194267, 28056.859715701496, 28123.0156944508, 28244.0, 3006.113196607006, 3078.9846050769825, 3085.1670049086847, 3092.690730926912, 3095.2107212660853, 17125.229364678496, 17182.39861604838, 17241.39655112065, 17306.048167350917, 17414.738778918327, 2110.4512826665023, 2120.3850883243995, 2268.3648578398074, 2290.7022087128735, 2292.885355732207, 112354.0, 137859.0, 138967.0, 139681.99999999898, 141417.0, 19299.903500482687, 22583.322500325015, 22588.435289117573, 22623.66064509034, 23004.0, 35479.64520354763, 35487.4676879847, 36145.638543614754, 36159.27681446409, 36292.637073628925, 11297.774044519063, 11564.537418503334, 11777.941110294565, 11804.704882377837, 11986.940065299617, 9549.9045009549, 9603.951980240054, 9641.180499657608, 9710.951445242728, 9828.508574571386, 10707.0, 11056.613018544313, 11091.44542772858, 11136.33182009082, 11198.328100314007, 4428.712133711363, 4469.173202957463, 4476.529964353761, 4481.731096134243, 4501.167284052461, 770.9498882572615, 931.5807886450989, 965.1217392173089, 971.3249291742171, 992.8808542974889, 22704.205352812573, 23390.18134365289, 23719.407014824767, 24097.879510602335, 24194.274171774876, 979.2264111351952, 983.3460748602099, 984.9359791613522, 1020.4744556553381, 1022.1516141602452, 12057.457414416373, 12665.240085594894, 12873.935630321788, 12886.871131288568, 12976.416061277265, 755888.0, 773858.0, 785495.0, 792831.0, 795768.0, 125785.37107314589, 128393.0, 128485.35757321154, 132148.33925830503, 134087.0, 1274.2609286613742, 1290.851552071505, 1304.1262354222692, 1307.2940612069376, 1311.0756916373934, 20484.795152048886, 20681.58636827225, 20924.05841737095, 20959.0, 21017.844018578515, 57019.42980570141, 57583.42416575781, 58502.12246816386, 58549.414505854395, 58937.115943260025, 76511.61744191243, 77525.449491011, 79425.60287198528, 79690.00774980673, 88343.0, 11789.528418863492, 12114.15200935926, 12316.876831231932, 12447.688807779878, 12487.375631218585, 11384.804595517351, 12808.231506109663, 13105.14816536922, 13563.525276615666, 13588.86411135876, 9594.808103838024, 9649.179819715402, 10261.435621040915, 11879.703007424883, 11919.821202681793, 10463.94768026155, 10936.50785714629, 11001.724956876142, 11192.608258710907, 11273.943630282125, 11780.823287650863, 11883.524659013889, 11952.282863028246, 12135.211211271238, 12180.756384872077, 10708.89291107079, 12081.335526546125, 12359.62921112368, 12471.628120906511, 12480.625581232578, 10493.160547156056, 11630.6510804676, 11754.529818807152, 11778.939895409454, 11948.641540753792], "value": 12015.704026333104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1623.2370785730702, 1641.8342976486695, 1655.3544117794063, 1658.5770628489734, 1668.4660908509275, 9735.026497350262, 10006.649767258148, 11034.558617655284, 11774.529018839236, 11792.528298868046, 11043.83434248487, 11485.483153258103, 11660.125490588218, 11667.883321166786, 11697.181197316191, 2450.779429851313, 2485.6644353012293, 2489.788367988723, 2490.2778194323623, 2494.3639371960217, 2369.1115831563166, 2444.2422848916804, 2496.051500429834, 2496.7253602103847, 2501.874906254689, 2448.3511869354634, 2453.2027091195346, 2456.349067497115, 2462.852228866265, 2463.15021317646, 2417.516496700658, 2423.164008417093, 2428.2351059416405, 2431.6960379952507, 2436.8903399347073, 1997.350860970176, 2397.0891061396733, 2405.7714517120844, 2406.362313986795, 2420.854748715065, 10656.147508199403, 11684.357360345177, 11694.181407301492, 11699.7075073123, 11702.122340824388, 5940.495057920039, 6122.846928826771, 6131.172291740602, 6145.5083593312875, 6149.29283132441, 3571.196480791801, 3604.1350075981836, 3661.286049220403, 3676.669099781006, 3704.944425833615, 1118.558169523034, 1256.760033051804, 1263.4693428759851, 1296.7406518696205, 1326.4031185966353, 14628.780568291484, 14824.925875370662, 16119.597010074842, 16220.918895405448, 16228.837711622851, 4984.77568509418, 5059.418166910852, 5102.36220472441, 5132.666376685504, 5133.589312855037, 13472.932635336956, 13543.119697219847, 13568.321583920764, 13588.524401645895, 13603.183808971493, 6124.908126378108, 6164.198654174928, 6167.105769663373, 6175.59858609189, 6177.135201071854, 4569.908601827945, 4744.620430365562, 5266.4470230626, 5550.0, 5551.056320425538, 4172.916541669149, 4252.0, 4255.234057869551, 4327.8485253016, 4364.192624364503, 1086.750047489082, 1112.7607564373698, 1155.7977353963022, 1174.5536696055444, 1186.8753780852885, 944.2776276148752, 961.2550273538064, 966.3090890013674, 975.7024107647094, 997.4115271989562, 6533.8693226136165, 6750.3249675033085, 6767.661616919135, 6878.828029299208, 6974.686139123752, 1031.1647565471992, 1061.8194906865851, 1070.384528895888, 1075.134516714037, 1077.272840832435, 4122.660135455989, 4201.411802347674, 4235.512916014698, 4380.8685739427865, 4431.645468362523, 13642.795358069638, 14179.078359906573, 14179.22014289224, 14180.432782688576, 14195.574132776033, 13292.800607990888, 13826.170429774245, 14087.647808804863, 14097.78853317201, 14109.0, 10376.377417354983, 10626.787464250672, 10683.145348372267, 10684.786304273872, 10708.625198117872, 6431.22825260953, 6684.732610695518, 7073.575585464889, 7076.540024898365, 7112.431005519649, 12585.748285034431, 12879.935600321938, 13026.674333141747, 13033.674158146123, 13036.869631303565, 12952.352382381032, 13240.337983100999, 13356.398962046536, 13358.332083395597, 13459.461621535029, 9705.175060119687, 9773.609055637979, 9862.112409883144, 9881.703548893545, 9920.0, 10729.839052414376, 10759.46202689878, 10808.18938579594, 10856.945715271373, 10879.728006799893, 7313.963430182815, 9172.587233574639, 9261.0, 9492.193163581172, 9549.28380371461, 7533.472656913963, 9061.818763624822, 9105.043970383014, 9177.036411176998, 9346.439213647203, 7920.0, 8110.351171906352, 8137.877931830909, 8214.712485062993, 8225.83548329018, 5939.881202376015, 6032.366601506954, 6076.270847498328, 6099.512039036955, 6216.160818289473, 5136.434992150786, 6043.397692629446, 6255.0, 6310.368963103653, 6316.431521163117], "value": 5353.178161950015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.60802725029714, 400.93384591542394, 401.1055346577133, 401.3057410679526, 405.3979839937693, 4964.578010869081, 5318.351311093559, 5587.38538760736, 5724.4561766632205, 5733.509287585227, 4425.911481770363, 4619.884502887429, 4800.687955282904, 5045.445001049881, 5375.838724838261, 3724.9383925581214, 4085.0, 4258.212230737308, 4317.0, 4542.227821270376, 4478.171538265431, 4637.420322459692, 4641.930371044437, 4675.532446755315, 4699.0, 3723.90144907253, 4086.4483294755123, 4313.935290970638, 4586.128635559233, 4627.699199552018, 3776.168558249247, 4385.894770510141, 4519.480259770103, 4570.840020599297, 4592.311153327011, 3263.624683161443, 3615.891523254307, 3983.64147226751, 4179.874603761892, 4194.5386007539455, 4797.30439086334, 4986.6562715524005, 5410.377806552257, 5454.045542030169, 5456.944111297686, 4386.605205531518, 5079.90781981872, 5143.331366922276, 5255.842324730264, 5287.709175995319, 5275.709835959021, 5474.698891560963, 5538.778448862041, 5656.91514627281, 5924.940750592482, 4051.372037334218, 4539.795709193094, 4555.954440455619, 4735.916196873364, 4774.40319960005, 5917.940820591782, 5961.374055724174, 5979.730912108966, 6058.2132219179675, 6235.131839353902, 3962.2669806085964, 4426.782634775463, 4577.992841574848, 4595.540445955377, 4797.472278049412, 3910.2179564087296, 4193.496780386312, 4887.877803054952, 4936.7284799336385, 4941.431735350408, 4446.843820606601, 4558.176729308237, 4641.466231383367, 5230.607704422184, 5243.041503664785, 4866.926996095061, 5040.430324491384, 5748.189320364072, 5871.412858714094, 5886.0, 1458.5186888326828, 1543.8456154384696, 1639.4262008297, 1654.5532706169267, 1661.3686799016539, 1134.6709454258337, 1165.7551914098135, 1231.8337024501757, 1239.0, 1268.4165283969294, 5113.693178409308, 5268.578513718893, 5515.751791169407, 5546.500814926676, 5553.916691249635, 5366.551031221548, 6136.011592464844, 6141.662208578482, 6152.876942461216, 6183.690815459299, 4735.408073990751, 5029.949700503022, 5160.767765450564, 5263.7104959226845, 5562.527185189223, 2853.444872544468, 3114.8786436882706, 3186.824724640167, 3216.726578240829, 3232.6444091149956, 5334.573234141337, 5478.178273259043, 5762.020456522401, 6018.337982821886, 6337.904931426032, 5175.767090480937, 5427.620066595379, 5476.863078422992, 5653.575981801381, 6297.622142671455, 5852.385499522574, 6303.896818056822, 6348.396902294275, 6371.776987805404, 6373.426391624776, 5943.7028148591535, 5986.371430999682, 6105.877882442415, 6113.2969708482315, 6114.633122012693, 5108.412532558803, 5959.582829201914, 6022.608530445507, 6042.8791424170395, 6072.939270607238, 5072.898542029065, 5422.9186562202385, 5655.688937108334, 6156.5998210116195, 6397.520185985976, 4064.4800223861394, 4517.644706587945, 4769.499202583817, 4780.498047704941, 4831.7041267686745, 4653.649906797181, 4976.751162441791, 5161.200013997836, 5291.59772660253, 6086.72178842448, 5364.0, 5425.83722488326, 5869.589128761117, 5930.495907847881, 5944.8811023780145, 5016.172331565251, 5584.0, 5586.944130558642, 5891.440313170242, 5922.890482496724, 5024.0, 5258.133362656202, 5307.99148161848, 5401.9202606995805, 5827.912581311369, 5132.230165475208, 5137.845864624067, 5675.8864822704145, 5757.683327417032, 5990.910136348046, 5246.475352464876, 5458.836234912959, 5481.397046324981, 5585.832425027255, 5846.503047241032], "value": 4413.803488323392, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.8854583266678, 728.2280782370688, 762.8398036412353, 831.3598529132568, 849.46908182386, 15609.687806243883, 16277.51167464975, 17002.82997170028, 17057.91471042643, 17068.0, 15584.532464026097, 15595.1422671753, 15757.763633545506, 15773.842261577382, 15780.447684331051, 981.1169947047661, 1010.3584224017728, 1027.5170669785189, 1028.7942411517727, 1029.6396261308557, 981.7545613596601, 1019.9133073688745, 1022.6829682798319, 1022.7136401807505, 1023.9795204095914, 1072.7371793910463, 1080.0117892128692, 1081.621432498627, 1082.0694202985464, 1083.5232497701027, 1075.0861767497643, 1075.7633320669518, 1075.9193060520493, 1076.6985244131656, 1077.7251800790827, 790.4071946040469, 799.6521513141802, 809.6761295481855, 809.6801763303521, 810.2828996338225, 14707.411703531845, 16067.517974460785, 16885.66228675432, 17034.82965170357, 17454.12729363527, 12174.634760957186, 14570.562883113524, 14593.343299551545, 14628.634284142876, 14929.626759331104, 12051.096167787451, 12095.334756588383, 12109.515619375214, 12152.338890582008, 12179.208351457128, 3747.906302342436, 3778.6032466590887, 3823.9151168335807, 3848.807559622008, 3996.3805610130476, 101295.98704012939, 112794.30808537878, 120390.39804801007, 121033.39483302528, 121440.39279803632, 10941.671749847517, 11086.334819910831, 11147.777044459066, 11243.887561124448, 11293.265937714186, 21820.45448863791, 22382.888085559793, 22389.664155037688, 22409.66385504219, 22485.0, 8158.551279679675, 8241.464304820287, 8257.42198046143, 8275.04836943747, 8324.167583241628, 4025.496812898388, 4530.320451932171, 4556.703814252063, 4592.127495775792, 4750.192467280571, 2998.310388610632, 3458.4985177149174, 3641.326354624403, 3650.653187947141, 3653.8538458461894, 658.499540349338, 669.0466085827676, 670.7618795327686, 674.305465370669, 681.819317880762, 658.6048370977376, 660.7687309441657, 687.88993760998, 697.3653974882832, 709.202147583968, 10702.785944281071, 10753.89246107529, 10769.25364700282, 10807.945960270148, 10940.0, 652.7128063651978, 690.7478770248823, 694.1080711285973, 695.8086526205334, 696.3809143740823, 4827.300041493963, 5113.667611605234, 5121.743912804345, 5129.743512824344, 5154.948450515447, 110072.0, 111248.33127503094, 113294.0, 113388.0, 114417.0, 92591.0, 94893.0, 95812.52093739486, 98317.0, 98942.0, 63625.681871591274, 66779.0, 67563.0, 68865.0, 78494.0, 10932.836007459735, 11150.553977840795, 11457.770844583229, 11505.482253298454, 11529.942350288195, 46172.769136155446, 46262.0, 49390.0, 50791.74604126956, 51228.48771512386, 57637.847243055745, 67397.66301168462, 69595.60808783898, 69976.6501167491, 72411.0, 12971.416286266953, 13089.149205301625, 13330.26683532415, 13658.658533536742, 13912.51306204278, 15171.544853654408, 15195.468158614838, 15357.155356455502, 15411.152386618844, 15624.84375156234, 14003.369848356644, 14004.579862604138, 14014.229217393142, 14376.28118594087, 14416.567502974507, 12744.745105097662, 12910.612681619567, 13769.31153442344, 14202.644933876736, 14246.501372451914, 13495.987800915167, 13757.269329922676, 13824.0, 14246.786298205328, 14459.204743738774, 9712.951435242778, 9798.0, 10089.798204036026, 10333.896661033294, 10478.318909270873, 9988.653800976754, 11047.937403312124, 11406.14453915943, 11650.41747912618, 11899.226550274205], "value": 7928.13379342523, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 157.0, 178.0, 118.0, 119.0, 119.0, 119.0, 120.0, 117.0, 141.0], "value": 129.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 108.0, 144.0, 110.0, 109.0, 142.0, 147.0, 159.0, 110.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 195.0, 194.0, 194.0, 196.0, 197.0, 193.0, 198.0, 193.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 56.0, 109.0, 87.0, 52.0, 72.0, 78.0, 97.0, 87.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 84.0, 69.0, 72.0, 69.0, 81.0, 69.0, 70.0, 81.0], "value": 74.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 103.0, 90.0, 90.0, 104.0, 89.0, 91.0, 89.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 97.0, 98.0, 100.0, 121.0, 97.0, 96.0, 100.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 129.0, 92.0, 91.0, 93.0, 91.0, 92.0, 91.0, 91.0, 91.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 68.0, 69.0, 68.0, 70.0, 69.0, 68.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 57.0, 62.0, 65.0, 67.0, 61.0, 59.0, 61.0, 60.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 83.0, 85.0, 87.0, 81.0, 86.0, 83.0, 87.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 135.0, 137.0, 133.0, 137.0, 134.0, 133.0, 138.0, 180.0, 176.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 160.0, 163.0, 166.0, 163.0, 163.0, 163.0, 162.0, 161.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 63.0, 71.0, 59.0, 64.0, 73.0, 82.0, 84.0], "value": 68.8, "unit": "score"}]}]} 02:48:55 INFO - Return code: 0 02:48:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:48:55 ERROR - # TBPL SUCCESS # 02:48:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:55 INFO - 2017-05-18 02:48:55.470 screenresolution[1660:10776] starting screenresolution argv=screenresolution get 02:48:55 INFO - 2017-05-18 02:48:55.473 screenresolution[1660:10776] Display 0: 1600x1200x32@60 02:48:55 INFO - 2017-05-18 02:48:55.478 screenresolution[1661:10779] starting screenresolution argv=screenresolution list 02:48:55 INFO - Available Modes on Display 0 02:48:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:55 INFO - Intel Iris: 02:48:55 INFO - Chipset Model: Intel Iris 02:48:55 INFO - Type: GPU 02:48:55 INFO - Bus: Built-In 02:48:55 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:55 INFO - Vendor: Intel (0x8086) 02:48:55 INFO - Device ID: 0x0a2e 02:48:55 INFO - Revision ID: 0x0009 02:48:55 INFO - Displays: 02:48:55 INFO - Display: 02:48:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:55 INFO - Display Serial Number: 1600x1200 60 02:48:55 INFO - Main Display: Yes 02:48:55 INFO - Mirror: Off 02:48:55 INFO - Online: Yes 02:48:55 INFO - Rotation: Supported 02:48:55 INFO - Return code: 0 02:48:55 INFO - Running post-action listener: _resource_record_post_action 02:48:55 INFO - [mozharness: 2017-05-18 09:48:55.711650Z] Finished run-tests step (success) 02:48:55 INFO - Running post-run listener: _resource_record_post_run 02:48:55 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 41617408; Write bytes: 426254848; Read time: 405; Write time: 2199 02:48:55 INFO - TinderboxPrint: CPU usage
54.4% 02:48:55 INFO - TinderboxPrint: I/O read bytes / time
41,617,408 / 405 02:48:55 INFO - TinderboxPrint: I/O write bytes / time
426,254,848 / 2,199 02:48:55 INFO - TinderboxPrint: CPU idle
2,172.1 (45.6%) 02:48:55 INFO - TinderboxPrint: CPU system
956.6 (20.1%) 02:48:55 INFO - TinderboxPrint: CPU user
1,636.9 (34.3%) 02:48:55 INFO - TinderboxPrint: Swap in / out
162,754,560 / 0 02:48:55 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121680384; Write bytes: 115922432; Read time: 11113; Write time: 243 02:48:55 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 39446528; Write bytes: 300178432; Read time: 381; Write time: 1922 02:48:56 INFO - Running post-run listener: _upload_blobber_files 02:48:56 INFO - Blob upload gear active. 02:48:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:56 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 02:48:56 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'] 02:48:56 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 02:48:56 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:48:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:48:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:57 INFO - (blobuploader) - INFO - Done attempting. 02:48:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:48:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:48:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:58 INFO - (blobuploader) - INFO - Done attempting. 02:48:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:48:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:48:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:59 INFO - (blobuploader) - INFO - Done attempting. 02:48:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:48:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:03 INFO - (blobuploader) - INFO - Done attempting. 02:49:03 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:03 INFO - Return code: 0 02:49:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/760dd75762515e573b1c5da11a679079c29bf2d74442135bc8f0763858b5ad04bbb10de7b1b92530ec5b25380267ac770a9f69d95324752184f1deb72fa81212", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff878cda5dd0d2e4ff7fae4de108a3455479410172dcd868506aeaf3158801487401cbf9b04833793b663f9b40368f35ca398dd9f712eb91bdaefd1cc6b92485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/553ef2e4bc81bbe73369a69243a48946383448876c1add08392e486264d38f9695864299f728a2c785cd26e6708a9674f0cc12e213ca6c539eee20c742f2f479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1918f68dd457e5948321954b52df66ace3a081c8682511b85754e9331f7f9bc923f43acffb1af0401d3a898c02408a53241a754e41372929cbf8c74b5f172e2"} 02:49:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:03 INFO - Contents: 02:49:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/760dd75762515e573b1c5da11a679079c29bf2d74442135bc8f0763858b5ad04bbb10de7b1b92530ec5b25380267ac770a9f69d95324752184f1deb72fa81212", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff878cda5dd0d2e4ff7fae4de108a3455479410172dcd868506aeaf3158801487401cbf9b04833793b663f9b40368f35ca398dd9f712eb91bdaefd1cc6b92485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/553ef2e4bc81bbe73369a69243a48946383448876c1add08392e486264d38f9695864299f728a2c785cd26e6708a9674f0cc12e213ca6c539eee20c742f2f479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1918f68dd457e5948321954b52df66ace3a081c8682511b85754e9331f7f9bc923f43acffb1af0401d3a898c02408a53241a754e41372929cbf8c74b5f172e2"} 02:49:03 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:03 INFO - Copying logs to upload dir... 02:49:03 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.564019 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-18 02:49:03.155545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:03.159249) ========= 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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/760dd75762515e573b1c5da11a679079c29bf2d74442135bc8f0763858b5ad04bbb10de7b1b92530ec5b25380267ac770a9f69d95324752184f1deb72fa81212", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff878cda5dd0d2e4ff7fae4de108a3455479410172dcd868506aeaf3158801487401cbf9b04833793b663f9b40368f35ca398dd9f712eb91bdaefd1cc6b92485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/553ef2e4bc81bbe73369a69243a48946383448876c1add08392e486264d38f9695864299f728a2c785cd26e6708a9674f0cc12e213ca6c539eee20c742f2f479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1918f68dd457e5948321954b52df66ace3a081c8682511b85754e9331f7f9bc923f43acffb1af0401d3a898c02408a53241a754e41372929cbf8c74b5f172e2"} build_url:https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/XJWMpVjSRHOgPsUWiK37QA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/760dd75762515e573b1c5da11a679079c29bf2d74442135bc8f0763858b5ad04bbb10de7b1b92530ec5b25380267ac770a9f69d95324752184f1deb72fa81212", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ff878cda5dd0d2e4ff7fae4de108a3455479410172dcd868506aeaf3158801487401cbf9b04833793b663f9b40368f35ca398dd9f712eb91bdaefd1cc6b92485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/553ef2e4bc81bbe73369a69243a48946383448876c1add08392e486264d38f9695864299f728a2c785cd26e6708a9674f0cc12e213ca6c539eee20c742f2f479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1918f68dd457e5948321954b52df66ace3a081c8682511b85754e9331f7f9bc923f43acffb1af0401d3a898c02408a53241a754e41372929cbf8c74b5f172e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:03.193060) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:03.193434) ========= 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.jBSRGSxSh6/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.wMu9Gu4z0Z/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:49:03.242839) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 02:49:03.243161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 02:49:03.243503) ========= ========= Total master_lag: 0.44 =========